builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-031 starttime: 1462777772.85 results: success (0) buildid: 20160508232942 builduid: 630289a7b1ca42f39c0d3611577e95bb revision: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.847134) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.847746) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.848037) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.864678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.864957) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 00:09:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-09 00:09:33 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079007 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.973605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:32.973879) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019946 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.040518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.040817) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 77c1d14c1e1f389a6e4dffd50e401cf739461a82 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 77c1d14c1e1f389a6e4dffd50e401cf739461a82 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 00:09:33,531 truncating revision to first 12 chars 2016-05-09 00:09:33,531 Setting DEBUG logging. 2016-05-09 00:09:33,531 attempt 1/10 2016-05-09 00:09:33,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/77c1d14c1e1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 00:09:33,982 unpacking tar archive at: fx-team-77c1d14c1e1f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.746717 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.805634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.805968) ========= script_repo_revision: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.806371) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.806652) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 00:09:33.817371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-09 00:09:33.817638) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:09:34 INFO - MultiFileLogger online at 20160509 00:09:34 in /builds/slave/test 00:09:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 00:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:09:34 INFO - {'append_to_log': False, 00:09:34 INFO - 'base_work_dir': '/builds/slave/test', 00:09:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 00:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:09:34 INFO - 'branch': 'Fx-Team-Non-PGO', 00:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 00:09:34 INFO - 'config_files': ('talos/linux_config.py',), 00:09:34 INFO - 'default_actions': ('clobber', 00:09:34 INFO - 'read-buildbot-config', 00:09:34 INFO - 'download-and-extract', 00:09:34 INFO - 'populate-webroot', 00:09:34 INFO - 'create-virtualenv', 00:09:34 INFO - 'install', 00:09:34 INFO - 'run-tests'), 00:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:09:34 INFO - 'download_minidump_stackwalk': True, 00:09:34 INFO - 'download_symbols': 'ondemand', 00:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:09:34 INFO - 'tooltool.py': '/tools/tooltool.py', 00:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:09:34 INFO - '/tools/misc-python/virtualenv.py')}, 00:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:09:34 INFO - 'installer_path': 'installer.exe', 00:09:34 INFO - 'log_level': 'info', 00:09:34 INFO - 'log_name': 'talos', 00:09:34 INFO - 'log_to_console': True, 00:09:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:09:34 INFO - 'opt_config_files': (), 00:09:34 INFO - 'pip_index': False, 00:09:34 INFO - 'sps_profile': False, 00:09:34 INFO - 'sps_profile_interval': 0, 00:09:34 INFO - 'suite': 'dromaeojs-e10s', 00:09:34 INFO - 'system_bits': '32', 00:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:09:34 INFO - 'title': 'talos-linux64-ix-031', 00:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:09:34 INFO - 'use_talos_json': True, 00:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:09:34 INFO - 'work_dir': 'build'} 00:09:34 INFO - ##### 00:09:34 INFO - ##### Running clobber step. 00:09:34 INFO - ##### 00:09:34 INFO - Running pre-action listener: _resource_record_pre_action 00:09:34 INFO - Running main action method: clobber 00:09:34 INFO - rmtree: /builds/slave/test/build 00:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:09:35 INFO - Running post-action listener: _resource_record_post_action 00:09:35 INFO - ##### 00:09:35 INFO - ##### Finished clobber step (success) 00:09:35 INFO - ##### 00:09:35 INFO - ##### 00:09:35 INFO - ##### Running read-buildbot-config step. 00:09:35 INFO - ##### 00:09:35 INFO - Running pre-action listener: _resource_record_pre_action 00:09:35 INFO - Running main action method: read_buildbot_config 00:09:35 INFO - Using buildbot properties: 00:09:35 INFO - { 00:09:35 INFO - "project": "", 00:09:35 INFO - "product": "firefox", 00:09:35 INFO - "script_repo_revision": "production", 00:09:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 00:09:35 INFO - "repository": "", 00:09:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 00:09:35 INFO - "buildid": "20160508232942", 00:09:35 INFO - "pgo_build": "False", 00:09:35 INFO - "basedir": "/builds/slave/test", 00:09:35 INFO - "buildnumber": 1262, 00:09:35 INFO - "slavename": "talos-linux64-ix-031", 00:09:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 00:09:35 INFO - "platform": "ubuntu64_hw", 00:09:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 00:09:35 INFO - "branch": "fx-team", 00:09:35 INFO - "repo_path": "integration/fx-team", 00:09:35 INFO - "revision": "77c1d14c1e1f389a6e4dffd50e401cf739461a82", 00:09:35 INFO - "stage_platform": "linux64", 00:09:35 INFO - "builduid": "630289a7b1ca42f39c0d3611577e95bb", 00:09:35 INFO - "slavebuilddir": "test" 00:09:35 INFO - } 00:09:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 00:09:35 INFO - Running post-action listener: _resource_record_post_action 00:09:35 INFO - ##### 00:09:35 INFO - ##### Finished read-buildbot-config step (success) 00:09:35 INFO - ##### 00:09:35 INFO - ##### 00:09:35 INFO - ##### Running download-and-extract step. 00:09:35 INFO - ##### 00:09:35 INFO - Running pre-action listener: _resource_record_pre_action 00:09:35 INFO - Running main action method: download_and_extract 00:09:35 INFO - mkdir: /builds/slave/test/build/tests 00:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:09:35 INFO - https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 00:09:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 00:09:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 00:09:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 00:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 00:09:44 INFO - Downloaded 1361 bytes. 00:09:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 00:09:44 INFO - Using the following test package requirements: 00:09:44 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 00:09:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:09:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:09:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'jsshell-linux-x86_64.zip'], 00:09:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:09:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 00:09:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:09:44 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:09:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:09:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 00:09:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:09:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:09:44 INFO - https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:09:52 INFO - Downloaded 22321369 bytes. 00:09:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:09:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 00:09:53 INFO - Return code: 0 00:09:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 00:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:09:53 INFO - https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:09:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 00:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:09:58 INFO - Downloaded 22321369 bytes. 00:09:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 00:10:00 INFO - Return code: 0 00:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:00 INFO - https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:10:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 00:10:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 00:10:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 00:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:10:01 INFO - Downloaded 10840152 bytes. 00:10:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:10:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 00:10:01 INFO - Return code: 0 00:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:01 INFO - https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 00:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 00:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 00:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 00:10:04 INFO - Downloaded 54847455 bytes. 00:10:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 00:10:04 INFO - mkdir: /builds/slave/test/properties 00:10:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:10:04 INFO - Writing to file /builds/slave/test/properties/build_url 00:10:04 INFO - Contents: 00:10:04 INFO - build_url:https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 00:10:04 INFO - Running post-action listener: _resource_record_post_action 00:10:04 INFO - Running post-action listener: set_extra_try_arguments 00:10:04 INFO - ##### 00:10:04 INFO - ##### Finished download-and-extract step (success) 00:10:04 INFO - ##### 00:10:04 INFO - ##### 00:10:04 INFO - ##### Running populate-webroot step. 00:10:04 INFO - ##### 00:10:04 INFO - Running pre-action listener: _resource_record_pre_action 00:10:04 INFO - Running main action method: populate_webroot 00:10:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:10:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:10:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:10:04 INFO - '--tppagecycles', 00:10:04 INFO - '7'], 00:10:04 INFO - 'tests': ['tsvgm']}}, 00:10:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:10:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:10:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:10:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:10:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:10:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:10:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['damp', 'tps']}, 00:10:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'tests': ['damp', 'tps']}, 00:10:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['dromaeo_dom']}, 00:10:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:10:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'other-e10s': {'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['a11yr', 00:10:04 INFO - 'ts_paint', 00:10:04 INFO - 'tpaint', 00:10:04 INFO - 'sessionrestore', 00:10:04 INFO - 'sessionrestore_no_auto_restore', 00:10:04 INFO - 'tabpaint']}, 00:10:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['tsvgx', 00:10:04 INFO - 'tsvgr_opacity', 00:10:04 INFO - 'tart', 00:10:04 INFO - 'tscrollx', 00:10:04 INFO - 'cart']}, 00:10:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:10:04 INFO - 'tsvgr_opacity', 00:10:04 INFO - 'tart', 00:10:04 INFO - 'tscrollx', 00:10:04 INFO - 'cart']}, 00:10:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'talos_options': ['--disable-e10s'], 00:10:04 INFO - 'tests': ['tp5o']}, 00:10:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'tests': ['tp5o']}, 00:10:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'talos_options': ['--disable-e10s', 00:10:04 INFO - '--xperf_path', 00:10:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:10:04 INFO - 'tests': ['tp5n']}, 00:10:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:10:04 INFO - 'talos_options': ['--xperf_path', 00:10:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:10:04 INFO - 'tests': ['tp5n']}}, 00:10:04 INFO - 'talos.zip': {'path': '', 00:10:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:10:04 INFO - Running post-action listener: _resource_record_post_action 00:10:04 INFO - ##### 00:10:04 INFO - ##### Finished populate-webroot step (success) 00:10:04 INFO - ##### 00:10:04 INFO - ##### 00:10:04 INFO - ##### Running create-virtualenv step. 00:10:04 INFO - ##### 00:10:04 INFO - Running pre-action listener: _resource_record_pre_action 00:10:04 INFO - Running main action method: create_virtualenv 00:10:04 INFO - Creating virtualenv /builds/slave/test/build/venv 00:10:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 00:10:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:10:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:10:04 INFO - Using real prefix '/usr' 00:10:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:10:05 INFO - Installing distribute.............................................................................................................................................................................................done. 00:10:07 INFO - Installing pip.................done. 00:10:07 INFO - Return code: 0 00:10:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:10:07 INFO - Using env: {'DISPLAY': ':0', 00:10:07 INFO - 'HOME': '/home/cltbld', 00:10:07 INFO - 'LANG': 'en_US.UTF-8', 00:10:07 INFO - 'LANGUAGE': 'en_US:en', 00:10:07 INFO - 'LOGNAME': 'cltbld', 00:10:07 INFO - 'MAIL': '/var/mail/cltbld', 00:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:07 INFO - 'MOZ_NO_REMOTE': '1', 00:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:07 INFO - 'NO_EM_RESTART': '1', 00:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:07 INFO - 'PWD': '/builds/slave/test', 00:10:07 INFO - 'SHELL': '/bin/bash', 00:10:07 INFO - 'SHLVL': '1', 00:10:07 INFO - 'TERM': 'linux', 00:10:07 INFO - 'TMOUT': '86400', 00:10:07 INFO - 'USER': 'cltbld', 00:10:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:07 INFO - '_': '/tools/buildbot/bin/python'} 00:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 00:10:07 INFO - Cleaning up... 00:10:07 INFO - Return code: 0 00:10:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 00:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 00:10:07 INFO - Using env: {'DISPLAY': ':0', 00:10:07 INFO - 'HOME': '/home/cltbld', 00:10:07 INFO - 'LANG': 'en_US.UTF-8', 00:10:07 INFO - 'LANGUAGE': 'en_US:en', 00:10:07 INFO - 'LOGNAME': 'cltbld', 00:10:07 INFO - 'MAIL': '/var/mail/cltbld', 00:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:07 INFO - 'MOZ_NO_REMOTE': '1', 00:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:07 INFO - 'NO_EM_RESTART': '1', 00:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:07 INFO - 'PWD': '/builds/slave/test', 00:10:07 INFO - 'SHELL': '/bin/bash', 00:10:07 INFO - 'SHLVL': '1', 00:10:07 INFO - 'TERM': 'linux', 00:10:07 INFO - 'TMOUT': '86400', 00:10:07 INFO - 'USER': 'cltbld', 00:10:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:07 INFO - '_': '/tools/buildbot/bin/python'} 00:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:07 INFO - Downloading/unpacking psutil>=0.7.1 00:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:10:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:10:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:10:09 INFO - Installing collected packages: psutil 00:10:09 INFO - Running setup.py install for psutil 00:10:09 INFO - building 'psutil._psutil_linux' extension 00:10:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 00:10:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 00:10:10 INFO - building 'psutil._psutil_posix' extension 00:10:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 00:10:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 00:10:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:10:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:10:10 INFO - Successfully installed psutil 00:10:10 INFO - Cleaning up... 00:10:10 INFO - Return code: 0 00:10:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 00:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 00:10:10 INFO - Using env: {'DISPLAY': ':0', 00:10:10 INFO - 'HOME': '/home/cltbld', 00:10:10 INFO - 'LANG': 'en_US.UTF-8', 00:10:10 INFO - 'LANGUAGE': 'en_US:en', 00:10:10 INFO - 'LOGNAME': 'cltbld', 00:10:10 INFO - 'MAIL': '/var/mail/cltbld', 00:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:10 INFO - 'MOZ_NO_REMOTE': '1', 00:10:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:10 INFO - 'NO_EM_RESTART': '1', 00:10:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:10 INFO - 'PWD': '/builds/slave/test', 00:10:10 INFO - 'SHELL': '/bin/bash', 00:10:10 INFO - 'SHLVL': '1', 00:10:10 INFO - 'TERM': 'linux', 00:10:10 INFO - 'TMOUT': '86400', 00:10:10 INFO - 'USER': 'cltbld', 00:10:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:10 INFO - '_': '/tools/buildbot/bin/python'} 00:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:10:12 INFO - Installing collected packages: mozsystemmonitor 00:10:12 INFO - Running setup.py install for mozsystemmonitor 00:10:12 INFO - Successfully installed mozsystemmonitor 00:10:12 INFO - Cleaning up... 00:10:12 INFO - Return code: 0 00:10:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:10:12 INFO - Using env: {'DISPLAY': ':0', 00:10:12 INFO - 'HOME': '/home/cltbld', 00:10:12 INFO - 'LANG': 'en_US.UTF-8', 00:10:12 INFO - 'LANGUAGE': 'en_US:en', 00:10:12 INFO - 'LOGNAME': 'cltbld', 00:10:12 INFO - 'MAIL': '/var/mail/cltbld', 00:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:12 INFO - 'MOZ_NO_REMOTE': '1', 00:10:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:12 INFO - 'NO_EM_RESTART': '1', 00:10:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:12 INFO - 'PWD': '/builds/slave/test', 00:10:12 INFO - 'SHELL': '/bin/bash', 00:10:12 INFO - 'SHLVL': '1', 00:10:12 INFO - 'TERM': 'linux', 00:10:12 INFO - 'TMOUT': '86400', 00:10:12 INFO - 'USER': 'cltbld', 00:10:12 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:12 INFO - '_': '/tools/buildbot/bin/python'} 00:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:12 INFO - Downloading/unpacking blobuploader==1.2.4 00:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:14 INFO - Downloading blobuploader-1.2.4.tar.gz 00:10:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:10:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:10:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:15 INFO - Downloading docopt-0.6.1.tar.gz 00:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:10:15 INFO - Installing collected packages: blobuploader, requests, docopt 00:10:15 INFO - Running setup.py install for blobuploader 00:10:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:10:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:10:15 INFO - Running setup.py install for requests 00:10:15 INFO - Running setup.py install for docopt 00:10:15 INFO - Successfully installed blobuploader requests docopt 00:10:15 INFO - Cleaning up... 00:10:16 INFO - Return code: 0 00:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 00:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:10:16 INFO - Using env: {'DISPLAY': ':0', 00:10:16 INFO - 'HOME': '/home/cltbld', 00:10:16 INFO - 'LANG': 'en_US.UTF-8', 00:10:16 INFO - 'LANGUAGE': 'en_US:en', 00:10:16 INFO - 'LOGNAME': 'cltbld', 00:10:16 INFO - 'MAIL': '/var/mail/cltbld', 00:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:16 INFO - 'MOZ_NO_REMOTE': '1', 00:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:16 INFO - 'NO_EM_RESTART': '1', 00:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:16 INFO - 'PWD': '/builds/slave/test', 00:10:16 INFO - 'SHELL': '/bin/bash', 00:10:16 INFO - 'SHLVL': '1', 00:10:16 INFO - 'TERM': 'linux', 00:10:16 INFO - 'TMOUT': '86400', 00:10:16 INFO - 'USER': 'cltbld', 00:10:16 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:16 INFO - '_': '/tools/buildbot/bin/python'} 00:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:10:16 INFO - Running setup.py (path:/tmp/pip-ho5SSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:10:16 INFO - Running setup.py (path:/tmp/pip-xiXo8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:10:16 INFO - Running setup.py (path:/tmp/pip-va2QAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:10:16 INFO - Running setup.py (path:/tmp/pip-tqdgeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:10:16 INFO - Running setup.py (path:/tmp/pip-7x28S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:10:16 INFO - Running setup.py (path:/tmp/pip-zjB75b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:10:16 INFO - Running setup.py (path:/tmp/pip-94mMz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:10:16 INFO - Running setup.py (path:/tmp/pip-jhTBGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:10:16 INFO - Running setup.py (path:/tmp/pip-7rnfS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:10:16 INFO - Running setup.py (path:/tmp/pip-1QLZHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:10:16 INFO - Running setup.py (path:/tmp/pip-K_y76F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:10:17 INFO - Running setup.py (path:/tmp/pip-e9Xu7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:10:17 INFO - Running setup.py (path:/tmp/pip-VWGlVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:10:17 INFO - Running setup.py (path:/tmp/pip-I5ueOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:10:17 INFO - Running setup.py (path:/tmp/pip-Wy6Gq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:10:17 INFO - Running setup.py (path:/tmp/pip-brT3ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:10:17 INFO - Running setup.py (path:/tmp/pip-M582r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:10:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:10:17 INFO - Running setup.py install for manifestparser 00:10:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:10:17 INFO - Running setup.py install for mozcrash 00:10:17 INFO - Running setup.py install for mozdebug 00:10:17 INFO - Running setup.py install for mozdevice 00:10:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:10:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:10:17 INFO - Running setup.py install for mozfile 00:10:18 INFO - Running setup.py install for mozhttpd 00:10:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozinfo 00:10:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozInstall 00:10:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozleak 00:10:18 INFO - Running setup.py install for mozlog 00:10:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for moznetwork 00:10:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozprocess 00:10:18 INFO - Running setup.py install for mozprofile 00:10:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozrunner 00:10:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:10:18 INFO - Running setup.py install for mozscreenshot 00:10:19 INFO - Running setup.py install for moztest 00:10:19 INFO - Running setup.py install for mozversion 00:10:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:10:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:10:19 INFO - Cleaning up... 00:10:19 INFO - Return code: 0 00:10:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 00:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:10:19 INFO - Using env: {'DISPLAY': ':0', 00:10:19 INFO - 'HOME': '/home/cltbld', 00:10:19 INFO - 'LANG': 'en_US.UTF-8', 00:10:19 INFO - 'LANGUAGE': 'en_US:en', 00:10:19 INFO - 'LOGNAME': 'cltbld', 00:10:19 INFO - 'MAIL': '/var/mail/cltbld', 00:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:19 INFO - 'MOZ_NO_REMOTE': '1', 00:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:19 INFO - 'NO_EM_RESTART': '1', 00:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:19 INFO - 'PWD': '/builds/slave/test', 00:10:19 INFO - 'SHELL': '/bin/bash', 00:10:19 INFO - 'SHLVL': '1', 00:10:19 INFO - 'TERM': 'linux', 00:10:19 INFO - 'TMOUT': '86400', 00:10:19 INFO - 'USER': 'cltbld', 00:10:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:19 INFO - '_': '/tools/buildbot/bin/python'} 00:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:10:19 INFO - Running setup.py (path:/tmp/pip-0CbRcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:10:19 INFO - Running setup.py (path:/tmp/pip-bs6wlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:10:19 INFO - Running setup.py (path:/tmp/pip-gnNmws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:10:19 INFO - Running setup.py (path:/tmp/pip-gg4M81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:10:19 INFO - Running setup.py (path:/tmp/pip-HJdlxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:10:19 INFO - Running setup.py (path:/tmp/pip-qUz7Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:10:19 INFO - Running setup.py (path:/tmp/pip-Oe4oSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 00:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:10:19 INFO - Running setup.py (path:/tmp/pip-EFeh4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:10:20 INFO - Running setup.py (path:/tmp/pip-FyGGCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:10:20 INFO - Running setup.py (path:/tmp/pip-2Ftcv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:10:20 INFO - Running setup.py (path:/tmp/pip-MEN7uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:10:20 INFO - Running setup.py (path:/tmp/pip-x0Zk0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:10:20 INFO - Running setup.py (path:/tmp/pip-nI9b1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:10:20 INFO - Running setup.py (path:/tmp/pip-wDXgWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:10:20 INFO - Running setup.py (path:/tmp/pip-RvCp9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:10:20 INFO - Running setup.py (path:/tmp/pip-G28tbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 00:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:10:20 INFO - Running setup.py (path:/tmp/pip-ANhwK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:10:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:22 INFO - Downloading blessings-1.6.tar.gz 00:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:10:22 INFO - Installing collected packages: blessings 00:10:22 INFO - Running setup.py install for blessings 00:10:22 INFO - Successfully installed blessings 00:10:22 INFO - Cleaning up... 00:10:22 INFO - Return code: 0 00:10:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:10:23 INFO - Reading from file tmpfile_stdout 00:10:23 INFO - Current package versions: 00:10:23 INFO - argparse == 1.2.1 00:10:23 INFO - blessings == 1.6 00:10:23 INFO - blobuploader == 1.2.4 00:10:23 INFO - docopt == 0.6.1 00:10:23 INFO - manifestparser == 1.1 00:10:23 INFO - mozInstall == 1.12 00:10:23 INFO - mozcrash == 0.17 00:10:23 INFO - mozdebug == 0.1 00:10:23 INFO - mozdevice == 0.48 00:10:23 INFO - mozfile == 1.2 00:10:23 INFO - mozhttpd == 0.7 00:10:23 INFO - mozinfo == 0.9 00:10:23 INFO - mozleak == 0.1 00:10:23 INFO - mozlog == 3.1 00:10:23 INFO - moznetwork == 0.27 00:10:23 INFO - mozprocess == 0.22 00:10:23 INFO - mozprofile == 0.28 00:10:23 INFO - mozrunner == 6.11 00:10:23 INFO - mozscreenshot == 0.1 00:10:23 INFO - mozsystemmonitor == 0.0 00:10:23 INFO - moztest == 0.7 00:10:23 INFO - mozversion == 1.4 00:10:23 INFO - psutil == 3.1.1 00:10:23 INFO - requests == 1.2.3 00:10:23 INFO - wsgiref == 0.1.2 00:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 00:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:10:23 INFO - Using env: {'DISPLAY': ':0', 00:10:23 INFO - 'HOME': '/home/cltbld', 00:10:23 INFO - 'LANG': 'en_US.UTF-8', 00:10:23 INFO - 'LANGUAGE': 'en_US:en', 00:10:23 INFO - 'LOGNAME': 'cltbld', 00:10:23 INFO - 'MAIL': '/var/mail/cltbld', 00:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:23 INFO - 'MOZ_NO_REMOTE': '1', 00:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:23 INFO - 'NO_EM_RESTART': '1', 00:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:23 INFO - 'PWD': '/builds/slave/test', 00:10:23 INFO - 'SHELL': '/bin/bash', 00:10:23 INFO - 'SHLVL': '1', 00:10:23 INFO - 'TERM': 'linux', 00:10:23 INFO - 'TMOUT': '86400', 00:10:23 INFO - 'USER': 'cltbld', 00:10:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:23 INFO - '_': '/tools/buildbot/bin/python'} 00:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:10:23 INFO - Cleaning up... 00:10:23 INFO - Return code: 0 00:10:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:10:23 INFO - Using env: {'DISPLAY': ':0', 00:10:23 INFO - 'HOME': '/home/cltbld', 00:10:23 INFO - 'LANG': 'en_US.UTF-8', 00:10:23 INFO - 'LANGUAGE': 'en_US:en', 00:10:23 INFO - 'LOGNAME': 'cltbld', 00:10:23 INFO - 'MAIL': '/var/mail/cltbld', 00:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:23 INFO - 'MOZ_NO_REMOTE': '1', 00:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:23 INFO - 'NO_EM_RESTART': '1', 00:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:23 INFO - 'PWD': '/builds/slave/test', 00:10:23 INFO - 'SHELL': '/bin/bash', 00:10:23 INFO - 'SHLVL': '1', 00:10:23 INFO - 'TERM': 'linux', 00:10:23 INFO - 'TMOUT': '86400', 00:10:23 INFO - 'USER': 'cltbld', 00:10:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:23 INFO - '_': '/tools/buildbot/bin/python'} 00:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:23 INFO - Downloading/unpacking jsonschema 00:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:10:25 INFO - Downloading/unpacking functools32 (from jsonschema) 00:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:25 INFO - Downloading functools32-3.2.3-2.tar.gz 00:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:10:25 INFO - warning: no files found matching '*.txt' 00:10:25 INFO - no previously-included directories found matching 'build' 00:10:25 INFO - no previously-included directories found matching 'dist' 00:10:25 INFO - no previously-included directories found matching '.git*' 00:10:25 INFO - Installing collected packages: jsonschema, functools32 00:10:25 INFO - Running setup.py install for functools32 00:10:25 INFO - warning: no files found matching '*.txt' 00:10:25 INFO - no previously-included directories found matching 'build' 00:10:25 INFO - no previously-included directories found matching 'dist' 00:10:25 INFO - no previously-included directories found matching '.git*' 00:10:25 INFO - Successfully installed jsonschema functools32 00:10:25 INFO - Cleaning up... 00:10:25 INFO - Return code: 0 00:10:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:10:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f75af532f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:10:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 00:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 00:10:25 INFO - Using env: {'DISPLAY': ':0', 00:10:25 INFO - 'HOME': '/home/cltbld', 00:10:25 INFO - 'LANG': 'en_US.UTF-8', 00:10:25 INFO - 'LANGUAGE': 'en_US:en', 00:10:25 INFO - 'LOGNAME': 'cltbld', 00:10:25 INFO - 'MAIL': '/var/mail/cltbld', 00:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:25 INFO - 'MOZ_NO_REMOTE': '1', 00:10:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:25 INFO - 'NO_EM_RESTART': '1', 00:10:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:25 INFO - 'PWD': '/builds/slave/test', 00:10:25 INFO - 'SHELL': '/bin/bash', 00:10:25 INFO - 'SHLVL': '1', 00:10:25 INFO - 'TERM': 'linux', 00:10:25 INFO - 'TMOUT': '86400', 00:10:25 INFO - 'USER': 'cltbld', 00:10:25 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:25 INFO - '_': '/tools/buildbot/bin/python'} 00:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:10:26 INFO - Downloading/unpacking flake8 00:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:10:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:10:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:10:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:10:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:10:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:10:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:10:28 INFO - no previously-included directories found matching 'docs/_build' 00:10:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:10:28 INFO - Running setup.py install for pep8 00:10:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:10:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:10:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:10:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:10:28 INFO - no previously-included directories found matching 'docs/_build' 00:10:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:10:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:10:28 INFO - Cleaning up... 00:10:28 INFO - Return code: 0 00:10:28 INFO - Running post-action listener: _resource_record_post_action 00:10:28 INFO - Running post-action listener: _start_resource_monitoring 00:10:28 INFO - Starting resource monitoring. 00:10:28 INFO - ##### 00:10:28 INFO - ##### Finished create-virtualenv step (success) 00:10:28 INFO - ##### 00:10:28 INFO - ##### 00:10:28 INFO - ##### Running install step. 00:10:28 INFO - ##### 00:10:28 INFO - Running pre-action listener: _resource_record_pre_action 00:10:28 INFO - Running main action method: install 00:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:10:29 INFO - Reading from file tmpfile_stdout 00:10:29 INFO - Detecting whether we're running mozinstall >=1.0... 00:10:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:10:29 INFO - Reading from file tmpfile_stdout 00:10:29 INFO - Output received: 00:10:29 INFO - Usage: mozinstall [options] installer 00:10:29 INFO - Options: 00:10:29 INFO - -h, --help show this help message and exit 00:10:29 INFO - -d DEST, --destination=DEST 00:10:29 INFO - Directory to install application into. [default: 00:10:29 INFO - "/builds/slave/test"] 00:10:29 INFO - --app=APP Application being installed. [default: firefox] 00:10:29 INFO - mkdir: /builds/slave/test/build/application 00:10:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 00:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 00:10:45 INFO - Reading from file tmpfile_stdout 00:10:45 INFO - Output received: 00:10:45 INFO - /builds/slave/test/build/application/firefox/firefox 00:10:45 INFO - Running post-action listener: _resource_record_post_action 00:10:45 INFO - ##### 00:10:45 INFO - ##### Finished install step (success) 00:10:45 INFO - ##### 00:10:45 INFO - ##### 00:10:45 INFO - ##### Running run-tests step. 00:10:45 INFO - ##### 00:10:45 INFO - Running pre-action listener: _resource_record_pre_action 00:10:45 INFO - Running main action method: run_tests 00:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:10:45 INFO - Python 2.7.3 00:10:45 INFO - Return code: 0 00:10:45 INFO - grabbing minidump binary from tooltool 00:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:10:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21419c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2142d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:10:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 00:10:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 00:10:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:10:45 INFO - Return code: 0 00:10:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:10:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:10:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:10:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:10:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:10:45 INFO - Return code: 0 00:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 00:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 00:10:45 INFO - Using env: {'DISPLAY': ':0', 00:10:45 INFO - 'HOME': '/home/cltbld', 00:10:45 INFO - 'LANG': 'en_US.UTF-8', 00:10:45 INFO - 'LANGUAGE': 'en_US:en', 00:10:45 INFO - 'LOGNAME': 'cltbld', 00:10:45 INFO - 'MAIL': '/var/mail/cltbld', 00:10:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:10:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:10:45 INFO - 'MOZ_NO_REMOTE': '1', 00:10:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:10:46 INFO - 'NO_EM_RESTART': '1', 00:10:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:10:46 INFO - 'PWD': '/builds/slave/test', 00:10:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:10:46 INFO - 'SHELL': '/bin/bash', 00:10:46 INFO - 'SHLVL': '1', 00:10:46 INFO - 'TERM': 'linux', 00:10:46 INFO - 'TMOUT': '86400', 00:10:46 INFO - 'USER': 'cltbld', 00:10:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322', 00:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:10:46 INFO - '_': '/tools/buildbot/bin/python'} 00:10:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 00:10:46 INFO - mozversion application_buildid: 20160508232942 00:10:46 INFO - mozversion application_changeset: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 00:10:46 INFO - mozversion application_display_name: Nightly 00:10:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:10:46 INFO - mozversion application_name: Firefox 00:10:46 INFO - mozversion application_remotingname: firefox 00:10:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:10:46 INFO - mozversion application_vendor: Mozilla 00:10:46 INFO - mozversion application_version: 49.0a1 00:10:46 INFO - mozversion platform_buildid: 20160508232942 00:10:46 INFO - mozversion platform_changeset: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 00:10:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:10:46 INFO - mozversion platform_version: 49.0a1 00:10:46 INFO - using testdate: 1462777846 00:10:46 INFO - actual date: 1462777846 00:10:46 INFO - starting webserver on 'localhost:47282' 00:10:46 INFO - SUITE-START | Running 2 tests 00:10:46 INFO - TEST-START | dromaeo_css 00:10:46 INFO - operating with platform_type : linux_ 00:10:46 INFO - Initialising browser for dromaeo_css test... 00:10:46 INFO - TEST-INFO | started process 1324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2XTiKV/profile http://localhost:47282/getInfo.html) 00:10:50 INFO - PROCESS | 1324 | 00:10:50 INFO - PROCESS | 1324 | (process:1392): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:10:51 INFO - PROCESS | 1324 | __metrics Screen width/height:1600/1200 00:10:51 INFO - PROCESS | 1324 | colorDepth:24 00:10:51 INFO - PROCESS | 1324 | Browser inner width/height: 1024/697 00:10:51 INFO - PROCESS | 1324 | __metrics 00:10:51 ERROR - PROCESS | 1324 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 00:10:52 INFO - TEST-INFO | 1324: exit 0 00:10:52 INFO - Browser initialized. 00:10:52 INFO - Running cycle 1/1 for dromaeo_css test... 00:10:52 INFO - TEST-INFO | started process 1444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2XTiKV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:10:54 INFO - PROCESS | 1444 | 00:10:54 INFO - PROCESS | 1444 | (process:1504): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:11:04 INFO - PROCESS | 1444 | RSS: Main: 164200448 00:11:04 INFO - PROCESS | 1444 | 00:11:54 INFO - PROCESS | 1444 | 1462777914228 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 00:14:05 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47282/tests/dromaeo/cssquery-ext.html) 00:14:07 INFO - PROCESS | 1444 | RSS: Main: 164851712 00:14:07 INFO - PROCESS | 1444 | 00:17:09 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-ext.html (next: http://localhost:47282/tests/dromaeo/cssquery-jquery.html) 00:17:09 INFO - PROCESS | 1444 | RSS: Main: 166150144 00:17:09 INFO - PROCESS | 1444 | 00:20:11 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47282/tests/dromaeo/cssquery-mootools.html) 00:20:12 INFO - PROCESS | 1444 | RSS: Main: 168022016 00:20:12 INFO - PROCESS | 1444 | 00:23:14 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47282/tests/dromaeo/cssquery-prototype.html) 00:23:14 INFO - PROCESS | 1444 | RSS: Main: 165388288 00:23:14 INFO - PROCESS | 1444 | 00:26:17 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47282/tests/dromaeo/cssquery-yui.html) 00:26:18 INFO - PROCESS | 1444 | RSS: Main: 167432192 00:26:18 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | Cycle 1(1): loaded http://localhost:47282/tests/dromaeo/cssquery-yui.html (next: http://localhost:47282/tests/dromaeo/cssquery-dojo.html) 00:29:20 INFO - PROCESS | 1444 | RSS: Main: 165720064 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | __start_tp_report 00:29:20 INFO - PROCESS | 1444 | _x_x_mozilla_page_load 00:29:20 INFO - PROCESS | 1444 | _x_x_mozilla_page_load_details 00:29:20 INFO - PROCESS | 1444 | |i|pagename|runs| 00:29:20 INFO - PROCESS | 1444 | |0;dojo.html;2605.7133715291316;2657.0965871603657;2671.1051797647788;2673.839569625824;2677.571588545833;4665.790039448225;4688.038952014837;4716.056788642272;4750.28745688147;4823.903521929568;4507.819687212515;4677.508861569538;4699.85900422987;4707.764611769415;4733.502982186872;4333.479982402108;4405.273129933557;4468.218061839181;4468.843590474334;4481.148581769454;605.5782084939208;4228.365745138223;4259.76571288579;4294.914101717964;4329.935050974238;4319.654427645796;4338.587834155782;4339.5660433956355;4359.956400436018;4393.319035549463;4339.06710057336;4372.322290045016;4376.496702879178;4381.693281470299;4413;4292.0772034012525;4318.805653745589;4351.26028575143;4355.5644435556505;4360.542143074963;4650.372199753057;4653.325267836145;4653.511381304948;4679.947011922291;4690.695104818176;4912.975435122838;4971.279164521125;4980.078685443204;5016.698998060128;5027.773750181251;4985.202367621199;5063.721495317756;5067;5073.162928116856;5099.107656160157;4645.837395691135;4723.173444647207;4739.620830333565;4752.073345697556;4782.187028205213;5073.264376665363;5113.335266415343;5133.178691409356;5154.329937108152;5198.92201616976;4939.5554400104165;4968.975155124273;4974.925376119361;4976.527229913152;5020.7740651670765;4957.578605818474;5005.099082165172;5006.774695138727;5012.849614511498;5076;4848.854534363974;4871.171900776876;4900.191468407673;4929.704217746947;5025.698458092526;4914.680545764585;4920.655554111249;4935.457099719028;4942.629302802305;4971.130052240844;4215;4237.322028475429;4243.745375277494;4259.53145154039;4304.096139810612;3675.3016926783826;3686;3717.75834570752;3748.6438788315068;3762.172322089136;4223.936640950388;4231.344141658048;4243.6605071594195;4264.189803937242;4328.329108988112;4988.90022199554;5004.3494345734825;5023.246513023076;5030.170021946338;5034.924476132861;4221.050263690707;4283.357496375506;4292.163028209532;4318.244307246219;4336.392904993303;4560.293154561048;4564.863054108382;4573.3368661544555;4576.702514336557;4618.538146185355;4614.284785858197;4704.082703872712;4711.151992641289;4712.717236965793;4770.809167633325;4929.309896614405;4989.60083193351;5038.269450929522;5052.646314757932;5062.645614806928;4658.697184682985;4663;4678.76606169697;4679.157751604744;4721.504242054672;4850.490698476609;4966.577840883421;4975.5522003019905;4976.353074100344;5046.419661738947;4940.357753492167;4973.701577905337;4983.601311894967;5000.199968005102;5018.698878067327;4868.294097355934;4872.196087645641;4874.195757699939;4876.5854902333695;4920.1389756791705;4777.044591081728;4805;4829.251466022772;4855.490173531728;4880.023995201045;4766.428028636586;4769.30845027476;4792.880177995578;4816.759162041813;4877.609791216765;4927.433345165208;4927.9507204927495;4929.408470983505;4945.060438516671;4966.255061740768;4711.081339138835;4799.832005879777;4819.060283244734;4850.684705494132;4873.975630121827 00:29:20 INFO - PROCESS | 1444 | |1;ext.html;562.9211910332551;600.8527910661888;608.1364462463301;612.4946918791998;614.0942110387178;41723;43446.34830477541;43763.56236437635;43934;48357.75821120898;43773.562264377346;44275.557244427546;44321;44414;44546.777266113626;7893.171217022202;7950.840983180333;8047.758567242963;8049.919500805005;8056.798580035516;8000.439969202158;8065.2741253287195;8066.11272759996;8067.515949043047;8082.313003394689;8051.315638170762;8078.232567906069;8093.878591821098;8094.433389662726;8105.918940810576;8010.198980102002;8014.078380986174;8020.999999999942;8053.315468185211;8067.838643227132;7898.249666281694;7900.367970562341;7952.966114405091;7954.9602251988945;7966.5618390988475;39542.80228598868;39577.40633890494;39948.20103597912;39994;40070.19859602821;3238.757093218018;3344;3463.480477928306;3737.8131093445218;3958.960410395888;29121.708782912112;29582.408351832844;29949.850250748827;29971.400571988655;30094.84952575223;3554.449060395643;3614.819259037064;3694.907627309312;3727.1241258304276;3760.793156376398;252778;252891;253163.99999999817;253368;253737;35409.46885796715;36856.44715329272;37024.444633329986;55773.721131394086;55921.44078559244;35721.82139089288;35788.64211357905;35900;36218.456723149175;36287;14051.367688454044;14141.22223277709;14179.291035448186;14212.502562410265;14243.501477448239;11995.160338776202;12019.699507512207;12024.87975120255;12038.879611203776;12054.276743395425;8277.95861020691;8315.750527484186;8320;8360.832783344298;8408.32733381328;3261.804291742503;3264.526643636707;3279.540864279003;3290.68738469845;3300.950485742716;750.3734381791156;828.7886588919907;862.2239984014312;923.9300879625262;928.8467402878449;28918.277043074093;29201.707982920325;29269.707302926698;29483.11550653484;29642.55536166959;943.6366998705411;1059.6926891201465;1062.9681109566725;1080.400377790318;1118.6140781430393;15606.609834753996;15658.45195418155;15737.370505179664;15819.367225311089;15962.60093497672;392696;393069;394047;395877;396559;51673.224901626505;52917.470825292025;52984;53001.734991324796;53108.468915311125;1313.8817506424277;1315.4475120449242;1318.5912367166209;1334.252818421687;1340.557615986722;28062.57906131369;28545.571816422354;29021.567935767893;29050.41899162047;29104.126876193746;44406.77796610996;44488.777556112014;44879.551204487536;44888.55111448844;45067.7746611278;319052;325796;325938;326353;327112;26166.60750088712;26328.21015369542;26360.604590931536;26544.336391590365;26761;44531.777341113084;45071.32393014173;45464.77267613641;45544.77227613841;45572.31641525446;37573.62426375811;37894;38673.613263867;39063;39470.40794388029;37043.07392315214;37572.62427375691;37779.43330849984;38184.236315274095;38457;36311;36516.087097821706;37271.44092838556;37407.6259237415;37720.24559508849;31920.79601002475;33867.830660847525;33880.49179262362;33962;34364;25434.23697289084;25843.483130337663;26263.23932521048;26382.736172638797;26549.336266592716 00:29:20 INFO - PROCESS | 1444 | |2;jquery.html;373.9401695728684;377.5111230955912;381.5345278759912;385.9787711675858;389.40615561269044;15879.920600396996;16595.917020414898;17331.30674773011;17464;17493.03788291645;10635.57457701691;16284.348626054943;17280.913595432005;17339.21973511195;17355.305787768506;4592.380028696125;4761.142994261032;4800.063987522417;4800.447948485917;4804.951950480503;3516.437370020797;5183.948160518385;5459.017376872162;5459.918101228484;5496.422875598065;5479.068558345091;5479.643823151482;5480.068388373966;5483.7806487740445;5494.945050549503;5736.741846616891;5853.122031695238;5885.735141918624;5902.232709747748;5902.6458412495385;5052.317937078521;5241.449647786965;5249.632525723201;5251.10731175701;5257.631965762398;18468.3536076238;19586.608267834563;19984.700229496568;20534.5893082139;20612.27857025012;1990.8606397552178;2007.8594498385119;2072.171131547378;2155.5257843274453;2390.916317928864;9253.6298548058;9264.212541933945;9439.858402123973;9606;9641.084097010773;1703.4378655043806;1727.0053088682591;1733.5319463744718;1781.8663600229904;1807.3403207829178;65314;66842.33157668362;67068.32931670718;67581.66209168972;68595;9465.763355916019;9534.809303813885;9733.610655573697;9797.755056123511;9956.153726933147;13040;13098.607041788571;13194.802077968838;13225.470981160839;13244.53644122451;7416.480846340813;7428.517146385468;7470.057784975946;7499.812504687427;7678.270564296383;7136.964315178391;7236.34872861445;7310.890336644955;7422.665980030912;7427.962860185665;4007.2987227235117;4036.1120553478186;4048.7975601219823;4053.5541090480024;4135.56576559463;1626;1687.2660392729201;1722.336900293371;1739.330357812226;1754.429810311654;831.563429199675;937.8171256604896;954.9761255968656;1001.7846163074973;1041.193075366597;16397.42609008679;16591.336346546002;16679.583010424838;16709.331626734795;16979.74530382045;921.4425272710048;923.0907556057278;952.7284723853669;960.6205548808322;995.3381001633977;9399.530023498797;9412.764680883032;9470.384425012353;9569.090936361035;9697.078777516124;367575;368076;369144;395942;397646;75208;75242.62378688072;75397.62301188459;77314;78699.21300787033;964.7858165510031;969.3311614985637;971.6502059258803;984.3011461862158;993.2004736187295;14688.55934321972;14702.705945881236;14807;15120.373626264742;15297.847021529642;34067;34151.65848341584;34306.31387372289;34855.82572087123;35075.649243507236;46896.531034689215;47698.52301476941;48882.75558622184;49217.26174107463;49322.753386234275;6816.420604248496;7578.242175782378;7622.771316860503;7780.144184139627;8101.149379315087;9287.210587100171;10605.840912386475;10789;10947.726306842393;11327.376994265394;9679.951600241953;9972.3019388645;10006.8498972514;10175.898241017496;10454.738631534272;9958.253131015057;10010.799784004425;10158.847617285599;10197.9490102549;10270.33242839213;10109.443980581138;10159.28884978074;10255.230857685554;10395.58417663285;10476.842847357144;7738.110117336353;7806.102298235548;7960.522368657899;8012.158723333966;8030.116687164291;9403.294752893424;10212.642557510748;10288.845667314847;10540.947295263475;10569.048785609331 00:29:20 INFO - PROCESS | 1444 | |3;mootools.html;1494.4545240987043;1517.977230341545;1670.240040781444;1775.3253763569842;1809.9004554749492;6618.205815302158;7031.8945215821805;7033.613151276678;7528.134264559577;7532.5103868248525;7116.359527642511;7293.4894557381;7343.485955983083;7345.081864766904;7345.265473452652;2170.9565808683815;2214.3135627955303;2215.579039982398;2216.1024784962115;2221.3224966385246;2143.678448232762;2151.2148065955894;2179.662152366378;2187.0814258011674;2198.1427243375097;2149.5915776002507;2160.7191065161505;2165.675148727688;2176.1621775616422;2210.3368989303144;2165.296278709418;2169.1323470611724;2176.6190916589535;2176.640854259045;2177.8366622503377;2159.5033142377342;2199.549092436047;2201.1965632544006;2203.933881983543;2204.889755512218;7242.0223269858425;7305.4155667546465;7310.305520975498;7313.268673132644;7336.082989626297;4569.977150114228;4622.098690755304;4651.302304654295;4658.580727734487;4707.976460117712;2862.5849251858363;2947.941041179186;3013.141254742388;3020.5771192033135;3032.3025704087963;956.9952150239204;975.2344409638471;996.1333639733479;997.7605374710017;1028.6913925822223;10111.797764044677;10120.797584048278;10143.442110683909;10183;10240.539175737109;4232.746035237834;4317.373980772833;4370.934435983463;4400.3399490076745;4469.307257375112;8924.643014279485;8954.059823718546;8954.149355811187;8991.505467199237;9022.278217742565;4588.128255631419;4618.769061546909;4627.051454451829;4718.764061796896;4735.881602959954;3707.5736290326417;4135.462389889295;4233.597808208216;4252.447181866338;4266.360045993064;3654.250878569887;3675.3384390809374;3775.301569209705;3786.6213378661914;3808.5810560838286;1183.7099910521806;1217.531250468576;1220.4690959432676;1225.6078055022485;1228.3551135653674;755.6901670315144;925.4956048953337;934.3693007220099;934.672864497434;955.3694561589317;5386.757595908193;5408.648437851527;5495.093309603951;5517.172424136412;5679.091345384719;925.6528801699362;946.7917058247175;959.7072892767633;962.6197651927589;963.7638778499313;3738.495303134042;3883.66988805949;3891.416287556889;3897.4738410314812;3940.5468371137113;8981.550922453851;9396.295277854051;9414.529273536296;9424.575894084783;9522.190613797766;9366.063393660716;9381.577828997575;9382.390144640576;9410.811783764286;9437.811243775086;6710.731570737214;6835.350641689031;6885.862282754317;6921.688524016431;6931;5361.785528578813;5439.347278326626;5467.316585426822;5571.0807716726285;5573.693446860461;8644.308455323684;8777.956110219407;8869.600867960828;8872.112788721077;8876.068012858816;8701.608427620899;8826.338024648048;8941.731748047569;8979.820403592023;9038.909610903807;5894.145348924296;6488.2538508072175;6505.381988711065;6629.105070815571;6776.898346524707;7207.9639601801655;7277.199508054216;7285.672144753393;7296.124465064227;7315.268473152642;6354.555181137461;6594;6603.933960660332;6631.3700198481065;6661.46708263328;6578.901316480353;6581.243157037002;6624.436922861418;6631.966840165961;6667.133272674521;6011.519078473799;6087.817365479043;6095.3904609538695;6108.297545782292;6129.049997250433;4425.645948324062;4487.7980490877335;4561.635069194523;4600.263957766741;4611.5619016193405;4461.263891457873;4497.325401189848;4503.301988191835;4529.728216307032;4538.364628952016 00:29:20 INFO - PROCESS | 1444 | |4;prototype.html;297.47644146302514;306.0283599571359;308.68051566628543;316.06287357983575;317.46190207598124;4824.758762061896;4947.529984651453;4993.3259010033635;5009.824656137041;5033.345665063541;4902.921357301388;4903.534164254399;4926.502183449755;4943.60451163907;4981.701097934127;3898.8830335089992;3901.6293452122004;3903.668188204006;3907.804609769514;3938.2911076006308;3598.6581274778987;3868.497095377597;3874.786886721229;3902.1415288636454;3925.156091440354;3797.810109494528;3798.9430158547643;3809.56190038145;3811.866584669537;3825.61743825618;3806.885793426202;3841.769493830379;3846.692264618852;3852.4221366794923;3898.7075969302414;3161.8102913825246;3222.242772948355;3328.1845947742718;3334.1331253873923;3339.1985923378456;4643.582077613031;4690.296455531663;4695.7886895089805;4710.929336059962;4794.544518270759;4303.483581970184;4308.870733877989;4317.0934103838235;4328.372386004044;4340.60934515895;5004.674696144775;5111.846644600668;5122.641415100945;5138.871528211825;5162.406323272834;3607.134287770942;3623.3477973964677;3630.80030598317;3633.854645814164;3637.4907512948207;5342.171963345688;5360.383555891083;5368.409474957752;5383.353997520323;5447.863803404907;3596.478510615946;3673.3020726061964;3700.50043244164;3703.500027496307;3744.0952352418417;3748.981255093707;3776.131489757316;3778.1499162688174;3783.4892289541112;3786.772793632391;4293.677974151951;4295.29127693927;4314.56854314566;4328.891777705582;4331.696781225284;5110.361204849394;5126.436092029874;5133.486651334912;5158.767855446514;5163.044836705221;1205.9267252145598;1260.4328052376468;1260.6007331861626;1265.5127775806382;1267.8668739782374;845.1337379186383;860.8278344331159;865.9004214515286;880.3265501891059;882.911708829112;4311.8490852820005;4371.672124590668;4398.8020539075815;4404.524484297732;4441.444819397575;5050.3939527256225;5102.693838369635;5155.871103222375;5173.198154286017;5198.740062996835;4246.8301267949555;4385;4389.824407023747;4404.471463424409;4420.911581768347;2658.4550167215675;2706.8090040382563;2730.9863450683015;2773.72262773721;2776.6390369251867;4761.214399624023;4852.490488498727;4863.197572400514;4865.9026819463415;4881.89999350415;5283.1546952487415;5298.178782288752;5305.575553955674;5339.519443250126;5371.328583927009;4490.753008584494;4750.453697824794;4766.880827979328;4790.568848803624;4797.976010119927;5084.135696931457;5116.590672746097;5162.354705661793;5195.428502864757;5232.514555586218;5084.41529224144;5086.669366491315;5103.489651034867;5129.846104616868;5201.878872676515;4657.093860081941;4756.239001759702;4758.381410416624;4859.198232291632;4863.221884498464;3623.474596183486;3627.1294889226924;3639.9636003639625;3642.7814331140216;3660.7803531788177;5142.897142057063;5145.588352931832;5151.871203219949;5214.661047031931;5301.575873930154;4845.515448455127;4978.4274808397495;4985.426675932169;5008.474110218374;5021.69002258965;4955.628327875352;4976.6407674820075;4988.351514303117;4999.450060493416;5010.5240002199735;4821.79455136216;4896.877578060577;4909.895273563419;4932.152920721843;5024.547790698854;4672.415948006499;4978.228374601942;5010.574101201439;5053.343065401475;5066.771995260148;4858.368412106403;4863.932828547924;4897.608191344755;4906.362172917497;4941.7645588602845 00:29:20 INFO - PROCESS | 1444 | |5;yui.html;780.2782426255714;820.33552822214;821.9848487118411;825.2078005115089;827.8758186272061;12499.43752531136;12674.746505069905;12823.166494177873;12944.482220711183;13062.150960187593;12402.81395779064;12412.131150819447;12445.502179912794;12485.563005294816;12595.748085038294;665.3579295979379;844.877492763549;850.421713235;857.6697971281039;874.2175752701329;856.1139220906332;856.7729551668813;858.9828203435927;865.2558799432483;867.1761826265042;909.7725568607848;910.4946754551221;914.3919293669702;916.3173435790312;920.3649481857497;904.4166512599337;908.4095338030301;909.6725178935578;911.8039621481347;912.0879120879121;667.4827009067963;672.1900110366996;672.8351553869285;673.7911247513238;674.197704731373;12719.173253738478;12738.87261127386;12794.42425090873;12814.935925320313;12857.871421285854;12247.510099596006;12340.876591234062;12409.875901240963;12593.307368094751;12806.167599106027;9630.188377634457;9758.414495130315;10163.491825408773;10179.931107233782;10246.6413675521;3378.1048022274117;3445.758796884219;3479.425894727367;3551.200979779555;3564.287142571496;44572.55427445749;44671;45150.09699805985;45261;45416.99999999967;9805.607775689035;9868.555914983843;10001.949990250148;10202.897971020344;10505.107065899485;18374.63250734978;18421.90789046046;18427.078646067643;18434.723479147822;18474.81525184758;6918.7232510699005;7040.471964602675;7147.499675022803;7158.391536719333;7255.056842610426;3741.1769410729594;3832.693384529231;3844.8654297100024;3855.3060449118866;3867.43922131295;2950.439416510856;3005.8647360868813;3026.772992025607;3029.9848500757357;3034.286942568495;500.97996080156383;507.8781092537764;518.3209994906684;519.5272302204976;534.9919751203735;577.9797707080232;649.3052433895687;650.3334082565408;654.1790053482848;666.0808084842886;7353.4484913631695;8164.959175204086;8227.794305142299;8241.381896357678;8289.171082891662;629.263761399155;638.1321402892063;652.9510286728514;656.8981807819796;658.3350815676106;3857.498525191707;3951.841926322915;3953.3081710700512;3993.6405723484445;4099.221147981933;53270.73364633152;53851;54117.45882541124;54380.18429723557;55321.72339138359;49666.255006174935;50198.24702629464;50378;51019.23471147936;51706;39169.21661566753;45851.77074114608;46137;46141.76929115333;46831;6560.507961902972;8120.878186827321;8233.50598964064;8328.500289982621;8506.404551681322;27030.32424189411;27552.311192219553;29832;30053.248668783457;30625.540616890317;34657.13357166091;34970;38704.61295387123;40874.1825163501;40973.79513102535;10432.426216558162;10509.632162874263;10665.46672666348;10720.303180293256;10725.195610329101;12397.324835046542;12528.248305101723;12566.246025238515;12626.36868156607;12648.177868438523;10984.395858227874;11148.163887708617;11185.105191584491;11716.23844450108;12142.271463712206;11323.830142548033;11673.533058677558;11951.402429878297;11971.461284242405;11980.221285616408;11530.481128349413;11591.362475063952;11617.244879082833;11718.70703232426;11840.822387664364;9446.38598491096;9561.9043809561;9772.853407198756;9913.851292230478;10039.849402259119;9597;10026.69919902404;10036.347637403545;10089.949550252202;10112.646057387956 00:29:20 INFO - PROCESS | 1444 | __end_tp_report 00:29:20 INFO - PROCESS | 1444 | __start_cc_report 00:29:20 INFO - PROCESS | 1444 | _x_x_mozilla_cycle_collect,377 00:29:20 INFO - PROCESS | 1444 | __end_cc_report 00:29:20 INFO - PROCESS | 1444 | __startTimestamp1462778960968__endTimestamp 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | ------- Summary: start ------- 00:29:20 INFO - PROCESS | 1444 | Number of tests: 6 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#0] dojo.html Cycles:165 Average:4612.74 Median:4766.43 stddev:568.48 (11.9%) stddev-sans-first:547.99 00:29:20 INFO - PROCESS | 1444 | Values: 2605.7 2657.1 2671.1 2673.8 2677.6 4665.8 4688.0 4716.1 4750.3 4823.9 4507.8 4677.5 4699.9 4707.8 4733.5 4333.5 4405.3 4468.2 4468.8 4481.1 605.6 4228.4 4259.8 4294.9 4329.9 4319.7 4338.6 4339.6 4360.0 4393.3 4339.1 4372.3 4376.5 4381.7 4413.0 4292.1 4318.8 4351.3 4355.6 4360.5 4650.4 4653.3 4653.5 4679.9 4690.7 4913.0 4971.3 4980.1 5016.7 5027.8 4985.2 5063.7 5067.0 5073.2 5099.1 4645.8 4723.2 4739.6 4752.1 4782.2 5073.3 5113.3 5133.2 5154.3 5198.9 4939.6 4969.0 4974.9 4976.5 5020.8 4957.6 5005.1 5006.8 5012.8 5076.0 4848.9 4871.2 4900.2 4929.7 5025.7 4914.7 4920.7 4935.5 4942.6 4971.1 4215.0 4237.3 4243.7 4259.5 4304.1 3675.3 3686.0 3717.8 3748.6 3762.2 4223.9 4231.3 4243.7 4264.2 4328.3 4988.9 5004.3 5023.2 5030.2 5034.9 4221.1 4283.4 4292.2 4318.2 4336.4 4560.3 4564.9 4573.3 4576.7 4618.5 4614.3 4704.1 4711.2 4712.7 4770.8 4929.3 4989.6 5038.3 5052.6 5062.6 4658.7 4663.0 4678.8 4679.2 4721.5 4850.5 4966.6 4975.6 4976.4 5046.4 4940.4 4973.7 4983.6 5000.2 5018.7 4868.3 4872.2 4874.2 4876.6 4920.1 4777.0 4805.0 4829.3 4855.5 4880.0 4766.4 4769.3 4792.9 4816.8 4877.6 4927.4 4928.0 4929.4 4945.1 4966.3 4711.1 4799.8 4819.1 4850.7 4874.0 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#1] ext.html Cycles:180 Average:47660.33 Median:37573.12 stddev:86768.50 (230.9%) stddev-sans-first:86939.85 00:29:20 INFO - PROCESS | 1444 | Values: 562.9 600.9 608.1 612.5 614.1 41723.0 43446.3 43763.6 43934.0 48357.8 43773.6 44275.6 44321.0 44414.0 44546.8 7893.2 7950.8 8047.8 8049.9 8056.8 8000.4 8065.3 8066.1 8067.5 8082.3 8051.3 8078.2 8093.9 8094.4 8105.9 8010.2 8014.1 8021.0 8053.3 8067.8 7898.2 7900.4 7953.0 7955.0 7966.6 39542.8 39577.4 39948.2 39994.0 40070.2 3238.8 3344.0 3463.5 3737.8 3959.0 29121.7 29582.4 29949.9 29971.4 30094.8 3554.4 3614.8 3694.9 3727.1 3760.8 252778.0 252891.0 253164.0 253368.0 253737.0 35409.5 36856.4 37024.4 55773.7 55921.4 35721.8 35788.6 35900.0 36218.5 36287.0 14051.4 14141.2 14179.3 14212.5 14243.5 11995.2 12019.7 12024.9 12038.9 12054.3 8278.0 8315.8 8320.0 8360.8 8408.3 3261.8 3264.5 3279.5 3290.7 3301.0 750.4 828.8 862.2 923.9 928.8 28918.3 29201.7 29269.7 29483.1 29642.6 943.6 1059.7 1063.0 1080.4 1118.6 15606.6 15658.5 15737.4 15819.4 15962.6 392696.0 393069.0 394047.0 395877.0 396559.0 51673.2 52917.5 52984.0 53001.7 53108.5 1313.9 1315.4 1318.6 1334.3 1340.6 28062.6 28545.6 29021.6 29050.4 29104.1 44406.8 44488.8 44879.6 44888.6 45067.8 319052.0 325796.0 325938.0 326353.0 327112.0 26166.6 26328.2 26360.6 26544.3 26761.0 44531.8 45071.3 45464.8 45544.8 45572.3 37573.6 37894.0 38673.6 39063.0 39470.4 37043.1 37572.6 37779.4 38184.2 38457.0 36311.0 36516.1 37271.4 37407.6 37720.2 31920.8 33867.8 33880.5 33962.0 34364.0 25434.2 25843.5 26263.2 26382.7 26549.3 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#2] jquery.html Cycles:180 Average:23687.95 Median:26229.83 stddev:62720.08 (239.1%) stddev-sans-first:62871.60 00:29:20 INFO - PROCESS | 1444 | Values: 373.9 377.5 381.5 386.0 389.4 15879.9 16595.9 17331.3 17464.0 17493.0 10635.6 16284.3 17280.9 17339.2 17355.3 4592.4 4761.1 4800.1 4800.4 4805.0 3516.4 5183.9 5459.0 5459.9 5496.4 5479.1 5479.6 5480.1 5483.8 5494.9 5736.7 5853.1 5885.7 5902.2 5902.6 5052.3 5241.4 5249.6 5251.1 5257.6 18468.4 19586.6 19984.7 20534.6 20612.3 1990.9 2007.9 2072.2 2155.5 2390.9 9253.6 9264.2 9439.9 9606.0 9641.1 1703.4 1727.0 1733.5 1781.9 1807.3 65314.0 66842.3 67068.3 67581.7 68595.0 9465.8 9534.8 9733.6 9797.8 9956.2 13040.0 13098.6 13194.8 13225.5 13244.5 7416.5 7428.5 7470.1 7499.8 7678.3 7137.0 7236.3 7310.9 7422.7 7428.0 4007.3 4036.1 4048.8 4053.6 4135.6 1626.0 1687.3 1722.3 1739.3 1754.4 831.6 937.8 955.0 1001.8 1041.2 16397.4 16591.3 16679.6 16709.3 16979.7 921.4 923.1 952.7 960.6 995.3 9399.5 9412.8 9470.4 9569.1 9697.1 367575.0 368076.0 369144.0 395942.0 397646.0 75208.0 75242.6 75397.6 77314.0 78699.2 964.8 969.3 971.7 984.3 993.2 14688.6 14702.7 14807.0 15120.4 15297.8 34067.0 34151.7 34306.3 34855.8 35075.6 46896.5 47698.5 48882.8 49217.3 49322.8 6816.4 7578.2 7622.8 7780.1 8101.1 9287.2 10605.8 10789.0 10947.7 11327.4 9680.0 9972.3 10006.8 10175.9 10454.7 9958.3 10010.8 10158.8 10197.9 10270.3 10109.4 10159.3 10255.2 10395.6 10476.8 7738.1 7806.1 7960.5 8012.2 8030.1 9403.3 10212.6 10288.8 10540.9 10569.0 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#3] mootools.html Cycles:180 Average:5061.09 Median:5481.20 stddev:2728.54 (49.8%) stddev-sans-first:2723.03 00:29:20 INFO - PROCESS | 1444 | Values: 1494.5 1518.0 1670.2 1775.3 1809.9 6618.2 7031.9 7033.6 7528.1 7532.5 7116.4 7293.5 7343.5 7345.1 7345.3 2171.0 2214.3 2215.6 2216.1 2221.3 2143.7 2151.2 2179.7 2187.1 2198.1 2149.6 2160.7 2165.7 2176.2 2210.3 2165.3 2169.1 2176.6 2176.6 2177.8 2159.5 2199.5 2201.2 2203.9 2204.9 7242.0 7305.4 7310.3 7313.3 7336.1 4570.0 4622.1 4651.3 4658.6 4708.0 2862.6 2947.9 3013.1 3020.6 3032.3 957.0 975.2 996.1 997.8 1028.7 10111.8 10120.8 10143.4 10183.0 10240.5 4232.7 4317.4 4370.9 4400.3 4469.3 8924.6 8954.1 8954.1 8991.5 9022.3 4588.1 4618.8 4627.1 4718.8 4735.9 3707.6 4135.5 4233.6 4252.4 4266.4 3654.3 3675.3 3775.3 3786.6 3808.6 1183.7 1217.5 1220.5 1225.6 1228.4 755.7 925.5 934.4 934.7 955.4 5386.8 5408.6 5495.1 5517.2 5679.1 925.7 946.8 959.7 962.6 963.8 3738.5 3883.7 3891.4 3897.5 3940.5 8981.6 9396.3 9414.5 9424.6 9522.2 9366.1 9381.6 9382.4 9410.8 9437.8 6710.7 6835.4 6885.9 6921.7 6931.0 5361.8 5439.3 5467.3 5571.1 5573.7 8644.3 8778.0 8869.6 8872.1 8876.1 8701.6 8826.3 8941.7 8979.8 9038.9 5894.1 6488.3 6505.4 6629.1 6776.9 7208.0 7277.2 7285.7 7296.1 7315.3 6354.6 6594.0 6603.9 6631.4 6661.5 6578.9 6581.2 6624.4 6632.0 6667.1 6011.5 6087.8 6095.4 6108.3 6129.0 4425.6 4487.8 4561.6 4600.3 4611.6 4461.3 4497.3 4503.3 4529.7 4538.4 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#4] prototype.html Cycles:180 Average:4199.05 Median:4764.05 stddev:1215.41 (25.5%) stddev-sans-first:1183.01 00:29:20 INFO - PROCESS | 1444 | Values: 297.5 306.0 308.7 316.1 317.5 4824.8 4947.5 4993.3 5009.8 5033.3 4902.9 4903.5 4926.5 4943.6 4981.7 3898.9 3901.6 3903.7 3907.8 3938.3 3598.7 3868.5 3874.8 3902.1 3925.2 3797.8 3798.9 3809.6 3811.9 3825.6 3806.9 3841.8 3846.7 3852.4 3898.7 3161.8 3222.2 3328.2 3334.1 3339.2 4643.6 4690.3 4695.8 4710.9 4794.5 4303.5 4308.9 4317.1 4328.4 4340.6 5004.7 5111.8 5122.6 5138.9 5162.4 3607.1 3623.3 3630.8 3633.9 3637.5 5342.2 5360.4 5368.4 5383.4 5447.9 3596.5 3673.3 3700.5 3703.5 3744.1 3749.0 3776.1 3778.1 3783.5 3786.8 4293.7 4295.3 4314.6 4328.9 4331.7 5110.4 5126.4 5133.5 5158.8 5163.0 1205.9 1260.4 1260.6 1265.5 1267.9 845.1 860.8 865.9 880.3 882.9 4311.8 4371.7 4398.8 4404.5 4441.4 5050.4 5102.7 5155.9 5173.2 5198.7 4246.8 4385.0 4389.8 4404.5 4420.9 2658.5 2706.8 2731.0 2773.7 2776.6 4761.2 4852.5 4863.2 4865.9 4881.9 5283.2 5298.2 5305.6 5339.5 5371.3 4490.8 4750.5 4766.9 4790.6 4798.0 5084.1 5116.6 5162.4 5195.4 5232.5 5084.4 5086.7 5103.5 5129.8 5201.9 4657.1 4756.2 4758.4 4859.2 4863.2 3623.5 3627.1 3640.0 3642.8 3660.8 5142.9 5145.6 5151.9 5214.7 5301.6 4845.5 4978.4 4985.4 5008.5 5021.7 4955.6 4976.6 4988.4 4999.5 5010.5 4821.8 4896.9 4909.9 4932.2 5024.5 4672.4 4978.2 5010.6 5053.3 5066.8 4858.4 4863.9 4897.6 4906.4 4941.8 00:29:20 INFO - PROCESS | 1444 | 00:29:20 INFO - PROCESS | 1444 | [#5] yui.html Cycles:180 Average:13129.54 Median:22536.51 stddev:14873.65 (66.0%) stddev-sans-first:14886.47 00:29:20 INFO - PROCESS | 1444 | Values: 780.3 820.3 822.0 825.2 827.9 12499.4 12674.7 12823.2 12944.5 13062.2 12402.8 12412.1 12445.5 12485.6 12595.7 665.4 844.9 850.4 857.7 874.2 856.1 856.8 859.0 865.3 867.2 909.8 910.5 914.4 916.3 920.4 904.4 908.4 909.7 911.8 912.1 667.5 672.2 672.8 673.8 674.2 12719.2 12738.9 12794.4 12814.9 12857.9 12247.5 12340.9 12409.9 12593.3 12806.2 9630.2 9758.4 10163.5 10179.9 10246.6 3378.1 3445.8 3479.4 3551.2 3564.3 44572.6 44671.0 45150.1 45261.0 45417.0 9805.6 9868.6 10001.9 10202.9 10505.1 18374.6 18421.9 18427.1 18434.7 18474.8 6918.7 7040.5 7147.5 7158.4 7255.1 3741.2 3832.7 3844.9 3855.3 3867.4 2950.4 3005.9 3026.8 3030.0 3034.3 501.0 507.9 518.3 519.5 535.0 578.0 649.3 650.3 654.2 666.1 7353.4 8165.0 8227.8 8241.4 8289.2 629.3 638.1 653.0 656.9 658.3 3857.5 3951.8 3953.3 3993.6 4099.2 53270.7 53851.0 54117.5 54380.2 55321.7 49666.3 50198.2 50378.0 51019.2 51706.0 39169.2 45851.8 46137.0 46141.8 46831.0 6560.5 8120.9 8233.5 8328.5 8506.4 27030.3 27552.3 29832.0 30053.2 30625.5 34657.1 34970.0 38704.6 40874.2 40973.8 10432.4 10509.6 10665.5 10720.3 10725.2 12397.3 12528.2 12566.2 12626.4 12648.2 10984.4 11148.2 11185.1 11716.2 12142.3 11323.8 11673.5 11951.4 11971.5 11980.2 11530.5 11591.4 11617.2 11718.7 11840.8 9446.4 9561.9 9772.9 9913.9 10039.8 9597.0 10026.7 10036.3 10089.9 10112.6 00:29:20 INFO - PROCESS | 1444 | -------- Summary: end -------- 00:29:20 INFO - PROCESS | 1444 | 00:29:21 INFO - TEST-INFO | 1444: exit 0 00:29:21 INFO - TEST-OK | dromaeo_css | took 1115466ms 00:29:21 INFO - TEST-START | kraken 00:29:21 INFO - operating with platform_type : linux_ 00:29:21 INFO - Initialising browser for kraken test... 00:29:21 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpudvaGj/profile http://localhost:47282/getInfo.html) 00:29:25 INFO - PROCESS | 1680 | 00:29:25 INFO - PROCESS | 1680 | (process:1745): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:29:26 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 00:29:26 INFO - PROCESS | 1680 | colorDepth:24 00:29:26 INFO - PROCESS | 1680 | Browser inner width/height: 1024/697 00:29:26 INFO - PROCESS | 1680 | __metrics 00:29:27 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 00:29:28 INFO - TEST-INFO | 1680: exit 0 00:29:28 INFO - Browser initialized. 00:29:28 INFO - Running cycle 1/1 for kraken test... 00:29:28 INFO - TEST-INFO | started process 1796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpudvaGj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:29:29 INFO - PROCESS | 1796 | 00:29:29 INFO - PROCESS | 1796 | (process:1855): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:29:39 INFO - PROCESS | 1796 | RSS: Main: 164749312 00:29:39 INFO - PROCESS | 1796 | 00:29:42 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47282/tests/kraken/driver.html?testName=audio-beat-detection) 00:29:43 INFO - PROCESS | 1796 | RSS: Main: 168042496 00:29:43 INFO - PROCESS | 1796 | 00:29:46 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47282/tests/kraken/driver.html?testName=audio-dft) 00:29:47 INFO - PROCESS | 1796 | RSS: Main: 169234432 00:29:47 INFO - PROCESS | 1796 | 00:29:49 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47282/tests/kraken/driver.html?testName=audio-fft) 00:29:50 INFO - PROCESS | 1796 | RSS: Main: 164130816 00:29:50 INFO - PROCESS | 1796 | 00:29:52 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47282/tests/kraken/driver.html?testName=audio-oscillator) 00:29:53 INFO - PROCESS | 1796 | RSS: Main: 163377152 00:29:53 INFO - PROCESS | 1796 | 00:29:55 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:29:56 INFO - PROCESS | 1796 | RSS: Main: 164249600 00:29:56 INFO - PROCESS | 1796 | 00:29:59 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47282/tests/kraken/driver.html?testName=imaging-darkroom) 00:30:00 INFO - PROCESS | 1796 | RSS: Main: 164638720 00:30:00 INFO - PROCESS | 1796 | 00:30:03 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47282/tests/kraken/driver.html?testName=imaging-desaturate) 00:30:04 INFO - PROCESS | 1796 | RSS: Main: 163385344 00:30:04 INFO - PROCESS | 1796 | 00:30:07 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47282/tests/kraken/driver.html?testName=json-parse-financial) 00:30:07 INFO - PROCESS | 1796 | RSS: Main: 163454976 00:30:07 INFO - PROCESS | 1796 | 00:30:09 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:30:10 INFO - PROCESS | 1796 | RSS: Main: 163950592 00:30:10 INFO - PROCESS | 1796 | 00:30:11 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:30:12 INFO - PROCESS | 1796 | RSS: Main: 163319808 00:30:12 INFO - PROCESS | 1796 | 00:30:14 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:30:15 INFO - PROCESS | 1796 | RSS: Main: 164487168 00:30:15 INFO - PROCESS | 1796 | 00:30:18 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:30:18 INFO - PROCESS | 1796 | RSS: Main: 164605952 00:30:18 INFO - PROCESS | 1796 | 00:30:21 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:30:22 INFO - PROCESS | 1796 | RSS: Main: 164589568 00:30:22 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:47282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47282/tests/kraken/driver.html?testName=ai-astar) 00:30:24 INFO - PROCESS | 1796 | RSS: Main: 165511168 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | __start_tp_report 00:30:24 INFO - PROCESS | 1796 | _x_x_mozilla_page_load 00:30:24 INFO - PROCESS | 1796 | _x_x_mozilla_page_load_details 00:30:24 INFO - PROCESS | 1796 | |i|pagename|runs| 00:30:24 INFO - PROCESS | 1796 | |0;ai-astar;112;116;109;113;112;113;108;114;114;115 00:30:24 INFO - PROCESS | 1796 | |1;audio-beat-detection;165;142;158;192;134;162;154;142;199;139 00:30:24 INFO - PROCESS | 1796 | |2;audio-dft;163;161;164;178;163;163;161;161;161;163 00:30:24 INFO - PROCESS | 1796 | |3;audio-fft;77;77;77;162;76;76;76;76;168;77 00:30:24 INFO - PROCESS | 1796 | |4;audio-oscillator;94;93;149;94;93;92;138;94;94;149 00:30:24 INFO - PROCESS | 1796 | |5;imaging-gaussian-blur;117;118;117;118;118;118;118;119;118;119 00:30:24 INFO - PROCESS | 1796 | |6;imaging-darkroom;147;147;147;147;148;147;147;149;147;147 00:30:24 INFO - PROCESS | 1796 | |7;imaging-desaturate;96;96;96;95;96;95;95;95;95;95 00:30:24 INFO - PROCESS | 1796 | |8;json-parse-financial;68;68;69;67;69;69;68;83;66;66 00:30:24 INFO - PROCESS | 1796 | |9;json-stringify-tinderbox;65;65;65;64;65;65;64;65;65;66 00:30:24 INFO - PROCESS | 1796 | |10;stanford-crypto-aes;83;82;84;84;85;84;87;90;86;84 00:30:24 INFO - PROCESS | 1796 | |11;stanford-crypto-ccm;134;134;156;133;134;133;133;136;210;135 00:30:24 INFO - PROCESS | 1796 | |12;stanford-crypto-pbkdf2;172;174;177;177;172;174;180;174;180;172 00:30:24 INFO - PROCESS | 1796 | |13;stanford-crypto-sha256-iterative;69;72;72;68;69;68;69;70;68;68 00:30:24 INFO - PROCESS | 1796 | __end_tp_report 00:30:24 INFO - PROCESS | 1796 | __start_cc_report 00:30:24 INFO - PROCESS | 1796 | _x_x_mozilla_cycle_collect,935 00:30:24 INFO - PROCESS | 1796 | __end_cc_report 00:30:24 INFO - PROCESS | 1796 | __startTimestamp1462779024565__endTimestamp 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | ------- Summary: start ------- 00:30:24 INFO - PROCESS | 1796 | Number of tests: 14 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.50 stddev:2.50 (2.2%) stddev-sans-first:2.65 00:30:24 INFO - PROCESS | 1796 | Values: 112.0 116.0 109.0 113.0 112.0 113.0 108.0 114.0 114.0 115.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#1] audio-beat-detection Cycles:10 Average:158.70 Median:160.00 stddev:22.02 (13.8%) stddev-sans-first:23.23 00:30:24 INFO - PROCESS | 1796 | Values: 165.0 142.0 158.0 192.0 134.0 162.0 154.0 142.0 199.0 139.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:5.12 (3.1%) stddev-sans-first:5.42 00:30:24 INFO - PROCESS | 1796 | Values: 163.0 161.0 164.0 178.0 163.0 163.0 161.0 161.0 161.0 163.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.50 stddev:37.34 (48.8%) stddev-sans-first:39.09 00:30:24 INFO - PROCESS | 1796 | Values: 77.0 77.0 77.0 162.0 76.0 76.0 76.0 76.0 168.0 77.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:93.50 stddev:25.26 (27.0%) stddev-sans-first:26.20 00:30:24 INFO - PROCESS | 1796 | Values: 94.0 93.0 149.0 94.0 93.0 92.0 138.0 94.0 94.0 149.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 00:30:24 INFO - PROCESS | 1796 | Values: 117.0 118.0 117.0 118.0 118.0 118.0 118.0 119.0 118.0 119.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 00:30:24 INFO - PROCESS | 1796 | Values: 147.0 147.0 147.0 147.0 148.0 147.0 147.0 149.0 147.0 147.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 00:30:24 INFO - PROCESS | 1796 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:68.50 stddev:4.95 (7.2%) stddev-sans-first:5.22 00:30:24 INFO - PROCESS | 1796 | Values: 68.0 68.0 69.0 67.0 69.0 69.0 68.0 83.0 66.0 66.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 00:30:24 INFO - PROCESS | 1796 | Values: 65.0 65.0 65.0 64.0 65.0 65.0 64.0 65.0 65.0 66.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.50 stddev:2.28 (2.7%) stddev-sans-first:2.32 00:30:24 INFO - PROCESS | 1796 | Values: 83.0 82.0 84.0 84.0 85.0 84.0 87.0 90.0 86.0 84.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:134.50 stddev:24.28 (18.1%) stddev-sans-first:25.50 00:30:24 INFO - PROCESS | 1796 | Values: 134.0 134.0 156.0 133.0 134.0 133.0 133.0 136.0 210.0 135.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.50 stddev:3.12 (1.8%) stddev-sans-first:3.09 00:30:24 INFO - PROCESS | 1796 | Values: 172.0 174.0 177.0 177.0 172.0 174.0 180.0 174.0 180.0 172.0 00:30:24 INFO - PROCESS | 1796 | 00:30:24 INFO - PROCESS | 1796 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.57 (2.3%) stddev-sans-first:1.66 00:30:24 INFO - PROCESS | 1796 | Values: 69.0 72.0 72.0 68.0 69.0 68.0 69.0 70.0 68.0 68.0 00:30:24 INFO - PROCESS | 1796 | -------- Summary: end -------- 00:30:24 INFO - PROCESS | 1796 | 00:30:25 INFO - TEST-INFO | 1796: exit 0 00:30:25 INFO - TEST-OK | kraken | took 63921ms 00:30:25 INFO - SUITE-END | took 1179s 00:30:25 INFO - Completed test suite (00:19:39) 00:30:25 INFO - javascript benchmark 00:30:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2605.7133715291316, 2657.0965871603657, 2671.1051797647788, 2673.839569625824, 2677.571588545833, 4665.790039448225, 4688.038952014837, 4716.056788642272, 4750.28745688147, 4823.903521929568, 4507.819687212515, 4677.508861569538, 4699.85900422987, 4707.764611769415, 4733.502982186872, 4333.479982402108, 4405.273129933557, 4468.218061839181, 4468.843590474334, 4481.148581769454, 605.5782084939208, 4228.365745138223, 4259.76571288579, 4294.914101717964, 4329.935050974238, 4319.654427645796, 4338.587834155782, 4339.5660433956355, 4359.956400436018, 4393.319035549463, 4339.06710057336, 4372.322290045016, 4376.496702879178, 4381.693281470299, 4413.0, 4292.0772034012525, 4318.805653745589, 4351.26028575143, 4355.5644435556505, 4360.542143074963, 4650.372199753057, 4653.325267836145, 4653.511381304948, 4679.947011922291, 4690.695104818176, 4912.975435122838, 4971.279164521125, 4980.078685443204, 5016.698998060128, 5027.773750181251, 4985.202367621199, 5063.721495317756, 5067.0, 5073.162928116856, 5099.107656160157, 4645.837395691135, 4723.173444647207, 4739.620830333565, 4752.073345697556, 4782.187028205213, 5073.264376665363, 5113.335266415343, 5133.178691409356, 5154.329937108152, 5198.92201616976, 4939.5554400104165, 4968.975155124273, 4974.925376119361, 4976.527229913152, 5020.7740651670765, 4957.578605818474, 5005.099082165172, 5006.774695138727, 5012.849614511498, 5076.0, 4848.854534363974, 4871.171900776876, 4900.191468407673, 4929.704217746947, 5025.698458092526, 4914.680545764585, 4920.655554111249, 4935.457099719028, 4942.629302802305, 4971.130052240844, 4215.0, 4237.322028475429, 4243.745375277494, 4259.53145154039, 4304.096139810612, 3675.3016926783826, 3686.0, 3717.75834570752, 3748.6438788315068, 3762.172322089136, 4223.936640950388, 4231.344141658048, 4243.6605071594195, 4264.189803937242, 4328.329108988112, 4988.90022199554, 5004.3494345734825, 5023.246513023076, 5030.170021946338, 5034.924476132861, 4221.050263690707, 4283.357496375506, 4292.163028209532, 4318.244307246219, 4336.392904993303, 4560.293154561048, 4564.863054108382, 4573.3368661544555, 4576.702514336557, 4618.538146185355, 4614.284785858197, 4704.082703872712, 4711.151992641289, 4712.717236965793, 4770.809167633325, 4929.309896614405, 4989.60083193351, 5038.269450929522, 5052.646314757932, 5062.645614806928, 4658.697184682985, 4663.0, 4678.76606169697, 4679.157751604744, 4721.504242054672, 4850.490698476609, 4966.577840883421, 4975.5522003019905, 4976.353074100344, 5046.419661738947, 4940.357753492167, 4973.701577905337, 4983.601311894967, 5000.199968005102, 5018.698878067327, 4868.294097355934, 4872.196087645641, 4874.195757699939, 4876.5854902333695, 4920.1389756791705, 4777.044591081728, 4805.0, 4829.251466022772, 4855.490173531728, 4880.023995201045, 4766.428028636586, 4769.30845027476, 4792.880177995578, 4816.759162041813, 4877.609791216765, 4927.433345165208, 4927.9507204927495, 4929.408470983505, 4945.060438516671, 4966.255061740768, 4711.081339138835, 4799.832005879777, 4819.060283244734, 4850.684705494132, 4873.975630121827], "value": 4579.17970392099, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.9211910332551, 600.8527910661888, 608.1364462463301, 612.4946918791998, 614.0942110387178, 41723.0, 43446.34830477541, 43763.56236437635, 43934.0, 48357.75821120898, 43773.562264377346, 44275.557244427546, 44321.0, 44414.0, 44546.777266113626, 7893.171217022202, 7950.840983180333, 8047.758567242963, 8049.919500805005, 8056.798580035516, 8000.439969202158, 8065.2741253287195, 8066.11272759996, 8067.515949043047, 8082.313003394689, 8051.315638170762, 8078.232567906069, 8093.878591821098, 8094.433389662726, 8105.918940810576, 8010.198980102002, 8014.078380986174, 8020.999999999942, 8053.315468185211, 8067.838643227132, 7898.249666281694, 7900.367970562341, 7952.966114405091, 7954.9602251988945, 7966.5618390988475, 39542.80228598868, 39577.40633890494, 39948.20103597912, 39994.0, 40070.19859602821, 3238.757093218018, 3344.0, 3463.480477928306, 3737.8131093445218, 3958.960410395888, 29121.708782912112, 29582.408351832844, 29949.850250748827, 29971.400571988655, 30094.84952575223, 3554.449060395643, 3614.819259037064, 3694.907627309312, 3727.1241258304276, 3760.793156376398, 252778.0, 252891.0, 253163.99999999817, 253368.0, 253737.0, 35409.46885796715, 36856.44715329272, 37024.444633329986, 55773.721131394086, 55921.44078559244, 35721.82139089288, 35788.64211357905, 35900.0, 36218.456723149175, 36287.0, 14051.367688454044, 14141.22223277709, 14179.291035448186, 14212.502562410265, 14243.501477448239, 11995.160338776202, 12019.699507512207, 12024.87975120255, 12038.879611203776, 12054.276743395425, 8277.95861020691, 8315.750527484186, 8320.0, 8360.832783344298, 8408.32733381328, 3261.804291742503, 3264.526643636707, 3279.540864279003, 3290.68738469845, 3300.950485742716, 750.3734381791156, 828.7886588919907, 862.2239984014312, 923.9300879625262, 928.8467402878449, 28918.277043074093, 29201.707982920325, 29269.707302926698, 29483.11550653484, 29642.55536166959, 943.6366998705411, 1059.6926891201465, 1062.9681109566725, 1080.400377790318, 1118.6140781430393, 15606.609834753996, 15658.45195418155, 15737.370505179664, 15819.367225311089, 15962.60093497672, 392696.0, 393069.0, 394047.0, 395877.0, 396559.0, 51673.224901626505, 52917.470825292025, 52984.0, 53001.734991324796, 53108.468915311125, 1313.8817506424277, 1315.4475120449242, 1318.5912367166209, 1334.252818421687, 1340.557615986722, 28062.57906131369, 28545.571816422354, 29021.567935767893, 29050.41899162047, 29104.126876193746, 44406.77796610996, 44488.777556112014, 44879.551204487536, 44888.55111448844, 45067.7746611278, 319052.0, 325796.0, 325938.0, 326353.0, 327112.0, 26166.60750088712, 26328.21015369542, 26360.604590931536, 26544.336391590365, 26761.0, 44531.777341113084, 45071.32393014173, 45464.77267613641, 45544.77227613841, 45572.31641525446, 37573.62426375811, 37894.0, 38673.613263867, 39063.0, 39470.40794388029, 37043.07392315214, 37572.62427375691, 37779.43330849984, 38184.236315274095, 38457.0, 36311.0, 36516.087097821706, 37271.44092838556, 37407.6259237415, 37720.24559508849, 31920.79601002475, 33867.830660847525, 33880.49179262362, 33962.0, 34364.0, 25434.23697289084, 25843.483130337663, 26263.23932521048, 26382.736172638797, 26549.336266592716], "value": 17211.47092009221, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.9401695728684, 377.5111230955912, 381.5345278759912, 385.9787711675858, 389.40615561269044, 15879.920600396996, 16595.917020414898, 17331.30674773011, 17464.0, 17493.03788291645, 10635.57457701691, 16284.348626054943, 17280.913595432005, 17339.21973511195, 17355.305787768506, 4592.380028696125, 4761.142994261032, 4800.063987522417, 4800.447948485917, 4804.951950480503, 3516.437370020797, 5183.948160518385, 5459.017376872162, 5459.918101228484, 5496.422875598065, 5479.068558345091, 5479.643823151482, 5480.068388373966, 5483.7806487740445, 5494.945050549503, 5736.741846616891, 5853.122031695238, 5885.735141918624, 5902.232709747748, 5902.6458412495385, 5052.317937078521, 5241.449647786965, 5249.632525723201, 5251.10731175701, 5257.631965762398, 18468.3536076238, 19586.608267834563, 19984.700229496568, 20534.5893082139, 20612.27857025012, 1990.8606397552178, 2007.8594498385119, 2072.171131547378, 2155.5257843274453, 2390.916317928864, 9253.6298548058, 9264.212541933945, 9439.858402123973, 9606.0, 9641.084097010773, 1703.4378655043806, 1727.0053088682591, 1733.5319463744718, 1781.8663600229904, 1807.3403207829178, 65314.0, 66842.33157668362, 67068.32931670718, 67581.66209168972, 68595.0, 9465.763355916019, 9534.809303813885, 9733.610655573697, 9797.755056123511, 9956.153726933147, 13040.0, 13098.607041788571, 13194.802077968838, 13225.470981160839, 13244.53644122451, 7416.480846340813, 7428.517146385468, 7470.057784975946, 7499.812504687427, 7678.270564296383, 7136.964315178391, 7236.34872861445, 7310.890336644955, 7422.665980030912, 7427.962860185665, 4007.2987227235117, 4036.1120553478186, 4048.7975601219823, 4053.5541090480024, 4135.56576559463, 1626.0, 1687.2660392729201, 1722.336900293371, 1739.330357812226, 1754.429810311654, 831.563429199675, 937.8171256604896, 954.9761255968656, 1001.7846163074973, 1041.193075366597, 16397.42609008679, 16591.336346546002, 16679.583010424838, 16709.331626734795, 16979.74530382045, 921.4425272710048, 923.0907556057278, 952.7284723853669, 960.6205548808322, 995.3381001633977, 9399.530023498797, 9412.764680883032, 9470.384425012353, 9569.090936361035, 9697.078777516124, 367575.0, 368076.0, 369144.0, 395942.0, 397646.0, 75208.0, 75242.62378688072, 75397.62301188459, 77314.0, 78699.21300787033, 964.7858165510031, 969.3311614985637, 971.6502059258803, 984.3011461862158, 993.2004736187295, 14688.55934321972, 14702.705945881236, 14807.0, 15120.373626264742, 15297.847021529642, 34067.0, 34151.65848341584, 34306.31387372289, 34855.82572087123, 35075.649243507236, 46896.531034689215, 47698.52301476941, 48882.75558622184, 49217.26174107463, 49322.753386234275, 6816.420604248496, 7578.242175782378, 7622.771316860503, 7780.144184139627, 8101.149379315087, 9287.210587100171, 10605.840912386475, 10789.0, 10947.726306842393, 11327.376994265394, 9679.951600241953, 9972.3019388645, 10006.8498972514, 10175.898241017496, 10454.738631534272, 9958.253131015057, 10010.799784004425, 10158.847617285599, 10197.9490102549, 10270.33242839213, 10109.443980581138, 10159.28884978074, 10255.230857685554, 10395.58417663285, 10476.842847357144, 7738.110117336353, 7806.102298235548, 7960.522368657899, 8012.158723333966, 8030.116687164291, 9403.294752893424, 10212.642557510748, 10288.845667314847, 10540.947295263475, 10569.048785609331], "value": 8151.289766510503, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1494.4545240987043, 1517.977230341545, 1670.240040781444, 1775.3253763569842, 1809.9004554749492, 6618.205815302158, 7031.8945215821805, 7033.613151276678, 7528.134264559577, 7532.5103868248525, 7116.359527642511, 7293.4894557381, 7343.485955983083, 7345.081864766904, 7345.265473452652, 2170.9565808683815, 2214.3135627955303, 2215.579039982398, 2216.1024784962115, 2221.3224966385246, 2143.678448232762, 2151.2148065955894, 2179.662152366378, 2187.0814258011674, 2198.1427243375097, 2149.5915776002507, 2160.7191065161505, 2165.675148727688, 2176.1621775616422, 2210.3368989303144, 2165.296278709418, 2169.1323470611724, 2176.6190916589535, 2176.640854259045, 2177.8366622503377, 2159.5033142377342, 2199.549092436047, 2201.1965632544006, 2203.933881983543, 2204.889755512218, 7242.0223269858425, 7305.4155667546465, 7310.305520975498, 7313.268673132644, 7336.082989626297, 4569.977150114228, 4622.098690755304, 4651.302304654295, 4658.580727734487, 4707.976460117712, 2862.5849251858363, 2947.941041179186, 3013.141254742388, 3020.5771192033135, 3032.3025704087963, 956.9952150239204, 975.2344409638471, 996.1333639733479, 997.7605374710017, 1028.6913925822223, 10111.797764044677, 10120.797584048278, 10143.442110683909, 10183.0, 10240.539175737109, 4232.746035237834, 4317.373980772833, 4370.934435983463, 4400.3399490076745, 4469.307257375112, 8924.643014279485, 8954.059823718546, 8954.149355811187, 8991.505467199237, 9022.278217742565, 4588.128255631419, 4618.769061546909, 4627.051454451829, 4718.764061796896, 4735.881602959954, 3707.5736290326417, 4135.462389889295, 4233.597808208216, 4252.447181866338, 4266.360045993064, 3654.250878569887, 3675.3384390809374, 3775.301569209705, 3786.6213378661914, 3808.5810560838286, 1183.7099910521806, 1217.531250468576, 1220.4690959432676, 1225.6078055022485, 1228.3551135653674, 755.6901670315144, 925.4956048953337, 934.3693007220099, 934.672864497434, 955.3694561589317, 5386.757595908193, 5408.648437851527, 5495.093309603951, 5517.172424136412, 5679.091345384719, 925.6528801699362, 946.7917058247175, 959.7072892767633, 962.6197651927589, 963.7638778499313, 3738.495303134042, 3883.66988805949, 3891.416287556889, 3897.4738410314812, 3940.5468371137113, 8981.550922453851, 9396.295277854051, 9414.529273536296, 9424.575894084783, 9522.190613797766, 9366.063393660716, 9381.577828997575, 9382.390144640576, 9410.811783764286, 9437.811243775086, 6710.731570737214, 6835.350641689031, 6885.862282754317, 6921.688524016431, 6931.0, 5361.785528578813, 5439.347278326626, 5467.316585426822, 5571.0807716726285, 5573.693446860461, 8644.308455323684, 8777.956110219407, 8869.600867960828, 8872.112788721077, 8876.068012858816, 8701.608427620899, 8826.338024648048, 8941.731748047569, 8979.820403592023, 9038.909610903807, 5894.145348924296, 6488.2538508072175, 6505.381988711065, 6629.105070815571, 6776.898346524707, 7207.9639601801655, 7277.199508054216, 7285.672144753393, 7296.124465064227, 7315.268473152642, 6354.555181137461, 6594.0, 6603.933960660332, 6631.3700198481065, 6661.46708263328, 6578.901316480353, 6581.243157037002, 6624.436922861418, 6631.966840165961, 6667.133272674521, 6011.519078473799, 6087.817365479043, 6095.3904609538695, 6108.297545782292, 6129.049997250433, 4425.645948324062, 4487.7980490877335, 4561.635069194523, 4600.263957766741, 4611.5619016193405, 4461.263891457873, 4497.325401189848, 4503.301988191835, 4529.728216307032, 4538.364628952016], "value": 4150.31839626183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.47644146302514, 306.0283599571359, 308.68051566628543, 316.06287357983575, 317.46190207598124, 4824.758762061896, 4947.529984651453, 4993.3259010033635, 5009.824656137041, 5033.345665063541, 4902.921357301388, 4903.534164254399, 4926.502183449755, 4943.60451163907, 4981.701097934127, 3898.8830335089992, 3901.6293452122004, 3903.668188204006, 3907.804609769514, 3938.2911076006308, 3598.6581274778987, 3868.497095377597, 3874.786886721229, 3902.1415288636454, 3925.156091440354, 3797.810109494528, 3798.9430158547643, 3809.56190038145, 3811.866584669537, 3825.61743825618, 3806.885793426202, 3841.769493830379, 3846.692264618852, 3852.4221366794923, 3898.7075969302414, 3161.8102913825246, 3222.242772948355, 3328.1845947742718, 3334.1331253873923, 3339.1985923378456, 4643.582077613031, 4690.296455531663, 4695.7886895089805, 4710.929336059962, 4794.544518270759, 4303.483581970184, 4308.870733877989, 4317.0934103838235, 4328.372386004044, 4340.60934515895, 5004.674696144775, 5111.846644600668, 5122.641415100945, 5138.871528211825, 5162.406323272834, 3607.134287770942, 3623.3477973964677, 3630.80030598317, 3633.854645814164, 3637.4907512948207, 5342.171963345688, 5360.383555891083, 5368.409474957752, 5383.353997520323, 5447.863803404907, 3596.478510615946, 3673.3020726061964, 3700.50043244164, 3703.500027496307, 3744.0952352418417, 3748.981255093707, 3776.131489757316, 3778.1499162688174, 3783.4892289541112, 3786.772793632391, 4293.677974151951, 4295.29127693927, 4314.56854314566, 4328.891777705582, 4331.696781225284, 5110.361204849394, 5126.436092029874, 5133.486651334912, 5158.767855446514, 5163.044836705221, 1205.9267252145598, 1260.4328052376468, 1260.6007331861626, 1265.5127775806382, 1267.8668739782374, 845.1337379186383, 860.8278344331159, 865.9004214515286, 880.3265501891059, 882.911708829112, 4311.8490852820005, 4371.672124590668, 4398.8020539075815, 4404.524484297732, 4441.444819397575, 5050.3939527256225, 5102.693838369635, 5155.871103222375, 5173.198154286017, 5198.740062996835, 4246.8301267949555, 4385.0, 4389.824407023747, 4404.471463424409, 4420.911581768347, 2658.4550167215675, 2706.8090040382563, 2730.9863450683015, 2773.72262773721, 2776.6390369251867, 4761.214399624023, 4852.490488498727, 4863.197572400514, 4865.9026819463415, 4881.89999350415, 5283.1546952487415, 5298.178782288752, 5305.575553955674, 5339.519443250126, 5371.328583927009, 4490.753008584494, 4750.453697824794, 4766.880827979328, 4790.568848803624, 4797.976010119927, 5084.135696931457, 5116.590672746097, 5162.354705661793, 5195.428502864757, 5232.514555586218, 5084.41529224144, 5086.669366491315, 5103.489651034867, 5129.846104616868, 5201.878872676515, 4657.093860081941, 4756.239001759702, 4758.381410416624, 4859.198232291632, 4863.221884498464, 3623.474596183486, 3627.1294889226924, 3639.9636003639625, 3642.7814331140216, 3660.7803531788177, 5142.897142057063, 5145.588352931832, 5151.871203219949, 5214.661047031931, 5301.575873930154, 4845.515448455127, 4978.4274808397495, 4985.426675932169, 5008.474110218374, 5021.69002258965, 4955.628327875352, 4976.6407674820075, 4988.351514303117, 4999.450060493416, 5010.5240002199735, 4821.79455136216, 4896.877578060577, 4909.895273563419, 4932.152920721843, 5024.547790698854, 4672.415948006499, 4978.228374601942, 5010.574101201439, 5053.343065401475, 5066.771995260148, 4858.368412106403, 4863.932828547924, 4897.608191344755, 4906.362172917497, 4941.7645588602845], "value": 3814.290978936613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.2782426255714, 820.33552822214, 821.9848487118411, 825.2078005115089, 827.8758186272061, 12499.43752531136, 12674.746505069905, 12823.166494177873, 12944.482220711183, 13062.150960187593, 12402.81395779064, 12412.131150819447, 12445.502179912794, 12485.563005294816, 12595.748085038294, 665.3579295979379, 844.877492763549, 850.421713235, 857.6697971281039, 874.2175752701329, 856.1139220906332, 856.7729551668813, 858.9828203435927, 865.2558799432483, 867.1761826265042, 909.7725568607848, 910.4946754551221, 914.3919293669702, 916.3173435790312, 920.3649481857497, 904.4166512599337, 908.4095338030301, 909.6725178935578, 911.8039621481347, 912.0879120879121, 667.4827009067963, 672.1900110366996, 672.8351553869285, 673.7911247513238, 674.197704731373, 12719.173253738478, 12738.87261127386, 12794.42425090873, 12814.935925320313, 12857.871421285854, 12247.510099596006, 12340.876591234062, 12409.875901240963, 12593.307368094751, 12806.167599106027, 9630.188377634457, 9758.414495130315, 10163.491825408773, 10179.931107233782, 10246.6413675521, 3378.1048022274117, 3445.758796884219, 3479.425894727367, 3551.200979779555, 3564.287142571496, 44572.55427445749, 44671.0, 45150.09699805985, 45261.0, 45416.99999999967, 9805.607775689035, 9868.555914983843, 10001.949990250148, 10202.897971020344, 10505.107065899485, 18374.63250734978, 18421.90789046046, 18427.078646067643, 18434.723479147822, 18474.81525184758, 6918.7232510699005, 7040.471964602675, 7147.499675022803, 7158.391536719333, 7255.056842610426, 3741.1769410729594, 3832.693384529231, 3844.8654297100024, 3855.3060449118866, 3867.43922131295, 2950.439416510856, 3005.8647360868813, 3026.772992025607, 3029.9848500757357, 3034.286942568495, 500.97996080156383, 507.8781092537764, 518.3209994906684, 519.5272302204976, 534.9919751203735, 577.9797707080232, 649.3052433895687, 650.3334082565408, 654.1790053482848, 666.0808084842886, 7353.4484913631695, 8164.959175204086, 8227.794305142299, 8241.381896357678, 8289.171082891662, 629.263761399155, 638.1321402892063, 652.9510286728514, 656.8981807819796, 658.3350815676106, 3857.498525191707, 3951.841926322915, 3953.3081710700512, 3993.6405723484445, 4099.221147981933, 53270.73364633152, 53851.0, 54117.45882541124, 54380.18429723557, 55321.72339138359, 49666.255006174935, 50198.24702629464, 50378.0, 51019.23471147936, 51706.0, 39169.21661566753, 45851.77074114608, 46137.0, 46141.76929115333, 46831.0, 6560.507961902972, 8120.878186827321, 8233.50598964064, 8328.500289982621, 8506.404551681322, 27030.32424189411, 27552.311192219553, 29832.0, 30053.248668783457, 30625.540616890317, 34657.13357166091, 34970.0, 38704.61295387123, 40874.1825163501, 40973.79513102535, 10432.426216558162, 10509.632162874263, 10665.46672666348, 10720.303180293256, 10725.195610329101, 12397.324835046542, 12528.248305101723, 12566.246025238515, 12626.36868156607, 12648.177868438523, 10984.395858227874, 11148.163887708617, 11185.105191584491, 11716.23844450108, 12142.271463712206, 11323.830142548033, 11673.533058677558, 11951.402429878297, 11971.461284242405, 11980.221285616408, 11530.481128349413, 11591.362475063952, 11617.244879082833, 11718.70703232426, 11840.822387664364, 9446.38598491096, 9561.9043809561, 9772.853407198756, 9913.851292230478, 10039.849402259119, 9597.0, 10026.69919902404, 10036.347637403545, 10089.949550252202, 10112.646057387956], "value": 6177.483965793498, "unit": "score"}], "name": "dromaeo_css", "value": 6305.186781023621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 109.0, 113.0, 112.0, 113.0, 108.0, 114.0, 114.0, 115.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 142.0, 158.0, 192.0, 134.0, 162.0, 154.0, 142.0, 199.0, 139.0], "value": 158.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 164.0, 178.0, 163.0, 163.0, 161.0, 161.0, 161.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 162.0, 76.0, 76.0, 76.0, 76.0, 168.0, 77.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 149.0, 94.0, 93.0, 92.0, 138.0, 94.0, 94.0, 149.0], "value": 109.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 119.0, 118.0, 119.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 149.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 69.0, 69.0, 68.0, 83.0, 66.0, 66.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 84.0, 85.0, 84.0, 87.0, 90.0, 86.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 156.0, 133.0, 134.0, 133.0, 133.0, 136.0, 210.0, 135.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 177.0, 177.0, 172.0, 174.0, 180.0, 174.0, 180.0, 172.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 72.0, 68.0, 69.0, 68.0, 69.0, 70.0, 68.0, 68.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1606.4, "alertThreshold": 2.0}]} 00:30:25 INFO - Return code: 0 00:30:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:30:25 ERROR - # TBPL SUCCESS # 00:30:25 INFO - Running post-action listener: _resource_record_post_action 00:30:25 INFO - ##### 00:30:25 INFO - ##### Finished run-tests step (success) 00:30:25 INFO - ##### 00:30:25 INFO - Running post-run listener: _resource_record_post_run 00:30:26 INFO - Total resource usage - Wall time: 1196s; CPU: 14.0%; Read bytes: 4096; Write bytes: 519925760; Read time: 8; Write time: 529988 00:30:26 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234508288; Read time: 0; Write time: 312164 00:30:26 INFO - run-tests - Wall time: 1181s; CPU: 14.0%; Read bytes: 4096; Write bytes: 278675456; Read time: 8; Write time: 217072 00:30:26 INFO - Running post-run listener: _upload_blobber_files 00:30:26 INFO - Blob upload gear active. 00:30:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:30:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:30:27 INFO - (blobuploader) - INFO - Open directory for files ... 00:30:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:30:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:30:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:30:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:30:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:30:28 INFO - (blobuploader) - INFO - Done attempting. 00:30:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:30:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:30:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:30:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:30:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:30:29 INFO - (blobuploader) - INFO - Done attempting. 00:30:29 INFO - (blobuploader) - INFO - Iteration through files over. 00:30:29 INFO - Return code: 0 00:30:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:30:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:30:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b159d7a276b11742966957d6dd05b9254a26f1e202b102654cdf0d5c00fb9d28834fdf68b1046bb6939f2b2c836016657444044ee5776a023f13e084b63a30f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dece54988fda2a3253fa4d7637dcfaf6d314847176e6849af6ca3eee6ef4b7de7ce617d38f3487245671f214312f92ca296d804ba87e4d2613cc602191015c0b"} 00:30:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:30:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:30:29 INFO - Contents: 00:30:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b159d7a276b11742966957d6dd05b9254a26f1e202b102654cdf0d5c00fb9d28834fdf68b1046bb6939f2b2c836016657444044ee5776a023f13e084b63a30f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dece54988fda2a3253fa4d7637dcfaf6d314847176e6849af6ca3eee6ef4b7de7ce617d38f3487245671f214312f92ca296d804ba87e4d2613cc602191015c0b"} 00:30:29 INFO - Running post-run listener: copy_logs_to_upload_dir 00:30:29 INFO - Copying logs to upload dir... 00:30:29 INFO - mkdir: /builds/slave/test/build/upload/logs 00:30:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.872399 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-09 00:30:28.716500) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 00:30:28.720004) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b159d7a276b11742966957d6dd05b9254a26f1e202b102654cdf0d5c00fb9d28834fdf68b1046bb6939f2b2c836016657444044ee5776a023f13e084b63a30f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dece54988fda2a3253fa4d7637dcfaf6d314847176e6849af6ca3eee6ef4b7de7ce617d38f3487245671f214312f92ca296d804ba87e4d2613cc602191015c0b"} build_url:https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/F_dyD0_3Tzixl8Q2OurlNQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b159d7a276b11742966957d6dd05b9254a26f1e202b102654cdf0d5c00fb9d28834fdf68b1046bb6939f2b2c836016657444044ee5776a023f13e084b63a30f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dece54988fda2a3253fa4d7637dcfaf6d314847176e6849af6ca3eee6ef4b7de7ce617d38f3487245671f214312f92ca296d804ba87e4d2613cc602191015c0b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 00:30:28.746827) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:30:28.747190) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1462777107.820158-100481322 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004367 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:30:28.832358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 00:30:28.832690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 00:30:28.833018) ========= ========= Total master_lag: 0.22 =========