builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1463412434.85 results: success (0) buildid: 20160516074433 builduid: c2f13e2278c2440aa4c221e7e2eb802a revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:14.845714) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:14.847146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:14.847432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:14.866940) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:14.867229) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 08:27:15-- 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% 18.4M=0.001s 2016-05-16 08:27:15 (18.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113224 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.002412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.002700) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017993 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.052345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.052700) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e9533997fcef31fa5d2db464c6ceec0ca6ea560f --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 e9533997fcef31fa5d2db464c6ceec0ca6ea560f --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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 08:27:15,339 truncating revision to first 12 chars 2016-05-16 08:27:15,340 Setting DEBUG logging. 2016-05-16 08:27:15,340 attempt 1/10 2016-05-16 08:27:15,340 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e9533997fcef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 08:27:15,576 unpacking tar archive at: fx-team-e9533997fcef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.502794 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.568583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.568904) ========= script_repo_revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.569296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.569576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 08:27:15.581021) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-16 08:27:15.581340) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:27:15 INFO - MultiFileLogger online at 20160516 08:27:15 in /builds/slave/test 08:27:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 08:27:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:27:15 INFO - {'append_to_log': False, 08:27:15 INFO - 'base_work_dir': '/builds/slave/test', 08:27:15 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:27:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:27:15 INFO - 'branch': 'Fx-Team-Non-PGO', 08:27:15 INFO - 'buildbot_json_path': 'buildprops.json', 08:27:15 INFO - 'config_files': ('talos/linux_config.py',), 08:27:15 INFO - 'default_actions': ('clobber', 08:27:15 INFO - 'read-buildbot-config', 08:27:15 INFO - 'download-and-extract', 08:27:15 INFO - 'populate-webroot', 08:27:15 INFO - 'create-virtualenv', 08:27:15 INFO - 'install', 08:27:15 INFO - 'run-tests'), 08:27:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:27:15 INFO - 'download_minidump_stackwalk': True, 08:27:15 INFO - 'download_symbols': 'ondemand', 08:27:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:27:15 INFO - 'tooltool.py': '/tools/tooltool.py', 08:27:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:27:15 INFO - '/tools/misc-python/virtualenv.py')}, 08:27:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:27:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:27:15 INFO - 'installer_path': 'installer.exe', 08:27:15 INFO - 'log_level': 'info', 08:27:15 INFO - 'log_name': 'talos', 08:27:15 INFO - 'log_to_console': True, 08:27:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:27:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:27:15 INFO - 'opt_config_files': (), 08:27:15 INFO - 'pip_index': False, 08:27:15 INFO - 'sps_profile': False, 08:27:15 INFO - 'sps_profile_interval': 0, 08:27:15 INFO - 'suite': 'dromaeojs', 08:27:15 INFO - 'system_bits': '32', 08:27:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:27:15 INFO - 'title': 'talos-linux64-ix-013', 08:27:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:27:15 INFO - 'use_talos_json': True, 08:27:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:27:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:27:15 INFO - 'work_dir': 'build'} 08:27:15 INFO - ##### 08:27:15 INFO - ##### Running clobber step. 08:27:15 INFO - ##### 08:27:15 INFO - Running pre-action listener: _resource_record_pre_action 08:27:15 INFO - Running main action method: clobber 08:27:15 INFO - rmtree: /builds/slave/test/build 08:27:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:27:16 INFO - Running post-action listener: _resource_record_post_action 08:27:16 INFO - ##### 08:27:16 INFO - ##### Finished clobber step (success) 08:27:16 INFO - ##### 08:27:16 INFO - ##### 08:27:16 INFO - ##### Running read-buildbot-config step. 08:27:16 INFO - ##### 08:27:16 INFO - Running pre-action listener: _resource_record_pre_action 08:27:16 INFO - Running main action method: read_buildbot_config 08:27:16 INFO - Using buildbot properties: 08:27:16 INFO - { 08:27:16 INFO - "project": "", 08:27:16 INFO - "product": "firefox", 08:27:16 INFO - "script_repo_revision": "production", 08:27:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:27:16 INFO - "repository": "", 08:27:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:27:16 INFO - "buildid": "20160516074433", 08:27:16 INFO - "pgo_build": "False", 08:27:16 INFO - "basedir": "/builds/slave/test", 08:27:16 INFO - "buildnumber": 1748, 08:27:16 INFO - "slavename": "talos-linux64-ix-013", 08:27:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:27:16 INFO - "platform": "ubuntu64_hw", 08:27:16 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:27:16 INFO - "branch": "fx-team", 08:27:16 INFO - "repo_path": "integration/fx-team", 08:27:16 INFO - "revision": "e9533997fcef31fa5d2db464c6ceec0ca6ea560f", 08:27:16 INFO - "stage_platform": "linux64", 08:27:16 INFO - "builduid": "c2f13e2278c2440aa4c221e7e2eb802a", 08:27:16 INFO - "slavebuilddir": "test" 08:27:16 INFO - } 08:27:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:27:16 INFO - Running post-action listener: _resource_record_post_action 08:27:16 INFO - ##### 08:27:16 INFO - ##### Finished read-buildbot-config step (success) 08:27:16 INFO - ##### 08:27:16 INFO - ##### 08:27:16 INFO - ##### Running download-and-extract step. 08:27:16 INFO - ##### 08:27:16 INFO - Running pre-action listener: _resource_record_pre_action 08:27:16 INFO - Running main action method: download_and_extract 08:27:16 INFO - mkdir: /builds/slave/test/build/tests 08:27: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')]} 08:27:16 INFO - https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:27:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:27:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:27:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:17 INFO - Downloaded 1361 bytes. 08:27:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:27:17 INFO - Using the following test package requirements: 08:27:17 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:27:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:27:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:27:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'jsshell-linux-x86_64.zip'], 08:27:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:27:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:27:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:27:17 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:27:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:27:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:27:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:27:17 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')]} 08:27:17 INFO - https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:27:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:27:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:27:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:23 INFO - Downloaded 22448136 bytes. 08:27:23 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'] 08:27:23 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 08:27:24 INFO - Return code: 0 08:27:24 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 08:27:24 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')]} 08:27:24 INFO - https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:27:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:27:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:27:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:30 INFO - Downloaded 22448136 bytes. 08:27:30 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'] 08:27:30 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 08:27:32 INFO - Return code: 0 08:27:32 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')]} 08:27:32 INFO - https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:27:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:27:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:27:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:33 INFO - Downloaded 10840194 bytes. 08:27:33 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'] 08:27:33 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 08:27:33 INFO - Return code: 0 08:27:33 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')]} 08:27:33 INFO - https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:27:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:27:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:27:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/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 08:27:35 INFO - Downloaded 54749379 bytes. 08:27:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:27:35 INFO - mkdir: /builds/slave/test/properties 08:27:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:27:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:27:35 INFO - Contents: 08:27:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:27:36 INFO - Running post-action listener: _resource_record_post_action 08:27:36 INFO - Running post-action listener: set_extra_try_arguments 08:27:36 INFO - ##### 08:27:36 INFO - ##### Finished download-and-extract step (success) 08:27:36 INFO - ##### 08:27:36 INFO - ##### 08:27:36 INFO - ##### Running populate-webroot step. 08:27:36 INFO - ##### 08:27:36 INFO - Running pre-action listener: _resource_record_pre_action 08:27:36 INFO - Running main action method: populate_webroot 08:27:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:27:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:27:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:27:36 INFO - '--tppagecycles', 08:27:36 INFO - '7'], 08:27:36 INFO - 'tests': ['tsvgm']}}, 08:27:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:27:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:27:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:27:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:27:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['damp', 'tps']}, 08:27:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'tests': ['damp', 'tps']}, 08:27:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['dromaeo_dom']}, 08:27:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:27:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['a11yr', 08:27:36 INFO - 'ts_paint', 08:27:36 INFO - 'tpaint', 08:27:36 INFO - 'sessionrestore', 08:27:36 INFO - 'sessionrestore_no_auto_restore', 08:27:36 INFO - 'tabpaint']}, 08:27:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['tsvgx', 08:27:36 INFO - 'tsvgr_opacity', 08:27:36 INFO - 'tart', 08:27:36 INFO - 'tscrollx', 08:27:36 INFO - 'cart']}, 08:27:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:27:36 INFO - 'tsvgr_opacity', 08:27:36 INFO - 'tart', 08:27:36 INFO - 'tscrollx', 08:27:36 INFO - 'cart']}, 08:27:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'talos_options': ['--disable-e10s'], 08:27:36 INFO - 'tests': ['tp5o']}, 08:27:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'tests': ['tp5o']}, 08:27:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'talos_options': ['--disable-e10s', 08:27:36 INFO - '--xperf_path', 08:27:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:36 INFO - 'tests': ['tp5n']}, 08:27:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:36 INFO - 'talos_options': ['--xperf_path', 08:27:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:36 INFO - 'tests': ['tp5n']}}, 08:27:36 INFO - 'talos.zip': {'path': '', 08:27:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:27:36 INFO - Running post-action listener: _resource_record_post_action 08:27:36 INFO - ##### 08:27:36 INFO - ##### Finished populate-webroot step (success) 08:27:36 INFO - ##### 08:27:36 INFO - ##### 08:27:36 INFO - ##### Running create-virtualenv step. 08:27:36 INFO - ##### 08:27:36 INFO - Running pre-action listener: _resource_record_pre_action 08:27:36 INFO - Running main action method: create_virtualenv 08:27:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:27:36 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 08:27:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:27:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:27:36 INFO - Using real prefix '/usr' 08:27:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:27:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:27:39 INFO - Installing pip.................done. 08:27:39 INFO - Return code: 0 08:27:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:27:39 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')]} 08:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:39 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')]} 08:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:39 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:39 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 08:27:39 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 08:27:39 INFO - Using env: {'DISPLAY': ':0', 08:27:39 INFO - 'HOME': '/home/cltbld', 08:27:39 INFO - 'LANG': 'en_US.UTF-8', 08:27:39 INFO - 'LANGUAGE': 'en_US:en', 08:27:39 INFO - 'LOGNAME': 'cltbld', 08:27:39 INFO - 'MAIL': '/var/mail/cltbld', 08:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:39 INFO - 'MOZ_NO_REMOTE': '1', 08:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:39 INFO - 'NO_EM_RESTART': '1', 08:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:39 INFO - 'PWD': '/builds/slave/test', 08:27:39 INFO - 'SHELL': '/bin/bash', 08:27:39 INFO - 'SHLVL': '1', 08:27:39 INFO - 'TERM': 'linux', 08:27:39 INFO - 'TMOUT': '86400', 08:27:39 INFO - 'USER': 'cltbld', 08:27:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:39 INFO - '_': '/tools/buildbot/bin/python'} 08:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:39 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 08:27:39 INFO - Cleaning up... 08:27:39 INFO - Return code: 0 08:27:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:27:39 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')]} 08:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:39 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')]} 08:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:39 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:39 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>=3.1.1'] in /builds/slave/test/build 08:27:39 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>=3.1.1 08:27:39 INFO - Using env: {'DISPLAY': ':0', 08:27:39 INFO - 'HOME': '/home/cltbld', 08:27:39 INFO - 'LANG': 'en_US.UTF-8', 08:27:39 INFO - 'LANGUAGE': 'en_US:en', 08:27:39 INFO - 'LOGNAME': 'cltbld', 08:27:39 INFO - 'MAIL': '/var/mail/cltbld', 08:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:39 INFO - 'MOZ_NO_REMOTE': '1', 08:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:39 INFO - 'NO_EM_RESTART': '1', 08:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:39 INFO - 'PWD': '/builds/slave/test', 08:27:39 INFO - 'SHELL': '/bin/bash', 08:27:39 INFO - 'SHLVL': '1', 08:27:39 INFO - 'TERM': 'linux', 08:27:39 INFO - 'TMOUT': '86400', 08:27:39 INFO - 'USER': 'cltbld', 08:27:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:39 INFO - '_': '/tools/buildbot/bin/python'} 08:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:39 INFO - Downloading/unpacking psutil>=3.1.1 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:27:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:41 INFO - Installing collected packages: psutil 08:27:41 INFO - Running setup.py install for psutil 08:27:41 INFO - building 'psutil._psutil_linux' extension 08:27:41 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 08:27:41 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 08:27:41 INFO - building 'psutil._psutil_posix' extension 08:27:41 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 08:27:41 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 08:27:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:41 INFO - Successfully installed psutil 08:27:41 INFO - Cleaning up... 08:27:42 INFO - Return code: 0 08:27:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:27:42 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')]} 08:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:42 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')]} 08:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:42 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:42 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.3'] in /builds/slave/test/build 08:27:42 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.3 08:27:42 INFO - Using env: {'DISPLAY': ':0', 08:27:42 INFO - 'HOME': '/home/cltbld', 08:27:42 INFO - 'LANG': 'en_US.UTF-8', 08:27:42 INFO - 'LANGUAGE': 'en_US:en', 08:27:42 INFO - 'LOGNAME': 'cltbld', 08:27:42 INFO - 'MAIL': '/var/mail/cltbld', 08:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:42 INFO - 'MOZ_NO_REMOTE': '1', 08:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:42 INFO - 'NO_EM_RESTART': '1', 08:27:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:42 INFO - 'PWD': '/builds/slave/test', 08:27:42 INFO - 'SHELL': '/bin/bash', 08:27:42 INFO - 'SHLVL': '1', 08:27:42 INFO - 'TERM': 'linux', 08:27:42 INFO - 'TMOUT': '86400', 08:27:42 INFO - 'USER': 'cltbld', 08:27:42 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:42 INFO - '_': '/tools/buildbot/bin/python'} 08:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:27:42 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 08:27:42 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 08:27:42 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 08:27:42 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 08:27:42 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 08:27:42 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 08:27:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:27:44 INFO - Installing collected packages: mozsystemmonitor 08:27:44 INFO - Running setup.py install for mozsystemmonitor 08:27:44 INFO - Successfully installed mozsystemmonitor 08:27:44 INFO - Cleaning up... 08:27:44 INFO - Return code: 0 08:27:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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')]} 08:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:44 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:44 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 08:27:44 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 08:27:44 INFO - Using env: {'DISPLAY': ':0', 08:27:44 INFO - 'HOME': '/home/cltbld', 08:27:44 INFO - 'LANG': 'en_US.UTF-8', 08:27:44 INFO - 'LANGUAGE': 'en_US:en', 08:27:44 INFO - 'LOGNAME': 'cltbld', 08:27:44 INFO - 'MAIL': '/var/mail/cltbld', 08:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:44 INFO - 'MOZ_NO_REMOTE': '1', 08:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:44 INFO - 'NO_EM_RESTART': '1', 08:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:44 INFO - 'PWD': '/builds/slave/test', 08:27:44 INFO - 'SHELL': '/bin/bash', 08:27:44 INFO - 'SHLVL': '1', 08:27:44 INFO - 'TERM': 'linux', 08:27:44 INFO - 'TMOUT': '86400', 08:27:44 INFO - 'USER': 'cltbld', 08:27:44 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:44 INFO - '_': '/tools/buildbot/bin/python'} 08:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:27:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:27:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:46 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 08:27:47 INFO - Downloading docopt-0.6.1.tar.gz 08:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:27:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:27:47 INFO - Running setup.py install for blobuploader 08:27:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:27:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:27:47 INFO - Running setup.py install for requests 08:27:47 INFO - Running setup.py install for docopt 08:27:47 INFO - Successfully installed blobuploader requests docopt 08:27:47 INFO - Cleaning up... 08:27:47 INFO - Return code: 0 08:27:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:47 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')]} 08:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:47 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')]} 08:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:47 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:47 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 08:27:47 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 08:27:47 INFO - Using env: {'DISPLAY': ':0', 08:27:47 INFO - 'HOME': '/home/cltbld', 08:27:47 INFO - 'LANG': 'en_US.UTF-8', 08:27:47 INFO - 'LANGUAGE': 'en_US:en', 08:27:47 INFO - 'LOGNAME': 'cltbld', 08:27:47 INFO - 'MAIL': '/var/mail/cltbld', 08:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:47 INFO - 'MOZ_NO_REMOTE': '1', 08:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:47 INFO - 'NO_EM_RESTART': '1', 08:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:47 INFO - 'PWD': '/builds/slave/test', 08:27:47 INFO - 'SHELL': '/bin/bash', 08:27:47 INFO - 'SHLVL': '1', 08:27:47 INFO - 'TERM': 'linux', 08:27:47 INFO - 'TMOUT': '86400', 08:27:47 INFO - 'USER': 'cltbld', 08:27:47 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:47 INFO - '_': '/tools/buildbot/bin/python'} 08:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:47 INFO - Running setup.py (path:/tmp/pip-Q0kovU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:48 INFO - Running setup.py (path:/tmp/pip-E86Buy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:48 INFO - Running setup.py (path:/tmp/pip-oCiPF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:48 INFO - Running setup.py (path:/tmp/pip-ybYVTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:48 INFO - Running setup.py (path:/tmp/pip-WnBN_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:48 INFO - Running setup.py (path:/tmp/pip-QrGOnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:48 INFO - Running setup.py (path:/tmp/pip-DL8m5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:48 INFO - Running setup.py (path:/tmp/pip-DGpORB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:48 INFO - Running setup.py (path:/tmp/pip-TvdIOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:48 INFO - Running setup.py (path:/tmp/pip-WQBMyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:48 INFO - Running setup.py (path:/tmp/pip-LN8ltK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:48 INFO - Running setup.py (path:/tmp/pip-WNv51F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:48 INFO - Running setup.py (path:/tmp/pip-xokS3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:48 INFO - Running setup.py (path:/tmp/pip-knLkje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:48 INFO - Running setup.py (path:/tmp/pip-Hhpc5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:49 INFO - Running setup.py (path:/tmp/pip-WyCuG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:49 INFO - Running setup.py (path:/tmp/pip-kjwSNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:27:49 INFO - Running setup.py install for manifestparser 08:27:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:27:49 INFO - Running setup.py install for mozcrash 08:27:49 INFO - Running setup.py install for mozdebug 08:27:49 INFO - Running setup.py install for mozdevice 08:27:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:27:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:27:49 INFO - Running setup.py install for mozfile 08:27:49 INFO - Running setup.py install for mozhttpd 08:27:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:27:49 INFO - Running setup.py install for mozinfo 08:27:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:27:49 INFO - Running setup.py install for mozInstall 08:27:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Running setup.py install for mozleak 08:27:50 INFO - Running setup.py install for mozlog 08:27:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Running setup.py install for moznetwork 08:27:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Running setup.py install for mozprocess 08:27:50 INFO - Running setup.py install for mozprofile 08:27:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Running setup.py install for mozrunner 08:27:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Running setup.py install for mozscreenshot 08:27:50 INFO - Running setup.py install for moztest 08:27:50 INFO - Running setup.py install for mozversion 08:27:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:27:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:27:50 INFO - Cleaning up... 08:27:50 INFO - Return code: 0 08:27:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:50 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')]} 08:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:51 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')]} 08:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:51 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:51 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 08:27:51 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 08:27:51 INFO - Using env: {'DISPLAY': ':0', 08:27:51 INFO - 'HOME': '/home/cltbld', 08:27:51 INFO - 'LANG': 'en_US.UTF-8', 08:27:51 INFO - 'LANGUAGE': 'en_US:en', 08:27:51 INFO - 'LOGNAME': 'cltbld', 08:27:51 INFO - 'MAIL': '/var/mail/cltbld', 08:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:51 INFO - 'MOZ_NO_REMOTE': '1', 08:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:51 INFO - 'NO_EM_RESTART': '1', 08:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:51 INFO - 'PWD': '/builds/slave/test', 08:27:51 INFO - 'SHELL': '/bin/bash', 08:27:51 INFO - 'SHLVL': '1', 08:27:51 INFO - 'TERM': 'linux', 08:27:51 INFO - 'TMOUT': '86400', 08:27:51 INFO - 'USER': 'cltbld', 08:27:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:51 INFO - '_': '/tools/buildbot/bin/python'} 08:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:51 INFO - Running setup.py (path:/tmp/pip-nZ0jH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:51 INFO - Running setup.py (path:/tmp/pip-HpaSor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:51 INFO - Running setup.py (path:/tmp/pip-QbM3Lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:51 INFO - Running setup.py (path:/tmp/pip-7fO5KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:51 INFO - Running setup.py (path:/tmp/pip-Qv0CjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:51 INFO - Running setup.py (path:/tmp/pip-z2vCS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:51 INFO - Running setup.py (path:/tmp/pip-fMYOxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:51 INFO - Running setup.py (path:/tmp/pip-PmAAf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:51 INFO - Running setup.py (path:/tmp/pip-qoQttg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:51 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:51 INFO - Running setup.py (path:/tmp/pip-31GguO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 08:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:51 INFO - Running setup.py (path:/tmp/pip-3s6whR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:52 INFO - Running setup.py (path:/tmp/pip-iYI_AJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:52 INFO - Running setup.py (path:/tmp/pip-TQcuhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:52 INFO - Running setup.py (path:/tmp/pip-uabHcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:52 INFO - Running setup.py (path:/tmp/pip-FAQ23P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:52 INFO - Running setup.py (path:/tmp/pip-yD0YTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:52 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)) 08:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:52 INFO - Running setup.py (path:/tmp/pip-fBpdM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:52 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)) 08:27:52 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)) 08:27:52 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)) 08:27:52 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)) 08:27:52 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)) 08:27:52 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)) 08:27:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:27:52 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 08:27:52 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 08:27:52 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 08:27:52 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 08:27:52 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 08:27:52 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 08:27:54 INFO - Downloading blessings-1.6.tar.gz 08:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:27:54 INFO - Installing collected packages: blessings 08:27:54 INFO - Running setup.py install for blessings 08:27:54 INFO - Successfully installed blessings 08:27:54 INFO - Cleaning up... 08:27:54 INFO - Return code: 0 08:27:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:27:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:27:54 INFO - Reading from file tmpfile_stdout 08:27:54 INFO - Current package versions: 08:27:54 INFO - argparse == 1.2.1 08:27:54 INFO - blessings == 1.6 08:27:54 INFO - blobuploader == 1.2.4 08:27:54 INFO - docopt == 0.6.1 08:27:54 INFO - manifestparser == 1.1 08:27:54 INFO - mozInstall == 1.12 08:27:54 INFO - mozcrash == 0.17 08:27:54 INFO - mozdebug == 0.1 08:27:54 INFO - mozdevice == 0.48 08:27:54 INFO - mozfile == 1.2 08:27:54 INFO - mozhttpd == 0.7 08:27:54 INFO - mozinfo == 0.9 08:27:54 INFO - mozleak == 0.1 08:27:54 INFO - mozlog == 3.2 08:27:54 INFO - moznetwork == 0.27 08:27:54 INFO - mozprocess == 0.22 08:27:54 INFO - mozprofile == 0.28 08:27:54 INFO - mozrunner == 6.11 08:27:54 INFO - mozscreenshot == 0.1 08:27:54 INFO - mozsystemmonitor == 0.3 08:27:54 INFO - moztest == 0.7 08:27:54 INFO - mozversion == 1.4 08:27:54 INFO - psutil == 3.1.1 08:27:54 INFO - requests == 1.2.3 08:27:54 INFO - wsgiref == 0.1.2 08:27:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:54 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')]} 08:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:54 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')]} 08:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:54 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:54 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 08:27:54 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 08:27:54 INFO - Using env: {'DISPLAY': ':0', 08:27:54 INFO - 'HOME': '/home/cltbld', 08:27:54 INFO - 'LANG': 'en_US.UTF-8', 08:27:54 INFO - 'LANGUAGE': 'en_US:en', 08:27:54 INFO - 'LOGNAME': 'cltbld', 08:27:54 INFO - 'MAIL': '/var/mail/cltbld', 08:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:54 INFO - 'MOZ_NO_REMOTE': '1', 08:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:54 INFO - 'NO_EM_RESTART': '1', 08:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:54 INFO - 'PWD': '/builds/slave/test', 08:27:54 INFO - 'SHELL': '/bin/bash', 08:27:54 INFO - 'SHLVL': '1', 08:27:54 INFO - 'TERM': 'linux', 08:27:54 INFO - 'TMOUT': '86400', 08:27:54 INFO - 'USER': 'cltbld', 08:27:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:54 INFO - '_': '/tools/buildbot/bin/python'} 08:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 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)) 08:27:55 INFO - Cleaning up... 08:27:55 INFO - Return code: 0 08:27:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:27:55 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')]} 08:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:55 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')]} 08:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:55 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:55 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 08:27:55 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 08:27:55 INFO - Using env: {'DISPLAY': ':0', 08:27:55 INFO - 'HOME': '/home/cltbld', 08:27:55 INFO - 'LANG': 'en_US.UTF-8', 08:27:55 INFO - 'LANGUAGE': 'en_US:en', 08:27:55 INFO - 'LOGNAME': 'cltbld', 08:27:55 INFO - 'MAIL': '/var/mail/cltbld', 08:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:55 INFO - 'MOZ_NO_REMOTE': '1', 08:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:55 INFO - 'NO_EM_RESTART': '1', 08:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:55 INFO - 'PWD': '/builds/slave/test', 08:27:55 INFO - 'SHELL': '/bin/bash', 08:27:55 INFO - 'SHLVL': '1', 08:27:55 INFO - 'TERM': 'linux', 08:27:55 INFO - 'TMOUT': '86400', 08:27:55 INFO - 'USER': 'cltbld', 08:27:55 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:55 INFO - '_': '/tools/buildbot/bin/python'} 08:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:55 INFO - Downloading/unpacking jsonschema 08:27:55 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 08:27:55 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 08:27:55 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 08:27:55 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 08:27:55 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 08:27:55 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 08:27:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:27:57 INFO - Downloading/unpacking functools32 (from jsonschema) 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:27:57 INFO - warning: no files found matching '*.txt' 08:27:57 INFO - no previously-included directories found matching 'build' 08:27:57 INFO - no previously-included directories found matching 'dist' 08:27:57 INFO - no previously-included directories found matching '.git*' 08:27:57 INFO - Installing collected packages: jsonschema, functools32 08:27:57 INFO - Running setup.py install for functools32 08:27:57 INFO - warning: no files found matching '*.txt' 08:27:57 INFO - no previously-included directories found matching 'build' 08:27:57 INFO - no previously-included directories found matching 'dist' 08:27:57 INFO - no previously-included directories found matching '.git*' 08:27:57 INFO - Successfully installed jsonschema functools32 08:27:57 INFO - Cleaning up... 08:27:57 INFO - Return code: 0 08:27:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:27:57 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')]} 08:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:57 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')]} 08:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:57 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 0x7f98b354df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe92770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', '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 08:27:57 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 08:27:57 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 08:27:57 INFO - Using env: {'DISPLAY': ':0', 08:27:57 INFO - 'HOME': '/home/cltbld', 08:27:57 INFO - 'LANG': 'en_US.UTF-8', 08:27:57 INFO - 'LANGUAGE': 'en_US:en', 08:27:57 INFO - 'LOGNAME': 'cltbld', 08:27:57 INFO - 'MAIL': '/var/mail/cltbld', 08:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:57 INFO - 'MOZ_NO_REMOTE': '1', 08:27:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:57 INFO - 'NO_EM_RESTART': '1', 08:27:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:57 INFO - 'PWD': '/builds/slave/test', 08:27:57 INFO - 'SHELL': '/bin/bash', 08:27:57 INFO - 'SHLVL': '1', 08:27:57 INFO - 'TERM': 'linux', 08:27:57 INFO - 'TMOUT': '86400', 08:27:57 INFO - 'USER': 'cltbld', 08:27:57 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:57 INFO - '_': '/tools/buildbot/bin/python'} 08:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:57 INFO - Downloading/unpacking flake8 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:57 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 08:27:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:27:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:27:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:28:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:28:00 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 08:28:00 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 08:28:00 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 08:28:00 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 08:28:00 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 08:28:00 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 08:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:28:00 INFO - no previously-included directories found matching 'docs/_build' 08:28:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:28:00 INFO - Running setup.py install for pep8 08:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:28:00 INFO - no previously-included directories found matching 'docs/_build' 08:28:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:28:00 INFO - Cleaning up... 08:28:00 INFO - Return code: 0 08:28:00 INFO - Running post-action listener: _resource_record_post_action 08:28:00 INFO - Running post-action listener: _start_resource_monitoring 08:28:00 INFO - Starting resource monitoring. 08:28:00 INFO - ##### 08:28:00 INFO - ##### Finished create-virtualenv step (success) 08:28:00 INFO - ##### 08:28:00 INFO - ##### 08:28:00 INFO - ##### Running install step. 08:28:00 INFO - ##### 08:28:00 INFO - Running pre-action listener: _resource_record_pre_action 08:28:00 INFO - Running main action method: install 08:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:00 INFO - Reading from file tmpfile_stdout 08:28:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:28:01 INFO - Reading from file tmpfile_stdout 08:28:01 INFO - Output received: 08:28:01 INFO - Usage: mozinstall [options] installer 08:28:01 INFO - Options: 08:28:01 INFO - -h, --help show this help message and exit 08:28:01 INFO - -d DEST, --destination=DEST 08:28:01 INFO - Directory to install application into. [default: 08:28:01 INFO - "/builds/slave/test"] 08:28:01 INFO - --app=APP Application being installed. [default: firefox] 08:28:01 INFO - mkdir: /builds/slave/test/build/application 08:28:01 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'] 08:28:01 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 08:28:16 INFO - Reading from file tmpfile_stdout 08:28:16 INFO - Output received: 08:28:16 INFO - /builds/slave/test/build/application/firefox/firefox 08:28:16 INFO - Running post-action listener: _resource_record_post_action 08:28:16 INFO - ##### 08:28:16 INFO - ##### Finished install step (success) 08:28:16 INFO - ##### 08:28:16 INFO - ##### 08:28:16 INFO - ##### Running run-tests step. 08:28:16 INFO - ##### 08:28:16 INFO - Running pre-action listener: _resource_record_pre_action 08:28:16 INFO - Running main action method: run_tests 08:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:28:16 INFO - Python 2.7.3 08:28:16 INFO - Return code: 0 08:28:16 INFO - grabbing minidump binary from tooltool 08:28: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')]} 08:28:16 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf219c0>, '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 0xf228c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf22d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:28:16 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 08:28:16 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 08:28:16 INFO - Calling ['/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'] with output_timeout 600 08:28:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:28:16 INFO - Return code: 0 08:28:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:28:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:28:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:28:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:28:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:28:17 INFO - Return code: 0 08:28:17 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:28:17 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:28:17 INFO - Using env: {'DISPLAY': ':0', 08:28:17 INFO - 'HOME': '/home/cltbld', 08:28:17 INFO - 'LANG': 'en_US.UTF-8', 08:28:17 INFO - 'LANGUAGE': 'en_US:en', 08:28:17 INFO - 'LOGNAME': 'cltbld', 08:28:17 INFO - 'MAIL': '/var/mail/cltbld', 08:28:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:28:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:17 INFO - 'MOZ_NO_REMOTE': '1', 08:28:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:17 INFO - 'NO_EM_RESTART': '1', 08:28:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:17 INFO - 'PWD': '/builds/slave/test', 08:28:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:28:17 INFO - 'SHELL': '/bin/bash', 08:28:17 INFO - 'SHLVL': '1', 08:28:17 INFO - 'TERM': 'linux', 08:28:17 INFO - 'TMOUT': '86400', 08:28:17 INFO - 'USER': 'cltbld', 08:28:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062', 08:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:17 INFO - '_': '/tools/buildbot/bin/python'} 08:28:17 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:28:17 INFO - mozversion application_buildid: 20160516074433 08:28:17 INFO - mozversion application_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 08:28:17 INFO - mozversion application_display_name: Nightly 08:28:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:28:17 INFO - mozversion application_name: Firefox 08:28:17 INFO - mozversion application_remotingname: firefox 08:28:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:28:17 INFO - mozversion application_vendor: Mozilla 08:28:17 INFO - mozversion application_version: 49.0a1 08:28:17 INFO - mozversion platform_buildid: 20160516074433 08:28:17 INFO - mozversion platform_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 08:28:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:28:17 INFO - mozversion platform_version: 49.0a1 08:28:17 INFO - using testdate: 1463412497 08:28:17 INFO - actual date: 1463412497 08:28:17 INFO - starting webserver on 'localhost:45935' 08:28:17 INFO - SUITE-START | Running 2 tests 08:28:17 INFO - TEST-START | dromaeo_css 08:28:17 INFO - operating with platform_type : linux_ 08:28:17 INFO - Initialising browser for dromaeo_css test... 08:28:17 INFO - TEST-INFO | started process 775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp17Ikmx/profile http://localhost:45935/getInfo.html) 08:28:22 INFO - PROCESS | 775 | __metrics Screen width/height:1600/1200 08:28:22 INFO - PROCESS | 775 | colorDepth:24 08:28:22 INFO - PROCESS | 775 | Browser inner width/height: 1024/697 08:28:22 INFO - PROCESS | 775 | __metrics 08:28:23 ERROR - PROCESS | 775 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:28:24 INFO - TEST-INFO | 775: exit 0 08:28:24 INFO - Browser initialized. 08:28:24 INFO - Running cycle 1/1 for dromaeo_css test... 08:28:24 INFO - TEST-INFO | started process 861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp17Ikmx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:36 INFO - PROCESS | 861 | RSS: Main: 154316800 08:28:36 INFO - PROCESS | 861 | 08:29:27 INFO - PROCESS | 861 | 1463412567782 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] 08:31:39 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45935/tests/dromaeo/cssquery-ext.html) 08:31:40 INFO - PROCESS | 861 | RSS: Main: 273174528 08:31:40 INFO - PROCESS | 861 | 08:34:43 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-ext.html (next: http://localhost:45935/tests/dromaeo/cssquery-jquery.html) 08:34:43 INFO - PROCESS | 861 | RSS: Main: 224546816 08:34:43 INFO - PROCESS | 861 | 08:37:46 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45935/tests/dromaeo/cssquery-mootools.html) 08:37:46 INFO - PROCESS | 861 | RSS: Main: 229933056 08:37:46 INFO - PROCESS | 861 | 08:40:48 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45935/tests/dromaeo/cssquery-prototype.html) 08:40:49 INFO - PROCESS | 861 | RSS: Main: 234217472 08:40:49 INFO - PROCESS | 861 | 08:43:52 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45935/tests/dromaeo/cssquery-yui.html) 08:43:53 INFO - PROCESS | 861 | RSS: Main: 210137088 08:43:53 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | Cycle 1(1): loaded http://localhost:45935/tests/dromaeo/cssquery-yui.html (next: http://localhost:45935/tests/dromaeo/cssquery-dojo.html) 08:46:56 INFO - PROCESS | 861 | RSS: Main: 284037120 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | __start_tp_report 08:46:56 INFO - PROCESS | 861 | _x_x_mozilla_page_load 08:46:56 INFO - PROCESS | 861 | _x_x_mozilla_page_load_details 08:46:56 INFO - PROCESS | 861 | |i|pagename|runs| 08:46:56 INFO - PROCESS | 861 | |0;dojo.html;2644.590088536277;2653.204038788363;2692.4345887363647;2695.878685459154;2697.878595463204;4603.539646035395;4624.976875115624;4672.486026537087;4673.112108699344;4720.952790472094;4676.602488788449;4705.411823522059;4725.881852953669;4733.668643194977;4744.525547445253;4303.526612072677;4350.891227719317;4400.933985990213;4428.446444194476;4526.6831321807485;4419.602235798778;4450.221211288012;4465.553444655525;4476.798544065508;4493.797779099932;4194.098268872207;4218.6625069994325;4311.762853043081;4329.610335069828;4367.781610919441;4287.849925252601;4288.742675439484;4293.205756934977;4336.1978034063795;4362.432883725095;4325.52419233884;4329.631981281595;4335.9566404335865;4336.783160841946;4385.912281754379;4478.41780568524;4593.150267200579;4638.327442520849;4639.628829693616;4647.302904564309;5055.823046193365;5085.262636917663;5097.923531147036;5111.9776044790815;5114.769835357418;5114.079465696173;5131.640785145041;5135.486451354872;5148.2020286855595;5155.432902380735;4643.9767801161115;4669.182892993713;4716.056788642285;4718.05638872227;4773.474917759044;5171.146760784503;5175.404828444702;5188.8962220755375;5220.765065571983;5225.111731005738;4867.294242334841;4959.801607935642;4960.082384758831;5000.39995200571;5013.448520662724;4970.428400733962;5002.724850133278;5012.523810238022;5021.648484606042;5032.7735251913755;4974.328465657162;4983.377077865267;4992.550670439678;4992.650514463952;5041.218611115283;4839.879003024953;4848.8787780305065;4963.379577552806;4974.328465657162;5025.974870125626;4146.730462519927;4167.97916010418;4180.561041090703;4182.247195504778;4230.238557059759;3704.7777133372083;3707.295613833363;3711.777293362407;3742.36379815432;3753.7935413551972;4261.126469073833;4374.256376416017;4381.539938306432;4384.912301753947;4426.667999899956;5040.546350828443;5093.981203759189;5096.031753966735;5111.488851114858;5114.693118412907;4372.584604462571;4400.119976004812;4400.251957167289;4401.691881568324;4447.977760111244;4450.977745111254;4511.954880451153;4552;4553.203189441834;4581.289900065495;4796.2805579163405;4799.400074990626;4825.0349930013435;4831.710097394027;4864.343313652612;5109.00374426998;5109.7700603472185;5110.770015349244;5115.56517696;5139.177731563082;4664.137134630104;4704.882377940579;4718.740469274223;4811.903761924672;4872.829450969199;4915;4928.778204980713;4943.307936888767;4945;4984.825531106394;4979.601631869514;5019.67372120811;5020.874478138076;5020.949790502048;5062.645614806928;4745.193317136026;4865.854024379274;4889.877753056202;4914.164592019437;4940.7035577865445;4887.266909963534;4887.657863949489;4903.239997800347;4907;4960.330355402119;4833.130036593307;4844.733539655353;4849.539293767087;4889.926651100165;4897.632677549127;4757.119932812441;4774.7135171889795;4818.349522814376;4874.53691899269;4881.51184881509;4842.007388485423;4847.515248475124;4855;4870.586000190024;4874.5856602189215 08:46:56 INFO - PROCESS | 861 | |1;ext.html;571.3058633759983;599.5563283170452;600.1717629671053;629.3643420145654;642.7557528139306;42752.786236068816;43966.78016609917;43997.78001109994;44041.559584404145;47945.363005034706;44347.556524434745;44716.552834471644;44793.32810007853;44817.55182448174;44861.327080093826;7742.187070357588;7896.526208427484;7902.170272121431;7904.249096335839;8075.555844428554;7650.464467487278;9886.802263954716;10137.138343240797;10139;10429.426381549012;10360.896391036105;10394;10408.427536485491;10415.062644362006;10415.947920260425;7690.884636730453;7808.5314881107315;10123.240756943222;10206.030427109412;10208.74478138045;7667.424943129287;9952;10299.691009269734;10307.484625768757;10312.381257124522;37863;38207.426888596696;38208.80895595504;41615;41717.79141104306;3211.759118066131;3248.675132486756;3425.520427140202;3681.3557627415094;3768;30720.539191911917;31337;31457;31530.684693153005;32306.515402268986;3377.3583019226267;3435.2270739083756;3459.584849818038;3472.2881809229048;3488.354654388946;270057;272668;273905.999999998;275672;275768;62987.370126299065;63349;63734;63746.36253637404;63805;68399.65800171068;69090.30909690939;69093;69109.30890691029;69195.30804691989;13746.312684365941;14273.57179284623;14275.42898284057;14289.642758930902;14299.714005719827;10246.846297305547;11325.150613704005;11382.54469821198;11632.709182270512;11985.52057917691;20164.69752953707;20284.188632454538;20362.49093772638;20383.286584969454;20427.897860510602;7381.778546643609;7383.372413344819;7522.623868806538;7585.2035536269;7598.696052157962;805.1586092533316;912.6167009855876;959.29971121082;982.577491678585;989.2679417231197;28205.294867628065;29389.706102939126;29576.260593485335;29577.55633665497;29623.85188074089;985.093713783321;1061.7398737309409;1063.3672964586058;1084.6962850401742;1089.411717672448;15116.924415377853;15435.61410964735;15498.225088745517;15708.528744137695;15863.920680396755;374941;514148;523640;524444;706557;54946;55264.44735552673;55486;55601.1659825103;55650.16524752132;1182.066167727503;1298.3702904091697;1307.7253776706998;1310.1811367895066;1319.940602672882;26335.473290534464;29729.256768580093;30070.69929300679;30654.540181897737;30833;44636;45284;45320;45361.54638453573;45413.772931135136;251310;257972;258173;259318;259471;23797.167099151447;26428.73571264263;26432.603510947734;27242.5913611292;27266.454670906867;39116.80441597888;39731.40402894017;44295;44583.33125003063;45215.32177017413;36796.63203367932;37293.627063729014;37358.439623406215;37667.2466550662;38022.42966355452;37096.44355334618;37577.248455031295;37682.43476347802;38000;38126.618733812305;36454.81772591226;37022.44466332953;37187.81406092952;37283.06792330214;37368.43947340846;31056.53415198819;33471.330573388885;34095.31809363848;34130.317393651494;34187.316253675286;25703.742962570133;25799.484010320062;26081.608775868757;26423.339416513973;26552 08:46:56 INFO - PROCESS | 861 | |2;jquery.html;381.1139101588807;391.77668728824574;394.74538922395055;396.4192458048959;417.2468694007317;12826.615201543946;13378.59864204073;16405.34378624855;18184.181711822966;18204.089795510205;16629.91685041573;16961.32154713813;17758.82241177588;17847.82152178478;17971.550711232227;4988.800447982077;5180.45605211453;5202.947970520303;5211.45279745627;5221.190715439113;4047.4738284023183;5112.616553758465;6752.831179220534;6755.324467553254;7162.140543134831;7070.363667269945;7103.396211322044;7175.964120179392;7196.136463624346;7230.060092188009;7099.148102227714;7186;7201.675924583406;7210.026646402747;7210.747623833141;6551.93448065518;6599.076129341896;6677.332266773284;6734.730610775563;6881.690323935435;23034.884825575766;24102.517949640907;24110.758892411028;24150.396240093964;24422.877885610458;2100.170432679083;2141.882196479193;2183.748868880083;2344.15610380263;2526.5325914705654;13933.930330348385;13975.720485590231;14543.49097781583;14724.779128313085;15230.923845380814;1899.838513726335;1924.8363889069306;1949.707543868417;1952.570434504421;1997.8301844343102;54216;82423.58788206021;89184;90482;91980.54009729976;16307.184640768151;17002.829971700372;17096.48710538686;17108.315667373416;17274.222659980333;30302;30420.847895760824;30594;30800.075997720105;30861.68537359707;9041.864372034424;9458.95270523643;9566.378185417925;9621.615135394646;9632.181264592587;7689.9615501922135;7836.529808211526;7888.092869319987;7893.13175550689;7933.246341597539;8763.298936085097;8776.78058048541;8885.555722213863;9055.81888362229;9060.456372617664;2683.409649877024;3208.6470488246273;3392.3045775615938;3464.670856268701;3547.1486843157454;825.6078362777632;896.3098414221014;932.715521765868;966.5650457294246;971.523953262896;16757.497275081525;16883.57791055209;16891.220182340454;17388.739168912478;17410.303587856342;889.4974339498162;915.949622770754;925.5372313843078;938.9812203755887;962.9711108666751;9233.492157931243;9658;9702.417854928726;9722.2708296878;9775.511224438891;307247;310209;404298;404511;407922;51216;51881.2217816733;52407.73796130995;53596;88672;935.5696379665294;960.2845879819579;965.9178969787506;973.9610415583439;976.9804603907882;15696.450624228097;15767.921160394124;15901.840981590036;15940.123293218985;15946.60133496672;37862.43206351852;37928.431073533364;38083.619163808005;38083.809580951915;38324.233515330096;49393.50606494033;55447.44552554423;55624.44375556193;55743.442565575446;56064.43935560592;5486.835394938158;6765.323467653196;7412.332890039922;7760.611969401394;7848.097468791162;10007.399556026661;12058.457369418573;12132.211406258564;12144.271343719405;12300.077494187793;8570.185832345916;9629.229661627192;10030.899691002996;10118.19054475655;10463.843042354523;10244.436555989492;10408.219383546415;10617.89382106169;10744.570217191225;10875.347479151276;9882;10422.947885260524;10752.731181720519;10838.620648277589;10874.863766967017;6600.273969863215;8053.114157442559;8283.83432331338;8320.126386729306;8352.791180220544;8589.570521473775;10306.072453479223;10512.159027277647;10517.579296828042;10665.946670266909 08:46:56 INFO - PROCESS | 861 | |3;mootools.html;1323.6624660711518;1543.205249296612;1568.7725279834422;1762.4272111563746;1894.3559189875448;7823.960880195598;8111.91888081119;8204.097549269576;8369.246767790897;8386;8207.753767386974;8364.958175209116;8383.748487545383;8388.203120703538;8397;2338.894749736266;2369.5497855407502;2371.9644205336936;2372.0511795281855;2372.110458578033;2298.7241531016302;2343.9179628713;2375.3467796355967;2381.0594815048044;2382.5115851250453;2345.343303874909;2350.8354415190943;2366.290112966112;2366.8934897929635;2380.2502211803226;2242.7084479017785;2262.242148880127;2287.9885600571974;2290.9083636654514;2303.6544518322216;2257.0520381439833;2258.6386178211574;2268.727752669674;2269.625511790569;2274.692916456274;7720.227977202291;7878.09401918781;8201.753947381589;8259.669613215465;8262.917370826275;4906.435759887623;5020.347354843883;5024.070546948826;5029.421616514074;5034.723090230036;3028.5911401960675;3032.4996375598;3094.891678791254;3099.9845000775076;3116.3611459650942;910.5947853205327;915.8855143107112;931.818295432391;938.4697645830153;973.4889183178888;11286.548538058469;11460.083193344512;11492.655220343404;11506.597269095542;11539.192256542045;4269.274223381971;4387.4954380246045;4454.844080457168;4455.955440445554;4463.665225108065;8946.150115739125;9004.50475223869;9005.594748236344;9011.819763604823;9035.096490350912;4857.465678775332;4859.07677541266;4863.416390033219;4865.440474345495;4867.634927380461;3814.771113733185;4300.311950088034;4387.407699960465;4453.688241823106;4458.843940462133;3591.6408359163875;3643.617420170897;3733.104055026774;3760.191558814868;3762.059485128718;1122.3826895207603;1123.9044681202026;1126.6507382711386;1131.7340425000118;1138.7950168969664;733.9633018349061;775.7633921653837;858.2490320969152;889.8531742262584;891.3671293381642;5549.861253468695;5660.603757737002;5703.144528320785;5787.218725472091;5823.941760582425;920;936.4194199596158;938.6761567259284;951.7668171297938;956.6747305916022;3583.7670551414076;3657.5245218122004;3680.9447858282147;3721.0883333583447;3746.4193050077283;9742;10152.746181345377;10201.897981020094;10217.13154381886;10248.948755256177;9941.40351578893;10004.649837255662;10043.548040338203;10054.64808731691;10065.094141527297;7200.855982880313;7261.455390845602;7320.231375705474;7331.156916954512;7430.442716796262;5093.439721630692;5181.715005674724;5308.973455132854;5361.865953351197;5396.514313711785;8934.508602026692;8941.731748047569;8959.328050396376;9019.052999434965;9082.636694532144;8734.65061397537;9091.954540227256;9110.134537218953;9113.544322783702;9127.817443651222;6619.702113405005;6719.361660642231;6733.966330168318;6737.59574425536;6742.25835158142;7351.301626345488;7950.7217247396065;8018.398620103399;8024.518528888052;8046.557439340659;6861.382475577222;7039.507234493536;7350.338469537767;7401.037865077506;7465.29079737424;6703.530752847254;6760.93239067603;6768.390844823989;6844.657767111525;6866.279040700654;6525.086487891794;6555.672216389066;6579.96710016466;6580.967095164494;6593.835154121185;4718.740469274223;4729.337892694956;4752.453467851241;4759.357486739246;4768.880777980578;4811.1820990431015;4858.878528036827;4875.731834749123;4901.50984901507;4955.925661115159 08:46:56 INFO - PROCESS | 861 | |4;prototype.html;322.83858070964516;324.1717412012308;329.95050742388634;330.92885029718605;332.9267561136549;4814.951850481494;4833.734144622044;4852.223644216917;4870.975645121769;4890.608751299892;4754.096721622889;4780.808767649299;4813.181759100952;4814.446338671054;4842.34628325176;3695.316366472198;3750.3061933542244;3801.562820275676;3823.9808800955957;3867.2652196082654;3800.771953682774;3830.444585535096;3849.2878817418728;3863.903402414934;3871.651551360377;3760.830762615675;3774.7735135891935;3803.6957043436596;3833.2141910908335;3845.9038524036982;3573.4282514797637;3704.8144593730153;3803.6006219346846;3819.770813751156;3837;3402.4556071028765;3407.3866703993517;3409.5226668266464;3410.5225268462436;3425.520427140202;4605.125026245037;4625.791839367236;4628.421447319085;4649.04694537619;4653.813847446098;4291.862656396058;4375.453068366454;4406.317020861771;4406.801693923781;4417.602415782595;4993.825216117455;5046.167382381903;5061.569766569809;5080.187170052774;5098.388193416777;3727.2172843702856;3727.384981478053;3737;3759.473673685659;3773.0755964788527;5107.948920510785;5265.894682106375;5267.710275934822;5272.815451459219;5306.177542480883;3673.3755261605593;3674.540682414698;3675.2649470106085;3701.0747313171705;3716.479692843004;3752.587215406303;3756.981215093907;3763.736538442283;3775.131719704483;3789.6968242541084;4089.8773036808943;4274.316109422478;4290.849820256276;4310.073334233155;4352.39066530686;4956.628252881048;5037.093323201786;5062.164742817467;5070.163423035158;5084.262781896604;1276.9489220431078;1303.361352937073;1304.8499422566335;1308.4700696218047;1312.53405041209;823.8393513264856;830.2403300979536;847.360243016531;875.3259989807813;894.5661354243186;4446.599806017474;4448.933266001012;4451.821927122944;4457.038902882721;4468.458381858254;5165.959446018876;5173.22401639757;5174.042802081627;5176.637635365563;5182.196759502283;4247.68142389322;4414.293713005968;4451.488078870907;4456.376107344974;4461.304704212379;2745.32739478825;2756.9172924812287;2757.1590664847413;2770.570561562961;2776.8472733999824;4887.828925987574;4904.6566740327835;4922.950770492249;4924.876878078005;4943.65394422387;5047.343845300087;5283.313169287968;5298.191958262128;5309.36287645485;5384.676919384771;4967.3294105296045;4984.327115839388;4985.703717033525;4990.351254336913;5003.77483013258;5248.186531087535;5273;5308.309919710413;5316;5337.226102215234;4925.768557860535;5188.299579556711;5207.947920520746;5214.191800270964;5216.921746173734;4957.917674576614;4988.625853060962;5001.674891132065;5002.87492812668;5016.598672106295;3730.869419570302;3730.90672733173;3795;3800.3919372900205;3802.8478860845257;5106.310648062613;5252.264682944315;5275.235090911873;5294.549963253166;5320.14877619579;5085.211792172219;5088.567471764942;5092.185250359925;5103.617228707936;5113.616478764033;5095.15929871567;5097.974510127426;5103.264889937439;5103.464136265788;5122.334096567422;4926.118210862591;4974.950250497449;4987.576056035277;5022.573081288131;5039.21892106724;4878.902421951612;4949.109160351171;5151.8196863109615;5158.277841102174;5168.9483105168465;5007.449180590059;5023.547880690755;5033.320501732219;5039.395272567315;5053.84838454847 08:46:56 INFO - PROCESS | 861 | |5;yui.html;635.4503354598272;642.5084810120259;652.9934700652992;664.9468042556599;671.2549070531705;10636.149108071355;10846.945765271183;11157;11318.26431281967;11572;11596.652100436979;11604.651860444179;11628.651140465778;11662;11668.533258669642;681.0601370109267;835.7534527314457;863.7149740585592;873.829603227371;893.0890491698464;867.8264347130568;868.5526953618892;870.3081050564784;871.255076909243;871.7297637732291;929.7303781903274;930.5161316115616;931.585444477208;931.9627214911395;931.9673811416602;707.695690852933;900.1673452056887;926.3422969691527;928.0533855467453;930.7998780262209;687.9828004299883;688.9896651550231;689.1867596236439;690.4476418864938;690.7271627707028;12416.317102559356;12422.37888105591;12458;12461.75076498474;12513.74972500554;10987.945060274727;11057.336559806437;11361.091112711047;11513.30920144794;11995.820062699066;10011.199104071657;10087.293889427743;11074.280171788809;11107.83338249927;11179;3274.67253274673;3307.371599396107;3314.4034073866696;3419.230673098558;3423.058658868806;48950.75524622354;49692.75153624209;49763.50236497625;49882.7505862472;49886.501134988546;9010.729678109667;9036.954815225881;9174.174324310845;9621.90378096224;9636.855447168298;16013.199340032952;16114.033158010323;16233.188340582923;16315.102669353062;16316;6596.835079122964;6598.769043083565;6641.1366522352755;6749.122614060239;6875.5874647521305;3609.4405367168133;3636.563612366533;3641.908952276214;3651.415773476231;3668.101315177745;2880.8127471714693;2976.0625402997985;2976.4195981783346;2978.44898693738;2978.6127803385853;529.9099153143975;545.3619265459422;555.7054760976689;559.829252078112;567.6877717255493;494.35486689870066;525.324957429701;549.1351121982893;597.6533610505896;624.341319907502;8020.278174964281;8158.836823263501;8262.339012878956;8276.462029968034;8365.581720914051;649.8765234605504;659.120074700271;660.1846719301659;668.2782594797607;670.6278015701332;3733.3653278942643;3823.101571130782;3885.9611403886165;3937.684985201177;3990.20195960809;47745;47767.522324776306;49189;49270.26094608584;55849;50497.49502504928;50733.492665072874;51152.23271650928;52542.21186682203;52637.2104418434;46993.76503117531;47325.52674473211;47736.5226347739;47797;47913.76043119762;8375.916240837514;8381.203785640355;8495.532745698798;8577.82844343122;8611.95694021526;31843.522347164348;32143.196420089684;32188.356232875678;32819.50770738489;33005.00984970455;37342.253154937294;39207.80396098001;41469.170616588104;41477.792611036755;41706.58293417027;9902.356346837432;10479;10569.577216911237;10629;10714.89285107139;11015.614453494089;11198;11260.436978151223;11463.598774042868;12098.818517722066;10783.946080269548;10916.181286403393;11086.334819910831;11193.888061119285;11194.216404851582;10881.18391120685;11149;11543.36511491876;11629;11752.76494470123;10845.457727113455;11140.10879129684;11277;11318.151138664469;11356.148288878201;9290.1174388433;9421;9753.60985560598;9819.312648114563;9931.900680993098;9382.812343753223;9460.385074970105;9703.369280996712;9997.20022398192;10067.446290454096 08:46:56 INFO - PROCESS | 861 | __end_tp_report 08:46:56 INFO - PROCESS | 861 | __start_cc_report 08:46:56 INFO - PROCESS | 861 | _x_x_mozilla_cycle_collect,1193 08:46:56 INFO - PROCESS | 861 | __end_cc_report 08:46:56 INFO - PROCESS | 861 | __startTimestamp1463413616289__endTimestamp 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | ------- Summary: start ------- 08:46:56 INFO - PROCESS | 861 | Number of tests: 6 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#0] dojo.html Cycles:165 Average:4659.42 Median:4799.40 stddev:484.30 (10.1%) stddev-sans-first:459.27 08:46:56 INFO - PROCESS | 861 | Values: 2644.6 2653.2 2692.4 2695.9 2697.9 4603.5 4625.0 4672.5 4673.1 4721.0 4676.6 4705.4 4725.9 4733.7 4744.5 4303.5 4350.9 4400.9 4428.4 4526.7 4419.6 4450.2 4465.6 4476.8 4493.8 4194.1 4218.7 4311.8 4329.6 4367.8 4287.8 4288.7 4293.2 4336.2 4362.4 4325.5 4329.6 4336.0 4336.8 4385.9 4478.4 4593.2 4638.3 4639.6 4647.3 5055.8 5085.3 5097.9 5112.0 5114.8 5114.1 5131.6 5135.5 5148.2 5155.4 4644.0 4669.2 4716.1 4718.1 4773.5 5171.1 5175.4 5188.9 5220.8 5225.1 4867.3 4959.8 4960.1 5000.4 5013.4 4970.4 5002.7 5012.5 5021.6 5032.8 4974.3 4983.4 4992.6 4992.7 5041.2 4839.9 4848.9 4963.4 4974.3 5026.0 4146.7 4168.0 4180.6 4182.2 4230.2 3704.8 3707.3 3711.8 3742.4 3753.8 4261.1 4374.3 4381.5 4384.9 4426.7 5040.5 5094.0 5096.0 5111.5 5114.7 4372.6 4400.1 4400.3 4401.7 4448.0 4451.0 4512.0 4552.0 4553.2 4581.3 4796.3 4799.4 4825.0 4831.7 4864.3 5109.0 5109.8 5110.8 5115.6 5139.2 4664.1 4704.9 4718.7 4811.9 4872.8 4915.0 4928.8 4943.3 4945.0 4984.8 4979.6 5019.7 5020.9 5020.9 5062.6 4745.2 4865.9 4889.9 4914.2 4940.7 4887.3 4887.7 4903.2 4907.0 4960.3 4833.1 4844.7 4849.5 4889.9 4897.6 4757.1 4774.7 4818.3 4874.5 4881.5 4842.0 4847.5 4855.0 4870.6 4874.6 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#1] ext.html Cycles:180 Average:52187.43 Median:19971.59 stddev:101095.03 (506.2%) stddev-sans-first:101304.34 08:46:56 INFO - PROCESS | 861 | Values: 571.3 599.6 600.2 629.4 642.8 42752.8 43966.8 43997.8 44041.6 47945.4 44347.6 44716.6 44793.3 44817.6 44861.3 7742.2 7896.5 7902.2 7904.2 8075.6 7650.5 9886.8 10137.1 10139.0 10429.4 10360.9 10394.0 10408.4 10415.1 10415.9 7690.9 7808.5 10123.2 10206.0 10208.7 7667.4 9952.0 10299.7 10307.5 10312.4 37863.0 38207.4 38208.8 41615.0 41717.8 3211.8 3248.7 3425.5 3681.4 3768.0 30720.5 31337.0 31457.0 31530.7 32306.5 3377.4 3435.2 3459.6 3472.3 3488.4 270057.0 272668.0 273906.0 275672.0 275768.0 62987.4 63349.0 63734.0 63746.4 63805.0 68399.7 69090.3 69093.0 69109.3 69195.3 13746.3 14273.6 14275.4 14289.6 14299.7 10246.8 11325.2 11382.5 11632.7 11985.5 20164.7 20284.2 20362.5 20383.3 20427.9 7381.8 7383.4 7522.6 7585.2 7598.7 805.2 912.6 959.3 982.6 989.3 28205.3 29389.7 29576.3 29577.6 29623.9 985.1 1061.7 1063.4 1084.7 1089.4 15116.9 15435.6 15498.2 15708.5 15863.9 374941.0 514148.0 523640.0 524444.0 706557.0 54946.0 55264.4 55486.0 55601.2 55650.2 1182.1 1298.4 1307.7 1310.2 1319.9 26335.5 29729.3 30070.7 30654.5 30833.0 44636.0 45284.0 45320.0 45361.5 45413.8 251310.0 257972.0 258173.0 259318.0 259471.0 23797.2 26428.7 26432.6 27242.6 27266.5 39116.8 39731.4 44295.0 44583.3 45215.3 36796.6 37293.6 37358.4 37667.2 38022.4 37096.4 37577.2 37682.4 38000.0 38126.6 36454.8 37022.4 37187.8 37283.1 37368.4 31056.5 33471.3 34095.3 34130.3 34187.3 25703.7 25799.5 26081.6 26423.3 26552.0 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#2] jquery.html Cycles:180 Average:24974.66 Median:204169.62 stddev:61124.52 (29.9%) stddev-sans-first:61268.09 08:46:56 INFO - PROCESS | 861 | Values: 381.1 391.8 394.7 396.4 417.2 12826.6 13378.6 16405.3 18184.2 18204.1 16629.9 16961.3 17758.8 17847.8 17971.6 4988.8 5180.5 5202.9 5211.5 5221.2 4047.5 5112.6 6752.8 6755.3 7162.1 7070.4 7103.4 7176.0 7196.1 7230.1 7099.1 7186.0 7201.7 7210.0 7210.7 6551.9 6599.1 6677.3 6734.7 6881.7 23034.9 24102.5 24110.8 24150.4 24422.9 2100.2 2141.9 2183.7 2344.2 2526.5 13933.9 13975.7 14543.5 14724.8 15230.9 1899.8 1924.8 1949.7 1952.6 1997.8 54216.0 82423.6 89184.0 90482.0 91980.5 16307.2 17002.8 17096.5 17108.3 17274.2 30302.0 30420.8 30594.0 30800.1 30861.7 9041.9 9459.0 9566.4 9621.6 9632.2 7690.0 7836.5 7888.1 7893.1 7933.2 8763.3 8776.8 8885.6 9055.8 9060.5 2683.4 3208.6 3392.3 3464.7 3547.1 825.6 896.3 932.7 966.6 971.5 16757.5 16883.6 16891.2 17388.7 17410.3 889.5 915.9 925.5 939.0 963.0 9233.5 9658.0 9702.4 9722.3 9775.5 307247.0 310209.0 404298.0 404511.0 407922.0 51216.0 51881.2 52407.7 53596.0 88672.0 935.6 960.3 965.9 974.0 977.0 15696.5 15767.9 15901.8 15940.1 15946.6 37862.4 37928.4 38083.6 38083.8 38324.2 49393.5 55447.4 55624.4 55743.4 56064.4 5486.8 6765.3 7412.3 7760.6 7848.1 10007.4 12058.5 12132.2 12144.3 12300.1 8570.2 9629.2 10030.9 10118.2 10463.8 10244.4 10408.2 10617.9 10744.6 10875.3 9882.0 10422.9 10752.7 10838.6 10874.9 6600.3 8053.1 8283.8 8320.1 8352.8 8589.6 10306.1 10512.2 10517.6 10665.9 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#3] mootools.html Cycles:180 Average:5343.54 Median:5026.75 stddev:2973.72 (59.2%) stddev-sans-first:2966.71 08:46:56 INFO - PROCESS | 861 | Values: 1323.7 1543.2 1568.8 1762.4 1894.4 7824.0 8111.9 8204.1 8369.2 8386.0 8207.8 8365.0 8383.7 8388.2 8397.0 2338.9 2369.5 2372.0 2372.1 2372.1 2298.7 2343.9 2375.3 2381.1 2382.5 2345.3 2350.8 2366.3 2366.9 2380.3 2242.7 2262.2 2288.0 2290.9 2303.7 2257.1 2258.6 2268.7 2269.6 2274.7 7720.2 7878.1 8201.8 8259.7 8262.9 4906.4 5020.3 5024.1 5029.4 5034.7 3028.6 3032.5 3094.9 3100.0 3116.4 910.6 915.9 931.8 938.5 973.5 11286.5 11460.1 11492.7 11506.6 11539.2 4269.3 4387.5 4454.8 4456.0 4463.7 8946.2 9004.5 9005.6 9011.8 9035.1 4857.5 4859.1 4863.4 4865.4 4867.6 3814.8 4300.3 4387.4 4453.7 4458.8 3591.6 3643.6 3733.1 3760.2 3762.1 1122.4 1123.9 1126.7 1131.7 1138.8 734.0 775.8 858.2 889.9 891.4 5549.9 5660.6 5703.1 5787.2 5823.9 920.0 936.4 938.7 951.8 956.7 3583.8 3657.5 3680.9 3721.1 3746.4 9742.0 10152.7 10201.9 10217.1 10248.9 9941.4 10004.6 10043.5 10054.6 10065.1 7200.9 7261.5 7320.2 7331.2 7430.4 5093.4 5181.7 5309.0 5361.9 5396.5 8934.5 8941.7 8959.3 9019.1 9082.6 8734.7 9092.0 9110.1 9113.5 9127.8 6619.7 6719.4 6734.0 6737.6 6742.3 7351.3 7950.7 8018.4 8024.5 8046.6 6861.4 7039.5 7350.3 7401.0 7465.3 6703.5 6760.9 6768.4 6844.7 6866.3 6525.1 6555.7 6580.0 6581.0 6593.8 4718.7 4729.3 4752.5 4759.4 4768.9 4811.2 4858.9 4875.7 4901.5 4955.9 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#4] prototype.html Cycles:180 Average:4229.43 Median:4838.04 stddev:1219.31 (25.2%) stddev-sans-first:1186.95 08:46:56 INFO - PROCESS | 861 | Values: 322.8 324.2 330.0 330.9 332.9 4815.0 4833.7 4852.2 4871.0 4890.6 4754.1 4780.8 4813.2 4814.4 4842.3 3695.3 3750.3 3801.6 3824.0 3867.3 3800.8 3830.4 3849.3 3863.9 3871.7 3760.8 3774.8 3803.7 3833.2 3845.9 3573.4 3704.8 3803.6 3819.8 3837.0 3402.5 3407.4 3409.5 3410.5 3425.5 4605.1 4625.8 4628.4 4649.0 4653.8 4291.9 4375.5 4406.3 4406.8 4417.6 4993.8 5046.2 5061.6 5080.2 5098.4 3727.2 3727.4 3737.0 3759.5 3773.1 5107.9 5265.9 5267.7 5272.8 5306.2 3673.4 3674.5 3675.3 3701.1 3716.5 3752.6 3757.0 3763.7 3775.1 3789.7 4089.9 4274.3 4290.8 4310.1 4352.4 4956.6 5037.1 5062.2 5070.2 5084.3 1276.9 1303.4 1304.8 1308.5 1312.5 823.8 830.2 847.4 875.3 894.6 4446.6 4448.9 4451.8 4457.0 4468.5 5166.0 5173.2 5174.0 5176.6 5182.2 4247.7 4414.3 4451.5 4456.4 4461.3 2745.3 2756.9 2757.2 2770.6 2776.8 4887.8 4904.7 4923.0 4924.9 4943.7 5047.3 5283.3 5298.2 5309.4 5384.7 4967.3 4984.3 4985.7 4990.4 5003.8 5248.2 5273.0 5308.3 5316.0 5337.2 4925.8 5188.3 5207.9 5214.2 5216.9 4957.9 4988.6 5001.7 5002.9 5016.6 3730.9 3730.9 3795.0 3800.4 3802.8 5106.3 5252.3 5275.2 5294.5 5320.1 5085.2 5088.6 5092.2 5103.6 5113.6 5095.2 5098.0 5103.3 5103.5 5122.3 4926.1 4975.0 4987.6 5022.6 5039.2 4878.9 4949.1 5151.8 5158.3 5168.9 5007.4 5023.5 5033.3 5039.4 5053.8 08:46:56 INFO - PROCESS | 861 | 08:46:56 INFO - PROCESS | 861 | [#5] yui.html Cycles:180 Average:13132.09 Median:47855.38 stddev:15316.04 (32.0%) stddev-sans-first:15330.26 08:46:56 INFO - PROCESS | 861 | Values: 635.5 642.5 653.0 664.9 671.3 10636.1 10846.9 11157.0 11318.3 11572.0 11596.7 11604.7 11628.7 11662.0 11668.5 681.1 835.8 863.7 873.8 893.1 867.8 868.6 870.3 871.3 871.7 929.7 930.5 931.6 932.0 932.0 707.7 900.2 926.3 928.1 930.8 688.0 689.0 689.2 690.4 690.7 12416.3 12422.4 12458.0 12461.8 12513.7 10987.9 11057.3 11361.1 11513.3 11995.8 10011.2 10087.3 11074.3 11107.8 11179.0 3274.7 3307.4 3314.4 3419.2 3423.1 48950.8 49692.8 49763.5 49882.8 49886.5 9010.7 9037.0 9174.2 9621.9 9636.9 16013.2 16114.0 16233.2 16315.1 16316.0 6596.8 6598.8 6641.1 6749.1 6875.6 3609.4 3636.6 3641.9 3651.4 3668.1 2880.8 2976.1 2976.4 2978.4 2978.6 529.9 545.4 555.7 559.8 567.7 494.4 525.3 549.1 597.7 624.3 8020.3 8158.8 8262.3 8276.5 8365.6 649.9 659.1 660.2 668.3 670.6 3733.4 3823.1 3886.0 3937.7 3990.2 47745.0 47767.5 49189.0 49270.3 55849.0 50497.5 50733.5 51152.2 52542.2 52637.2 46993.8 47325.5 47736.5 47797.0 47913.8 8375.9 8381.2 8495.5 8577.8 8612.0 31843.5 32143.2 32188.4 32819.5 33005.0 37342.3 39207.8 41469.2 41477.8 41706.6 9902.4 10479.0 10569.6 10629.0 10714.9 11015.6 11198.0 11260.4 11463.6 12098.8 10783.9 10916.2 11086.3 11193.9 11194.2 10881.2 11149.0 11543.4 11629.0 11752.8 10845.5 11140.1 11277.0 11318.2 11356.1 9290.1 9421.0 9753.6 9819.3 9931.9 9382.8 9460.4 9703.4 9997.2 10067.4 08:46:56 INFO - PROCESS | 861 | -------- Summary: end -------- 08:46:56 INFO - PROCESS | 861 | 08:46:57 INFO - TEST-INFO | 861: exit 0 08:46:57 INFO - TEST-OK | dromaeo_css | took 1119122ms 08:46:57 INFO - TEST-START | kraken 08:46:57 INFO - operating with platform_type : linux_ 08:46:57 INFO - Initialising browser for kraken test... 08:46:57 INFO - TEST-INFO | started process 1047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpizp5sN/profile http://localhost:45935/getInfo.html) 08:47:01 INFO - PROCESS | 1047 | __metrics Screen width/height:1600/1200 08:47:01 INFO - PROCESS | 1047 | colorDepth:24 08:47:01 INFO - PROCESS | 1047 | Browser inner width/height: 1024/697 08:47:01 INFO - PROCESS | 1047 | __metrics 08:47:02 ERROR - PROCESS | 1047 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:47:03 INFO - TEST-INFO | 1047: exit 0 08:47:03 INFO - Browser initialized. 08:47:03 INFO - Running cycle 1/1 for kraken test... 08:47:03 INFO - TEST-INFO | started process 1185 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpizp5sN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:15 INFO - PROCESS | 1185 | RSS: Main: 154431488 08:47:15 INFO - PROCESS | 1185 | 08:47:18 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45935/tests/kraken/driver.html?testName=audio-beat-detection) 08:47:19 INFO - PROCESS | 1185 | RSS: Main: 209727488 08:47:19 INFO - PROCESS | 1185 | 08:47:21 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45935/tests/kraken/driver.html?testName=audio-dft) 08:47:22 INFO - PROCESS | 1185 | RSS: Main: 198287360 08:47:22 INFO - PROCESS | 1185 | 08:47:25 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45935/tests/kraken/driver.html?testName=audio-fft) 08:47:26 INFO - PROCESS | 1185 | RSS: Main: 266620928 08:47:26 INFO - PROCESS | 1185 | 08:47:28 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45935/tests/kraken/driver.html?testName=audio-oscillator) 08:47:29 INFO - PROCESS | 1185 | RSS: Main: 176340992 08:47:29 INFO - PROCESS | 1185 | 08:47:31 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45935/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:47:32 INFO - PROCESS | 1185 | RSS: Main: 180486144 08:47:32 INFO - PROCESS | 1185 | 08:47:35 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45935/tests/kraken/driver.html?testName=imaging-darkroom) 08:47:36 INFO - PROCESS | 1185 | RSS: Main: 215986176 08:47:36 INFO - PROCESS | 1185 | 08:47:39 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45935/tests/kraken/driver.html?testName=imaging-desaturate) 08:47:40 INFO - PROCESS | 1185 | RSS: Main: 214921216 08:47:40 INFO - PROCESS | 1185 | 08:47:43 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45935/tests/kraken/driver.html?testName=json-parse-financial) 08:47:44 INFO - PROCESS | 1185 | RSS: Main: 214597632 08:47:44 INFO - PROCESS | 1185 | 08:47:45 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45935/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:47:46 INFO - PROCESS | 1185 | RSS: Main: 209731584 08:47:46 INFO - PROCESS | 1185 | 08:47:48 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:47:49 INFO - PROCESS | 1185 | RSS: Main: 210178048 08:47:49 INFO - PROCESS | 1185 | 08:47:51 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:47:52 INFO - PROCESS | 1185 | RSS: Main: 215318528 08:47:52 INFO - PROCESS | 1185 | 08:47:54 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:47:55 INFO - PROCESS | 1185 | RSS: Main: 219770880 08:47:55 INFO - PROCESS | 1185 | 08:47:58 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:47:59 INFO - PROCESS | 1185 | RSS: Main: 216670208 08:47:59 INFO - PROCESS | 1185 | 08:48:00 INFO - PROCESS | 1185 | Cycle 1(1): loaded http://localhost:45935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45935/tests/kraken/driver.html?testName=ai-astar) 08:48:01 INFO - PROCESS | 1185 | RSS: Main: 262672384 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | __start_tp_report 08:48:01 INFO - PROCESS | 1185 | _x_x_mozilla_page_load 08:48:01 INFO - PROCESS | 1185 | _x_x_mozilla_page_load_details 08:48:01 INFO - PROCESS | 1185 | |i|pagename|runs| 08:48:01 INFO - PROCESS | 1185 | |0;ai-astar;113;111;114;113;111;113;112;112;111;112 08:48:01 INFO - PROCESS | 1185 | |1;audio-beat-detection;160;130;156;215;130;151;163;131;194;133 08:48:01 INFO - PROCESS | 1185 | |2;audio-dft;162;160;161;162;161;162;160;162;161;160 08:48:01 INFO - PROCESS | 1185 | |3;audio-fft;74;75;77;157;76;75;77;75;123;77 08:48:01 INFO - PROCESS | 1185 | |4;audio-oscillator;92;92;151;94;92;93;122;92;93;132 08:48:01 INFO - PROCESS | 1185 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;116 08:48:01 INFO - PROCESS | 1185 | |6;imaging-darkroom;147;146;146;145;147;145;146;146;146;145 08:48:01 INFO - PROCESS | 1185 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;95 08:48:01 INFO - PROCESS | 1185 | |8;json-parse-financial;65;65;64;64;66;65;65;65;64;63 08:48:01 INFO - PROCESS | 1185 | |9;json-stringify-tinderbox;63;63;63;63;64;64;63;63;63;63 08:48:01 INFO - PROCESS | 1185 | |10;stanford-crypto-aes;82;82;81;81;81;82;83;83;149;85 08:48:01 INFO - PROCESS | 1185 | |11;stanford-crypto-ccm;139;131;133;133;132;124;127;131;148;138 08:48:01 INFO - PROCESS | 1185 | |12;stanford-crypto-pbkdf2;174;171;174;179;170;179;172;181;168;169 08:48:01 INFO - PROCESS | 1185 | |13;stanford-crypto-sha256-iterative;66;66;67;67;65;66;68;66;64;65 08:48:01 INFO - PROCESS | 1185 | __end_tp_report 08:48:01 INFO - PROCESS | 1185 | __start_cc_report 08:48:01 INFO - PROCESS | 1185 | _x_x_mozilla_cycle_collect,2102 08:48:01 INFO - PROCESS | 1185 | __end_cc_report 08:48:01 INFO - PROCESS | 1185 | __startTimestamp1463413681066__endTimestamp 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | ------- Summary: start ------- 08:48:01 INFO - PROCESS | 1185 | Number of tests: 14 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:1.03 (0.9%) stddev-sans-first:1.05 08:48:01 INFO - PROCESS | 1185 | Values: 113.0 111.0 114.0 113.0 111.0 113.0 112.0 112.0 111.0 112.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#1] audio-beat-detection Cycles:10 Average:156.30 Median:158.00 stddev:28.91 (18.3%) stddev-sans-first:30.63 08:48:01 INFO - PROCESS | 1185 | Values: 160.0 130.0 156.0 215.0 130.0 151.0 163.0 131.0 194.0 133.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.88 (0.5%) stddev-sans-first:0.87 08:48:01 INFO - PROCESS | 1185 | Values: 162.0 160.0 161.0 162.0 161.0 162.0 160.0 162.0 161.0 160.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#3] audio-fft Cycles:10 Average:88.60 Median:75.50 stddev:28.27 (37.4%) stddev-sans-first:29.49 08:48:01 INFO - PROCESS | 1185 | Values: 74.0 75.0 77.0 157.0 76.0 75.0 77.0 75.0 123.0 77.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:92.00 stddev:21.65 (23.5%) stddev-sans-first:22.42 08:48:01 INFO - PROCESS | 1185 | Values: 92.0 92.0 151.0 94.0 92.0 93.0 122.0 92.0 93.0 132.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:48:01 INFO - PROCESS | 1185 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 08:48:01 INFO - PROCESS | 1185 | Values: 147.0 146.0 146.0 145.0 147.0 145.0 146.0 146.0 146.0 145.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:48:01 INFO - PROCESS | 1185 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 08:48:01 INFO - PROCESS | 1185 | Values: 65.0 65.0 64.0 64.0 66.0 65.0 65.0 65.0 64.0 63.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 08:48:01 INFO - PROCESS | 1185 | Values: 63.0 63.0 63.0 63.0 64.0 64.0 63.0 63.0 63.0 63.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:82.00 stddev:21.15 (25.8%) stddev-sans-first:22.29 08:48:01 INFO - PROCESS | 1185 | Values: 82.0 82.0 81.0 81.0 81.0 82.0 83.0 83.0 149.0 85.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:133.00 stddev:6.74 (5.1%) stddev-sans-first:6.86 08:48:01 INFO - PROCESS | 1185 | Values: 139.0 131.0 133.0 133.0 132.0 124.0 127.0 131.0 148.0 138.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:174.00 stddev:4.57 (2.6%) stddev-sans-first:4.85 08:48:01 INFO - PROCESS | 1185 | Values: 174.0 171.0 174.0 179.0 170.0 179.0 172.0 181.0 168.0 169.0 08:48:01 INFO - PROCESS | 1185 | 08:48:01 INFO - PROCESS | 1185 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 08:48:01 INFO - PROCESS | 1185 | Values: 66.0 66.0 67.0 67.0 65.0 66.0 68.0 66.0 64.0 65.0 08:48:01 INFO - PROCESS | 1185 | -------- Summary: end -------- 08:48:01 INFO - PROCESS | 1185 | 08:48:02 INFO - TEST-INFO | 1185: exit 0 08:48:02 INFO - TEST-OK | kraken | took 65029ms 08:48:02 INFO - SUITE-END | took 1184s 08:48:02 INFO - Completed test suite (00:19:44) 08:48:02 INFO - javascript benchmark 08:48:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.590088536277, 2653.204038788363, 2692.4345887363647, 2695.878685459154, 2697.878595463204, 4603.539646035395, 4624.976875115624, 4672.486026537087, 4673.112108699344, 4720.952790472094, 4676.602488788449, 4705.411823522059, 4725.881852953669, 4733.668643194977, 4744.525547445253, 4303.526612072677, 4350.891227719317, 4400.933985990213, 4428.446444194476, 4526.6831321807485, 4419.602235798778, 4450.221211288012, 4465.553444655525, 4476.798544065508, 4493.797779099932, 4194.098268872207, 4218.6625069994325, 4311.762853043081, 4329.610335069828, 4367.781610919441, 4287.849925252601, 4288.742675439484, 4293.205756934977, 4336.1978034063795, 4362.432883725095, 4325.52419233884, 4329.631981281595, 4335.9566404335865, 4336.783160841946, 4385.912281754379, 4478.41780568524, 4593.150267200579, 4638.327442520849, 4639.628829693616, 4647.302904564309, 5055.823046193365, 5085.262636917663, 5097.923531147036, 5111.9776044790815, 5114.769835357418, 5114.079465696173, 5131.640785145041, 5135.486451354872, 5148.2020286855595, 5155.432902380735, 4643.9767801161115, 4669.182892993713, 4716.056788642285, 4718.05638872227, 4773.474917759044, 5171.146760784503, 5175.404828444702, 5188.8962220755375, 5220.765065571983, 5225.111731005738, 4867.294242334841, 4959.801607935642, 4960.082384758831, 5000.39995200571, 5013.448520662724, 4970.428400733962, 5002.724850133278, 5012.523810238022, 5021.648484606042, 5032.7735251913755, 4974.328465657162, 4983.377077865267, 4992.550670439678, 4992.650514463952, 5041.218611115283, 4839.879003024953, 4848.8787780305065, 4963.379577552806, 4974.328465657162, 5025.974870125626, 4146.730462519927, 4167.97916010418, 4180.561041090703, 4182.247195504778, 4230.238557059759, 3704.7777133372083, 3707.295613833363, 3711.777293362407, 3742.36379815432, 3753.7935413551972, 4261.126469073833, 4374.256376416017, 4381.539938306432, 4384.912301753947, 4426.667999899956, 5040.546350828443, 5093.981203759189, 5096.031753966735, 5111.488851114858, 5114.693118412907, 4372.584604462571, 4400.119976004812, 4400.251957167289, 4401.691881568324, 4447.977760111244, 4450.977745111254, 4511.954880451153, 4552.0, 4553.203189441834, 4581.289900065495, 4796.2805579163405, 4799.400074990626, 4825.0349930013435, 4831.710097394027, 4864.343313652612, 5109.00374426998, 5109.7700603472185, 5110.770015349244, 5115.56517696, 5139.177731563082, 4664.137134630104, 4704.882377940579, 4718.740469274223, 4811.903761924672, 4872.829450969199, 4915.0, 4928.778204980713, 4943.307936888767, 4945.0, 4984.825531106394, 4979.601631869514, 5019.67372120811, 5020.874478138076, 5020.949790502048, 5062.645614806928, 4745.193317136026, 4865.854024379274, 4889.877753056202, 4914.164592019437, 4940.7035577865445, 4887.266909963534, 4887.657863949489, 4903.239997800347, 4907.0, 4960.330355402119, 4833.130036593307, 4844.733539655353, 4849.539293767087, 4889.926651100165, 4897.632677549127, 4757.119932812441, 4774.7135171889795, 4818.349522814376, 4874.53691899269, 4881.51184881509, 4842.007388485423, 4847.515248475124, 4855.0, 4870.586000190024, 4874.5856602189215], "value": 4629.029199190933, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.3058633759983, 599.5563283170452, 600.1717629671053, 629.3643420145654, 642.7557528139306, 42752.786236068816, 43966.78016609917, 43997.78001109994, 44041.559584404145, 47945.363005034706, 44347.556524434745, 44716.552834471644, 44793.32810007853, 44817.55182448174, 44861.327080093826, 7742.187070357588, 7896.526208427484, 7902.170272121431, 7904.249096335839, 8075.555844428554, 7650.464467487278, 9886.802263954716, 10137.138343240797, 10139.0, 10429.426381549012, 10360.896391036105, 10394.0, 10408.427536485491, 10415.062644362006, 10415.947920260425, 7690.884636730453, 7808.5314881107315, 10123.240756943222, 10206.030427109412, 10208.74478138045, 7667.424943129287, 9952.0, 10299.691009269734, 10307.484625768757, 10312.381257124522, 37863.0, 38207.426888596696, 38208.80895595504, 41615.0, 41717.79141104306, 3211.759118066131, 3248.675132486756, 3425.520427140202, 3681.3557627415094, 3768.0, 30720.539191911917, 31337.0, 31457.0, 31530.684693153005, 32306.515402268986, 3377.3583019226267, 3435.2270739083756, 3459.584849818038, 3472.2881809229048, 3488.354654388946, 270057.0, 272668.0, 273905.999999998, 275672.0, 275768.0, 62987.370126299065, 63349.0, 63734.0, 63746.36253637404, 63805.0, 68399.65800171068, 69090.30909690939, 69093.0, 69109.30890691029, 69195.30804691989, 13746.312684365941, 14273.57179284623, 14275.42898284057, 14289.642758930902, 14299.714005719827, 10246.846297305547, 11325.150613704005, 11382.54469821198, 11632.709182270512, 11985.52057917691, 20164.69752953707, 20284.188632454538, 20362.49093772638, 20383.286584969454, 20427.897860510602, 7381.778546643609, 7383.372413344819, 7522.623868806538, 7585.2035536269, 7598.696052157962, 805.1586092533316, 912.6167009855876, 959.29971121082, 982.577491678585, 989.2679417231197, 28205.294867628065, 29389.706102939126, 29576.260593485335, 29577.55633665497, 29623.85188074089, 985.093713783321, 1061.7398737309409, 1063.3672964586058, 1084.6962850401742, 1089.411717672448, 15116.924415377853, 15435.61410964735, 15498.225088745517, 15708.528744137695, 15863.920680396755, 374941.0, 514148.0, 523640.0, 524444.0, 706557.0, 54946.0, 55264.44735552673, 55486.0, 55601.1659825103, 55650.16524752132, 1182.066167727503, 1298.3702904091697, 1307.7253776706998, 1310.1811367895066, 1319.940602672882, 26335.473290534464, 29729.256768580093, 30070.69929300679, 30654.540181897737, 30833.0, 44636.0, 45284.0, 45320.0, 45361.54638453573, 45413.772931135136, 251310.0, 257972.0, 258173.0, 259318.0, 259471.0, 23797.167099151447, 26428.73571264263, 26432.603510947734, 27242.5913611292, 27266.454670906867, 39116.80441597888, 39731.40402894017, 44295.0, 44583.33125003063, 45215.32177017413, 36796.63203367932, 37293.627063729014, 37358.439623406215, 37667.2466550662, 38022.42966355452, 37096.44355334618, 37577.248455031295, 37682.43476347802, 38000.0, 38126.618733812305, 36454.81772591226, 37022.44466332953, 37187.81406092952, 37283.06792330214, 37368.43947340846, 31056.53415198819, 33471.330573388885, 34095.31809363848, 34130.317393651494, 34187.316253675286, 25703.742962570133, 25799.484010320062, 26081.608775868757, 26423.339416513973, 26552.0], "value": 19009.123362714403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.1139101588807, 391.77668728824574, 394.74538922395055, 396.4192458048959, 417.2468694007317, 12826.615201543946, 13378.59864204073, 16405.34378624855, 18184.181711822966, 18204.089795510205, 16629.91685041573, 16961.32154713813, 17758.82241177588, 17847.82152178478, 17971.550711232227, 4988.800447982077, 5180.45605211453, 5202.947970520303, 5211.45279745627, 5221.190715439113, 4047.4738284023183, 5112.616553758465, 6752.831179220534, 6755.324467553254, 7162.140543134831, 7070.363667269945, 7103.396211322044, 7175.964120179392, 7196.136463624346, 7230.060092188009, 7099.148102227714, 7186.0, 7201.675924583406, 7210.026646402747, 7210.747623833141, 6551.93448065518, 6599.076129341896, 6677.332266773284, 6734.730610775563, 6881.690323935435, 23034.884825575766, 24102.517949640907, 24110.758892411028, 24150.396240093964, 24422.877885610458, 2100.170432679083, 2141.882196479193, 2183.748868880083, 2344.15610380263, 2526.5325914705654, 13933.930330348385, 13975.720485590231, 14543.49097781583, 14724.779128313085, 15230.923845380814, 1899.838513726335, 1924.8363889069306, 1949.707543868417, 1952.570434504421, 1997.8301844343102, 54216.0, 82423.58788206021, 89184.0, 90482.0, 91980.54009729976, 16307.184640768151, 17002.829971700372, 17096.48710538686, 17108.315667373416, 17274.222659980333, 30302.0, 30420.847895760824, 30594.0, 30800.075997720105, 30861.68537359707, 9041.864372034424, 9458.95270523643, 9566.378185417925, 9621.615135394646, 9632.181264592587, 7689.9615501922135, 7836.529808211526, 7888.092869319987, 7893.13175550689, 7933.246341597539, 8763.298936085097, 8776.78058048541, 8885.555722213863, 9055.81888362229, 9060.456372617664, 2683.409649877024, 3208.6470488246273, 3392.3045775615938, 3464.670856268701, 3547.1486843157454, 825.6078362777632, 896.3098414221014, 932.715521765868, 966.5650457294246, 971.523953262896, 16757.497275081525, 16883.57791055209, 16891.220182340454, 17388.739168912478, 17410.303587856342, 889.4974339498162, 915.949622770754, 925.5372313843078, 938.9812203755887, 962.9711108666751, 9233.492157931243, 9658.0, 9702.417854928726, 9722.2708296878, 9775.511224438891, 307247.0, 310209.0, 404298.0, 404511.0, 407922.0, 51216.0, 51881.2217816733, 52407.73796130995, 53596.0, 88672.0, 935.5696379665294, 960.2845879819579, 965.9178969787506, 973.9610415583439, 976.9804603907882, 15696.450624228097, 15767.921160394124, 15901.840981590036, 15940.123293218985, 15946.60133496672, 37862.43206351852, 37928.431073533364, 38083.619163808005, 38083.809580951915, 38324.233515330096, 49393.50606494033, 55447.44552554423, 55624.44375556193, 55743.442565575446, 56064.43935560592, 5486.835394938158, 6765.323467653196, 7412.332890039922, 7760.611969401394, 7848.097468791162, 10007.399556026661, 12058.457369418573, 12132.211406258564, 12144.271343719405, 12300.077494187793, 8570.185832345916, 9629.229661627192, 10030.899691002996, 10118.19054475655, 10463.843042354523, 10244.436555989492, 10408.219383546415, 10617.89382106169, 10744.570217191225, 10875.347479151276, 9882.0, 10422.947885260524, 10752.731181720519, 10838.620648277589, 10874.863766967017, 6600.273969863215, 8053.114157442559, 8283.83432331338, 8320.126386729306, 8352.791180220544, 8589.570521473775, 10306.072453479223, 10512.159027277647, 10517.579296828042, 10665.946670266909], "value": 9412.127999803282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.6624660711518, 1543.205249296612, 1568.7725279834422, 1762.4272111563746, 1894.3559189875448, 7823.960880195598, 8111.91888081119, 8204.097549269576, 8369.246767790897, 8386.0, 8207.753767386974, 8364.958175209116, 8383.748487545383, 8388.203120703538, 8397.0, 2338.894749736266, 2369.5497855407502, 2371.9644205336936, 2372.0511795281855, 2372.110458578033, 2298.7241531016302, 2343.9179628713, 2375.3467796355967, 2381.0594815048044, 2382.5115851250453, 2345.343303874909, 2350.8354415190943, 2366.290112966112, 2366.8934897929635, 2380.2502211803226, 2242.7084479017785, 2262.242148880127, 2287.9885600571974, 2290.9083636654514, 2303.6544518322216, 2257.0520381439833, 2258.6386178211574, 2268.727752669674, 2269.625511790569, 2274.692916456274, 7720.227977202291, 7878.09401918781, 8201.753947381589, 8259.669613215465, 8262.917370826275, 4906.435759887623, 5020.347354843883, 5024.070546948826, 5029.421616514074, 5034.723090230036, 3028.5911401960675, 3032.4996375598, 3094.891678791254, 3099.9845000775076, 3116.3611459650942, 910.5947853205327, 915.8855143107112, 931.818295432391, 938.4697645830153, 973.4889183178888, 11286.548538058469, 11460.083193344512, 11492.655220343404, 11506.597269095542, 11539.192256542045, 4269.274223381971, 4387.4954380246045, 4454.844080457168, 4455.955440445554, 4463.665225108065, 8946.150115739125, 9004.50475223869, 9005.594748236344, 9011.819763604823, 9035.096490350912, 4857.465678775332, 4859.07677541266, 4863.416390033219, 4865.440474345495, 4867.634927380461, 3814.771113733185, 4300.311950088034, 4387.407699960465, 4453.688241823106, 4458.843940462133, 3591.6408359163875, 3643.617420170897, 3733.104055026774, 3760.191558814868, 3762.059485128718, 1122.3826895207603, 1123.9044681202026, 1126.6507382711386, 1131.7340425000118, 1138.7950168969664, 733.9633018349061, 775.7633921653837, 858.2490320969152, 889.8531742262584, 891.3671293381642, 5549.861253468695, 5660.603757737002, 5703.144528320785, 5787.218725472091, 5823.941760582425, 920.0, 936.4194199596158, 938.6761567259284, 951.7668171297938, 956.6747305916022, 3583.7670551414076, 3657.5245218122004, 3680.9447858282147, 3721.0883333583447, 3746.4193050077283, 9742.0, 10152.746181345377, 10201.897981020094, 10217.13154381886, 10248.948755256177, 9941.40351578893, 10004.649837255662, 10043.548040338203, 10054.64808731691, 10065.094141527297, 7200.855982880313, 7261.455390845602, 7320.231375705474, 7331.156916954512, 7430.442716796262, 5093.439721630692, 5181.715005674724, 5308.973455132854, 5361.865953351197, 5396.514313711785, 8934.508602026692, 8941.731748047569, 8959.328050396376, 9019.052999434965, 9082.636694532144, 8734.65061397537, 9091.954540227256, 9110.134537218953, 9113.544322783702, 9127.817443651222, 6619.702113405005, 6719.361660642231, 6733.966330168318, 6737.59574425536, 6742.25835158142, 7351.301626345488, 7950.7217247396065, 8018.398620103399, 8024.518528888052, 8046.557439340659, 6861.382475577222, 7039.507234493536, 7350.338469537767, 7401.037865077506, 7465.29079737424, 6703.530752847254, 6760.93239067603, 6768.390844823989, 6844.657767111525, 6866.279040700654, 6525.086487891794, 6555.672216389066, 6579.96710016466, 6580.967095164494, 6593.835154121185, 4718.740469274223, 4729.337892694956, 4752.453467851241, 4759.357486739246, 4768.880777980578, 4811.1820990431015, 4858.878528036827, 4875.731834749123, 4901.50984901507, 4955.925661115159], "value": 4311.980748490869, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.83858070964516, 324.1717412012308, 329.95050742388634, 330.92885029718605, 332.9267561136549, 4814.951850481494, 4833.734144622044, 4852.223644216917, 4870.975645121769, 4890.608751299892, 4754.096721622889, 4780.808767649299, 4813.181759100952, 4814.446338671054, 4842.34628325176, 3695.316366472198, 3750.3061933542244, 3801.562820275676, 3823.9808800955957, 3867.2652196082654, 3800.771953682774, 3830.444585535096, 3849.2878817418728, 3863.903402414934, 3871.651551360377, 3760.830762615675, 3774.7735135891935, 3803.6957043436596, 3833.2141910908335, 3845.9038524036982, 3573.4282514797637, 3704.8144593730153, 3803.6006219346846, 3819.770813751156, 3837.0, 3402.4556071028765, 3407.3866703993517, 3409.5226668266464, 3410.5225268462436, 3425.520427140202, 4605.125026245037, 4625.791839367236, 4628.421447319085, 4649.04694537619, 4653.813847446098, 4291.862656396058, 4375.453068366454, 4406.317020861771, 4406.801693923781, 4417.602415782595, 4993.825216117455, 5046.167382381903, 5061.569766569809, 5080.187170052774, 5098.388193416777, 3727.2172843702856, 3727.384981478053, 3737.0, 3759.473673685659, 3773.0755964788527, 5107.948920510785, 5265.894682106375, 5267.710275934822, 5272.815451459219, 5306.177542480883, 3673.3755261605593, 3674.540682414698, 3675.2649470106085, 3701.0747313171705, 3716.479692843004, 3752.587215406303, 3756.981215093907, 3763.736538442283, 3775.131719704483, 3789.6968242541084, 4089.8773036808943, 4274.316109422478, 4290.849820256276, 4310.073334233155, 4352.39066530686, 4956.628252881048, 5037.093323201786, 5062.164742817467, 5070.163423035158, 5084.262781896604, 1276.9489220431078, 1303.361352937073, 1304.8499422566335, 1308.4700696218047, 1312.53405041209, 823.8393513264856, 830.2403300979536, 847.360243016531, 875.3259989807813, 894.5661354243186, 4446.599806017474, 4448.933266001012, 4451.821927122944, 4457.038902882721, 4468.458381858254, 5165.959446018876, 5173.22401639757, 5174.042802081627, 5176.637635365563, 5182.196759502283, 4247.68142389322, 4414.293713005968, 4451.488078870907, 4456.376107344974, 4461.304704212379, 2745.32739478825, 2756.9172924812287, 2757.1590664847413, 2770.570561562961, 2776.8472733999824, 4887.828925987574, 4904.6566740327835, 4922.950770492249, 4924.876878078005, 4943.65394422387, 5047.343845300087, 5283.313169287968, 5298.191958262128, 5309.36287645485, 5384.676919384771, 4967.3294105296045, 4984.327115839388, 4985.703717033525, 4990.351254336913, 5003.77483013258, 5248.186531087535, 5273.0, 5308.309919710413, 5316.0, 5337.226102215234, 4925.768557860535, 5188.299579556711, 5207.947920520746, 5214.191800270964, 5216.921746173734, 4957.917674576614, 4988.625853060962, 5001.674891132065, 5002.87492812668, 5016.598672106295, 3730.869419570302, 3730.90672733173, 3795.0, 3800.3919372900205, 3802.8478860845257, 5106.310648062613, 5252.264682944315, 5275.235090911873, 5294.549963253166, 5320.14877619579, 5085.211792172219, 5088.567471764942, 5092.185250359925, 5103.617228707936, 5113.616478764033, 5095.15929871567, 5097.974510127426, 5103.264889937439, 5103.464136265788, 5122.334096567422, 4926.118210862591, 4974.950250497449, 4987.576056035277, 5022.573081288131, 5039.21892106724, 4878.902421951612, 4949.109160351171, 5151.8196863109615, 5158.277841102174, 5168.9483105168465, 5007.449180590059, 5023.547880690755, 5033.320501732219, 5039.395272567315, 5053.84838454847], "value": 3848.1258656580667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4503354598272, 642.5084810120259, 652.9934700652992, 664.9468042556599, 671.2549070531705, 10636.149108071355, 10846.945765271183, 11157.0, 11318.26431281967, 11572.0, 11596.652100436979, 11604.651860444179, 11628.651140465778, 11662.0, 11668.533258669642, 681.0601370109267, 835.7534527314457, 863.7149740585592, 873.829603227371, 893.0890491698464, 867.8264347130568, 868.5526953618892, 870.3081050564784, 871.255076909243, 871.7297637732291, 929.7303781903274, 930.5161316115616, 931.585444477208, 931.9627214911395, 931.9673811416602, 707.695690852933, 900.1673452056887, 926.3422969691527, 928.0533855467453, 930.7998780262209, 687.9828004299883, 688.9896651550231, 689.1867596236439, 690.4476418864938, 690.7271627707028, 12416.317102559356, 12422.37888105591, 12458.0, 12461.75076498474, 12513.74972500554, 10987.945060274727, 11057.336559806437, 11361.091112711047, 11513.30920144794, 11995.820062699066, 10011.199104071657, 10087.293889427743, 11074.280171788809, 11107.83338249927, 11179.0, 3274.67253274673, 3307.371599396107, 3314.4034073866696, 3419.230673098558, 3423.058658868806, 48950.75524622354, 49692.75153624209, 49763.50236497625, 49882.7505862472, 49886.501134988546, 9010.729678109667, 9036.954815225881, 9174.174324310845, 9621.90378096224, 9636.855447168298, 16013.199340032952, 16114.033158010323, 16233.188340582923, 16315.102669353062, 16316.0, 6596.835079122964, 6598.769043083565, 6641.1366522352755, 6749.122614060239, 6875.5874647521305, 3609.4405367168133, 3636.563612366533, 3641.908952276214, 3651.415773476231, 3668.101315177745, 2880.8127471714693, 2976.0625402997985, 2976.4195981783346, 2978.44898693738, 2978.6127803385853, 529.9099153143975, 545.3619265459422, 555.7054760976689, 559.829252078112, 567.6877717255493, 494.35486689870066, 525.324957429701, 549.1351121982893, 597.6533610505896, 624.341319907502, 8020.278174964281, 8158.836823263501, 8262.339012878956, 8276.462029968034, 8365.581720914051, 649.8765234605504, 659.120074700271, 660.1846719301659, 668.2782594797607, 670.6278015701332, 3733.3653278942643, 3823.101571130782, 3885.9611403886165, 3937.684985201177, 3990.20195960809, 47745.0, 47767.522324776306, 49189.0, 49270.26094608584, 55849.0, 50497.49502504928, 50733.492665072874, 51152.23271650928, 52542.21186682203, 52637.2104418434, 46993.76503117531, 47325.52674473211, 47736.5226347739, 47797.0, 47913.76043119762, 8375.916240837514, 8381.203785640355, 8495.532745698798, 8577.82844343122, 8611.95694021526, 31843.522347164348, 32143.196420089684, 32188.356232875678, 32819.50770738489, 33005.00984970455, 37342.253154937294, 39207.80396098001, 41469.170616588104, 41477.792611036755, 41706.58293417027, 9902.356346837432, 10479.0, 10569.577216911237, 10629.0, 10714.89285107139, 11015.614453494089, 11198.0, 11260.436978151223, 11463.598774042868, 12098.818517722066, 10783.946080269548, 10916.181286403393, 11086.334819910831, 11193.888061119285, 11194.216404851582, 10881.18391120685, 11149.0, 11543.36511491876, 11629.0, 11752.76494470123, 10845.457727113455, 11140.10879129684, 11277.0, 11318.151138664469, 11356.148288878201, 9290.1174388433, 9421.0, 9753.60985560598, 9819.312648114563, 9931.900680993098, 9382.812343753223, 9460.385074970105, 9703.369280996712, 9997.20022398192, 10067.446290454096], "value": 6040.06630549141, "unit": "score"}], "name": "dromaeo_css", "value": 6604.808467308107, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 114.0, 113.0, 111.0, 113.0, 112.0, 112.0, 111.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 130.0, 156.0, 215.0, 130.0, 151.0, 163.0, 131.0, 194.0, 133.0], "value": 156.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 162.0, 161.0, 162.0, 160.0, 162.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 157.0, 76.0, 75.0, 77.0, 75.0, 123.0, 77.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 94.0, 92.0, 93.0, 122.0, 92.0, 93.0, 132.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 66.0, 65.0, 65.0, 65.0, 64.0, 63.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 81.0, 81.0, 82.0, 83.0, 83.0, 149.0, 85.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 131.0, 133.0, 133.0, 132.0, 124.0, 127.0, 131.0, 148.0, 138.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 174.0, 179.0, 170.0, 179.0, 172.0, 181.0, 168.0, 169.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 65.0, 66.0, 68.0, 66.0, 64.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1571.2, "alertThreshold": 2.0}]} 08:48:02 INFO - Return code: 0 08:48:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:48:02 ERROR - # TBPL SUCCESS # 08:48:02 INFO - Running post-action listener: _resource_record_post_action 08:48:02 INFO - ##### 08:48:02 INFO - ##### Finished run-tests step (success) 08:48:02 INFO - ##### 08:48:02 INFO - Running post-run listener: _resource_record_post_run 08:48:02 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 531316736; Read time: 12; Write time: 491360 08:48:02 INFO - TinderboxPrint: CPU usage
13.1% 08:48:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:48:02 INFO - TinderboxPrint: I/O write bytes / time
531,316,736 / 491,360 08:48:02 INFO - TinderboxPrint: CPU idle
8,329.3 (86.9%) 08:48:02 INFO - TinderboxPrint: CPU user
1,212.0 (12.6%) 08:48:02 INFO - TinderboxPrint: Swap in / out
0 / 0 08:48:02 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236249088; Read time: 0; Write time: 277704 08:48:02 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295067648; Read time: 12; Write time: 213656 08:48:02 INFO - Running post-run listener: _upload_blobber_files 08:48:02 INFO - Blob upload gear active. 08:48:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:48:02 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 08:48:02 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'] 08:48:02 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 08:48:03 INFO - (blobuploader) - INFO - Open directory for files ... 08:48:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:48:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:04 INFO - (blobuploader) - INFO - Done attempting. 08:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:08 INFO - (blobuploader) - INFO - Done attempting. 08:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:09 INFO - (blobuploader) - INFO - Done attempting. 08:48:09 INFO - (blobuploader) - INFO - Iteration through files over. 08:48:09 INFO - Return code: 0 08:48:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:48:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:48:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/824eb8990da545be4bfad6d2d5c519ee68755681f9c49b737053e5dda0e43802caa2e554758ea58f0e3bf8a1938c4f67911294a1a815baae7da197dcc917c900", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dc0b78b266ea7b7e1621ee45ebf26f4eb318961be9e4cfa1dc0ce1c849beda868d60711e36f43d0567eef1e2c47a0aeb90d2fa84b8007d845d7f8df3f008560b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90b25ae2db219b9d3e8d33a9fdc14256fdab3a8a1f773504fc07e8c44a3c101d051c3ca7e1a439fd3f2ddac4b436199771100eee674430fb98ef5143bc876167"} 08:48:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:48:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:48:09 INFO - Contents: 08:48:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/824eb8990da545be4bfad6d2d5c519ee68755681f9c49b737053e5dda0e43802caa2e554758ea58f0e3bf8a1938c4f67911294a1a815baae7da197dcc917c900", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dc0b78b266ea7b7e1621ee45ebf26f4eb318961be9e4cfa1dc0ce1c849beda868d60711e36f43d0567eef1e2c47a0aeb90d2fa84b8007d845d7f8df3f008560b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90b25ae2db219b9d3e8d33a9fdc14256fdab3a8a1f773504fc07e8c44a3c101d051c3ca7e1a439fd3f2ddac4b436199771100eee674430fb98ef5143bc876167"} 08:48:09 INFO - Running post-run listener: copy_logs_to_upload_dir 08:48:09 INFO - Copying logs to upload dir... 08:48:09 INFO - mkdir: /builds/slave/test/build/upload/logs 08:48:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.327834 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-16 08:48:08.926432) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:08.929971) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/824eb8990da545be4bfad6d2d5c519ee68755681f9c49b737053e5dda0e43802caa2e554758ea58f0e3bf8a1938c4f67911294a1a815baae7da197dcc917c900", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dc0b78b266ea7b7e1621ee45ebf26f4eb318961be9e4cfa1dc0ce1c849beda868d60711e36f43d0567eef1e2c47a0aeb90d2fa84b8007d845d7f8df3f008560b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90b25ae2db219b9d3e8d33a9fdc14256fdab3a8a1f773504fc07e8c44a3c101d051c3ca7e1a439fd3f2ddac4b436199771100eee674430fb98ef5143bc876167"} build_url:https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/Oze2vKL_RH2oLoxq7LjV1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/824eb8990da545be4bfad6d2d5c519ee68755681f9c49b737053e5dda0e43802caa2e554758ea58f0e3bf8a1938c4f67911294a1a815baae7da197dcc917c900", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/dc0b78b266ea7b7e1621ee45ebf26f4eb318961be9e4cfa1dc0ce1c849beda868d60711e36f43d0567eef1e2c47a0aeb90d2fa84b8007d845d7f8df3f008560b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90b25ae2db219b9d3e8d33a9fdc14256fdab3a8a1f773504fc07e8c44a3c101d051c3ca7e1a439fd3f2ddac4b436199771100eee674430fb98ef5143bc876167"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:08.954656) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:08.954989) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1463411942.120311-1907427062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.039463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 08:48:09.039788) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 08:48:09.040076) ========= ========= Total master_lag: 0.18 =========