builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1463422727.04 results: success (0) buildid: 20160516083029 builduid: 8f664dff4974456ab19959ee3e861b6c revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.038268) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.039340) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.040213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.072617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.072928) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 11:18:47-- 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% 30.7M=0s 2016-05-16 11:18:47 (30.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118924 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.205780) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.206144) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018493 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:47.258756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 11:18:47.259557) ========= 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=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 11:18:47,663 truncating revision to first 12 chars 2016-05-16 11:18:47,663 Setting DEBUG logging. 2016-05-16 11:18:47,663 attempt 1/10 2016-05-16 11:18:47,663 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 11:18:48,901 unpacking tar archive at: fx-team-e9533997fcef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.527180 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 11:18:48.806145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:48.806914) ========= script_repo_revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:48.807862) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:48.808584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 11:18:48.819937) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-16 11:18:48.820260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:18:49 INFO - MultiFileLogger online at 20160516 11:18:49 in /builds/slave/test 11:18:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:18:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:18:49 INFO - {'append_to_log': False, 11:18:49 INFO - 'base_work_dir': '/builds/slave/test', 11:18:49 INFO - 'blob_upload_branch': 'Fx-Team', 11:18:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:18:49 INFO - 'branch': 'Fx-Team', 11:18:49 INFO - 'buildbot_json_path': 'buildprops.json', 11:18:49 INFO - 'config_files': ('talos/linux_config.py',), 11:18:49 INFO - 'default_actions': ('clobber', 11:18:49 INFO - 'read-buildbot-config', 11:18:49 INFO - 'download-and-extract', 11:18:49 INFO - 'populate-webroot', 11:18:49 INFO - 'create-virtualenv', 11:18:49 INFO - 'install', 11:18:49 INFO - 'run-tests'), 11:18:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:18:49 INFO - 'download_minidump_stackwalk': True, 11:18:49 INFO - 'download_symbols': 'ondemand', 11:18:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:18:49 INFO - 'tooltool.py': '/tools/tooltool.py', 11:18:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:18:49 INFO - '/tools/misc-python/virtualenv.py')}, 11:18:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:18:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:18:49 INFO - 'installer_path': 'installer.exe', 11:18:49 INFO - 'log_level': 'info', 11:18:49 INFO - 'log_name': 'talos', 11:18:49 INFO - 'log_to_console': True, 11:18:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:18:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:18:49 INFO - 'opt_config_files': (), 11:18:49 INFO - 'pip_index': False, 11:18:49 INFO - 'sps_profile': False, 11:18:49 INFO - 'sps_profile_interval': 0, 11:18:49 INFO - 'suite': 'dromaeojs', 11:18:49 INFO - 'system_bits': '32', 11:18:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:18:49 INFO - 'title': 'talos-linux64-ix-031', 11:18:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:18:49 INFO - 'use_talos_json': True, 11:18:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:18:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:18:49 INFO - 'work_dir': 'build'} 11:18:49 INFO - ##### 11:18:49 INFO - ##### Running clobber step. 11:18:49 INFO - ##### 11:18:49 INFO - Running pre-action listener: _resource_record_pre_action 11:18:49 INFO - Running main action method: clobber 11:18:49 INFO - rmtree: /builds/slave/test/build 11:18:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:18:49 INFO - Running post-action listener: _resource_record_post_action 11:18:49 INFO - ##### 11:18:49 INFO - ##### Finished clobber step (success) 11:18:49 INFO - ##### 11:18:49 INFO - ##### 11:18:49 INFO - ##### Running read-buildbot-config step. 11:18:49 INFO - ##### 11:18:49 INFO - Running pre-action listener: _resource_record_pre_action 11:18:49 INFO - Running main action method: read_buildbot_config 11:18:49 INFO - Using buildbot properties: 11:18:49 INFO - { 11:18:49 INFO - "project": "", 11:18:49 INFO - "product": "firefox", 11:18:49 INFO - "script_repo_revision": "production", 11:18:49 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 11:18:49 INFO - "repository": "", 11:18:49 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 11:18:49 INFO - "buildid": "20160516083029", 11:18:49 INFO - "pgo_build": "True", 11:18:49 INFO - "basedir": "/builds/slave/test", 11:18:49 INFO - "buildnumber": 686, 11:18:49 INFO - "slavename": "talos-linux64-ix-031", 11:18:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:18:49 INFO - "platform": "ubuntu64_hw", 11:18:49 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 11:18:49 INFO - "branch": "fx-team", 11:18:49 INFO - "repo_path": "integration/fx-team", 11:18:49 INFO - "revision": "e9533997fcef31fa5d2db464c6ceec0ca6ea560f", 11:18:49 INFO - "stage_platform": "linux64-pgo", 11:18:49 INFO - "builduid": "8f664dff4974456ab19959ee3e861b6c", 11:18:49 INFO - "slavebuilddir": "test" 11:18:49 INFO - } 11:18:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 11:18:49 INFO - Running post-action listener: _resource_record_post_action 11:18:49 INFO - ##### 11:18:49 INFO - ##### Finished read-buildbot-config step (success) 11:18:49 INFO - ##### 11:18:49 INFO - ##### 11:18:49 INFO - ##### Running download-and-extract step. 11:18:49 INFO - ##### 11:18:49 INFO - Running pre-action listener: _resource_record_pre_action 11:18:49 INFO - Running main action method: download_and_extract 11:18:49 INFO - mkdir: /builds/slave/test/build/tests 11:18:49 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')]} 11:18:49 INFO - https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:18:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:18:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:18:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:51 INFO - Downloaded 1361 bytes. 11:18:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:18:51 INFO - Using the following test package requirements: 11:18:51 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:18:51 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:18:51 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:18:51 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'jsshell-linux-x86_64.zip'], 11:18:51 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:18:51 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:18:51 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:18:51 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:18:51 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:18:51 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:18:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:18: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')]} 11:18:51 INFO - https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:18:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:18:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:18:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:52 INFO - Downloaded 24023786 bytes. 11:18:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:18:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:18:53 INFO - Return code: 0 11:18:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:53 INFO - https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:18:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:18:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:18:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:54 INFO - Downloaded 24023786 bytes. 11:18:54 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'] 11:18:54 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 11:18:56 INFO - Return code: 0 11:18:56 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')]} 11:18:56 INFO - https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:57 INFO - Downloaded 10840194 bytes. 11:18:57 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'] 11:18:57 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 11:18:57 INFO - Return code: 0 11:18: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')]} 11:18:57 INFO - https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:18:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:18:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:18:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H67eVTFCRjyrwtKgE6ydzA/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 11:19:04 INFO - Downloaded 58395842 bytes. 11:19:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:19:04 INFO - mkdir: /builds/slave/test/properties 11:19:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:19:04 INFO - Writing to file /builds/slave/test/properties/build_url 11:19:04 INFO - Contents: 11:19:04 INFO - build_url:https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:19:05 INFO - Running post-action listener: _resource_record_post_action 11:19:05 INFO - Running post-action listener: set_extra_try_arguments 11:19:05 INFO - ##### 11:19:05 INFO - ##### Finished download-and-extract step (success) 11:19:05 INFO - ##### 11:19:05 INFO - ##### 11:19:05 INFO - ##### Running populate-webroot step. 11:19:05 INFO - ##### 11:19:05 INFO - Running pre-action listener: _resource_record_pre_action 11:19:05 INFO - Running main action method: populate_webroot 11:19:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:19:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:19:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:19:05 INFO - '--tppagecycles', 11:19:05 INFO - '7'], 11:19:05 INFO - 'tests': ['tsvgm']}}, 11:19:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:19:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:19:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:19:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:19:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['damp', 'tps']}, 11:19:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'tests': ['damp', 'tps']}, 11:19:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['dromaeo_dom']}, 11:19:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:19:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'other-e10s': {'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['a11yr', 11:19:05 INFO - 'ts_paint', 11:19:05 INFO - 'tpaint', 11:19:05 INFO - 'sessionrestore', 11:19:05 INFO - 'sessionrestore_no_auto_restore', 11:19:05 INFO - 'tabpaint']}, 11:19:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['tsvgx', 11:19:05 INFO - 'tsvgr_opacity', 11:19:05 INFO - 'tart', 11:19:05 INFO - 'tscrollx', 11:19:05 INFO - 'cart']}, 11:19:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:19:05 INFO - 'tsvgr_opacity', 11:19:05 INFO - 'tart', 11:19:05 INFO - 'tscrollx', 11:19:05 INFO - 'cart']}, 11:19:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'talos_options': ['--disable-e10s'], 11:19:05 INFO - 'tests': ['tp5o']}, 11:19:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'tests': ['tp5o']}, 11:19:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'talos_options': ['--disable-e10s', 11:19:05 INFO - '--xperf_path', 11:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:05 INFO - 'tests': ['tp5n']}, 11:19:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:05 INFO - 'talos_options': ['--xperf_path', 11:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:05 INFO - 'tests': ['tp5n']}}, 11:19:05 INFO - 'talos.zip': {'path': '', 11:19:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:19:05 INFO - Running post-action listener: _resource_record_post_action 11:19:05 INFO - ##### 11:19:05 INFO - ##### Finished populate-webroot step (success) 11:19:05 INFO - ##### 11:19:05 INFO - ##### 11:19:05 INFO - ##### Running create-virtualenv step. 11:19:05 INFO - ##### 11:19:05 INFO - Running pre-action listener: _resource_record_pre_action 11:19:05 INFO - Running main action method: create_virtualenv 11:19:05 INFO - Creating virtualenv /builds/slave/test/build/venv 11:19:05 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 11:19:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:19:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:19:05 INFO - Using real prefix '/usr' 11:19:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:19:05 INFO - Installing distribute.............................................................................................................................................................................................done. 11:19:07 INFO - Installing pip.................done. 11:19:07 INFO - Return code: 0 11:19:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:19:07 INFO - Using env: {'DISPLAY': ':0', 11:19:07 INFO - 'HOME': '/home/cltbld', 11:19:07 INFO - 'LANG': 'en_US.UTF-8', 11:19:07 INFO - 'LANGUAGE': 'en_US:en', 11:19:07 INFO - 'LOGNAME': 'cltbld', 11:19:07 INFO - 'MAIL': '/var/mail/cltbld', 11:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:07 INFO - 'MOZ_NO_REMOTE': '1', 11:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:07 INFO - 'NO_EM_RESTART': '1', 11:19:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:07 INFO - 'PWD': '/builds/slave/test', 11:19:07 INFO - 'SHELL': '/bin/bash', 11:19:07 INFO - 'SHLVL': '1', 11:19:07 INFO - 'TERM': 'linux', 11:19:07 INFO - 'TMOUT': '86400', 11:19:07 INFO - 'USER': 'cltbld', 11:19:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:07 INFO - '_': '/tools/buildbot/bin/python'} 11:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:19:07 INFO - Cleaning up... 11:19:07 INFO - Return code: 0 11:19:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:19:07 INFO - Using env: {'DISPLAY': ':0', 11:19:07 INFO - 'HOME': '/home/cltbld', 11:19:07 INFO - 'LANG': 'en_US.UTF-8', 11:19:07 INFO - 'LANGUAGE': 'en_US:en', 11:19:07 INFO - 'LOGNAME': 'cltbld', 11:19:07 INFO - 'MAIL': '/var/mail/cltbld', 11:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:07 INFO - 'MOZ_NO_REMOTE': '1', 11:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:07 INFO - 'NO_EM_RESTART': '1', 11:19:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:07 INFO - 'PWD': '/builds/slave/test', 11:19:07 INFO - 'SHELL': '/bin/bash', 11:19:07 INFO - 'SHLVL': '1', 11:19:07 INFO - 'TERM': 'linux', 11:19:07 INFO - 'TMOUT': '86400', 11:19:07 INFO - 'USER': 'cltbld', 11:19:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:07 INFO - '_': '/tools/buildbot/bin/python'} 11:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:08 INFO - Downloading/unpacking psutil>=3.1.1 11:19:08 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 11:19:08 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 11:19:08 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 11:19:08 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 11:19:08 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 11:19:08 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 11:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:10 INFO - Installing collected packages: psutil 11:19:10 INFO - Running setup.py install for psutil 11:19:10 INFO - building 'psutil._psutil_linux' extension 11:19:10 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 11:19:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:19:10 INFO - building 'psutil._psutil_posix' extension 11:19:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:19:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:10 INFO - Successfully installed psutil 11:19:10 INFO - Cleaning up... 11:19:10 INFO - Return code: 0 11:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:19:10 INFO - Using env: {'DISPLAY': ':0', 11:19:10 INFO - 'HOME': '/home/cltbld', 11:19:10 INFO - 'LANG': 'en_US.UTF-8', 11:19:10 INFO - 'LANGUAGE': 'en_US:en', 11:19:10 INFO - 'LOGNAME': 'cltbld', 11:19:10 INFO - 'MAIL': '/var/mail/cltbld', 11:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:10 INFO - 'MOZ_NO_REMOTE': '1', 11:19:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:10 INFO - 'NO_EM_RESTART': '1', 11:19:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:10 INFO - 'PWD': '/builds/slave/test', 11:19:10 INFO - 'SHELL': '/bin/bash', 11:19:10 INFO - 'SHLVL': '1', 11:19:10 INFO - 'TERM': 'linux', 11:19:10 INFO - 'TMOUT': '86400', 11:19:10 INFO - 'USER': 'cltbld', 11:19:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:10 INFO - '_': '/tools/buildbot/bin/python'} 11:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:19:12 INFO - Installing collected packages: mozsystemmonitor 11:19:12 INFO - Running setup.py install for mozsystemmonitor 11:19:12 INFO - Successfully installed mozsystemmonitor 11:19:12 INFO - Cleaning up... 11:19:12 INFO - Return code: 0 11:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:19:12 INFO - Using env: {'DISPLAY': ':0', 11:19:12 INFO - 'HOME': '/home/cltbld', 11:19:12 INFO - 'LANG': 'en_US.UTF-8', 11:19:12 INFO - 'LANGUAGE': 'en_US:en', 11:19:12 INFO - 'LOGNAME': 'cltbld', 11:19:12 INFO - 'MAIL': '/var/mail/cltbld', 11:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:12 INFO - 'MOZ_NO_REMOTE': '1', 11:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:12 INFO - 'NO_EM_RESTART': '1', 11:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:12 INFO - 'PWD': '/builds/slave/test', 11:19:12 INFO - 'SHELL': '/bin/bash', 11:19:12 INFO - 'SHLVL': '1', 11:19:12 INFO - 'TERM': 'linux', 11:19:12 INFO - 'TMOUT': '86400', 11:19:12 INFO - 'USER': 'cltbld', 11:19:12 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:12 INFO - '_': '/tools/buildbot/bin/python'} 11:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:19:13 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 11:19:13 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 11:19:13 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 11:19:13 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 11:19:13 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 11:19:13 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 11:19:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:19:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:15 INFO - Downloading docopt-0.6.1.tar.gz 11:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:19:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:19:15 INFO - Running setup.py install for blobuploader 11:19:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:19:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:19:15 INFO - Running setup.py install for requests 11:19:16 INFO - Running setup.py install for docopt 11:19:16 INFO - Successfully installed blobuploader requests docopt 11:19:16 INFO - Cleaning up... 11:19:16 INFO - Return code: 0 11:19:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:16 INFO - Using env: {'DISPLAY': ':0', 11:19:16 INFO - 'HOME': '/home/cltbld', 11:19:16 INFO - 'LANG': 'en_US.UTF-8', 11:19:16 INFO - 'LANGUAGE': 'en_US:en', 11:19:16 INFO - 'LOGNAME': 'cltbld', 11:19:16 INFO - 'MAIL': '/var/mail/cltbld', 11:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:16 INFO - 'MOZ_NO_REMOTE': '1', 11:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:16 INFO - 'NO_EM_RESTART': '1', 11:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:16 INFO - 'PWD': '/builds/slave/test', 11:19:16 INFO - 'SHELL': '/bin/bash', 11:19:16 INFO - 'SHLVL': '1', 11:19:16 INFO - 'TERM': 'linux', 11:19:16 INFO - 'TMOUT': '86400', 11:19:16 INFO - 'USER': 'cltbld', 11:19:16 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:16 INFO - '_': '/tools/buildbot/bin/python'} 11:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:16 INFO - Running setup.py (path:/tmp/pip-pSzgpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:16 INFO - Running setup.py (path:/tmp/pip-r2Wgwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:16 INFO - Running setup.py (path:/tmp/pip-HYczSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:16 INFO - Running setup.py (path:/tmp/pip-AwmPVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:16 INFO - Running setup.py (path:/tmp/pip-MlSSnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:16 INFO - Running setup.py (path:/tmp/pip-n4wKsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:16 INFO - Running setup.py (path:/tmp/pip-yn_ReK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:17 INFO - Running setup.py (path:/tmp/pip-8Txdtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:17 INFO - Running setup.py (path:/tmp/pip-WZaXFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:17 INFO - Running setup.py (path:/tmp/pip-T8AfTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:17 INFO - Running setup.py (path:/tmp/pip-3gilkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:17 INFO - Running setup.py (path:/tmp/pip-LlpRzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:17 INFO - Running setup.py (path:/tmp/pip-v_CzQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:17 INFO - Running setup.py (path:/tmp/pip-2HMcoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:17 INFO - Running setup.py (path:/tmp/pip-g8gyEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:17 INFO - Running setup.py (path:/tmp/pip-J8ELcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:17 INFO - Running setup.py (path:/tmp/pip-5PqLT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:19:17 INFO - Running setup.py install for manifestparser 11:19:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:19:17 INFO - Running setup.py install for mozcrash 11:19:17 INFO - Running setup.py install for mozdebug 11:19:18 INFO - Running setup.py install for mozdevice 11:19:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for mozfile 11:19:18 INFO - Running setup.py install for mozhttpd 11:19:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for mozinfo 11:19:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for mozInstall 11:19:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for mozleak 11:19:18 INFO - Running setup.py install for mozlog 11:19:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for moznetwork 11:19:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:19:18 INFO - Running setup.py install for mozprocess 11:19:18 INFO - Running setup.py install for mozprofile 11:19:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:19:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:19:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:19:19 INFO - Running setup.py install for mozrunner 11:19:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:19:19 INFO - Running setup.py install for mozscreenshot 11:19:19 INFO - Running setup.py install for moztest 11:19:19 INFO - Running setup.py install for mozversion 11:19:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:19:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:19:19 INFO - Cleaning up... 11:19:19 INFO - Return code: 0 11:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:19 INFO - Using env: {'DISPLAY': ':0', 11:19:19 INFO - 'HOME': '/home/cltbld', 11:19:19 INFO - 'LANG': 'en_US.UTF-8', 11:19:19 INFO - 'LANGUAGE': 'en_US:en', 11:19:19 INFO - 'LOGNAME': 'cltbld', 11:19:19 INFO - 'MAIL': '/var/mail/cltbld', 11:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:19 INFO - 'MOZ_NO_REMOTE': '1', 11:19:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:19 INFO - 'NO_EM_RESTART': '1', 11:19:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:19 INFO - 'PWD': '/builds/slave/test', 11:19:19 INFO - 'SHELL': '/bin/bash', 11:19:19 INFO - 'SHLVL': '1', 11:19:19 INFO - 'TERM': 'linux', 11:19:19 INFO - 'TMOUT': '86400', 11:19:19 INFO - 'USER': 'cltbld', 11:19:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:19 INFO - '_': '/tools/buildbot/bin/python'} 11:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:19 INFO - Running setup.py (path:/tmp/pip-chlfBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:19 INFO - Running setup.py (path:/tmp/pip-SS4Zbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:19 INFO - Running setup.py (path:/tmp/pip-p_g4dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:19 INFO - Running setup.py (path:/tmp/pip-7fBGdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:20 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)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:20 INFO - Running setup.py (path:/tmp/pip-yyMWEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:20 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)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:20 INFO - Running setup.py (path:/tmp/pip-1HNVFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:20 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)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:20 INFO - Running setup.py (path:/tmp/pip-sBHMAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:20 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)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:20 INFO - Running setup.py (path:/tmp/pip-0Zfyh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:20 INFO - Running setup.py (path:/tmp/pip-lVMLiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:20 INFO - Running setup.py (path:/tmp/pip-gjloue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:20 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)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:20 INFO - Running setup.py (path:/tmp/pip-ppMrm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:20 INFO - Running setup.py (path:/tmp/pip-nQFfcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:20 INFO - Running setup.py (path:/tmp/pip-uGhnzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:20 INFO - Running setup.py (path:/tmp/pip-kecVa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:20 INFO - Running setup.py (path:/tmp/pip-5yREbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:20 INFO - Running setup.py (path:/tmp/pip-8gmW1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:20 INFO - Running setup.py (path:/tmp/pip-OtTo2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:21 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)) 11:19:21 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)) 11:19:21 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)) 11:19:21 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)) 11:19:21 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)) 11:19:21 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)) 11:19:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:21 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 11:19:21 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 11:19:21 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 11:19:21 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 11:19:21 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 11:19:21 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 11:19:22 INFO - Downloading blessings-1.6.tar.gz 11:19:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:19:22 INFO - Installing collected packages: blessings 11:19:22 INFO - Running setup.py install for blessings 11:19:23 INFO - Successfully installed blessings 11:19:23 INFO - Cleaning up... 11:19:23 INFO - Return code: 0 11:19:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:23 INFO - Reading from file tmpfile_stdout 11:19:23 INFO - Current package versions: 11:19:23 INFO - argparse == 1.2.1 11:19:23 INFO - blessings == 1.6 11:19:23 INFO - blobuploader == 1.2.4 11:19:23 INFO - docopt == 0.6.1 11:19:23 INFO - manifestparser == 1.1 11:19:23 INFO - mozInstall == 1.12 11:19:23 INFO - mozcrash == 0.17 11:19:23 INFO - mozdebug == 0.1 11:19:23 INFO - mozdevice == 0.48 11:19:23 INFO - mozfile == 1.2 11:19:23 INFO - mozhttpd == 0.7 11:19:23 INFO - mozinfo == 0.9 11:19:23 INFO - mozleak == 0.1 11:19:23 INFO - mozlog == 3.2 11:19:23 INFO - moznetwork == 0.27 11:19:23 INFO - mozprocess == 0.22 11:19:23 INFO - mozprofile == 0.28 11:19:23 INFO - mozrunner == 6.11 11:19:23 INFO - mozscreenshot == 0.1 11:19:23 INFO - mozsystemmonitor == 0.3 11:19:23 INFO - moztest == 0.7 11:19:23 INFO - mozversion == 1.4 11:19:23 INFO - psutil == 3.1.1 11:19:23 INFO - requests == 1.2.3 11:19:23 INFO - wsgiref == 0.1.2 11:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:23 INFO - Using env: {'DISPLAY': ':0', 11:19:23 INFO - 'HOME': '/home/cltbld', 11:19:23 INFO - 'LANG': 'en_US.UTF-8', 11:19:23 INFO - 'LANGUAGE': 'en_US:en', 11:19:23 INFO - 'LOGNAME': 'cltbld', 11:19:23 INFO - 'MAIL': '/var/mail/cltbld', 11:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:23 INFO - 'MOZ_NO_REMOTE': '1', 11:19:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:23 INFO - 'NO_EM_RESTART': '1', 11:19:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:23 INFO - 'PWD': '/builds/slave/test', 11:19:23 INFO - 'SHELL': '/bin/bash', 11:19:23 INFO - 'SHLVL': '1', 11:19:23 INFO - 'TERM': 'linux', 11:19:23 INFO - 'TMOUT': '86400', 11:19:23 INFO - 'USER': 'cltbld', 11:19:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:23 INFO - '_': '/tools/buildbot/bin/python'} 11:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:23 INFO - Cleaning up... 11:19:23 INFO - Return code: 0 11:19:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:19:23 INFO - Using env: {'DISPLAY': ':0', 11:19:23 INFO - 'HOME': '/home/cltbld', 11:19:23 INFO - 'LANG': 'en_US.UTF-8', 11:19:23 INFO - 'LANGUAGE': 'en_US:en', 11:19:23 INFO - 'LOGNAME': 'cltbld', 11:19:23 INFO - 'MAIL': '/var/mail/cltbld', 11:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:23 INFO - 'MOZ_NO_REMOTE': '1', 11:19:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:23 INFO - 'NO_EM_RESTART': '1', 11:19:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:23 INFO - 'PWD': '/builds/slave/test', 11:19:23 INFO - 'SHELL': '/bin/bash', 11:19:23 INFO - 'SHLVL': '1', 11:19:23 INFO - 'TERM': 'linux', 11:19:23 INFO - 'TMOUT': '86400', 11:19:23 INFO - 'USER': 'cltbld', 11:19:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:23 INFO - '_': '/tools/buildbot/bin/python'} 11:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:23 INFO - Downloading/unpacking jsonschema 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:19:25 INFO - Downloading/unpacking functools32 (from jsonschema) 11:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:19:25 INFO - warning: no files found matching '*.txt' 11:19:25 INFO - no previously-included directories found matching 'build' 11:19:25 INFO - no previously-included directories found matching 'dist' 11:19:25 INFO - no previously-included directories found matching '.git*' 11:19:25 INFO - Installing collected packages: jsonschema, functools32 11:19:25 INFO - Running setup.py install for functools32 11:19:26 INFO - warning: no files found matching '*.txt' 11:19:26 INFO - no previously-included directories found matching 'build' 11:19:26 INFO - no previously-included directories found matching 'dist' 11:19:26 INFO - no previously-included directories found matching '.git*' 11:19:26 INFO - Successfully installed jsonschema functools32 11:19:26 INFO - Cleaning up... 11:19:26 INFO - Return code: 0 11:19:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:19:26 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')]} 11:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:26 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')]} 11:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:26 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 0x7f3b67e9af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', '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 11:19:26 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 11:19:26 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 11:19:26 INFO - Using env: {'DISPLAY': ':0', 11:19:26 INFO - 'HOME': '/home/cltbld', 11:19:26 INFO - 'LANG': 'en_US.UTF-8', 11:19:26 INFO - 'LANGUAGE': 'en_US:en', 11:19:26 INFO - 'LOGNAME': 'cltbld', 11:19:26 INFO - 'MAIL': '/var/mail/cltbld', 11:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:26 INFO - 'MOZ_NO_REMOTE': '1', 11:19:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:26 INFO - 'NO_EM_RESTART': '1', 11:19:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:26 INFO - 'PWD': '/builds/slave/test', 11:19:26 INFO - 'SHELL': '/bin/bash', 11:19:26 INFO - 'SHLVL': '1', 11:19:26 INFO - 'TERM': 'linux', 11:19:26 INFO - 'TMOUT': '86400', 11:19:26 INFO - 'USER': 'cltbld', 11:19:26 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:26 INFO - '_': '/tools/buildbot/bin/python'} 11:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:26 INFO - Downloading/unpacking flake8 11:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:19:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:19:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:19:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:19:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:19:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:19:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:19:28 INFO - no previously-included directories found matching 'docs/_build' 11:19:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:19:29 INFO - Running setup.py install for pep8 11:19:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:19:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:19:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:19:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:19:29 INFO - no previously-included directories found matching 'docs/_build' 11:19:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:19:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:19:29 INFO - Cleaning up... 11:19:30 INFO - Return code: 0 11:19:30 INFO - Running post-action listener: _resource_record_post_action 11:19:30 INFO - Running post-action listener: _start_resource_monitoring 11:19:30 INFO - Starting resource monitoring. 11:19:30 INFO - ##### 11:19:30 INFO - ##### Finished create-virtualenv step (success) 11:19:30 INFO - ##### 11:19:30 INFO - ##### 11:19:30 INFO - ##### Running install step. 11:19:30 INFO - ##### 11:19:30 INFO - Running pre-action listener: _resource_record_pre_action 11:19:30 INFO - Running main action method: install 11:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:30 INFO - Reading from file tmpfile_stdout 11:19:30 INFO - Detecting whether we're running mozinstall >=1.0... 11:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:19:30 INFO - Reading from file tmpfile_stdout 11:19:30 INFO - Output received: 11:19:30 INFO - Usage: mozinstall [options] installer 11:19:30 INFO - Options: 11:19:30 INFO - -h, --help show this help message and exit 11:19:30 INFO - -d DEST, --destination=DEST 11:19:30 INFO - Directory to install application into. [default: 11:19:30 INFO - "/builds/slave/test"] 11:19:30 INFO - --app=APP Application being installed. [default: firefox] 11:19:30 INFO - mkdir: /builds/slave/test/build/application 11:19:30 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'] 11:19:30 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 11:19:46 INFO - Reading from file tmpfile_stdout 11:19:46 INFO - Output received: 11:19:46 INFO - /builds/slave/test/build/application/firefox/firefox 11:19:46 INFO - Running post-action listener: _resource_record_post_action 11:19:46 INFO - ##### 11:19:46 INFO - ##### Finished install step (success) 11:19:46 INFO - ##### 11:19:46 INFO - ##### 11:19:46 INFO - ##### Running run-tests step. 11:19:46 INFO - ##### 11:19:46 INFO - Running pre-action listener: _resource_record_pre_action 11:19:46 INFO - Running main action method: run_tests 11:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:19:46 INFO - Python 2.7.3 11:19:46 INFO - Return code: 0 11:19:46 INFO - grabbing minidump binary from tooltool 11:19:46 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')]} 11:19:46 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 0x137e9c0>, '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 0x137f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137fd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:19:46 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 11:19:46 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 11:19:46 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 11:19:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:19:46 INFO - Return code: 0 11:19:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:19:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:19:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:19:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:19:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:19:47 INFO - Return code: 0 11:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:19:47 INFO - Using env: {'DISPLAY': ':0', 11:19:47 INFO - 'HOME': '/home/cltbld', 11:19:47 INFO - 'LANG': 'en_US.UTF-8', 11:19:47 INFO - 'LANGUAGE': 'en_US:en', 11:19:47 INFO - 'LOGNAME': 'cltbld', 11:19:47 INFO - 'MAIL': '/var/mail/cltbld', 11:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:47 INFO - 'MOZ_NO_REMOTE': '1', 11:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:47 INFO - 'NO_EM_RESTART': '1', 11:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:47 INFO - 'PWD': '/builds/slave/test', 11:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:19:47 INFO - 'SHELL': '/bin/bash', 11:19:47 INFO - 'SHLVL': '1', 11:19:47 INFO - 'TERM': 'linux', 11:19:47 INFO - 'TMOUT': '86400', 11:19:47 INFO - 'USER': 'cltbld', 11:19:47 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459', 11:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:47 INFO - '_': '/tools/buildbot/bin/python'} 11:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:19:48 INFO - mozversion application_buildid: 20160516083029 11:19:48 INFO - mozversion application_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 11:19:48 INFO - mozversion application_display_name: Nightly 11:19:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:19:48 INFO - mozversion application_name: Firefox 11:19:48 INFO - mozversion application_remotingname: firefox 11:19:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:19:48 INFO - mozversion application_vendor: Mozilla 11:19:48 INFO - mozversion application_version: 49.0a1 11:19:48 INFO - mozversion platform_buildid: 20160516083029 11:19:48 INFO - mozversion platform_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 11:19:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:19:48 INFO - mozversion platform_version: 49.0a1 11:19:48 INFO - using testdate: 1463422788 11:19:48 INFO - actual date: 1463422788 11:19:48 INFO - starting webserver on 'localhost:45097' 11:19:48 INFO - SUITE-START | Running 2 tests 11:19:48 INFO - TEST-START | dromaeo_css 11:19:48 INFO - operating with platform_type : linux_ 11:19:48 INFO - Initialising browser for dromaeo_css test... 11:19:48 INFO - TEST-INFO | started process 27237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9n7hJq/profile http://localhost:45097/getInfo.html) 11:19:52 INFO - PROCESS | 27237 | __metrics Screen width/height:1600/1200 11:19:52 INFO - PROCESS | 27237 | colorDepth:24 11:19:52 INFO - PROCESS | 27237 | Browser inner width/height: 1024/697 11:19:52 INFO - PROCESS | 27237 | __metrics 11:19:53 ERROR - PROCESS | 27237 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:19:53 INFO - TEST-INFO | 27237: exit 0 11:19:53 INFO - Browser initialized. 11:19:53 INFO - Running cycle 1/1 for dromaeo_css test... 11:19:53 INFO - TEST-INFO | started process 27317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9n7hJq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:20:05 INFO - PROCESS | 27317 | RSS: Main: 152416256 11:20:05 INFO - PROCESS | 27317 | 11:20:57 INFO - PROCESS | 27317 | 1463422857446 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] 11:23:05 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45097/tests/dromaeo/cssquery-ext.html) 11:23:06 INFO - PROCESS | 27317 | RSS: Main: 249544704 11:23:06 INFO - PROCESS | 27317 | 11:26:08 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-ext.html (next: http://localhost:45097/tests/dromaeo/cssquery-jquery.html) 11:26:09 INFO - PROCESS | 27317 | RSS: Main: 235573248 11:26:09 INFO - PROCESS | 27317 | 11:29:11 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45097/tests/dromaeo/cssquery-mootools.html) 11:29:11 INFO - PROCESS | 27317 | RSS: Main: 237338624 11:29:11 INFO - PROCESS | 27317 | 11:32:13 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45097/tests/dromaeo/cssquery-prototype.html) 11:32:14 INFO - PROCESS | 27317 | RSS: Main: 224452608 11:32:14 INFO - PROCESS | 27317 | 11:35:16 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45097/tests/dromaeo/cssquery-yui.html) 11:35:17 INFO - PROCESS | 27317 | RSS: Main: 225964032 11:35:17 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | Cycle 1(1): loaded http://localhost:45097/tests/dromaeo/cssquery-yui.html (next: http://localhost:45097/tests/dromaeo/cssquery-dojo.html) 11:38:20 INFO - PROCESS | 27317 | RSS: Main: 270258176 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | __start_tp_report 11:38:20 INFO - PROCESS | 27317 | _x_x_mozilla_page_load 11:38:20 INFO - PROCESS | 27317 | _x_x_mozilla_page_load_details 11:38:20 INFO - PROCESS | 27317 | |i|pagename|runs| 11:38:20 INFO - PROCESS | 27317 | |0;dojo.html;3149.9999999999995;3183.1246407238004;3191.1224413286345;3212.983935080324;3237.125975986484;5316.946830531693;5323.361196656402;5372.059889519328;5446.972765136174;5462.754176062077;5275.894482110365;5333.679979201251;5346;5349.010433069875;5355.919661205085;4781.952180478194;4867.2942423348595;4869.659123861331;4925.458199598042;4972.627052971024;4915.188993816016;4959.082569724613;4973.3286006389035;4989.75051247438;5032.396112466509;4793.544613261752;4794.184988551954;4840.661153719224;4876.756162191876;4881.511848815126;4781.402324709411;4791.209450440673;4802.087603355351;4863.635227357962;4907.386576677915;4837.782299796518;4850.393700787402;4857;4858.78135483904;4860.635452341088;5202.297689811865;5256.237845512379;5336.519713225828;5337.066013447631;5423.457654234585;5804;5858.062709966428;5878.588498805086;5888.205092312526;5895.793647222326;5566.666000040011;5905.557083218733;5928.614640048383;5930.673812940287;5933.080372542263;5330.253764472977;5386.380566234894;5388.838334849922;5395;5441.510264076292;5953.672548009901;5970.671613061237;5980.252468441445;5988.3113441953865;5989.610675306091;5462;5571.609987300931;5746.856328591735;5746.88506229873;5752.626079304831;5690.118031705092;5749.022666146765;5751.654900705971;5758.856028599319;5820.447057529528;5726.398728133569;5744.052231381859;5745.13822926556;5755.0791873300905;5764.337101233412;5452.482014208644;5620.718964051781;5658.151277308437;5744.052231381859;5753.913691294634;4337.110892267141;4339.262325404688;4358.65130789536;4392.670549708785;4400.779961001937;3889.358255887747;3899.1616802387057;3912.902177445587;3924.0582260257333;3934.3311637021775;5253.711045892436;5276.287701160371;5320.12217984036;5391.892162156735;5408.053590621704;5788.3343415506915;5797.391273916262;5800.361960184377;5815.04051831443;5849.532037436995;5363.517283444431;5412.323459567554;5417.404085550586;5432.565394768409;5440.809571664972;4858.198397264482;4899.6570240083565;4932.334134891816;4945.406551213878;4981.501849815062;5949.0481522956115;5949.077892926603;5950.494207992369;5950.702464876739;5953.553483488756;5664.5751568631695;5764.106563482667;5774.971125144348;5792.420757924174;5794.0439827428;5895.145203945318;5980.760769569169;5996.790112346047;6010.2186715726675;6034.426729460694;5720.17057526648;5722.799702010409;5728.971355143198;5729.369769325454;5731;5491.066518691912;5705.686187259741;5802.738876750472;5835.766569337179;5848.327442344185;5593.692346920959;5704.771809127588;5719.628224165416;5756.65460072397;5762.164486149569;5684.090545512863;5688.544916406595;5693.459121383462;5693.5445164387575;5697.116946873241;5375.650582712111;5451.236826844166;5551.861203469945;5614.803481878114;5622.522085622604;5614.775408983595;5715.371309155907;5725.370209277059;5739.282589676291;5744.167095771007;5542.140968149944;5551.333839939233;5560.777568897199;5568.136938774496;5580.553555715452 11:38:20 INFO - PROCESS | 27317 | |1;ext.html;597.742970522675;615.9876802463949;637.4358692557086;649.2112083818162;650.343153415051;33791;44992.77503612481;45802;45864.54135458644;45924;45964.310535341916;46253.30620040702;46423.767881160544;46441.76779116108;46503.534964650346;8179.713710020151;8919.331050171231;9106.544672766368;9107.271418286522;10448.947755261213;10462.738431539196;10620.309679870823;10620.415877126756;10625.681229563126;10625.840612390783;10737.570497180142;10762.461876906162;10781.514831832546;10784;10785;8152.1440248773615;8327.37544684145;8329.37529685276;10644.41455719935;10651.147908167328;10494.265401421904;10540.367577945348;10556.524956376981;10559.41923194224;10562;41838.37242441366;42286.57713422857;42329.1534169318;42348.15303693909;42352.576474235175;3557.0929412999776;3598.2623562169692;3613.656702613274;3636.6363363663686;3697.8335974881193;31888;32527.837360813282;32604.347913041842;32850.34299314;33893.6610633893;4401.779911004437;4468.084042771224;4471.932921006188;4517.570830771071;4518.1415531049;248555;339044.99999999756;339278;339560;340078.2996085028;60497.39502605006;61080.38919610747;61115;61206;61209.38790612037;37037.25925481475;37868.62131378706;37913.431298530544;39397;39401.80299098486;17697.911510442365;17922.820771792376;17979.280828766965;17997;17998.730019049726;13803.654908627364;14010.719785604435;14016.369263383172;14018.859811401959;14026.719465610631;20515.794842051688;20542.589148216954;20558.897205513877;20617.58764824695;20672.896635517027;7219.566825990458;7276.818079548054;7402.555846649218;7470.402367810562;7512.474126811071;816.4162623724065;990.8761404824397;1111.183280288987;1161.9128565357632;1218.7623413434474;30794.384112317628;30934.226644333623;31062.53406198909;31509;31587.52618710721;1034.3897100710467;1174.7180676637715;1193.6836738264367;1240.9007279417624;1266.5820279307807;16578.50264492067;17190.828091718922;17215.483535493957;17251.482455526355;17378.565535861704;500388.9727719097;503162;505322;506664;507573;59987.70006149941;60063.399366006655;60248.39751602516;60592;60637.69681151566;1388.5487216654628;1425.237497938598;1440.171901156839;1442.6032840968608;1443.7473442147582;29587.85206073956;30043;30229.48122674051;30689.846550767998;31086;53004.46995529995;53410.4658953421;53420.73289633527;53527.464725353806;53581;394965;398141;399285;400160;400878;28658.713412865603;29064.27339316534;29522.704772951995;29538.261543461584;29568.55647165251;49425;49922.500774991786;50127.74936125296;50757.492425075274;51179.744101279255;41587.58412415837;42161.36757948695;42901.356479653456;46026;46145.30782038205;43624.31204354954;43663.781681091394;43717.781411094016;44140;44710;43680.56319436892;43988.12023759443;44771.32843007292;45623;46803;37216.255674886896;38234.80882595569;38293.80853095828;38790;39034.609653904234;29188.124356268498;29320.706792931796;29691.703082969758;29778.255543611584;29877.850610746806 11:38:20 INFO - PROCESS | 27317 | |2;jquery.html;367.2453108861289;382.388178913738;385.17956752118005;397.703710735502;404.2904702247555;16887.49337519875;18638.53403664909;18779.342723004716;19051.333203337887;20063;17672.20475078621;18320.725189122175;18353.7246941296;19083.90458047708;19092.140853661585;5104.566111880486;5214.113600687873;5265.710385928792;5304.840854774363;5307.973460132714;5150.587952963772;5235.790568377261;5259;5264.947350526503;5310.867228319304;5235.397929238129;5273.472652734734;5274.525292723654;5277.182036784286;5284.4187139414635;5140.5373516383515;5238.240455133984;5270.183121616155;5271;5271.288376069258;4784.378030855966;5111.897762044776;5112.36095488064;5113.054084994288;5115.462876397962;11976.28142311464;12584.244945303311;12687.873121268762;12729.745405091939;12741.808872866914;2247.494313779403;2361.6221404575185;2560.6799150106235;2925.88296468141;2952.527595584696;8964;9265.590946353928;9508.714738557785;9947.403155810674;10107.89892101077;1774.7052133836019;1833.596608746087;1876.9906150469296;1915.3104882242521;1932.5361913140885;74687.6265618674;78557;78911.60544197299;82964.75552866711;84857;11055.115590752881;11119.110471162288;11246.100311975022;11339.659810205707;11820.40897955116;15715.68568628621;15743.842561574238;15828.52514424569;15828.52514424569;15853.68292634141;8529.786755331043;8615.827683446296;8627.568621568897;8707.259882910024;8782.60478278479;7569.962150189213;7786.922130778733;8320.958395207985;8574.142585741502;8590.441621294596;4694.201985662446;4732.692374995614;4824.758762061883;4863.2461968394955;4945.826896058693;1953.9316123935594;1962.5388033812042;1986.9701954470695;1993.132987150594;2011.647961606713;961.2118063188259;1027.41950797799;1040.7242080848582;1119.043218048573;1134.733337665648;18731.625367492576;19066.428007159808;19139.23443062262;19268.32560860363;19336.802590025458;1028.8816786069697;1044.0968562193648;1052.8315469524991;1076.1337123615408;1095.5672509358758;10248.436336001592;10693.946530267454;10809.405482698527;10809.567617295377;10863.239573229956;451348;457757;465457;466598;468547;71936;82708.58645706854;82710.17289827146;92452.53773731088;96165.51917240368;1028.6039874648313;1050.7635781949152;1085.0993675249524;1085.978280434387;1086.0768346905224;17031;17284.222210000833;17322.913385432992;17343.132843358035;17529.386471473437;40422.393664094474;41590;41624.79187604043;41629;42536.57463425326;56760.716196418754;57969;58263;58704.70647646734;59825.7008714971;8560.957195213985;8577.914220857712;8609.65561377538;8746.562671866508;8820.162084601952;13177.802332965204;13687;13711.931440343133;13727.862721372658;13742.587722368346;11675.299482031105;12007.519699211934;12027.518899243933;12087.39563021863;12129.636110916686;11846.348450834943;11920.701982450508;11936;11974.70063248391;12259.69350766238;11964.880351196725;12279.140460167702;12387.876121238673;12502.312372819582;12518.436670350038;8359.247667709935;8446.704365347428;8597.140285971353;8688.78278043054;8850.424722393023;10675;11809.64571062835;12210.57262995791;12438.937805310916;12440.253584784941 11:38:20 INFO - PROCESS | 27317 | |3;mootools.html;1364.720232352368;1553.1224857955253;1586.5478338673477;1756.8858024228425;1900.0309841980595;8498.277646400056;8943.955280223607;8950.1497357751;9097.135772101656;9104;8868.778280542989;9084.636614535411;9086.273098152149;9089.727308180765;9093.408928419665;2059.351304339136;2137.454948988006;2138.561594873055;2346.436855154763;2368.2658375903525;2293.908243670251;2374.560706269337;2377.5720370333333;2390.438247011951;2390.5457962987066;2296.5636529059507;2310.849794763343;2314.8032417244553;2326.8022218111482;2371.9288421347387;2006.5384961458801;2307.90768369265;2322.6980492535945;2354.75275096115;2362.0905951208824;2326.6044772388736;2359.9174028908906;2364.337985364101;2365.66880636711;2368.46709490365;8993.460392376479;9025.593848276778;9027.909720902839;9046.276297896153;9062.864057039149;5294.708791016493;5415.566754659618;5424.783008679648;5427.945720542783;5434.375046869582;3268.297316077031;3292.160499072745;3422.863085476578;3441.6558344165633;3455.533502977091;1141.0928311991986;1157.1205883528494;1172.618898857866;1183.982240266388;1219.1587804414926;13086.672833179151;13097.279649619268;13105.737885242243;13108.082434229607;13132.934335328358;4570.382998295254;4772.117158325721;4827.951720482821;4846.103470857903;4846.5153484651255;10146.847797283046;10179.18566514677;10239.897601024044;10240.231982601186;10248.692539223835;5387.4073851876265;5390.137577987503;5397.055515284809;5399.217113518596;5411.2153737708595;4239;4668.603168730628;4978.726170060609;5035.446100928895;5039.571636410946;3896.6687831534637;3902.1610353774076;3904.3796824318156;3999.1201935574127;4029.778362190049;1133.4842646595944;1145.9255148415327;1146.0888593568134;1149.8217776244696;1155.930644161353;885.968991085309;935.7707361696292;938.7840796616816;957.9329446938787;958.8322043642335;5535.280413546213;5588.944110558842;5628.465295796893;5657.971710141423;5811.837632473522;901.4366021236727;935.3686261773412;984.6849008317413;987.3039507147444;999.5202302894651;3665.835037423269;3675.1730860556163;3685;3696.260747850387;3797.9430308545393;11306.604268850715;11432.085433165326;11575.479103440366;11724.062075033977;11841.171118021828;11668;11723.765524689628;11764.058875289957;11780.88219117815;11792.115591330685;8385.119562445978;8588.098249683693;8602.053774084845;8624.741257762402;8652.480851148952;5363.2759577456545;5397.78408863641;5448.673079615236;5543;5578.609497335147;10269;10280.537375817956;10406.375617462976;10694.946525267324;10752.462376880969;10149.746256343651;10463.843042354218;10471.057604815604;10825.404602746925;10847.45762711877;8062.637181326972;8091.1099779025435;8178.059523154913;8237.46456480327;8241.752747417588;8635.438696484709;8643.438176518506;8669.393142480218;8704;8710.69512567057;7587.468877178544;7738.7678369649;7829.373650108091;7852.411069169721;7865.4494185406475;7635.045619297588;7834.490758100933;7856.057273127033;7926.682932682628;7968.6414111363965;7193.064901562763;7257.673404696907;7306.96346518264;7311.780646580611;7315.414766818535;4955.306257124078;4958.181899986462;4967.155583550877;4990.176620857663;5018.5232402921665;4870.439899411472;4902.779374928065;4954.306397104337;4993.450720420678;5008.323876276656 11:38:20 INFO - PROCESS | 27317 | |4;prototype.html;326.10646827692125;331.54247138948256;332.6905977440979;333.2019812548944;347.5638074216861;5432.619716619838;5467.6719396836115;5531.419200983894;5596.3564190118095;5618.775248990036;5411.998780225651;5422.593305502093;5493.681516436057;5526.678794906139;5531.834044978657;4298.742075475466;4304.935425968613;4322.178786030652;4324.97837510812;4326.826926922935;3954.940675889864;4188.81150348235;4284.329111646461;4311.6335111515555;4313.331433627793;4239.215745087154;4259.51015633203;4274.230638485072;4286.271333873249;4287.292596721552;4247.299195632717;4260.68044896634;4263.806134282406;4273.7008409411355;4297.269464191095;3674.228412033476;3775.6979441644603;3825.6939444844347;3829.885103446901;3836.5396152461603;5185.61107916904;5202.765875535572;5231;5249.842504724865;5253.52718255355;4832.323474713543;4868.659193856432;4880.511948805127;4889.266610008492;4897.216445368759;5412.621116521845;5666.291713535808;5669.773209071674;5673.6879471629045;5743.329351851409;4034.979825100885;4037.9394309085387;4047.817848196838;4052.959470405288;4059.6143366380147;5919.260092488439;5935.2877654681715;5936.317323507808;5972.492338151263;6018.7291571879805;3963.425303331001;4138.6482149017675;4143.544210136883;4148.502179738391;4176.62410383067;4199.475065616798;4209.284421648327;4226.619604235633;4229.788510574459;4256.34026725858;4843.2250839865455;4857.295692124622;4862.589848943838;4864.616337994357;4873.926891096637;5568.387477377485;5736.627119237236;5800.998655463858;5813.505852002542;5868;1342.9798553021712;1451.8693317601467;1460.882424944918;1478.039274471602;1503.225838693074;924.9445033297889;953.5220408367003;957.5403806173076;966.6906589891308;978.882534095913;4801.031577053461;4837.250226214942;4880.6583539152625;4891.2174052152195;4897.681650692693;5149.794008239628;5166.767495462713;5197.558207552324;5220.765065571983;5226.947730522722;4765.737884416321;4846.321514987904;4955.5044495550155;4956;4974.786220132007;2748.186197109898;2787.1638508447386;2803.5001274318142;2804.2428544293334;2809.4521568294394;5301.284326615934;5333.413324534298;5353.2237825515085;5355.919661205085;5381.515663590296;4939.789751510831;5220;5221.355273088965;5234.482000219894;5252.951348973903;5212.687238765686;5276.445973172838;5284.233786100917;5284.969430960926;5304.7347632618985;5188.7405629719115;5216.478352164753;5226.0070586589945;5234.476552344735;5269.734014373856;4783.497732738012;5001.924971125509;5125.334825890591;5134.229865520202;5164.225366195101;5223.373195216446;5263.6841789492755;5271.525562699222;5278.706716854395;5315.973420132875;3852.6725228355904;3889;3908.335582950962;3922.1172438257286;3926.8821935341984;4843.685160464559;5151.278820965143;5211.244369566468;5683.272029425816;5751.194832723506;5698.857528561653;5754.683492407958;5756.791073874533;5811.476967072984;5831.958814414921;5691.857703557444;5720.399358067343;5722.968346237026;5727.369989301257;5727.971360143172;5418;5626.240457538343;5635.549156067423;5652.378238393691;5655.179998900218;5659.42496164236;5723.713814309352;5780.450857168562;5790.334111577054;5854.268216472941;5417.43116972728;5621.409751975983;5637.746301416364;5673.74468148926;5684.005299072728 11:38:20 INFO - PROCESS | 27317 | |5;yui.html;750.5421692767414;827.8302947895681;875.8379699755545;890.7194233816351;898.5372533145431;13740.931295343522;14174.078684885475;14199.645008874757;14234.50179243728;14281.285935703212;14879.25603719815;14938;14940.402383904631;14943.551693449186;14966.850331496682;687.3744892148146;802.626778547975;831.7587899509135;833.1585099049972;837.8785076163953;832.2551316571664;837.271573730855;840.4369072721291;840.4789030800912;840.6889450903175;887.1261807119981;890.0076414797519;891.1133422244875;891.5319457284912;891.777055736066;723.3417589993107;865.3812524045306;884.4560595233885;884.7743825324533;888.4846788862444;639.7728806273752;640.3340525853107;643.7167646235641;645.7223393940603;646.4666650013758;14164.220967846764;14214.85785142156;14216.431342746278;14243;14247.145171289652;14117;14420.855791442056;14479.78280325796;14485.855141448556;14570.854291457055;12606.81089783654;12865.549705760348;12955.352232388343;12958.416871240817;13042.804357934638;4557.339185818071;4568.70303430276;4573.108243892442;4612.607928326096;4664.55686709762;52594;53718.19422708663;54041.1893821593;56945.145822812694;61393.999999999556;11447.828282575769;11623;11723.02448650099;11844.874736899981;11987.88012119885;20145.496362591053;20245.392638220877;20252.493687657632;20643.483912901997;20664.483387915123;8496.235338819411;8542.829143417097;8690.08754080825;8903.821923561492;8965.910340896638;4928.162212423897;4961.2558116282125;5075.847724568269;5109.233614957711;5138.768755406016;3547.7693949893173;3627.3289441453417;3633.7456378053284;3633.8183090845882;3643.2531331077066;617.6695467924728;682.5904457325565;704.0389867830374;725.1081170160732;737.8634952533798;684.4763755726873;762.7902326860047;773.9806504837425;792.8335049639641;826.4669288309007;9412;9666.516674166263;9710.708678739507;9772.12050915421;9778.462184579774;700.7687463137153;705.9752908648172;768.3929695540572;786.6184900323339;794.1224946434107;4855.902881942341;4877.244027175793;4957.157283261854;4960.70235785854;4994.550490455804;68658;74897;77188;77584.61207693926;78040.60979695179;66338.33661663419;66841;66931;67352.32647673559;68943.65528172327;46710.53289467062;48736.51263487391;54663.18005229925;60582.39417605768;61671.38328616746;9493.715188544355;9773;9831.410115393099;9840.311178217456;9985.550650220903;34354.312913742084;35185.296294074484;35599.15206783503;36113.45829812502;36184.457233140995;42696.14607707766;47213.763931180125;47765.89521681079;48195.75902120467;49293.01413971772;12682.619521414372;12938.482460701467;12942.158759680591;13131.343432828511;13171.407286672315;13667.111637743912;14711.043782154125;14887.702245955237;14996.400143994118;15516.534503964898;12709.36453177319;12982.610521684364;13804.93097534546;14241.857581424054;14976.026558273677;12667.556635517712;13668.52160174389;13770.65573360674;14043.227622480861;14141;12309.507619695112;12897.355132243163;13072.869271307545;13436.865631343562;13533.323333833467;10737.677869663923;10841.132709383388;10860.52790489948;10963.780724385628;11374.658760237206;11474.885251147381;11537.538498459968;11769.293842369143;11773.234739741889;11837.526498940291 11:38:20 INFO - PROCESS | 27317 | __end_tp_report 11:38:20 INFO - PROCESS | 27317 | __start_cc_report 11:38:20 INFO - PROCESS | 27317 | _x_x_mozilla_cycle_collect,820 11:38:20 INFO - PROCESS | 27317 | __end_cc_report 11:38:20 INFO - PROCESS | 27317 | __startTimestamp1463423900513__endTimestamp 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | ------- Summary: start ------- 11:38:20 INFO - PROCESS | 27317 | Number of tests: 6 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#0] dojo.html Cycles:165 Average:5351.06 Median:5551.86 stddev:615.35 (11.1%) stddev-sans-first:592.52 11:38:20 INFO - PROCESS | 27317 | Values: 3150.0 3183.1 3191.1 3213.0 3237.1 5316.9 5323.4 5372.1 5447.0 5462.8 5275.9 5333.7 5346.0 5349.0 5355.9 4782.0 4867.3 4869.7 4925.5 4972.6 4915.2 4959.1 4973.3 4989.8 5032.4 4793.5 4794.2 4840.7 4876.8 4881.5 4781.4 4791.2 4802.1 4863.6 4907.4 4837.8 4850.4 4857.0 4858.8 4860.6 5202.3 5256.2 5336.5 5337.1 5423.5 5804.0 5858.1 5878.6 5888.2 5895.8 5566.7 5905.6 5928.6 5930.7 5933.1 5330.3 5386.4 5388.8 5395.0 5441.5 5953.7 5970.7 5980.3 5988.3 5989.6 5462.0 5571.6 5746.9 5746.9 5752.6 5690.1 5749.0 5751.7 5758.9 5820.4 5726.4 5744.1 5745.1 5755.1 5764.3 5452.5 5620.7 5658.2 5744.1 5753.9 4337.1 4339.3 4358.7 4392.7 4400.8 3889.4 3899.2 3912.9 3924.1 3934.3 5253.7 5276.3 5320.1 5391.9 5408.1 5788.3 5797.4 5800.4 5815.0 5849.5 5363.5 5412.3 5417.4 5432.6 5440.8 4858.2 4899.7 4932.3 4945.4 4981.5 5949.0 5949.1 5950.5 5950.7 5953.6 5664.6 5764.1 5775.0 5792.4 5794.0 5895.1 5980.8 5996.8 6010.2 6034.4 5720.2 5722.8 5729.0 5729.4 5731.0 5491.1 5705.7 5802.7 5835.8 5848.3 5593.7 5704.8 5719.6 5756.7 5762.2 5684.1 5688.5 5693.5 5693.5 5697.1 5375.7 5451.2 5551.9 5614.8 5622.5 5614.8 5715.4 5725.4 5739.3 5744.2 5542.1 5551.3 5560.8 5568.1 5580.6 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#1] ext.html Cycles:180 Average:57875.75 Median:38074.12 stddev:109785.08 (288.3%) stddev-sans-first:110008.82 11:38:20 INFO - PROCESS | 27317 | Values: 597.7 616.0 637.4 649.2 650.3 33791.0 44992.8 45802.0 45864.5 45924.0 45964.3 46253.3 46423.8 46441.8 46503.5 8179.7 8919.3 9106.5 9107.3 10448.9 10462.7 10620.3 10620.4 10625.7 10625.8 10737.6 10762.5 10781.5 10784.0 10785.0 8152.1 8327.4 8329.4 10644.4 10651.1 10494.3 10540.4 10556.5 10559.4 10562.0 41838.4 42286.6 42329.2 42348.2 42352.6 3557.1 3598.3 3613.7 3636.6 3697.8 31888.0 32527.8 32604.3 32850.3 33893.7 4401.8 4468.1 4471.9 4517.6 4518.1 248555.0 339045.0 339278.0 339560.0 340078.3 60497.4 61080.4 61115.0 61206.0 61209.4 37037.3 37868.6 37913.4 39397.0 39401.8 17697.9 17922.8 17979.3 17997.0 17998.7 13803.7 14010.7 14016.4 14018.9 14026.7 20515.8 20542.6 20558.9 20617.6 20672.9 7219.6 7276.8 7402.6 7470.4 7512.5 816.4 990.9 1111.2 1161.9 1218.8 30794.4 30934.2 31062.5 31509.0 31587.5 1034.4 1174.7 1193.7 1240.9 1266.6 16578.5 17190.8 17215.5 17251.5 17378.6 500389.0 503162.0 505322.0 506664.0 507573.0 59987.7 60063.4 60248.4 60592.0 60637.7 1388.5 1425.2 1440.2 1442.6 1443.7 29587.9 30043.0 30229.5 30689.8 31086.0 53004.5 53410.5 53420.7 53527.5 53581.0 394965.0 398141.0 399285.0 400160.0 400878.0 28658.7 29064.3 29522.7 29538.3 29568.6 49425.0 49922.5 50127.7 50757.5 51179.7 41587.6 42161.4 42901.4 46026.0 46145.3 43624.3 43663.8 43717.8 44140.0 44710.0 43680.6 43988.1 44771.3 45623.0 46803.0 37216.3 38234.8 38293.8 38790.0 39034.6 29188.1 29320.7 29691.7 29778.3 29877.9 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#2] jquery.html Cycles:180 Average:27702.03 Median:2304.56 stddev:76225.20 (3307.6%) stddev-sans-first:76411.40 11:38:20 INFO - PROCESS | 27317 | Values: 367.2 382.4 385.2 397.7 404.3 16887.5 18638.5 18779.3 19051.3 20063.0 17672.2 18320.7 18353.7 19083.9 19092.1 5104.6 5214.1 5265.7 5304.8 5308.0 5150.6 5235.8 5259.0 5264.9 5310.9 5235.4 5273.5 5274.5 5277.2 5284.4 5140.5 5238.2 5270.2 5271.0 5271.3 4784.4 5111.9 5112.4 5113.1 5115.5 11976.3 12584.2 12687.9 12729.7 12741.8 2247.5 2361.6 2560.7 2925.9 2952.5 8964.0 9265.6 9508.7 9947.4 10107.9 1774.7 1833.6 1877.0 1915.3 1932.5 74687.6 78557.0 78911.6 82964.8 84857.0 11055.1 11119.1 11246.1 11339.7 11820.4 15715.7 15743.8 15828.5 15828.5 15853.7 8529.8 8615.8 8627.6 8707.3 8782.6 7570.0 7786.9 8321.0 8574.1 8590.4 4694.2 4732.7 4824.8 4863.2 4945.8 1953.9 1962.5 1987.0 1993.1 2011.6 961.2 1027.4 1040.7 1119.0 1134.7 18731.6 19066.4 19139.2 19268.3 19336.8 1028.9 1044.1 1052.8 1076.1 1095.6 10248.4 10693.9 10809.4 10809.6 10863.2 451348.0 457757.0 465457.0 466598.0 468547.0 71936.0 82708.6 82710.2 92452.5 96165.5 1028.6 1050.8 1085.1 1086.0 1086.1 17031.0 17284.2 17322.9 17343.1 17529.4 40422.4 41590.0 41624.8 41629.0 42536.6 56760.7 57969.0 58263.0 58704.7 59825.7 8561.0 8577.9 8609.7 8746.6 8820.2 13177.8 13687.0 13711.9 13727.9 13742.6 11675.3 12007.5 12027.5 12087.4 12129.6 11846.3 11920.7 11936.0 11974.7 12259.7 11964.9 12279.1 12387.9 12502.3 12518.4 8359.2 8446.7 8597.1 8688.8 8850.4 10675.0 11809.6 12210.6 12438.9 12440.3 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#3] mootools.html Cycles:180 Average:5910.19 Median:4846.31 stddev:3484.71 (71.9%) stddev-sans-first:3477.75 11:38:20 INFO - PROCESS | 27317 | Values: 1364.7 1553.1 1586.5 1756.9 1900.0 8498.3 8944.0 8950.1 9097.1 9104.0 8868.8 9084.6 9086.3 9089.7 9093.4 2059.4 2137.5 2138.6 2346.4 2368.3 2293.9 2374.6 2377.6 2390.4 2390.5 2296.6 2310.8 2314.8 2326.8 2371.9 2006.5 2307.9 2322.7 2354.8 2362.1 2326.6 2359.9 2364.3 2365.7 2368.5 8993.5 9025.6 9027.9 9046.3 9062.9 5294.7 5415.6 5424.8 5427.9 5434.4 3268.3 3292.2 3422.9 3441.7 3455.5 1141.1 1157.1 1172.6 1184.0 1219.2 13086.7 13097.3 13105.7 13108.1 13132.9 4570.4 4772.1 4828.0 4846.1 4846.5 10146.8 10179.2 10239.9 10240.2 10248.7 5387.4 5390.1 5397.1 5399.2 5411.2 4239.0 4668.6 4978.7 5035.4 5039.6 3896.7 3902.2 3904.4 3999.1 4029.8 1133.5 1145.9 1146.1 1149.8 1155.9 886.0 935.8 938.8 957.9 958.8 5535.3 5588.9 5628.5 5658.0 5811.8 901.4 935.4 984.7 987.3 999.5 3665.8 3675.2 3685.0 3696.3 3797.9 11306.6 11432.1 11575.5 11724.1 11841.2 11668.0 11723.8 11764.1 11780.9 11792.1 8385.1 8588.1 8602.1 8624.7 8652.5 5363.3 5397.8 5448.7 5543.0 5578.6 10269.0 10280.5 10406.4 10694.9 10752.5 10149.7 10463.8 10471.1 10825.4 10847.5 8062.6 8091.1 8178.1 8237.5 8241.8 8635.4 8643.4 8669.4 8704.0 8710.7 7587.5 7738.8 7829.4 7852.4 7865.4 7635.0 7834.5 7856.1 7926.7 7968.6 7193.1 7257.7 7307.0 7311.8 7315.4 4955.3 4958.2 4967.2 4990.2 5018.5 4870.4 4902.8 4954.3 4993.5 5008.3 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#4] prototype.html Cycles:180 Average:4612.38 Median:5187.18 stddev:1326.84 (25.6%) stddev-sans-first:1290.97 11:38:20 INFO - PROCESS | 27317 | Values: 326.1 331.5 332.7 333.2 347.6 5432.6 5467.7 5531.4 5596.4 5618.8 5412.0 5422.6 5493.7 5526.7 5531.8 4298.7 4304.9 4322.2 4325.0 4326.8 3954.9 4188.8 4284.3 4311.6 4313.3 4239.2 4259.5 4274.2 4286.3 4287.3 4247.3 4260.7 4263.8 4273.7 4297.3 3674.2 3775.7 3825.7 3829.9 3836.5 5185.6 5202.8 5231.0 5249.8 5253.5 4832.3 4868.7 4880.5 4889.3 4897.2 5412.6 5666.3 5669.8 5673.7 5743.3 4035.0 4037.9 4047.8 4053.0 4059.6 5919.3 5935.3 5936.3 5972.5 6018.7 3963.4 4138.6 4143.5 4148.5 4176.6 4199.5 4209.3 4226.6 4229.8 4256.3 4843.2 4857.3 4862.6 4864.6 4873.9 5568.4 5736.6 5801.0 5813.5 5868.0 1343.0 1451.9 1460.9 1478.0 1503.2 924.9 953.5 957.5 966.7 978.9 4801.0 4837.3 4880.7 4891.2 4897.7 5149.8 5166.8 5197.6 5220.8 5226.9 4765.7 4846.3 4955.5 4956.0 4974.8 2748.2 2787.2 2803.5 2804.2 2809.5 5301.3 5333.4 5353.2 5355.9 5381.5 4939.8 5220.0 5221.4 5234.5 5253.0 5212.7 5276.4 5284.2 5285.0 5304.7 5188.7 5216.5 5226.0 5234.5 5269.7 4783.5 5001.9 5125.3 5134.2 5164.2 5223.4 5263.7 5271.5 5278.7 5316.0 3852.7 3889.0 3908.3 3922.1 3926.9 4843.7 5151.3 5211.2 5683.3 5751.2 5698.9 5754.7 5756.8 5811.5 5832.0 5691.9 5720.4 5723.0 5727.4 5728.0 5418.0 5626.2 5635.5 5652.4 5655.2 5659.4 5723.7 5780.5 5790.3 5854.3 5417.4 5621.4 5637.7 5673.7 5684.0 11:38:20 INFO - PROCESS | 27317 | 11:38:20 INFO - PROCESS | 27317 | [#5] yui.html Cycles:180 Average:16173.10 Median:26796.21 stddev:19407.13 (72.4%) stddev-sans-first:19427.01 11:38:20 INFO - PROCESS | 27317 | Values: 750.5 827.8 875.8 890.7 898.5 13740.9 14174.1 14199.6 14234.5 14281.3 14879.3 14938.0 14940.4 14943.6 14966.9 687.4 802.6 831.8 833.2 837.9 832.3 837.3 840.4 840.5 840.7 887.1 890.0 891.1 891.5 891.8 723.3 865.4 884.5 884.8 888.5 639.8 640.3 643.7 645.7 646.5 14164.2 14214.9 14216.4 14243.0 14247.1 14117.0 14420.9 14479.8 14485.9 14570.9 12606.8 12865.5 12955.4 12958.4 13042.8 4557.3 4568.7 4573.1 4612.6 4664.6 52594.0 53718.2 54041.2 56945.1 61394.0 11447.8 11623.0 11723.0 11844.9 11987.9 20145.5 20245.4 20252.5 20643.5 20664.5 8496.2 8542.8 8690.1 8903.8 8965.9 4928.2 4961.3 5075.8 5109.2 5138.8 3547.8 3627.3 3633.7 3633.8 3643.3 617.7 682.6 704.0 725.1 737.9 684.5 762.8 774.0 792.8 826.5 9412.0 9666.5 9710.7 9772.1 9778.5 700.8 706.0 768.4 786.6 794.1 4855.9 4877.2 4957.2 4960.7 4994.6 68658.0 74897.0 77188.0 77584.6 78040.6 66338.3 66841.0 66931.0 67352.3 68943.7 46710.5 48736.5 54663.2 60582.4 61671.4 9493.7 9773.0 9831.4 9840.3 9985.6 34354.3 35185.3 35599.2 36113.5 36184.5 42696.1 47213.8 47765.9 48195.8 49293.0 12682.6 12938.5 12942.2 13131.3 13171.4 13667.1 14711.0 14887.7 14996.4 15516.5 12709.4 12982.6 13804.9 14241.9 14976.0 12667.6 13668.5 13770.7 14043.2 14141.0 12309.5 12897.4 13072.9 13436.9 13533.3 10737.7 10841.1 10860.5 10963.8 11374.7 11474.9 11537.5 11769.3 11773.2 11837.5 11:38:20 INFO - PROCESS | 27317 | -------- Summary: end -------- 11:38:20 INFO - PROCESS | 27317 | 11:38:21 INFO - TEST-INFO | 27317: exit 0 11:38:21 INFO - TEST-OK | dromaeo_css | took 1113138ms 11:38:21 INFO - TEST-START | kraken 11:38:21 INFO - operating with platform_type : linux_ 11:38:21 INFO - Initialising browser for kraken test... 11:38:21 INFO - TEST-INFO | started process 27497 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj8Hfzc/profile http://localhost:45097/getInfo.html) 11:38:25 INFO - PROCESS | 27497 | __metrics Screen width/height:1600/1200 11:38:25 INFO - PROCESS | 27497 | colorDepth:24 11:38:25 INFO - PROCESS | 27497 | Browser inner width/height: 1024/697 11:38:25 INFO - PROCESS | 27497 | __metrics 11:38:26 ERROR - PROCESS | 27497 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:38:27 INFO - TEST-INFO | 27497: exit 0 11:38:27 INFO - Browser initialized. 11:38:27 INFO - Running cycle 1/1 for kraken test... 11:38:27 INFO - TEST-INFO | started process 27576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj8Hfzc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:38:39 INFO - PROCESS | 27576 | RSS: Main: 152559616 11:38:39 INFO - PROCESS | 27576 | 11:38:41 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45097/tests/kraken/driver.html?testName=audio-beat-detection) 11:38:42 INFO - PROCESS | 27576 | RSS: Main: 208883712 11:38:42 INFO - PROCESS | 27576 | 11:38:45 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45097/tests/kraken/driver.html?testName=audio-dft) 11:38:45 INFO - PROCESS | 27576 | RSS: Main: 196624384 11:38:45 INFO - PROCESS | 27576 | 11:38:49 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45097/tests/kraken/driver.html?testName=audio-fft) 11:38:49 INFO - PROCESS | 27576 | RSS: Main: 264921088 11:38:49 INFO - PROCESS | 27576 | 11:38:51 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45097/tests/kraken/driver.html?testName=audio-oscillator) 11:38:52 INFO - PROCESS | 27576 | RSS: Main: 174567424 11:38:52 INFO - PROCESS | 27576 | 11:38:54 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:38:55 INFO - PROCESS | 27576 | RSS: Main: 178380800 11:38:55 INFO - PROCESS | 27576 | 11:38:58 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45097/tests/kraken/driver.html?testName=imaging-darkroom) 11:38:59 INFO - PROCESS | 27576 | RSS: Main: 214593536 11:38:59 INFO - PROCESS | 27576 | 11:39:02 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45097/tests/kraken/driver.html?testName=imaging-desaturate) 11:39:03 INFO - PROCESS | 27576 | RSS: Main: 212578304 11:39:03 INFO - PROCESS | 27576 | 11:39:06 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45097/tests/kraken/driver.html?testName=json-parse-financial) 11:39:06 INFO - PROCESS | 27576 | RSS: Main: 214077440 11:39:06 INFO - PROCESS | 27576 | 11:39:08 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:39:08 INFO - PROCESS | 27576 | RSS: Main: 203956224 11:39:08 INFO - PROCESS | 27576 | 11:39:10 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:39:11 INFO - PROCESS | 27576 | RSS: Main: 206356480 11:39:11 INFO - PROCESS | 27576 | 11:39:13 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:39:14 INFO - PROCESS | 27576 | RSS: Main: 217649152 11:39:14 INFO - PROCESS | 27576 | 11:39:16 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:39:17 INFO - PROCESS | 27576 | RSS: Main: 224153600 11:39:17 INFO - PROCESS | 27576 | 11:39:20 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:39:20 INFO - PROCESS | 27576 | RSS: Main: 218603520 11:39:20 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | Cycle 1(1): loaded http://localhost:45097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45097/tests/kraken/driver.html?testName=ai-astar) 11:39:22 INFO - PROCESS | 27576 | RSS: Main: 264364032 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | __start_tp_report 11:39:22 INFO - PROCESS | 27576 | _x_x_mozilla_page_load 11:39:22 INFO - PROCESS | 27576 | _x_x_mozilla_page_load_details 11:39:22 INFO - PROCESS | 27576 | |i|pagename|runs| 11:39:22 INFO - PROCESS | 27576 | |0;ai-astar;108;104;109;109;109;108;109;110;109;111 11:39:22 INFO - PROCESS | 27576 | |1;audio-beat-detection;145;123;147;209;124;162;147;132;191;124 11:39:22 INFO - PROCESS | 27576 | |2;audio-dft;159;160;161;161;160;159;161;163;168;161 11:39:22 INFO - PROCESS | 27576 | |3;audio-fft;76;74;74;152;75;75;75;75;145;75 11:39:22 INFO - PROCESS | 27576 | |4;audio-oscillator;92;92;151;92;91;91;126;92;91;116 11:39:22 INFO - PROCESS | 27576 | |5;imaging-gaussian-blur;117;117;116;117;116;117;116;117;117;119 11:39:22 INFO - PROCESS | 27576 | |6;imaging-darkroom;146;146;146;145;146;145;145;145;147;145 11:39:22 INFO - PROCESS | 27576 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;95;95 11:39:22 INFO - PROCESS | 27576 | |8;json-parse-financial;54;53;56;54;58;55;55;107;53;53 11:39:22 INFO - PROCESS | 27576 | |9;json-stringify-tinderbox;58;60;59;59;59;59;59;58;59;59 11:39:22 INFO - PROCESS | 27576 | |10;stanford-crypto-aes;82;80;77;76;79;79;77;79;80;78 11:39:22 INFO - PROCESS | 27576 | |11;stanford-crypto-ccm;121;122;122;119;115;116;115;117;197;123 11:39:22 INFO - PROCESS | 27576 | |12;stanford-crypto-pbkdf2;161;163;165;159;167;159;163;159;160;159 11:39:22 INFO - PROCESS | 27576 | |13;stanford-crypto-sha256-iterative;66;63;63;62;65;63;66;65;61;62 11:39:22 INFO - PROCESS | 27576 | __end_tp_report 11:39:22 INFO - PROCESS | 27576 | __start_cc_report 11:39:22 INFO - PROCESS | 27576 | _x_x_mozilla_cycle_collect,1846 11:39:22 INFO - PROCESS | 27576 | __end_cc_report 11:39:22 INFO - PROCESS | 27576 | __startTimestamp1463423962684__endTimestamp 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | ------- Summary: start ------- 11:39:22 INFO - PROCESS | 27576 | Number of tests: 14 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.00 stddev:1.84 (1.7%) stddev-sans-first:1.94 11:39:22 INFO - PROCESS | 27576 | Values: 108.0 104.0 109.0 109.0 109.0 108.0 109.0 110.0 109.0 111.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:147.00 stddev:29.35 (20.0%) stddev-sans-first:31.06 11:39:22 INFO - PROCESS | 27576 | Values: 145.0 123.0 147.0 209.0 124.0 162.0 147.0 132.0 191.0 124.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:2.63 (1.6%) stddev-sans-first:2.65 11:39:22 INFO - PROCESS | 27576 | Values: 159.0 160.0 161.0 161.0 160.0 159.0 161.0 163.0 168.0 161.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#3] audio-fft Cycles:10 Average:89.60 Median:75.00 stddev:31.09 (41.5%) stddev-sans-first:32.59 11:39:22 INFO - PROCESS | 27576 | Values: 76.0 74.0 74.0 152.0 75.0 75.0 75.0 75.0 145.0 75.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:91.50 stddev:20.86 (22.8%) stddev-sans-first:21.71 11:39:22 INFO - PROCESS | 27576 | Values: 92.0 92.0 151.0 92.0 91.0 91.0 126.0 92.0 91.0 116.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 11:39:22 INFO - PROCESS | 27576 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 119.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 11:39:22 INFO - PROCESS | 27576 | Values: 146.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 147.0 145.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:39:22 INFO - PROCESS | 27576 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:54.50 stddev:16.66 (30.6%) stddev-sans-first:17.54 11:39:22 INFO - PROCESS | 27576 | Values: 54.0 53.0 56.0 54.0 58.0 55.0 55.0 107.0 53.0 53.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 11:39:22 INFO - PROCESS | 27576 | Values: 58.0 60.0 59.0 59.0 59.0 59.0 59.0 58.0 59.0 59.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:1.77 (2.2%) stddev-sans-first:1.41 11:39:22 INFO - PROCESS | 27576 | Values: 82.0 80.0 77.0 76.0 79.0 79.0 77.0 79.0 80.0 78.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:121.50 stddev:24.89 (20.5%) stddev-sans-first:26.31 11:39:22 INFO - PROCESS | 27576 | Values: 121.0 122.0 122.0 119.0 115.0 116.0 115.0 117.0 197.0 123.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:2.88 (1.8%) stddev-sans-first:3.05 11:39:22 INFO - PROCESS | 27576 | Values: 161.0 163.0 165.0 159.0 167.0 159.0 163.0 159.0 160.0 159.0 11:39:22 INFO - PROCESS | 27576 | 11:39:22 INFO - PROCESS | 27576 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:1.78 (2.8%) stddev-sans-first:1.66 11:39:22 INFO - PROCESS | 27576 | Values: 66.0 63.0 63.0 62.0 65.0 63.0 66.0 65.0 61.0 62.0 11:39:22 INFO - PROCESS | 27576 | -------- Summary: end -------- 11:39:22 INFO - PROCESS | 27576 | 11:39:23 INFO - TEST-INFO | 27576: exit 0 11:39:23 INFO - TEST-OK | kraken | took 62459ms 11:39:23 INFO - SUITE-END | took 1175s 11:39:23 INFO - Completed test suite (00:19:35) 11:39:23 INFO - javascript benchmark 11:39:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.9999999999995, 3183.1246407238004, 3191.1224413286345, 3212.983935080324, 3237.125975986484, 5316.946830531693, 5323.361196656402, 5372.059889519328, 5446.972765136174, 5462.754176062077, 5275.894482110365, 5333.679979201251, 5346.0, 5349.010433069875, 5355.919661205085, 4781.952180478194, 4867.2942423348595, 4869.659123861331, 4925.458199598042, 4972.627052971024, 4915.188993816016, 4959.082569724613, 4973.3286006389035, 4989.75051247438, 5032.396112466509, 4793.544613261752, 4794.184988551954, 4840.661153719224, 4876.756162191876, 4881.511848815126, 4781.402324709411, 4791.209450440673, 4802.087603355351, 4863.635227357962, 4907.386576677915, 4837.782299796518, 4850.393700787402, 4857.0, 4858.78135483904, 4860.635452341088, 5202.297689811865, 5256.237845512379, 5336.519713225828, 5337.066013447631, 5423.457654234585, 5804.0, 5858.062709966428, 5878.588498805086, 5888.205092312526, 5895.793647222326, 5566.666000040011, 5905.557083218733, 5928.614640048383, 5930.673812940287, 5933.080372542263, 5330.253764472977, 5386.380566234894, 5388.838334849922, 5395.0, 5441.510264076292, 5953.672548009901, 5970.671613061237, 5980.252468441445, 5988.3113441953865, 5989.610675306091, 5462.0, 5571.609987300931, 5746.856328591735, 5746.88506229873, 5752.626079304831, 5690.118031705092, 5749.022666146765, 5751.654900705971, 5758.856028599319, 5820.447057529528, 5726.398728133569, 5744.052231381859, 5745.13822926556, 5755.0791873300905, 5764.337101233412, 5452.482014208644, 5620.718964051781, 5658.151277308437, 5744.052231381859, 5753.913691294634, 4337.110892267141, 4339.262325404688, 4358.65130789536, 4392.670549708785, 4400.779961001937, 3889.358255887747, 3899.1616802387057, 3912.902177445587, 3924.0582260257333, 3934.3311637021775, 5253.711045892436, 5276.287701160371, 5320.12217984036, 5391.892162156735, 5408.053590621704, 5788.3343415506915, 5797.391273916262, 5800.361960184377, 5815.04051831443, 5849.532037436995, 5363.517283444431, 5412.323459567554, 5417.404085550586, 5432.565394768409, 5440.809571664972, 4858.198397264482, 4899.6570240083565, 4932.334134891816, 4945.406551213878, 4981.501849815062, 5949.0481522956115, 5949.077892926603, 5950.494207992369, 5950.702464876739, 5953.553483488756, 5664.5751568631695, 5764.106563482667, 5774.971125144348, 5792.420757924174, 5794.0439827428, 5895.145203945318, 5980.760769569169, 5996.790112346047, 6010.2186715726675, 6034.426729460694, 5720.17057526648, 5722.799702010409, 5728.971355143198, 5729.369769325454, 5731.0, 5491.066518691912, 5705.686187259741, 5802.738876750472, 5835.766569337179, 5848.327442344185, 5593.692346920959, 5704.771809127588, 5719.628224165416, 5756.65460072397, 5762.164486149569, 5684.090545512863, 5688.544916406595, 5693.459121383462, 5693.5445164387575, 5697.116946873241, 5375.650582712111, 5451.236826844166, 5551.861203469945, 5614.803481878114, 5622.522085622604, 5614.775408983595, 5715.371309155907, 5725.370209277059, 5739.282589676291, 5744.167095771007, 5542.140968149944, 5551.333839939233, 5560.777568897199, 5568.136938774496, 5580.553555715452], "value": 5309.8299007114565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.742970522675, 615.9876802463949, 637.4358692557086, 649.2112083818162, 650.343153415051, 33791.0, 44992.77503612481, 45802.0, 45864.54135458644, 45924.0, 45964.310535341916, 46253.30620040702, 46423.767881160544, 46441.76779116108, 46503.534964650346, 8179.713710020151, 8919.331050171231, 9106.544672766368, 9107.271418286522, 10448.947755261213, 10462.738431539196, 10620.309679870823, 10620.415877126756, 10625.681229563126, 10625.840612390783, 10737.570497180142, 10762.461876906162, 10781.514831832546, 10784.0, 10785.0, 8152.1440248773615, 8327.37544684145, 8329.37529685276, 10644.41455719935, 10651.147908167328, 10494.265401421904, 10540.367577945348, 10556.524956376981, 10559.41923194224, 10562.0, 41838.37242441366, 42286.57713422857, 42329.1534169318, 42348.15303693909, 42352.576474235175, 3557.0929412999776, 3598.2623562169692, 3613.656702613274, 3636.6363363663686, 3697.8335974881193, 31888.0, 32527.837360813282, 32604.347913041842, 32850.34299314, 33893.6610633893, 4401.779911004437, 4468.084042771224, 4471.932921006188, 4517.570830771071, 4518.1415531049, 248555.0, 339044.99999999756, 339278.0, 339560.0, 340078.2996085028, 60497.39502605006, 61080.38919610747, 61115.0, 61206.0, 61209.38790612037, 37037.25925481475, 37868.62131378706, 37913.431298530544, 39397.0, 39401.80299098486, 17697.911510442365, 17922.820771792376, 17979.280828766965, 17997.0, 17998.730019049726, 13803.654908627364, 14010.719785604435, 14016.369263383172, 14018.859811401959, 14026.719465610631, 20515.794842051688, 20542.589148216954, 20558.897205513877, 20617.58764824695, 20672.896635517027, 7219.566825990458, 7276.818079548054, 7402.555846649218, 7470.402367810562, 7512.474126811071, 816.4162623724065, 990.8761404824397, 1111.183280288987, 1161.9128565357632, 1218.7623413434474, 30794.384112317628, 30934.226644333623, 31062.53406198909, 31509.0, 31587.52618710721, 1034.3897100710467, 1174.7180676637715, 1193.6836738264367, 1240.9007279417624, 1266.5820279307807, 16578.50264492067, 17190.828091718922, 17215.483535493957, 17251.482455526355, 17378.565535861704, 500388.9727719097, 503162.0, 505322.0, 506664.0, 507573.0, 59987.70006149941, 60063.399366006655, 60248.39751602516, 60592.0, 60637.69681151566, 1388.5487216654628, 1425.237497938598, 1440.171901156839, 1442.6032840968608, 1443.7473442147582, 29587.85206073956, 30043.0, 30229.48122674051, 30689.846550767998, 31086.0, 53004.46995529995, 53410.4658953421, 53420.73289633527, 53527.464725353806, 53581.0, 394965.0, 398141.0, 399285.0, 400160.0, 400878.0, 28658.713412865603, 29064.27339316534, 29522.704772951995, 29538.261543461584, 29568.55647165251, 49425.0, 49922.500774991786, 50127.74936125296, 50757.492425075274, 51179.744101279255, 41587.58412415837, 42161.36757948695, 42901.356479653456, 46026.0, 46145.30782038205, 43624.31204354954, 43663.781681091394, 43717.781411094016, 44140.0, 44710.0, 43680.56319436892, 43988.12023759443, 44771.32843007292, 45623.0, 46803.0, 37216.255674886896, 38234.80882595569, 38293.80853095828, 38790.0, 39034.609653904234, 29188.124356268498, 29320.706792931796, 29691.703082969758, 29778.255543611584, 29877.850610746806], "value": 20665.160480930615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.2453108861289, 382.388178913738, 385.17956752118005, 397.703710735502, 404.2904702247555, 16887.49337519875, 18638.53403664909, 18779.342723004716, 19051.333203337887, 20063.0, 17672.20475078621, 18320.725189122175, 18353.7246941296, 19083.90458047708, 19092.140853661585, 5104.566111880486, 5214.113600687873, 5265.710385928792, 5304.840854774363, 5307.973460132714, 5150.587952963772, 5235.790568377261, 5259.0, 5264.947350526503, 5310.867228319304, 5235.397929238129, 5273.472652734734, 5274.525292723654, 5277.182036784286, 5284.4187139414635, 5140.5373516383515, 5238.240455133984, 5270.183121616155, 5271.0, 5271.288376069258, 4784.378030855966, 5111.897762044776, 5112.36095488064, 5113.054084994288, 5115.462876397962, 11976.28142311464, 12584.244945303311, 12687.873121268762, 12729.745405091939, 12741.808872866914, 2247.494313779403, 2361.6221404575185, 2560.6799150106235, 2925.88296468141, 2952.527595584696, 8964.0, 9265.590946353928, 9508.714738557785, 9947.403155810674, 10107.89892101077, 1774.7052133836019, 1833.596608746087, 1876.9906150469296, 1915.3104882242521, 1932.5361913140885, 74687.6265618674, 78557.0, 78911.60544197299, 82964.75552866711, 84857.0, 11055.115590752881, 11119.110471162288, 11246.100311975022, 11339.659810205707, 11820.40897955116, 15715.68568628621, 15743.842561574238, 15828.52514424569, 15828.52514424569, 15853.68292634141, 8529.786755331043, 8615.827683446296, 8627.568621568897, 8707.259882910024, 8782.60478278479, 7569.962150189213, 7786.922130778733, 8320.958395207985, 8574.142585741502, 8590.441621294596, 4694.201985662446, 4732.692374995614, 4824.758762061883, 4863.2461968394955, 4945.826896058693, 1953.9316123935594, 1962.5388033812042, 1986.9701954470695, 1993.132987150594, 2011.647961606713, 961.2118063188259, 1027.41950797799, 1040.7242080848582, 1119.043218048573, 1134.733337665648, 18731.625367492576, 19066.428007159808, 19139.23443062262, 19268.32560860363, 19336.802590025458, 1028.8816786069697, 1044.0968562193648, 1052.8315469524991, 1076.1337123615408, 1095.5672509358758, 10248.436336001592, 10693.946530267454, 10809.405482698527, 10809.567617295377, 10863.239573229956, 451348.0, 457757.0, 465457.0, 466598.0, 468547.0, 71936.0, 82708.58645706854, 82710.17289827146, 92452.53773731088, 96165.51917240368, 1028.6039874648313, 1050.7635781949152, 1085.0993675249524, 1085.978280434387, 1086.0768346905224, 17031.0, 17284.222210000833, 17322.913385432992, 17343.132843358035, 17529.386471473437, 40422.393664094474, 41590.0, 41624.79187604043, 41629.0, 42536.57463425326, 56760.716196418754, 57969.0, 58263.0, 58704.70647646734, 59825.7008714971, 8560.957195213985, 8577.914220857712, 8609.65561377538, 8746.562671866508, 8820.162084601952, 13177.802332965204, 13687.0, 13711.931440343133, 13727.862721372658, 13742.587722368346, 11675.299482031105, 12007.519699211934, 12027.518899243933, 12087.39563021863, 12129.636110916686, 11846.348450834943, 11920.701982450508, 11936.0, 11974.70063248391, 12259.69350766238, 11964.880351196725, 12279.140460167702, 12387.876121238673, 12502.312372819582, 12518.436670350038, 8359.247667709935, 8446.704365347428, 8597.140285971353, 8688.78278043054, 8850.424722393023, 10675.0, 11809.64571062835, 12210.57262995791, 12438.937805310916, 12440.253584784941], "value": 9025.693996147036, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.720232352368, 1553.1224857955253, 1586.5478338673477, 1756.8858024228425, 1900.0309841980595, 8498.277646400056, 8943.955280223607, 8950.1497357751, 9097.135772101656, 9104.0, 8868.778280542989, 9084.636614535411, 9086.273098152149, 9089.727308180765, 9093.408928419665, 2059.351304339136, 2137.454948988006, 2138.561594873055, 2346.436855154763, 2368.2658375903525, 2293.908243670251, 2374.560706269337, 2377.5720370333333, 2390.438247011951, 2390.5457962987066, 2296.5636529059507, 2310.849794763343, 2314.8032417244553, 2326.8022218111482, 2371.9288421347387, 2006.5384961458801, 2307.90768369265, 2322.6980492535945, 2354.75275096115, 2362.0905951208824, 2326.6044772388736, 2359.9174028908906, 2364.337985364101, 2365.66880636711, 2368.46709490365, 8993.460392376479, 9025.593848276778, 9027.909720902839, 9046.276297896153, 9062.864057039149, 5294.708791016493, 5415.566754659618, 5424.783008679648, 5427.945720542783, 5434.375046869582, 3268.297316077031, 3292.160499072745, 3422.863085476578, 3441.6558344165633, 3455.533502977091, 1141.0928311991986, 1157.1205883528494, 1172.618898857866, 1183.982240266388, 1219.1587804414926, 13086.672833179151, 13097.279649619268, 13105.737885242243, 13108.082434229607, 13132.934335328358, 4570.382998295254, 4772.117158325721, 4827.951720482821, 4846.103470857903, 4846.5153484651255, 10146.847797283046, 10179.18566514677, 10239.897601024044, 10240.231982601186, 10248.692539223835, 5387.4073851876265, 5390.137577987503, 5397.055515284809, 5399.217113518596, 5411.2153737708595, 4239.0, 4668.603168730628, 4978.726170060609, 5035.446100928895, 5039.571636410946, 3896.6687831534637, 3902.1610353774076, 3904.3796824318156, 3999.1201935574127, 4029.778362190049, 1133.4842646595944, 1145.9255148415327, 1146.0888593568134, 1149.8217776244696, 1155.930644161353, 885.968991085309, 935.7707361696292, 938.7840796616816, 957.9329446938787, 958.8322043642335, 5535.280413546213, 5588.944110558842, 5628.465295796893, 5657.971710141423, 5811.837632473522, 901.4366021236727, 935.3686261773412, 984.6849008317413, 987.3039507147444, 999.5202302894651, 3665.835037423269, 3675.1730860556163, 3685.0, 3696.260747850387, 3797.9430308545393, 11306.604268850715, 11432.085433165326, 11575.479103440366, 11724.062075033977, 11841.171118021828, 11668.0, 11723.765524689628, 11764.058875289957, 11780.88219117815, 11792.115591330685, 8385.119562445978, 8588.098249683693, 8602.053774084845, 8624.741257762402, 8652.480851148952, 5363.2759577456545, 5397.78408863641, 5448.673079615236, 5543.0, 5578.609497335147, 10269.0, 10280.537375817956, 10406.375617462976, 10694.946525267324, 10752.462376880969, 10149.746256343651, 10463.843042354218, 10471.057604815604, 10825.404602746925, 10847.45762711877, 8062.637181326972, 8091.1099779025435, 8178.059523154913, 8237.46456480327, 8241.752747417588, 8635.438696484709, 8643.438176518506, 8669.393142480218, 8704.0, 8710.69512567057, 7587.468877178544, 7738.7678369649, 7829.373650108091, 7852.411069169721, 7865.4494185406475, 7635.045619297588, 7834.490758100933, 7856.057273127033, 7926.682932682628, 7968.6414111363965, 7193.064901562763, 7257.673404696907, 7306.96346518264, 7311.780646580611, 7315.414766818535, 4955.306257124078, 4958.181899986462, 4967.155583550877, 4990.176620857663, 5018.5232402921665, 4870.439899411472, 4902.779374928065, 4954.306397104337, 4993.450720420678, 5008.323876276656], "value": 4672.688622374678, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.10646827692125, 331.54247138948256, 332.6905977440979, 333.2019812548944, 347.5638074216861, 5432.619716619838, 5467.6719396836115, 5531.419200983894, 5596.3564190118095, 5618.775248990036, 5411.998780225651, 5422.593305502093, 5493.681516436057, 5526.678794906139, 5531.834044978657, 4298.742075475466, 4304.935425968613, 4322.178786030652, 4324.97837510812, 4326.826926922935, 3954.940675889864, 4188.81150348235, 4284.329111646461, 4311.6335111515555, 4313.331433627793, 4239.215745087154, 4259.51015633203, 4274.230638485072, 4286.271333873249, 4287.292596721552, 4247.299195632717, 4260.68044896634, 4263.806134282406, 4273.7008409411355, 4297.269464191095, 3674.228412033476, 3775.6979441644603, 3825.6939444844347, 3829.885103446901, 3836.5396152461603, 5185.61107916904, 5202.765875535572, 5231.0, 5249.842504724865, 5253.52718255355, 4832.323474713543, 4868.659193856432, 4880.511948805127, 4889.266610008492, 4897.216445368759, 5412.621116521845, 5666.291713535808, 5669.773209071674, 5673.6879471629045, 5743.329351851409, 4034.979825100885, 4037.9394309085387, 4047.817848196838, 4052.959470405288, 4059.6143366380147, 5919.260092488439, 5935.2877654681715, 5936.317323507808, 5972.492338151263, 6018.7291571879805, 3963.425303331001, 4138.6482149017675, 4143.544210136883, 4148.502179738391, 4176.62410383067, 4199.475065616798, 4209.284421648327, 4226.619604235633, 4229.788510574459, 4256.34026725858, 4843.2250839865455, 4857.295692124622, 4862.589848943838, 4864.616337994357, 4873.926891096637, 5568.387477377485, 5736.627119237236, 5800.998655463858, 5813.505852002542, 5868.0, 1342.9798553021712, 1451.8693317601467, 1460.882424944918, 1478.039274471602, 1503.225838693074, 924.9445033297889, 953.5220408367003, 957.5403806173076, 966.6906589891308, 978.882534095913, 4801.031577053461, 4837.250226214942, 4880.6583539152625, 4891.2174052152195, 4897.681650692693, 5149.794008239628, 5166.767495462713, 5197.558207552324, 5220.765065571983, 5226.947730522722, 4765.737884416321, 4846.321514987904, 4955.5044495550155, 4956.0, 4974.786220132007, 2748.186197109898, 2787.1638508447386, 2803.5001274318142, 2804.2428544293334, 2809.4521568294394, 5301.284326615934, 5333.413324534298, 5353.2237825515085, 5355.919661205085, 5381.515663590296, 4939.789751510831, 5220.0, 5221.355273088965, 5234.482000219894, 5252.951348973903, 5212.687238765686, 5276.445973172838, 5284.233786100917, 5284.969430960926, 5304.7347632618985, 5188.7405629719115, 5216.478352164753, 5226.0070586589945, 5234.476552344735, 5269.734014373856, 4783.497732738012, 5001.924971125509, 5125.334825890591, 5134.229865520202, 5164.225366195101, 5223.373195216446, 5263.6841789492755, 5271.525562699222, 5278.706716854395, 5315.973420132875, 3852.6725228355904, 3889.0, 3908.335582950962, 3922.1172438257286, 3926.8821935341984, 4843.685160464559, 5151.278820965143, 5211.244369566468, 5683.272029425816, 5751.194832723506, 5698.857528561653, 5754.683492407958, 5756.791073874533, 5811.476967072984, 5831.958814414921, 5691.857703557444, 5720.399358067343, 5722.968346237026, 5727.369989301257, 5727.971360143172, 5418.0, 5626.240457538343, 5635.549156067423, 5652.378238393691, 5655.179998900218, 5659.42496164236, 5723.713814309352, 5780.450857168562, 5790.334111577054, 5854.268216472941, 5417.43116972728, 5621.409751975983, 5637.746301416364, 5673.74468148926, 5684.005299072728], "value": 4192.694062533541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.5421692767414, 827.8302947895681, 875.8379699755545, 890.7194233816351, 898.5372533145431, 13740.931295343522, 14174.078684885475, 14199.645008874757, 14234.50179243728, 14281.285935703212, 14879.25603719815, 14938.0, 14940.402383904631, 14943.551693449186, 14966.850331496682, 687.3744892148146, 802.626778547975, 831.7587899509135, 833.1585099049972, 837.8785076163953, 832.2551316571664, 837.271573730855, 840.4369072721291, 840.4789030800912, 840.6889450903175, 887.1261807119981, 890.0076414797519, 891.1133422244875, 891.5319457284912, 891.777055736066, 723.3417589993107, 865.3812524045306, 884.4560595233885, 884.7743825324533, 888.4846788862444, 639.7728806273752, 640.3340525853107, 643.7167646235641, 645.7223393940603, 646.4666650013758, 14164.220967846764, 14214.85785142156, 14216.431342746278, 14243.0, 14247.145171289652, 14117.0, 14420.855791442056, 14479.78280325796, 14485.855141448556, 14570.854291457055, 12606.81089783654, 12865.549705760348, 12955.352232388343, 12958.416871240817, 13042.804357934638, 4557.339185818071, 4568.70303430276, 4573.108243892442, 4612.607928326096, 4664.55686709762, 52594.0, 53718.19422708663, 54041.1893821593, 56945.145822812694, 61393.999999999556, 11447.828282575769, 11623.0, 11723.02448650099, 11844.874736899981, 11987.88012119885, 20145.496362591053, 20245.392638220877, 20252.493687657632, 20643.483912901997, 20664.483387915123, 8496.235338819411, 8542.829143417097, 8690.08754080825, 8903.821923561492, 8965.910340896638, 4928.162212423897, 4961.2558116282125, 5075.847724568269, 5109.233614957711, 5138.768755406016, 3547.7693949893173, 3627.3289441453417, 3633.7456378053284, 3633.8183090845882, 3643.2531331077066, 617.6695467924728, 682.5904457325565, 704.0389867830374, 725.1081170160732, 737.8634952533798, 684.4763755726873, 762.7902326860047, 773.9806504837425, 792.8335049639641, 826.4669288309007, 9412.0, 9666.516674166263, 9710.708678739507, 9772.12050915421, 9778.462184579774, 700.7687463137153, 705.9752908648172, 768.3929695540572, 786.6184900323339, 794.1224946434107, 4855.902881942341, 4877.244027175793, 4957.157283261854, 4960.70235785854, 4994.550490455804, 68658.0, 74897.0, 77188.0, 77584.61207693926, 78040.60979695179, 66338.33661663419, 66841.0, 66931.0, 67352.32647673559, 68943.65528172327, 46710.53289467062, 48736.51263487391, 54663.18005229925, 60582.39417605768, 61671.38328616746, 9493.715188544355, 9773.0, 9831.410115393099, 9840.311178217456, 9985.550650220903, 34354.312913742084, 35185.296294074484, 35599.15206783503, 36113.45829812502, 36184.457233140995, 42696.14607707766, 47213.763931180125, 47765.89521681079, 48195.75902120467, 49293.01413971772, 12682.619521414372, 12938.482460701467, 12942.158759680591, 13131.343432828511, 13171.407286672315, 13667.111637743912, 14711.043782154125, 14887.702245955237, 14996.400143994118, 15516.534503964898, 12709.36453177319, 12982.610521684364, 13804.93097534546, 14241.857581424054, 14976.026558273677, 12667.556635517712, 13668.52160174389, 13770.65573360674, 14043.227622480861, 14141.0, 12309.507619695112, 12897.355132243163, 13072.869271307545, 13436.865631343562, 13533.323333833467, 10737.677869663923, 10841.132709383388, 10860.52790489948, 10963.780724385628, 11374.658760237206, 11474.885251147381, 11537.538498459968, 11769.293842369143, 11773.234739741889, 11837.526498940291], "value": 7212.850431861112, "unit": "score"}], "name": "dromaeo_css", "value": 7205.578036690639, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 104.0, 109.0, 109.0, 109.0, 108.0, 109.0, 110.0, 109.0, 111.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 123.0, 147.0, 209.0, 124.0, 162.0, 147.0, 132.0, 191.0, 124.0], "value": 150.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 161.0, 160.0, 159.0, 161.0, 163.0, 168.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 152.0, 75.0, 75.0, 75.0, 75.0, 145.0, 75.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 92.0, 91.0, 91.0, 126.0, 92.0, 91.0, 116.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 119.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 147.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 56.0, 54.0, 58.0, 55.0, 55.0, 107.0, 53.0, 53.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 77.0, 76.0, 79.0, 79.0, 77.0, 79.0, 80.0, 78.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 122.0, 122.0, 119.0, 115.0, 116.0, 115.0, 117.0, 197.0, 123.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 165.0, 159.0, 167.0, 159.0, 163.0, 159.0, 160.0, 159.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 62.0, 65.0, 63.0, 66.0, 65.0, 61.0, 62.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1519.8, "alertThreshold": 2.0}]} 11:39:23 INFO - Return code: 0 11:39:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:39:24 ERROR - # TBPL SUCCESS # 11:39:24 INFO - Running post-action listener: _resource_record_post_action 11:39:24 INFO - ##### 11:39:24 INFO - ##### Finished run-tests step (success) 11:39:24 INFO - ##### 11:39:24 INFO - Running post-run listener: _resource_record_post_run 11:39:24 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 366055424; Read time: 20; Write time: 213112 11:39:24 INFO - TinderboxPrint: CPU usage
13.2% 11:39:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 11:39:24 INFO - TinderboxPrint: I/O write bytes / time
366,055,424 / 213,112 11:39:24 INFO - TinderboxPrint: CPU idle
8,266.3 (86.8%) 11:39:24 INFO - TinderboxPrint: CPU user
1,211.0 (12.7%) 11:39:24 INFO - TinderboxPrint: Swap in / out
0 / 0 11:39:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 72577024; Read time: 0; Write time: 32896 11:39:24 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288755712; Read time: 20; Write time: 180020 11:39:24 INFO - Running post-run listener: _upload_blobber_files 11:39:24 INFO - Blob upload gear active. 11:39:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:39:24 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 11:39:24 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:39:24 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:39:25 INFO - (blobuploader) - INFO - Open directory for files ... 11:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:28 INFO - (blobuploader) - INFO - Done attempting. 11:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:29 INFO - (blobuploader) - INFO - Done attempting. 11:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:39:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:30 INFO - (blobuploader) - INFO - Done attempting. 11:39:30 INFO - (blobuploader) - INFO - Iteration through files over. 11:39:30 INFO - Return code: 0 11:39:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:39:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:39:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d009144c125f319f2e8d3f1c92b74ac1d35a307d01b052f3ac540458fe1130113e2640a51ca9e74988fe27de69c31b9266da519c311ac2c9c4428efa55e24d7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7cdcb334b6adb1c8acfbfd52ee6fdcf8da31d851b96791af182786735bf0fa14fc72428aae656a3c56a0296d907731b238185581f4c33d1ca9957563ddcb1e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd0a5af2193ba75521acfe5a6a48eaf6eba3e34f21570fe47fd12fd3423544b264157b8121932f49cdb2fc7bc12faab79f3672c70e678aa02b293a1275323046"} 11:39:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:39:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:39:30 INFO - Contents: 11:39:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d009144c125f319f2e8d3f1c92b74ac1d35a307d01b052f3ac540458fe1130113e2640a51ca9e74988fe27de69c31b9266da519c311ac2c9c4428efa55e24d7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7cdcb334b6adb1c8acfbfd52ee6fdcf8da31d851b96791af182786735bf0fa14fc72428aae656a3c56a0296d907731b238185581f4c33d1ca9957563ddcb1e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd0a5af2193ba75521acfe5a6a48eaf6eba3e34f21570fe47fd12fd3423544b264157b8121932f49cdb2fc7bc12faab79f3672c70e678aa02b293a1275323046"} 11:39:30 INFO - Running post-run listener: copy_logs_to_upload_dir 11:39:30 INFO - Copying logs to upload dir... 11:39:30 INFO - mkdir: /builds/slave/test/build/upload/logs 11:39:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.556042 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-16 11:39:30.406701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:39:30.409886) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 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/sha512/d009144c125f319f2e8d3f1c92b74ac1d35a307d01b052f3ac540458fe1130113e2640a51ca9e74988fe27de69c31b9266da519c311ac2c9c4428efa55e24d7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7cdcb334b6adb1c8acfbfd52ee6fdcf8da31d851b96791af182786735bf0fa14fc72428aae656a3c56a0296d907731b238185581f4c33d1ca9957563ddcb1e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd0a5af2193ba75521acfe5a6a48eaf6eba3e34f21570fe47fd12fd3423544b264157b8121932f49cdb2fc7bc12faab79f3672c70e678aa02b293a1275323046"} build_url:https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/H67eVTFCRjyrwtKgE6ydzA/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/sha512/d009144c125f319f2e8d3f1c92b74ac1d35a307d01b052f3ac540458fe1130113e2640a51ca9e74988fe27de69c31b9266da519c311ac2c9c4428efa55e24d7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7cdcb334b6adb1c8acfbfd52ee6fdcf8da31d851b96791af182786735bf0fa14fc72428aae656a3c56a0296d907731b238185581f4c33d1ca9957563ddcb1e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd0a5af2193ba75521acfe5a6a48eaf6eba3e34f21570fe47fd12fd3423544b264157b8121932f49cdb2fc7bc12faab79f3672c70e678aa02b293a1275323046"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:39:30.435889) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:39:30.436257) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1463422310.75718-2102562459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:39:30.518378) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:39:30.518727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:39:30.519048) ========= ========= Total master_lag: 0.19 =========