builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1463120980.79 results: success (0) buildid: 20160512224705 builduid: 176baf6029694ac687ff2a990a6d4375 revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.789101) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.789541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.789882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.801100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.801496) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 23:29:41-- 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% 84.5M=0s 2016-05-12 23:29:41 (84.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091317 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.901802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.902151) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009628 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.951928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:40.952811) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 23:29:41,199 truncating revision to first 12 chars 2016-05-12 23:29:41,199 Setting DEBUG logging. 2016-05-12 23:29:41,199 attempt 1/10 2016-05-12 23:29:41,199 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/943ca7ad1ea3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 23:29:41,900 unpacking tar archive at: fx-team-943ca7ad1ea3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942253 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:41.905926) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:41.906283) ========= script_repo_revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:41.906768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:41.907097) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:41.913111) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-12 23:29:41.913468) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:29:42 INFO - MultiFileLogger online at 20160512 23:29:42 in /builds/slave/test 23:29:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 23:29:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:29:42 INFO - {'append_to_log': False, 23:29:42 INFO - 'base_work_dir': '/builds/slave/test', 23:29:42 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 23:29:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:29:42 INFO - 'branch': 'Fx-Team-Non-PGO', 23:29:42 INFO - 'buildbot_json_path': 'buildprops.json', 23:29:42 INFO - 'config_files': ('talos/linux_config.py',), 23:29:42 INFO - 'default_actions': ('clobber', 23:29:42 INFO - 'read-buildbot-config', 23:29:42 INFO - 'download-and-extract', 23:29:42 INFO - 'populate-webroot', 23:29:42 INFO - 'create-virtualenv', 23:29:42 INFO - 'install', 23:29:42 INFO - 'run-tests'), 23:29:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:29:42 INFO - 'download_minidump_stackwalk': True, 23:29:42 INFO - 'download_symbols': 'ondemand', 23:29:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:29:42 INFO - 'tooltool.py': '/tools/tooltool.py', 23:29:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:29:42 INFO - '/tools/misc-python/virtualenv.py')}, 23:29:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:29:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:29:42 INFO - 'installer_path': 'installer.exe', 23:29:42 INFO - 'log_level': 'info', 23:29:42 INFO - 'log_name': 'talos', 23:29:42 INFO - 'log_to_console': True, 23:29:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:29:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:29:42 INFO - 'opt_config_files': (), 23:29:42 INFO - 'pip_index': False, 23:29:42 INFO - 'sps_profile': False, 23:29:42 INFO - 'sps_profile_interval': 0, 23:29:42 INFO - 'suite': 'dromaeojs-e10s', 23:29:42 INFO - 'system_bits': '32', 23:29:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:29:42 INFO - 'title': 'talos-linux64-ix-011', 23:29:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:29:42 INFO - 'use_talos_json': True, 23:29:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:29:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:29:42 INFO - 'work_dir': 'build'} 23:29:42 INFO - ##### 23:29:42 INFO - ##### Running clobber step. 23:29:42 INFO - ##### 23:29:42 INFO - Running pre-action listener: _resource_record_pre_action 23:29:42 INFO - Running main action method: clobber 23:29:42 INFO - rmtree: /builds/slave/test/build 23:29:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:29:42 INFO - Running post-action listener: _resource_record_post_action 23:29:42 INFO - ##### 23:29:42 INFO - ##### Finished clobber step (success) 23:29:42 INFO - ##### 23:29:42 INFO - ##### 23:29:42 INFO - ##### Running read-buildbot-config step. 23:29:42 INFO - ##### 23:29:42 INFO - Running pre-action listener: _resource_record_pre_action 23:29:42 INFO - Running main action method: read_buildbot_config 23:29:42 INFO - Using buildbot properties: 23:29:42 INFO - { 23:29:42 INFO - "project": "", 23:29:42 INFO - "product": "firefox", 23:29:42 INFO - "script_repo_revision": "production", 23:29:42 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 23:29:42 INFO - "repository": "", 23:29:42 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 23:29:42 INFO - "buildid": "20160512224705", 23:29:42 INFO - "pgo_build": "False", 23:29:42 INFO - "basedir": "/builds/slave/test", 23:29:42 INFO - "buildnumber": 1501, 23:29:42 INFO - "slavename": "talos-linux64-ix-011", 23:29:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:29:42 INFO - "platform": "ubuntu64_hw", 23:29:42 INFO - "scheduler": "tests-fx-team-linux64-talos", 23:29:42 INFO - "branch": "fx-team", 23:29:42 INFO - "repo_path": "integration/fx-team", 23:29:42 INFO - "revision": "943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15", 23:29:42 INFO - "stage_platform": "linux64", 23:29:42 INFO - "builduid": "176baf6029694ac687ff2a990a6d4375", 23:29:42 INFO - "slavebuilddir": "test" 23:29:42 INFO - } 23:29:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:29:42 INFO - Running post-action listener: _resource_record_post_action 23:29:42 INFO - ##### 23:29:42 INFO - ##### Finished read-buildbot-config step (success) 23:29:42 INFO - ##### 23:29:42 INFO - ##### 23:29:42 INFO - ##### Running download-and-extract step. 23:29:42 INFO - ##### 23:29:42 INFO - Running pre-action listener: _resource_record_pre_action 23:29:42 INFO - Running main action method: download_and_extract 23:29:42 INFO - mkdir: /builds/slave/test/build/tests 23:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:29:42 INFO - https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:29:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:29:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:29:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:43 INFO - Downloaded 1361 bytes. 23:29:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:29:43 INFO - Using the following test package requirements: 23:29:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:29:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:29:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:29:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'jsshell-linux-x86_64.zip'], 23:29:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:29:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:29:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:29:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:29:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:29:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:29:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:29:43 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')]} 23:29:43 INFO - https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:29:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:44 INFO - Downloaded 22329640 bytes. 23:29:44 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'] 23:29:44 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 23:29:46 INFO - Return code: 0 23:29:46 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 23:29: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')]} 23:29:46 INFO - https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:29:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:29:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:29:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:48 INFO - Downloaded 22329640 bytes. 23:29:48 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'] 23:29:48 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 23:29:50 INFO - Return code: 0 23:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:29:50 INFO - https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:29:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:29:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:29:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:51 INFO - Downloaded 10840239 bytes. 23:29:51 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'] 23:29:51 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 23:29:52 INFO - Return code: 0 23:29:52 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')]} 23:29:52 INFO - https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:29:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:29:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:29:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/deZV3iFvRR-7mzIGD1XE8g/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 23:29:53 INFO - Downloaded 54682412 bytes. 23:29:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:29:53 INFO - mkdir: /builds/slave/test/properties 23:29:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:29:53 INFO - Writing to file /builds/slave/test/properties/build_url 23:29:53 INFO - Contents: 23:29:53 INFO - build_url:https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:29:54 INFO - Running post-action listener: _resource_record_post_action 23:29:54 INFO - Running post-action listener: set_extra_try_arguments 23:29:54 INFO - ##### 23:29:54 INFO - ##### Finished download-and-extract step (success) 23:29:54 INFO - ##### 23:29:54 INFO - ##### 23:29:54 INFO - ##### Running populate-webroot step. 23:29:54 INFO - ##### 23:29:54 INFO - Running pre-action listener: _resource_record_pre_action 23:29:54 INFO - Running main action method: populate_webroot 23:29:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:29:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:29:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:29:54 INFO - '--tppagecycles', 23:29:54 INFO - '7'], 23:29:54 INFO - 'tests': ['tsvgm']}}, 23:29:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:29:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:29:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:29:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:29:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:29:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:29:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['damp', 'tps']}, 23:29:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'tests': ['damp', 'tps']}, 23:29:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['dromaeo_dom']}, 23:29:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:29:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'other-e10s': {'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['a11yr', 23:29:54 INFO - 'ts_paint', 23:29:54 INFO - 'tpaint', 23:29:54 INFO - 'sessionrestore', 23:29:54 INFO - 'sessionrestore_no_auto_restore', 23:29:54 INFO - 'tabpaint']}, 23:29:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['tsvgx', 23:29:54 INFO - 'tsvgr_opacity', 23:29:54 INFO - 'tart', 23:29:54 INFO - 'tscrollx', 23:29:54 INFO - 'cart']}, 23:29:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:29:54 INFO - 'tsvgr_opacity', 23:29:54 INFO - 'tart', 23:29:54 INFO - 'tscrollx', 23:29:54 INFO - 'cart']}, 23:29:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'talos_options': ['--disable-e10s'], 23:29:54 INFO - 'tests': ['tp5o']}, 23:29:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'tests': ['tp5o']}, 23:29:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'talos_options': ['--disable-e10s', 23:29:54 INFO - '--xperf_path', 23:29:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:29:54 INFO - 'tests': ['tp5n']}, 23:29:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:29:54 INFO - 'talos_options': ['--xperf_path', 23:29:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:29:54 INFO - 'tests': ['tp5n']}}, 23:29:54 INFO - 'talos.zip': {'path': '', 23:29:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:29:54 INFO - Running post-action listener: _resource_record_post_action 23:29:54 INFO - ##### 23:29:54 INFO - ##### Finished populate-webroot step (success) 23:29:54 INFO - ##### 23:29:54 INFO - ##### 23:29:54 INFO - ##### Running create-virtualenv step. 23:29:54 INFO - ##### 23:29:54 INFO - Running pre-action listener: _resource_record_pre_action 23:29:54 INFO - Running main action method: create_virtualenv 23:29:54 INFO - Creating virtualenv /builds/slave/test/build/venv 23:29:54 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 23:29:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:29:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:29:54 INFO - Using real prefix '/usr' 23:29:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:29:54 INFO - Installing distribute.............................................................................................................................................................................................done. 23:29:56 INFO - Installing pip.................done. 23:29:56 INFO - Return code: 0 23:29:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:29: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')]} 23:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29: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')]} 23:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29:56 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:29:56 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 23:29:56 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 23:29:56 INFO - Using env: {'DISPLAY': ':0', 23:29:56 INFO - 'HOME': '/home/cltbld', 23:29:56 INFO - 'LANG': 'en_US.UTF-8', 23:29:56 INFO - 'LANGUAGE': 'en_US:en', 23:29:56 INFO - 'LOGNAME': 'cltbld', 23:29:56 INFO - 'MAIL': '/var/mail/cltbld', 23:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:29:56 INFO - 'MOZ_NO_REMOTE': '1', 23:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:29:56 INFO - 'NO_EM_RESTART': '1', 23:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:29:56 INFO - 'PWD': '/builds/slave/test', 23:29:56 INFO - 'SHELL': '/bin/bash', 23:29:56 INFO - 'SHLVL': '1', 23:29:56 INFO - 'TERM': 'linux', 23:29:56 INFO - 'TMOUT': '86400', 23:29:56 INFO - 'USER': 'cltbld', 23:29:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:29:56 INFO - '_': '/tools/buildbot/bin/python'} 23:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:29:56 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 23:29:56 INFO - Cleaning up... 23:29:56 INFO - Return code: 0 23:29:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:29: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')]} 23:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29: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')]} 23:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:29:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:29:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:29:56 INFO - Using env: {'DISPLAY': ':0', 23:29:56 INFO - 'HOME': '/home/cltbld', 23:29:56 INFO - 'LANG': 'en_US.UTF-8', 23:29:56 INFO - 'LANGUAGE': 'en_US:en', 23:29:56 INFO - 'LOGNAME': 'cltbld', 23:29:56 INFO - 'MAIL': '/var/mail/cltbld', 23:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:29:56 INFO - 'MOZ_NO_REMOTE': '1', 23:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:29:56 INFO - 'NO_EM_RESTART': '1', 23:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:29:56 INFO - 'PWD': '/builds/slave/test', 23:29:56 INFO - 'SHELL': '/bin/bash', 23:29:56 INFO - 'SHLVL': '1', 23:29:56 INFO - 'TERM': 'linux', 23:29:56 INFO - 'TMOUT': '86400', 23:29:56 INFO - 'USER': 'cltbld', 23:29:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:29:56 INFO - '_': '/tools/buildbot/bin/python'} 23:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:29:57 INFO - Downloading/unpacking psutil>=0.7.1 23:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:29:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:29:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:29:58 INFO - Installing collected packages: psutil 23:29:58 INFO - Running setup.py install for psutil 23:29:59 INFO - building 'psutil._psutil_linux' extension 23:29:59 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 23:29:59 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 23:29:59 INFO - building 'psutil._psutil_posix' extension 23:29:59 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 23:29:59 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 23:29:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:29:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:29:59 INFO - Successfully installed psutil 23:29:59 INFO - Cleaning up... 23:29:59 INFO - Return code: 0 23:29:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:29:59 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')]} 23:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29:59 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')]} 23:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:29:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:29:59 INFO - Using env: {'DISPLAY': ':0', 23:29:59 INFO - 'HOME': '/home/cltbld', 23:29:59 INFO - 'LANG': 'en_US.UTF-8', 23:29:59 INFO - 'LANGUAGE': 'en_US:en', 23:29:59 INFO - 'LOGNAME': 'cltbld', 23:29:59 INFO - 'MAIL': '/var/mail/cltbld', 23:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:29:59 INFO - 'MOZ_NO_REMOTE': '1', 23:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:29:59 INFO - 'NO_EM_RESTART': '1', 23:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:29:59 INFO - 'PWD': '/builds/slave/test', 23:29:59 INFO - 'SHELL': '/bin/bash', 23:29:59 INFO - 'SHLVL': '1', 23:29:59 INFO - 'TERM': 'linux', 23:29:59 INFO - 'TMOUT': '86400', 23:29:59 INFO - 'USER': 'cltbld', 23:29:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:29:59 INFO - '_': '/tools/buildbot/bin/python'} 23:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:29:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:30:01 INFO - Installing collected packages: mozsystemmonitor 23:30:01 INFO - Running setup.py install for mozsystemmonitor 23:30:01 INFO - Successfully installed mozsystemmonitor 23:30:01 INFO - Cleaning up... 23:30:01 INFO - Return code: 0 23:30:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:01 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:01 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 23:30:01 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 23:30:01 INFO - Using env: {'DISPLAY': ':0', 23:30:01 INFO - 'HOME': '/home/cltbld', 23:30:01 INFO - 'LANG': 'en_US.UTF-8', 23:30:01 INFO - 'LANGUAGE': 'en_US:en', 23:30:01 INFO - 'LOGNAME': 'cltbld', 23:30:01 INFO - 'MAIL': '/var/mail/cltbld', 23:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:01 INFO - 'MOZ_NO_REMOTE': '1', 23:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:01 INFO - 'NO_EM_RESTART': '1', 23:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:01 INFO - 'PWD': '/builds/slave/test', 23:30:01 INFO - 'SHELL': '/bin/bash', 23:30:01 INFO - 'SHLVL': '1', 23:30:01 INFO - 'TERM': 'linux', 23:30:01 INFO - 'TMOUT': '86400', 23:30:01 INFO - 'USER': 'cltbld', 23:30:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:01 INFO - '_': '/tools/buildbot/bin/python'} 23:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:01 INFO - Downloading/unpacking blobuploader==1.2.4 23:30:01 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 23:30:01 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 23:30:01 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 23:30:01 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 23:30:01 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 23:30:01 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 23:30:03 INFO - Downloading blobuploader-1.2.4.tar.gz 23:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:30:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:30:03 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 23:30:03 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 23:30:03 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 23:30:03 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 23:30:03 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 23:30:03 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 23:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:30:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:30:04 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 23:30:04 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 23:30:04 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 23:30:04 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 23:30:04 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 23:30:04 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 23:30:04 INFO - Downloading docopt-0.6.1.tar.gz 23:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:30:04 INFO - Installing collected packages: blobuploader, requests, docopt 23:30:04 INFO - Running setup.py install for blobuploader 23:30:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:30:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:30:04 INFO - Running setup.py install for requests 23:30:04 INFO - Running setup.py install for docopt 23:30:04 INFO - Successfully installed blobuploader requests docopt 23:30:04 INFO - Cleaning up... 23:30:05 INFO - Return code: 0 23:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:30:05 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')]} 23:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:05 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')]} 23:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:05 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:05 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 23:30:05 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 23:30:05 INFO - Using env: {'DISPLAY': ':0', 23:30:05 INFO - 'HOME': '/home/cltbld', 23:30:05 INFO - 'LANG': 'en_US.UTF-8', 23:30:05 INFO - 'LANGUAGE': 'en_US:en', 23:30:05 INFO - 'LOGNAME': 'cltbld', 23:30:05 INFO - 'MAIL': '/var/mail/cltbld', 23:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:05 INFO - 'MOZ_NO_REMOTE': '1', 23:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:05 INFO - 'NO_EM_RESTART': '1', 23:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:05 INFO - 'PWD': '/builds/slave/test', 23:30:05 INFO - 'SHELL': '/bin/bash', 23:30:05 INFO - 'SHLVL': '1', 23:30:05 INFO - 'TERM': 'linux', 23:30:05 INFO - 'TMOUT': '86400', 23:30:05 INFO - 'USER': 'cltbld', 23:30:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:05 INFO - '_': '/tools/buildbot/bin/python'} 23:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:30:05 INFO - Running setup.py (path:/tmp/pip-qa50HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:30:05 INFO - Running setup.py (path:/tmp/pip-5CDQ9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:30:05 INFO - Running setup.py (path:/tmp/pip-z3tOru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:30:05 INFO - Running setup.py (path:/tmp/pip-gIjnNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:30:05 INFO - Running setup.py (path:/tmp/pip-Z21bjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:30:05 INFO - Running setup.py (path:/tmp/pip-prZ1V2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:30:05 INFO - Running setup.py (path:/tmp/pip-H15VSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:30:05 INFO - Running setup.py (path:/tmp/pip-1rOHj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:30:05 INFO - Running setup.py (path:/tmp/pip-VQGob2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:30:05 INFO - Running setup.py (path:/tmp/pip-Jf6Ax6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:30:05 INFO - Running setup.py (path:/tmp/pip-zcJvhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:30:05 INFO - Running setup.py (path:/tmp/pip-99suSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:30:05 INFO - Running setup.py (path:/tmp/pip-TNhLMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:30:05 INFO - Running setup.py (path:/tmp/pip-dFcMFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:06 INFO - Running setup.py (path:/tmp/pip-DSrtQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:30:06 INFO - Running setup.py (path:/tmp/pip-UinPtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:30:06 INFO - Running setup.py (path:/tmp/pip-oqnuSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:30:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:30:06 INFO - Running setup.py install for manifestparser 23:30:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Running setup.py install for mozcrash 23:30:06 INFO - Running setup.py install for mozdebug 23:30:06 INFO - Running setup.py install for mozdevice 23:30:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Running setup.py install for mozfile 23:30:06 INFO - Running setup.py install for mozhttpd 23:30:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Running setup.py install for mozinfo 23:30:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Running setup.py install for mozInstall 23:30:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:30:06 INFO - Running setup.py install for mozleak 23:30:06 INFO - Running setup.py install for mozlog 23:30:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Running setup.py install for moznetwork 23:30:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Running setup.py install for mozprocess 23:30:07 INFO - Running setup.py install for mozprofile 23:30:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Running setup.py install for mozrunner 23:30:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Running setup.py install for mozscreenshot 23:30:07 INFO - Running setup.py install for moztest 23:30:07 INFO - Running setup.py install for mozversion 23:30:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:30:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:30:07 INFO - Cleaning up... 23:30:07 INFO - Return code: 0 23:30:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:30: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')]} 23:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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')]} 23:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:07 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:07 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 23:30:07 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 23:30:07 INFO - Using env: {'DISPLAY': ':0', 23:30:07 INFO - 'HOME': '/home/cltbld', 23:30:07 INFO - 'LANG': 'en_US.UTF-8', 23:30:07 INFO - 'LANGUAGE': 'en_US:en', 23:30:07 INFO - 'LOGNAME': 'cltbld', 23:30:07 INFO - 'MAIL': '/var/mail/cltbld', 23:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:07 INFO - 'MOZ_NO_REMOTE': '1', 23:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:07 INFO - 'NO_EM_RESTART': '1', 23:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:07 INFO - 'PWD': '/builds/slave/test', 23:30:07 INFO - 'SHELL': '/bin/bash', 23:30:07 INFO - 'SHLVL': '1', 23:30:07 INFO - 'TERM': 'linux', 23:30:07 INFO - 'TMOUT': '86400', 23:30:07 INFO - 'USER': 'cltbld', 23:30:07 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:07 INFO - '_': '/tools/buildbot/bin/python'} 23:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:30:07 INFO - Running setup.py (path:/tmp/pip-eEp52t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:30:08 INFO - Running setup.py (path:/tmp/pip-qQm4y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:30:08 INFO - Running setup.py (path:/tmp/pip-J1prHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:30:08 INFO - Running setup.py (path:/tmp/pip-_JhdI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:30:08 INFO - Running setup.py (path:/tmp/pip-YrHBFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:30:08 INFO - Running setup.py (path:/tmp/pip-zlFlNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:30:08 INFO - Running setup.py (path:/tmp/pip-X8_gnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:30:08 INFO - Running setup.py (path:/tmp/pip-P_YdRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:30:08 INFO - Running setup.py (path:/tmp/pip-SqXuwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:30:08 INFO - Running setup.py (path:/tmp/pip-gLkh_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:30:08 INFO - Running setup.py (path:/tmp/pip-Sb90Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:30:08 INFO - Running setup.py (path:/tmp/pip-A8gtwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:30:08 INFO - Running setup.py (path:/tmp/pip-ZmKSx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:30:08 INFO - Running setup.py (path:/tmp/pip-RzEkiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:08 INFO - Running setup.py (path:/tmp/pip-p_5bBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:30:08 INFO - Running setup.py (path:/tmp/pip-UsXfCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:30:08 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)) 23:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:30:08 INFO - Running setup.py (path:/tmp/pip-DgBkH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:30:09 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)) 23:30:09 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)) 23:30:09 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)) 23:30:09 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)) 23:30:09 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)) 23:30:09 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)) 23:30:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:30:09 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 23:30:09 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 23:30:09 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 23:30:09 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 23:30:09 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 23:30:09 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 23:30:10 INFO - Downloading blessings-1.6.tar.gz 23:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:30:10 INFO - Installing collected packages: blessings 23:30:10 INFO - Running setup.py install for blessings 23:30:11 INFO - Successfully installed blessings 23:30:11 INFO - Cleaning up... 23:30:11 INFO - Return code: 0 23:30:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:30:11 INFO - Reading from file tmpfile_stdout 23:30:11 INFO - Current package versions: 23:30:11 INFO - argparse == 1.2.1 23:30:11 INFO - blessings == 1.6 23:30:11 INFO - blobuploader == 1.2.4 23:30:11 INFO - docopt == 0.6.1 23:30:11 INFO - manifestparser == 1.1 23:30:11 INFO - mozInstall == 1.12 23:30:11 INFO - mozcrash == 0.17 23:30:11 INFO - mozdebug == 0.1 23:30:11 INFO - mozdevice == 0.48 23:30:11 INFO - mozfile == 1.2 23:30:11 INFO - mozhttpd == 0.7 23:30:11 INFO - mozinfo == 0.9 23:30:11 INFO - mozleak == 0.1 23:30:11 INFO - mozlog == 3.2 23:30:11 INFO - moznetwork == 0.27 23:30:11 INFO - mozprocess == 0.22 23:30:11 INFO - mozprofile == 0.28 23:30:11 INFO - mozrunner == 6.11 23:30:11 INFO - mozscreenshot == 0.1 23:30:11 INFO - mozsystemmonitor == 0.0 23:30:11 INFO - moztest == 0.7 23:30:11 INFO - mozversion == 1.4 23:30:11 INFO - psutil == 3.1.1 23:30:11 INFO - requests == 1.2.3 23:30:11 INFO - wsgiref == 0.1.2 23:30:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:30:11 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')]} 23:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:11 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')]} 23:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:11 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:11 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 23:30:11 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 23:30:11 INFO - Using env: {'DISPLAY': ':0', 23:30:11 INFO - 'HOME': '/home/cltbld', 23:30:11 INFO - 'LANG': 'en_US.UTF-8', 23:30:11 INFO - 'LANGUAGE': 'en_US:en', 23:30:11 INFO - 'LOGNAME': 'cltbld', 23:30:11 INFO - 'MAIL': '/var/mail/cltbld', 23:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:11 INFO - 'MOZ_NO_REMOTE': '1', 23:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:11 INFO - 'NO_EM_RESTART': '1', 23:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:11 INFO - 'PWD': '/builds/slave/test', 23:30:11 INFO - 'SHELL': '/bin/bash', 23:30:11 INFO - 'SHLVL': '1', 23:30:11 INFO - 'TERM': 'linux', 23:30:11 INFO - 'TMOUT': '86400', 23:30:11 INFO - 'USER': 'cltbld', 23:30:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:11 INFO - '_': '/tools/buildbot/bin/python'} 23:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 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)) 23:30:11 INFO - Cleaning up... 23:30:11 INFO - Return code: 0 23:30:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:30:11 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')]} 23:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:11 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')]} 23:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:11 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:11 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 23:30:11 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 23:30:11 INFO - Using env: {'DISPLAY': ':0', 23:30:11 INFO - 'HOME': '/home/cltbld', 23:30:11 INFO - 'LANG': 'en_US.UTF-8', 23:30:11 INFO - 'LANGUAGE': 'en_US:en', 23:30:11 INFO - 'LOGNAME': 'cltbld', 23:30:11 INFO - 'MAIL': '/var/mail/cltbld', 23:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:11 INFO - 'MOZ_NO_REMOTE': '1', 23:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:11 INFO - 'NO_EM_RESTART': '1', 23:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:11 INFO - 'PWD': '/builds/slave/test', 23:30:11 INFO - 'SHELL': '/bin/bash', 23:30:11 INFO - 'SHLVL': '1', 23:30:11 INFO - 'TERM': 'linux', 23:30:11 INFO - 'TMOUT': '86400', 23:30:11 INFO - 'USER': 'cltbld', 23:30:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:11 INFO - '_': '/tools/buildbot/bin/python'} 23:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:11 INFO - Downloading/unpacking jsonschema 23:30:11 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 23:30:11 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 23:30:11 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 23:30:11 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 23:30:11 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 23:30:11 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 23:30:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:30:13 INFO - Downloading/unpacking functools32 (from jsonschema) 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30:13 INFO - Downloading functools32-3.2.3-2.tar.gz 23:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:30:13 INFO - warning: no files found matching '*.txt' 23:30:13 INFO - no previously-included directories found matching 'build' 23:30:13 INFO - no previously-included directories found matching 'dist' 23:30:13 INFO - no previously-included directories found matching '.git*' 23:30:13 INFO - Installing collected packages: jsonschema, functools32 23:30:13 INFO - Running setup.py install for functools32 23:30:13 INFO - warning: no files found matching '*.txt' 23:30:13 INFO - no previously-included directories found matching 'build' 23:30:13 INFO - no previously-included directories found matching 'dist' 23:30:13 INFO - no previously-included directories found matching '.git*' 23:30:13 INFO - Successfully installed jsonschema functools32 23:30:13 INFO - Cleaning up... 23:30:13 INFO - Return code: 0 23:30:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:30:13 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')]} 23:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:13 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')]} 23:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:13 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 0x7f3510f24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', '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 23:30:13 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 23:30:13 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 23:30:13 INFO - Using env: {'DISPLAY': ':0', 23:30:13 INFO - 'HOME': '/home/cltbld', 23:30:13 INFO - 'LANG': 'en_US.UTF-8', 23:30:13 INFO - 'LANGUAGE': 'en_US:en', 23:30:13 INFO - 'LOGNAME': 'cltbld', 23:30:13 INFO - 'MAIL': '/var/mail/cltbld', 23:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:13 INFO - 'MOZ_NO_REMOTE': '1', 23:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:13 INFO - 'NO_EM_RESTART': '1', 23:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:13 INFO - 'PWD': '/builds/slave/test', 23:30:13 INFO - 'SHELL': '/bin/bash', 23:30:13 INFO - 'SHLVL': '1', 23:30:13 INFO - 'TERM': 'linux', 23:30:13 INFO - 'TMOUT': '86400', 23:30:13 INFO - 'USER': 'cltbld', 23:30:13 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:13 INFO - '_': '/tools/buildbot/bin/python'} 23:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:14 INFO - Downloading/unpacking flake8 23:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:30:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:30:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 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 23:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:30:16 INFO - no previously-included directories found matching 'docs/_build' 23:30:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:30:16 INFO - Running setup.py install for pep8 23:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:30:16 INFO - no previously-included directories found matching 'docs/_build' 23:30:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:30:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:30:16 INFO - Cleaning up... 23:30:16 INFO - Return code: 0 23:30:16 INFO - Running post-action listener: _resource_record_post_action 23:30:16 INFO - Running post-action listener: _start_resource_monitoring 23:30:16 INFO - Starting resource monitoring. 23:30:16 INFO - ##### 23:30:16 INFO - ##### Finished create-virtualenv step (success) 23:30:16 INFO - ##### 23:30:16 INFO - ##### 23:30:16 INFO - ##### Running install step. 23:30:16 INFO - ##### 23:30:16 INFO - Running pre-action listener: _resource_record_pre_action 23:30:16 INFO - Running main action method: install 23:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:30:17 INFO - Reading from file tmpfile_stdout 23:30:17 INFO - Detecting whether we're running mozinstall >=1.0... 23:30:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:30:17 INFO - Reading from file tmpfile_stdout 23:30:17 INFO - Output received: 23:30:17 INFO - Usage: mozinstall [options] installer 23:30:17 INFO - Options: 23:30:17 INFO - -h, --help show this help message and exit 23:30:17 INFO - -d DEST, --destination=DEST 23:30:17 INFO - Directory to install application into. [default: 23:30:17 INFO - "/builds/slave/test"] 23:30:17 INFO - --app=APP Application being installed. [default: firefox] 23:30:17 INFO - mkdir: /builds/slave/test/build/application 23:30:17 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'] 23:30:17 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 23:30:32 INFO - Reading from file tmpfile_stdout 23:30:32 INFO - Output received: 23:30:32 INFO - /builds/slave/test/build/application/firefox/firefox 23:30:32 INFO - Running post-action listener: _resource_record_post_action 23:30:32 INFO - ##### 23:30:32 INFO - ##### Finished install step (success) 23:30:32 INFO - ##### 23:30:32 INFO - ##### 23:30:32 INFO - ##### Running run-tests step. 23:30:32 INFO - ##### 23:30:32 INFO - Running pre-action listener: _resource_record_pre_action 23:30:32 INFO - Running main action method: run_tests 23:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:30:32 INFO - Python 2.7.3 23:30:32 INFO - Return code: 0 23:30:32 INFO - grabbing minidump binary from tooltool 23:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:30:32 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 0x2bfb9c0>, '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 0x2bfc8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfcd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:30:32 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 23:30:32 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 23:30:32 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 23:30:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:30:33 INFO - Return code: 0 23:30:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:30:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:30:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:30:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:30:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:30:33 INFO - Return code: 0 23:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:30:33 INFO - Using env: {'DISPLAY': ':0', 23:30:33 INFO - 'HOME': '/home/cltbld', 23:30:33 INFO - 'LANG': 'en_US.UTF-8', 23:30:33 INFO - 'LANGUAGE': 'en_US:en', 23:30:33 INFO - 'LOGNAME': 'cltbld', 23:30:33 INFO - 'MAIL': '/var/mail/cltbld', 23:30:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:30:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:33 INFO - 'MOZ_NO_REMOTE': '1', 23:30:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:30:33 INFO - 'NO_EM_RESTART': '1', 23:30:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:33 INFO - 'PWD': '/builds/slave/test', 23:30:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:30:33 INFO - 'SHELL': '/bin/bash', 23:30:33 INFO - 'SHLVL': '1', 23:30:33 INFO - 'TERM': 'linux', 23:30:33 INFO - 'TMOUT': '86400', 23:30:33 INFO - 'USER': 'cltbld', 23:30:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942', 23:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:33 INFO - '_': '/tools/buildbot/bin/python'} 23:30:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:30:34 INFO - mozversion application_buildid: 20160512224705 23:30:34 INFO - mozversion application_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 23:30:34 INFO - mozversion application_display_name: Nightly 23:30:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:30:34 INFO - mozversion application_name: Firefox 23:30:34 INFO - mozversion application_remotingname: firefox 23:30:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:30:34 INFO - mozversion application_vendor: Mozilla 23:30:34 INFO - mozversion application_version: 49.0a1 23:30:34 INFO - mozversion platform_buildid: 20160512224705 23:30:34 INFO - mozversion platform_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 23:30:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:30:34 INFO - mozversion platform_version: 49.0a1 23:30:34 INFO - using testdate: 1463121034 23:30:34 INFO - actual date: 1463121034 23:30:34 INFO - starting webserver on 'localhost:35072' 23:30:34 INFO - SUITE-START | Running 2 tests 23:30:34 INFO - TEST-START | dromaeo_css 23:30:34 INFO - operating with platform_type : linux_ 23:30:34 INFO - Initialising browser for dromaeo_css test... 23:30:34 INFO - TEST-INFO | started process 25109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5EKcRz/profile http://localhost:35072/getInfo.html) 23:30:38 INFO - PROCESS | 25109 | 23:30:38 INFO - PROCESS | 25109 | (process:25177): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:30:39 INFO - PROCESS | 25109 | __metrics Screen width/height:1600/1200 23:30:39 INFO - PROCESS | 25109 | colorDepth:24 23:30:39 INFO - PROCESS | 25109 | Browser inner width/height: 1024/697 23:30:39 INFO - PROCESS | 25109 | __metrics 23:30:40 ERROR - PROCESS | 25109 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:30:41 INFO - TEST-INFO | 25109: exit 0 23:30:41 INFO - Browser initialized. 23:30:41 INFO - Running cycle 1/1 for dromaeo_css test... 23:30:41 INFO - TEST-INFO | started process 25228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5EKcRz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:30:42 INFO - PROCESS | 25228 | 23:30:42 INFO - PROCESS | 25228 | (process:25289): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:30:52 INFO - PROCESS | 25228 | RSS: Main: 163971072 23:30:52 INFO - PROCESS | 25228 | 23:31:42 INFO - PROCESS | 25228 | 1463121102480 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] 23:33:54 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35072/tests/dromaeo/cssquery-ext.html) 23:33:55 INFO - PROCESS | 25228 | RSS: Main: 166801408 23:33:55 INFO - PROCESS | 25228 | 23:36:57 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-ext.html (next: http://localhost:35072/tests/dromaeo/cssquery-jquery.html) 23:36:57 INFO - PROCESS | 25228 | RSS: Main: 167022592 23:36:57 INFO - PROCESS | 25228 | 23:39:59 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35072/tests/dromaeo/cssquery-mootools.html) 23:40:00 INFO - PROCESS | 25228 | RSS: Main: 167813120 23:40:00 INFO - PROCESS | 25228 | 23:43:02 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35072/tests/dromaeo/cssquery-prototype.html) 23:43:02 INFO - PROCESS | 25228 | RSS: Main: 165408768 23:43:02 INFO - PROCESS | 25228 | 23:46:05 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35072/tests/dromaeo/cssquery-yui.html) 23:46:05 INFO - PROCESS | 25228 | RSS: Main: 166866944 23:46:05 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | Cycle 1(1): loaded http://localhost:35072/tests/dromaeo/cssquery-yui.html (next: http://localhost:35072/tests/dromaeo/cssquery-dojo.html) 23:49:08 INFO - PROCESS | 25228 | RSS: Main: 168726528 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | __start_tp_report 23:49:08 INFO - PROCESS | 25228 | _x_x_mozilla_page_load 23:49:08 INFO - PROCESS | 25228 | _x_x_mozilla_page_load_details 23:49:08 INFO - PROCESS | 25228 | |i|pagename|runs| 23:49:08 INFO - PROCESS | 25228 | |0;dojo.html;2548.401125735452;2565.756253155952;2591.8574478403684;2620.6200100985357;2637.696664883539;4704.670673052888;4704.858854234374;4732.4321081470225;4772.475027746949;4821.927671084936;4642.675012749109;4654.27858681904;4722.055588882229;4805.591524720394;4813.8796530086765;4327.978360108203;4405.493368262642;4449.688521803475;4458.242098843204;4470.888227794315;488.39989146669063;4315.331123675835;4323.783810809463;4369.279068953619;4538.659600529957;4325.286327755916;4347.826086956518;4354.673399495035;4367.323064924942;4393.53867843875;4359.6076353128055;4370.104128653618;4391.5169331373845;4419.226635338803;4451.020775429401;4301;4344.9137715571105;4367;4368.453943257093;4380.255356589388;4690.9530904690855;4712.929306060412;4720.173969555314;4724.385829842133;4747.026859493795;5098.082345177866;5153.613478989091;5161.974190129063;5171.017506673757;5193.298904647862;5007.298978143063;5010.924836127461;5048.697078175322;5118.129917913963;5244.947550524484;4688.929666055012;4731.597814185833;4747.240441529373;4758.262469317261;4817.614590832725;5129.12804823181;5143.996920600447;5163.948360516347;5168.948310516922;5175.327207463006;4867.975660121677;4937.777799998937;5001.024800164006;5005.349304590452;5038.496150385005;5050.49495050492;5075.898482030412;5096.592272618182;5097.311862898461;5117.590592752571;5109.08036553424;5136.8715782105;5148.944466384382;5152.124138896396;5152.793888244429;4970.975145124251;5031.899362012812;5081.415637201769;5081.796728130834;5094.133997220481;4381.846635367747;4401.889952751207;4406.6474682026;4407.581279778416;4490.955090449119;3664.1206110533813;3794.1083845296334;3805.46723458716;3828.4831547741255;3857.8456861725217;4252.957470425318;4348.673849461303;4372.475302963665;4399.252127138394;4412.580804823537;5093.617978651616;5122.257272695439;5172.301739265151;5189.299444575009;5235.973820130875;4348.28253338202;4427.977860110679;4445.533219012022;4459.799309031101;4508;4591.701539399928;4601.631869450436;4629.3518907353;4643.071385722869;4646.977664913714;4728.503507131771;4787.3776409066595;4823.3729615149805;4827.686200396963;4854.466008739036;5056.786371270942;5197.8440646780655;5199.71401572902;5203.297554830049;5267.9464107177955;4860.756962151949;4875.878103047452;4877.048975449895;4891.33966914462;4925.359703238556;5196.194589838581;5199.870003249949;5209.426963033988;5213.6350455467755;5262.8947421052135;5020.347354843847;5094.312267843794;5200.959808038332;5291.470852914677;5292.047431462373;5000.224965130411;5033.446320904624;5045.470225626404;5072.54347108762;5106.821261255838;4961.875953101201;5029.094762942559;5063.291139240436;5073.822416215415;5080.644354895122;4739.905201895874;4871.707697538159;4914.631402644744;4919.557239848431;4976;5086.771095300646;5091.287219789307;5103.183490641628;5128.820491282787;5166.199239117943;4992.450830408579;5005.049040682405;5011.173156429294;5023.120953833138;5025.3718285214345 23:49:08 INFO - PROCESS | 25228 | |1;ext.html;557.7298472012541;570.7916610437192;575.6172145523227;593.7357875745294;606.9848253793657;33632;37639.247215055715;37702.62297377025;37739;37854.4321835172;37516;37898;37905.43141852868;37922.24155516888;37986.62013379866;7637.847243055136;7787.688492460323;7796.844063118734;7807.804804879866;7821.100573434042;7684.231576842299;7782.338501227402;7796.922030779676;7798.961005194966;7817.804554886116;7833.373330133576;7842.372610191171;7865.724699635514;7867.921320786805;7888.842223155533;7796.883046754247;7804.921950780476;7836.568988705619;7837.529748215125;7853.842923141534;7741;7782.143964163937;7819.726309579194;7830.138684744674;7875.8031049223655;29689.851550742325;35404.468932966025;35592.64407355919;35789.284214315565;35969.64030359689;3199.392115498048;3302.4550949093373;3399.405104106796;3441.1225137589754;3462.307538492312;27120.864395678094;27549.31126721828;27570.86214568914;29528.26179345512;29672;3258.8913985410595;3312.9834350828332;3366.629670736217;3450.6894379505966;3464.428369319059;235538;239919;240033.79983100147;242328;242645;34010.65989340124;34827.651723482944;35202.11994700153;56305.15542266869;58326.41673583295;35395;35644.64355356465;35763.284734305686;35877.64122358743;35956.28087438236;12959.805602915963;13067.477300907858;13095.934520327337;13116.672083197996;13124.343782810822;10726.088282495919;10727.356358618508;10788.730281742863;10831.512581933832;10853.620123295646;8473.364497662576;8475;8491.702790402307;8521.10528394522;8531.82936341282;3383.323335332894;3405.11467018572;3405.165734395089;3412.675795799395;3431.3823511768123;854.8760429737653;916.4547094478775;936.4240991790136;946.3360639799081;1037.0303765978833;26147;26155.215343539723;26395.472090558465;26537.203883883514;26673.19980400591;1056.1709058389129;1062.1078294232882;1065.781514789482;1072.0940805019745;1098.0611577101622;14241.003129781024;14323.713525729636;14379.281035948161;14392.568222953329;14651.487197948234;360080;362150;362921;364010;364558;49615;51547;51584.48415515797;51600.96798064018;51670;1283.6341642631992;1284.5953524639708;1304.2174695183005;1305.7649623067748;1309.423853504436;26753.732462675904;26937.461250775268;27148.457030858877;27905.44189116247;27973.580396293666;41684.16631667289;42215.366769499095;42508.14983700371;43237.567624323354;43442;323014;325402;325664;326433;326481;25809.6128558068;26464.470710585294;27561.173164805088;27700.168994930184;28866.422671546872;41666.79166604148;41824.16351673011;42355.576444236394;43156.78421607872;43316;36593.26813463769;36704.26591468209;37057.629423706494;37105.81447092747;37872.62127378691;36265.818670906476;36328.81835590805;36499.45250821186;36603.45094823633;37285.440718389786;34627.826860865534;35739.64260357363;37441.251174975805;38043;41665;32482.675173247964;33022.83488582542;33371.16572085717;33775.32449351048;33787.83106084454;24218.546887186825;24533.87733061323;24610.630840537047;24625.50748985046;24791.50416991686 23:49:08 INFO - PROCESS | 25228 | |2;jquery.html;382.2851268128598;385.4295642449175;387.1849756263066;391.92749341371865;408.5301902811766;13359.59921202363;15008.063751067268;16005.759913601305;17496.91251543742;17586.82413175868;16341.5914602135;16684.58288542787;17401.564960875985;17660.735088973677;17661.381851635164;4952.777125029383;5077.263796749469;5197.8440646780655;5238.449962753894;5357.812476563321;3410.079278594778;6558.311377305386;6962.338577835098;6970.721171153148;6987.231404545496;6805.659717014154;6924.930750692479;6947.756828510978;6968.303169683017;7081.5396999195145;6971.233164351917;6976.5116441849095;6977.965110174468;7081.327273908971;7097.893531597005;5987.730552125165;6433.2601750798785;6441.066045423434;6510.18622672166;6525.053867189278;21617.56764864694;22906.427339316484;23635.172768953005;24003.759962400327;24004.63993040106;2011.5675129847007;2163.3401812447196;2169.858959167649;2198.6811912272633;2228.565429741201;13876.653083672887;13893.861061389358;13964.790528142086;14677.486287979977;14808.259587020606;1817.7000794868832;1839.3562253211403;1867.5237814357388;1935.6612592796205;1971.4874132725452;59690.701546492426;63773.04340434944;78251;82645.17354826436;84763.57618211932;15503;17066.23201955915;17102.828971710125;17121.657566848844;17124;29517.852410737807;29637.703622963927;29870.85064574707;29987.700122998926;30236;8832.895888013998;9169.128932751379;9186.86219706705;9274.304427167854;9328.95335523318;7464.514806537558;7506.887396689054;7571.318581327707;7625.084989801259;7629.961850190824;3501.3697534443536;8657;8711.0417854036;8758.521172314226;8920.24177944869;1876.080720446971;1929.2283086765126;1931.1406424141123;1940.495471177504;1941.1458958058408;811.6104269950339;899.1637776867445;917.9954100229589;926.1109335038304;952.1858810716876;15800.656944159646;16013.679726405406;16097.356105755638;16152.192390380435;16519.91740041316;850.753281548344;870.1776820904312;886.7605746448552;923.1645360948353;953.5280036382036;7049;8773.42972706772;9631.903680963242;9764.414135151914;9989.250806189564;150976;405956;407430;408846;413077;72237.63881180665;77153;77517;78972.21027789649;80430.59784701039;999.9950000249952;1005.8591797148405;1009.4952523738131;1030.386919782728;1033.0702367868978;15408.383664653737;15415.306311216247;15442.768358474857;15474;15772.842271577138;33822.46331525096;34966.825165874005;35715;35789;36414.4537831938;45513.77243113763;47373.763131184125;48122.759386202844;48674.269885952446;54506.18240726313;7593.696252149852;7646.617669116633;7691.57696326685;7705.88441173394;7931.960340198262;9681.757956051155;11629.76740465203;11640.592579260025;11726.88273117292;11886.940565297464;4657.6506761992305;5516.558675306046;10374.585016599553;10478.790424191626;10561.313514621525;9968.451735154636;10111.443870587189;10257.538410771682;10377.740556486147;10601.416922069051;10385.948070259903;10492.737681558023;10519.263651544625;10637.14902807758;10938.671839844817;7532.623368831427;7635.00744903159;7692;7716.5755883426955;7746.225377462209;10251;10657.307275027099;10663.093637040938;10698.251122421667;10813.080888124598 23:49:08 INFO - PROCESS | 25228 | |3;mootools.html;1446.1973604649418;1508.110214973166;1658.5687721192487;1800.9914447909164;1802.161994672477;6666.366695163963;7235.204127545966;7459.328660420561;7975.162607926164;7999.160088190736;7313.085864266966;7536.4724469287175;7541.698332066724;7555.773326800191;7575.886361704579;2140.3685912655765;2175.0755928730305;2180.0734687757717;2185.399015270805;2186.9015894284717;2111.7149184860077;2123.2250228666503;2147.076756994492;2157.590057888996;2158.816500597451;2132.2643687927716;2138.0378829526776;2139.6362618354838;2142.0146732503067;2155.8059774620283;2059.588082383522;2061.7319748432606;2067.782882797307;2071.3785864240667;2076.179908935977;2037.6841589553494;2081.4484161697164;2086.040421406155;2090.5818836232816;2091.633964056299;7383.6677349519405;7543.094828620546;7551.848963020709;7554.508956917783;7566.432517561205;4519.7740112994215;4641.280601506772;4650.302454631798;4661.114388266218;4664.277037059262;2795.790315726308;2843.218115018366;2923.941521169586;2936.2512559297456;2938.44169607776;929.1126973740062;954.6420092465326;955.254901177083;959.194276807485;961.4567769210374;10455.320404173704;10487.79024419515;10493.895061049368;10533.736656583571;10619.469026548642;4189.560096189917;4218.620324170839;4357.54245804186;4388.4733831940375;4413;8419.789505262295;8459.49243045407;8461.619227134794;8489.660413583388;8510.191531804468;4651.139539185261;4658.440987081572;4661.906761864811;4662.790174442091;4670.229412146958;3586.5875424326014;4078.510578730512;4235.4917409910395;4320.762358070337;4333.458317710286;3348.4977253411694;3364.2430453147845;3381.306832099414;3423.1099914022534;3513.771604845677;994.3834822410151;996.0836030851634;1001.6344034427382;1004.8442491413842;1016.8474728790741;815.8123631564773;820.9096999330069;844.6705784743955;927.4667066436825;932.2868005975434;5074.898502030012;5250.711210883362;5347.598930080259;5383.300170977826;5578.86052848682;907.686848037426;943.688582767685;949.8575213717997;954.9809003820023;959.9904000960041;3695.224002959355;3740.3454395481226;3791.639794219545;3808.9428658570146;3861.9806900965314;8663.826723465496;9039.096090390909;9209.401388909699;9575.473348965874;9594.896586892457;9190.207909457591;9228.169464748205;9243.168114869559;9265.934417541934;9271.490068046322;7313.4880558361465;7345.632718364061;7481.625918704151;7494.587797671181;7508.211637777955;5463.562914966714;5497.890042199214;5671.291088613923;5674.971625141848;5806.361300256884;8774.078721734126;8836.955815220883;9095.818083638422;9144.817103657757;9153.221976131837;8054.597270136353;9031.954840226019;9077.319201059814;9105.817883642423;9139.086091390807;5958.582899197015;6448.935510645021;6598.670066496752;6687.164104486939;7000.299970002959;7331.523450975669;7373.705051798082;7397.4082073435075;7410.333070023724;7459.291367320096;6823.522353435411;6924;6989.440844732308;7002.2647622000995;7110.5022648414115;6703.497237707289;6762.154730658668;6830.556013859084;6873.965630171817;6955.513114081966;6208.72060757258;6296.307406185224;6410.711517981609;6451.870962580816;6492.448141907991;4639.86080417588;4697.8355757548325;4740.2178640523925;4775.140474714584;4791.760411979318;4591.724496530219;4691.976540117277;4708.693934894221;4712.246040633482;4794.736289504111 23:49:08 INFO - PROCESS | 25228 | |4;prototype.html;307.71075189322033;315.2843046284935;316.45569620253184;317.32093320294564;320.65529555727596;4918.581920536754;4934.629902757299;4970.850874473763;4972.179590367585;4992.729762005887;4772.78522466489;4833.589144922677;4834.854954351375;4853.223484242522;4869.318295438633;3635.927281454363;3746.1383881707225;3856.4986551748234;3865.207632435358;3871.3031654302213;3841.9615803842025;3858.787766672832;3865.8067096645195;3873.283442563121;3889.572147063821;3816.7709937403706;3824.9672588401113;3847.0574709196285;3852.4799152114524;3870.612938706135;3552.289542091566;3783.243351329745;3830.012590179664;3840.9437404713785;3854.6338097880657;3320.3857286402094;3324.202191474053;3331.0506505646017;3342.5487559179687;3356.261622443083;4588.587027167538;4673.322368256584;4677.438707355139;4688.132695451352;4691.483936766953;4292.356146578007;4352.693072671457;4361.171377438277;4370.2133615949115;4372.5627437256335;4961.355023846912;5081.822136225214;5088.465711100318;5088.626070960857;5101.642884998052;3653.9269214615824;3664.395374763161;3665.2669466106618;3668.5414323209598;3678.981605091984;5276.3140791697215;5318.867028324285;5331.546818520431;5342.492463215988;5369;3564.750467467304;3620.2759448109955;3670.1742108025483;3681.4477828325967;3692.667659910621;3730.8321125549414;3737.140457694691;3741.401375779862;3767.7550959187565;3768.4347347898033;4271.31658934569;4280.59334363235;4290.806913688892;4305.287668768656;4312.310030395184;4889.853304400874;5081.898362032813;5144.9742751286;5148.073346797538;5154.329937108227;1274.898008159345;1287.67808047988;1297.7209899871573;1298.727267273864;1310.927898965566;830.33158307563;836.4897412578322;838.3651878836293;855.3072011670566;855.4824331279486;4389.078293558325;4493.123840851003;4499;4520.819167233274;4530.954690453119;5177.249298851721;5215.348081489814;5247.291615631917;5259.973700131475;5292.20616907467;4500.829784256117;4508.549145085531;4518.864434066983;4526.117407105583;4542.954570454253;2662.7337266273216;2835.191970288458;2836.929076773056;2852.857357132135;2859.9275271773085;4957.82647607332;4966.304717339575;4975.029869175476;4978;5004.374453193351;5254.500822421864;5361.490658387447;5362.812301569426;5403.567714582824;5443;4911.880955593224;4914.1080683936225;4927.556519913225;4940.6788558743565;4940.802367905316;5289.9735501323785;5304.230886521355;5312.096943519534;5325.467453254643;5371.623986321077;5020.121478741279;5120.027194833119;5145.716985565829;5168.715720635401;5259.316288882421;4856.009036295102;4857.417109946829;4863.465018847854;4874.610031197425;4878;3328.450805617046;3362.69735723786;3373.2241584436206;3383.323335332894;3407.7273818093995;4505.887352816206;4634.328022436795;4639.443266808004;4704.858854234379;4722.693024953367;4443.933340999823;4492.505824359383;4511.278195488706;4539.024109816405;4567.200739870443;4486.66350023743;4541.43232095988;4560.361549383023;4561.657875659273;4582.9312560310955;4439.5338489458145;4453.487848897418;4461.888452788707;4471.932921006252;4483.237849565517;4811.639127065414;4897.779599917941;5042.84871453857;5150.510701483353;5183.585313174862;5001;5016.573591244785;5039.924401133912;5040.420351659606;5050.873728156826 23:49:08 INFO - PROCESS | 25228 | |5;yui.html;726.1177669132007;729.0449511140406;741.2032065529556;775.2712450296723;797.6330887791615;11940.10449216309;12228.44971976261;12439.502419903205;12440.564580239694;12480.375981200948;12562.93718531406;12688.30214338211;12739.681507962305;13150.408231629575;13938;670.0920253057111;848.1433751910553;856.9357298202644;864.7881269089082;866.0430224601819;859.4499520307008;863.9568021598894;866.6403442571325;871.0679572857014;875.3216257400502;910.7632015675906;913.1325241021024;914.1178762494192;914.6387177065088;918.361738591679;903.2051794420901;906.9546522673905;908.246155690775;909.1136142261308;909.5543183839903;663.0716996205302;663.1611012069735;663.2174034639124;666.3736088077192;667.1194023888469;13254.46982120714;13452.663683407993;13454.596362109152;13462.124961877447;13533.729325413437;12492.188632648214;12687.302198379086;12823.294718790463;12909.87090129096;13124.278164700938;9916.851247231298;10021.899781002243;10226.948865255701;10237.18102551794;10240.231982601335;3348.8995330140133;3427.725781937439;3446.8621255149765;3510.508528805969;3532.487789270567;50010.249846252336;50285;50458.74770626123;50833.23750143788;50908.74545627248;9903.158231550398;9984.950075249577;10113.190944724405;10317.690469285933;10340.10635764745;16471.423500177436;17750.644987100186;17783.82216177822;17846.821531784775;17898.373556925446;6832.350926661959;7155.3202445767565;7213.49505534618;7307.744228951961;7530.28462296081;3973.205358928226;4022.8189731462153;4067.166230922713;4068.959310406917;4083.959160408358;2779.291280723443;2858.714128587125;2862.5992361069466;2876.6835648078695;2884.79806413549;528.3158309988575;544.0316237097971;547.7535109200876;549.1872029396436;562.8986782379211;568.3804652928328;600.8317671052113;618.6442795392667;619.0837560410617;620.736187120472;7408.888866667005;7672.539647621161;7786.416018798613;7879.212078792075;7895.960520197362;503.6807438977085;584.9824505264849;592.599995003371;593.9792107276224;604.3080672629815;3684.428913518409;3807.162424266657;3826.5267871869532;3873.0898238913833;3874.321993651156;50761;52269.477305226465;52353.476465235624;52377.2143417849;52469.47530524722;44092.55907440949;46994.10022155313;48287.75856120697;49314.50685493171;49736.751316243186;41492;45636.315455268195;45689.31466027946;46274;47056.5294347059;7796.961015194887;7936.444448888522;8057.919420805952;8135.593220339078;8145.144759800136;27784.444311114068;29261.85369073141;29612.259693507836;29633.25916852096;29871.70128298689;35342.29315413729;37161.25677486381;38916;39035.6096439032;39753;11699.23954942926;11828.17202795796;12017.879821201675;12034.398280086136;12158.148929574845;12418.677755008539;12497.437615307152;12566.308853013172;12880.227186368847;13303.733925321245;11493.597724079616;11496.310221386744;11715.707107322385;11769.529218831492;11853.940730296294;11846.060738345006;11865.110116741107;11950.581729639422;11953.521859125885;12144.33206173669;11438.256513326607;11461.140414468782;11531.8270225945;11610.419479026184;11682.357470339213;9371.671991480265;9957.701268961942;10026.197904167795;10026.448545330077;10067.798644027225;8320.292775114183;8367.874481882898;8411.873821892554;8605.870911936201;8695.30437565006 23:49:08 INFO - PROCESS | 25228 | __end_tp_report 23:49:08 INFO - PROCESS | 25228 | __start_cc_report 23:49:08 INFO - PROCESS | 25228 | _x_x_mozilla_cycle_collect,330 23:49:08 INFO - PROCESS | 25228 | __end_cc_report 23:49:08 INFO - PROCESS | 25228 | __startTimestamp1463122148522__endTimestamp 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | ------- Summary: start ------- 23:49:08 INFO - PROCESS | 25228 | Number of tests: 6 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#0] dojo.html Cycles:165 Average:4711.68 Median:4871.71 stddev:612.30 (12.6%) stddev-sans-first:590.19 23:49:08 INFO - PROCESS | 25228 | Values: 2548.4 2565.8 2591.9 2620.6 2637.7 4704.7 4704.9 4732.4 4772.5 4821.9 4642.7 4654.3 4722.1 4805.6 4813.9 4328.0 4405.5 4449.7 4458.2 4470.9 488.4 4315.3 4323.8 4369.3 4538.7 4325.3 4347.8 4354.7 4367.3 4393.5 4359.6 4370.1 4391.5 4419.2 4451.0 4301.0 4344.9 4367.0 4368.5 4380.3 4691.0 4712.9 4720.2 4724.4 4747.0 5098.1 5153.6 5162.0 5171.0 5193.3 5007.3 5010.9 5048.7 5118.1 5244.9 4688.9 4731.6 4747.2 4758.3 4817.6 5129.1 5144.0 5163.9 5168.9 5175.3 4868.0 4937.8 5001.0 5005.3 5038.5 5050.5 5075.9 5096.6 5097.3 5117.6 5109.1 5136.9 5148.9 5152.1 5152.8 4971.0 5031.9 5081.4 5081.8 5094.1 4381.8 4401.9 4406.6 4407.6 4491.0 3664.1 3794.1 3805.5 3828.5 3857.8 4253.0 4348.7 4372.5 4399.3 4412.6 5093.6 5122.3 5172.3 5189.3 5236.0 4348.3 4428.0 4445.5 4459.8 4508.0 4591.7 4601.6 4629.4 4643.1 4647.0 4728.5 4787.4 4823.4 4827.7 4854.5 5056.8 5197.8 5199.7 5203.3 5267.9 4860.8 4875.9 4877.0 4891.3 4925.4 5196.2 5199.9 5209.4 5213.6 5262.9 5020.3 5094.3 5201.0 5291.5 5292.0 5000.2 5033.4 5045.5 5072.5 5106.8 4961.9 5029.1 5063.3 5073.8 5080.6 4739.9 4871.7 4914.6 4919.6 4976.0 5086.8 5091.3 5103.2 5128.8 5166.2 4992.5 5005.0 5011.2 5023.1 5025.4 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#1] ext.html Cycles:180 Average:45315.49 Median:35692.14 stddev:82638.58 (231.5%) stddev-sans-first:82802.07 23:49:08 INFO - PROCESS | 25228 | Values: 557.7 570.8 575.6 593.7 607.0 33632.0 37639.2 37702.6 37739.0 37854.4 37516.0 37898.0 37905.4 37922.2 37986.6 7637.8 7787.7 7796.8 7807.8 7821.1 7684.2 7782.3 7796.9 7799.0 7817.8 7833.4 7842.4 7865.7 7867.9 7888.8 7796.9 7804.9 7836.6 7837.5 7853.8 7741.0 7782.1 7819.7 7830.1 7875.8 29689.9 35404.5 35592.6 35789.3 35969.6 3199.4 3302.5 3399.4 3441.1 3462.3 27120.9 27549.3 27570.9 29528.3 29672.0 3258.9 3313.0 3366.6 3450.7 3464.4 235538.0 239919.0 240033.8 242328.0 242645.0 34010.7 34827.7 35202.1 56305.2 58326.4 35395.0 35644.6 35763.3 35877.6 35956.3 12959.8 13067.5 13095.9 13116.7 13124.3 10726.1 10727.4 10788.7 10831.5 10853.6 8473.4 8475.0 8491.7 8521.1 8531.8 3383.3 3405.1 3405.2 3412.7 3431.4 854.9 916.5 936.4 946.3 1037.0 26147.0 26155.2 26395.5 26537.2 26673.2 1056.2 1062.1 1065.8 1072.1 1098.1 14241.0 14323.7 14379.3 14392.6 14651.5 360080.0 362150.0 362921.0 364010.0 364558.0 49615.0 51547.0 51584.5 51601.0 51670.0 1283.6 1284.6 1304.2 1305.8 1309.4 26753.7 26937.5 27148.5 27905.4 27973.6 41684.2 42215.4 42508.1 43237.6 43442.0 323014.0 325402.0 325664.0 326433.0 326481.0 25809.6 26464.5 27561.2 27700.2 28866.4 41666.8 41824.2 42355.6 43156.8 43316.0 36593.3 36704.3 37057.6 37105.8 37872.6 36265.8 36328.8 36499.5 36603.5 37285.4 34627.8 35739.6 37441.3 38043.0 41665.0 32482.7 33022.8 33371.2 33775.3 33787.8 24218.5 24533.9 24610.6 24625.5 24791.5 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#2] jquery.html Cycles:180 Average:24502.97 Median:389.56 stddev:61627.04 (15819.8%) stddev-sans-first:61773.31 23:49:08 INFO - PROCESS | 25228 | Values: 382.3 385.4 387.2 391.9 408.5 13359.6 15008.1 16005.8 17496.9 17586.8 16341.6 16684.6 17401.6 17660.7 17661.4 4952.8 5077.3 5197.8 5238.4 5357.8 3410.1 6558.3 6962.3 6970.7 6987.2 6805.7 6924.9 6947.8 6968.3 7081.5 6971.2 6976.5 6978.0 7081.3 7097.9 5987.7 6433.3 6441.1 6510.2 6525.1 21617.6 22906.4 23635.2 24003.8 24004.6 2011.6 2163.3 2169.9 2198.7 2228.6 13876.7 13893.9 13964.8 14677.5 14808.3 1817.7 1839.4 1867.5 1935.7 1971.5 59690.7 63773.0 78251.0 82645.2 84763.6 15503.0 17066.2 17102.8 17121.7 17124.0 29517.9 29637.7 29870.9 29987.7 30236.0 8832.9 9169.1 9186.9 9274.3 9329.0 7464.5 7506.9 7571.3 7625.1 7630.0 3501.4 8657.0 8711.0 8758.5 8920.2 1876.1 1929.2 1931.1 1940.5 1941.1 811.6 899.2 918.0 926.1 952.2 15800.7 16013.7 16097.4 16152.2 16519.9 850.8 870.2 886.8 923.2 953.5 7049.0 8773.4 9631.9 9764.4 9989.3 150976.0 405956.0 407430.0 408846.0 413077.0 72237.6 77153.0 77517.0 78972.2 80430.6 1000.0 1005.9 1009.5 1030.4 1033.1 15408.4 15415.3 15442.8 15474.0 15772.8 33822.5 34966.8 35715.0 35789.0 36414.5 45513.8 47373.8 48122.8 48674.3 54506.2 7593.7 7646.6 7691.6 7705.9 7932.0 9681.8 11629.8 11640.6 11726.9 11886.9 4657.7 5516.6 10374.6 10478.8 10561.3 9968.5 10111.4 10257.5 10377.7 10601.4 10385.9 10492.7 10519.3 10637.1 10938.7 7532.6 7635.0 7692.0 7716.6 7746.2 10251.0 10657.3 10663.1 10698.3 10813.1 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#3] mootools.html Cycles:180 Average:5097.76 Median:5673.13 stddev:2801.59 (49.4%) stddev-sans-first:2796.01 23:49:08 INFO - PROCESS | 25228 | Values: 1446.2 1508.1 1658.6 1801.0 1802.2 6666.4 7235.2 7459.3 7975.2 7999.2 7313.1 7536.5 7541.7 7555.8 7575.9 2140.4 2175.1 2180.1 2185.4 2186.9 2111.7 2123.2 2147.1 2157.6 2158.8 2132.3 2138.0 2139.6 2142.0 2155.8 2059.6 2061.7 2067.8 2071.4 2076.2 2037.7 2081.4 2086.0 2090.6 2091.6 7383.7 7543.1 7551.8 7554.5 7566.4 4519.8 4641.3 4650.3 4661.1 4664.3 2795.8 2843.2 2923.9 2936.3 2938.4 929.1 954.6 955.3 959.2 961.5 10455.3 10487.8 10493.9 10533.7 10619.5 4189.6 4218.6 4357.5 4388.5 4413.0 8419.8 8459.5 8461.6 8489.7 8510.2 4651.1 4658.4 4661.9 4662.8 4670.2 3586.6 4078.5 4235.5 4320.8 4333.5 3348.5 3364.2 3381.3 3423.1 3513.8 994.4 996.1 1001.6 1004.8 1016.8 815.8 820.9 844.7 927.5 932.3 5074.9 5250.7 5347.6 5383.3 5578.9 907.7 943.7 949.9 955.0 960.0 3695.2 3740.3 3791.6 3808.9 3862.0 8663.8 9039.1 9209.4 9575.5 9594.9 9190.2 9228.2 9243.2 9265.9 9271.5 7313.5 7345.6 7481.6 7494.6 7508.2 5463.6 5497.9 5671.3 5675.0 5806.4 8774.1 8837.0 9095.8 9144.8 9153.2 8054.6 9032.0 9077.3 9105.8 9139.1 5958.6 6448.9 6598.7 6687.2 7000.3 7331.5 7373.7 7397.4 7410.3 7459.3 6823.5 6924.0 6989.4 7002.3 7110.5 6703.5 6762.2 6830.6 6874.0 6955.5 6208.7 6296.3 6410.7 6451.9 6492.4 4639.9 4697.8 4740.2 4775.1 4791.8 4591.7 4692.0 4708.7 4712.2 4794.7 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#4] prototype.html Cycles:180 Average:4164.64 Median:4542.19 stddev:1202.87 (26.5%) stddev-sans-first:1170.89 23:49:08 INFO - PROCESS | 25228 | Values: 307.7 315.3 316.5 317.3 320.7 4918.6 4934.6 4970.9 4972.2 4992.7 4772.8 4833.6 4834.9 4853.2 4869.3 3635.9 3746.1 3856.5 3865.2 3871.3 3842.0 3858.8 3865.8 3873.3 3889.6 3816.8 3825.0 3847.1 3852.5 3870.6 3552.3 3783.2 3830.0 3840.9 3854.6 3320.4 3324.2 3331.1 3342.5 3356.3 4588.6 4673.3 4677.4 4688.1 4691.5 4292.4 4352.7 4361.2 4370.2 4372.6 4961.4 5081.8 5088.5 5088.6 5101.6 3653.9 3664.4 3665.3 3668.5 3679.0 5276.3 5318.9 5331.5 5342.5 5369.0 3564.8 3620.3 3670.2 3681.4 3692.7 3730.8 3737.1 3741.4 3767.8 3768.4 4271.3 4280.6 4290.8 4305.3 4312.3 4889.9 5081.9 5145.0 5148.1 5154.3 1274.9 1287.7 1297.7 1298.7 1310.9 830.3 836.5 838.4 855.3 855.5 4389.1 4493.1 4499.0 4520.8 4531.0 5177.2 5215.3 5247.3 5260.0 5292.2 4500.8 4508.5 4518.9 4526.1 4543.0 2662.7 2835.2 2836.9 2852.9 2859.9 4957.8 4966.3 4975.0 4978.0 5004.4 5254.5 5361.5 5362.8 5403.6 5443.0 4911.9 4914.1 4927.6 4940.7 4940.8 5290.0 5304.2 5312.1 5325.5 5371.6 5020.1 5120.0 5145.7 5168.7 5259.3 4856.0 4857.4 4863.5 4874.6 4878.0 3328.5 3362.7 3373.2 3383.3 3407.7 4505.9 4634.3 4639.4 4704.9 4722.7 4443.9 4492.5 4511.3 4539.0 4567.2 4486.7 4541.4 4560.4 4561.7 4582.9 4439.5 4453.5 4461.9 4471.9 4483.2 4811.6 4897.8 5042.8 5150.5 5183.6 5001.0 5016.6 5039.9 5040.4 5050.9 23:49:08 INFO - PROCESS | 25228 | 23:49:08 INFO - PROCESS | 25228 | [#5] yui.html Cycles:180 Average:13169.13 Median:42792.28 stddev:14898.96 (34.8%) stddev-sans-first:14911.46 23:49:08 INFO - PROCESS | 25228 | Values: 726.1 729.0 741.2 775.3 797.6 11940.1 12228.4 12439.5 12440.6 12480.4 12562.9 12688.3 12739.7 13150.4 13938.0 670.1 848.1 856.9 864.8 866.0 859.4 864.0 866.6 871.1 875.3 910.8 913.1 914.1 914.6 918.4 903.2 907.0 908.2 909.1 909.6 663.1 663.2 663.2 666.4 667.1 13254.5 13452.7 13454.6 13462.1 13533.7 12492.2 12687.3 12823.3 12909.9 13124.3 9916.9 10021.9 10226.9 10237.2 10240.2 3348.9 3427.7 3446.9 3510.5 3532.5 50010.2 50285.0 50458.7 50833.2 50908.7 9903.2 9985.0 10113.2 10317.7 10340.1 16471.4 17750.6 17783.8 17846.8 17898.4 6832.4 7155.3 7213.5 7307.7 7530.3 3973.2 4022.8 4067.2 4069.0 4084.0 2779.3 2858.7 2862.6 2876.7 2884.8 528.3 544.0 547.8 549.2 562.9 568.4 600.8 618.6 619.1 620.7 7408.9 7672.5 7786.4 7879.2 7896.0 503.7 585.0 592.6 594.0 604.3 3684.4 3807.2 3826.5 3873.1 3874.3 50761.0 52269.5 52353.5 52377.2 52469.5 44092.6 46994.1 48287.8 49314.5 49736.8 41492.0 45636.3 45689.3 46274.0 47056.5 7797.0 7936.4 8057.9 8135.6 8145.1 27784.4 29261.9 29612.3 29633.3 29871.7 35342.3 37161.3 38916.0 39035.6 39753.0 11699.2 11828.2 12017.9 12034.4 12158.1 12418.7 12497.4 12566.3 12880.2 13303.7 11493.6 11496.3 11715.7 11769.5 11853.9 11846.1 11865.1 11950.6 11953.5 12144.3 11438.3 11461.1 11531.8 11610.4 11682.4 9371.7 9957.7 10026.2 10026.4 10067.8 8320.3 8367.9 8411.9 8605.9 8695.3 23:49:08 INFO - PROCESS | 25228 | -------- Summary: end -------- 23:49:08 INFO - PROCESS | 25228 | 23:49:09 INFO - TEST-INFO | 25228: exit 0 23:49:09 INFO - TEST-OK | dromaeo_css | took 1115108ms 23:49:09 INFO - TEST-START | kraken 23:49:09 INFO - operating with platform_type : linux_ 23:49:09 INFO - Initialising browser for kraken test... 23:49:09 INFO - TEST-INFO | started process 25458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaMfhh9/profile http://localhost:35072/getInfo.html) 23:49:13 INFO - PROCESS | 25458 | 23:49:13 INFO - PROCESS | 25458 | (process:25523): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:49:14 INFO - PROCESS | 25458 | __metrics Screen width/height:1600/1200 23:49:14 INFO - PROCESS | 25458 | colorDepth:24 23:49:14 INFO - PROCESS | 25458 | Browser inner width/height: 1024/697 23:49:14 INFO - PROCESS | 25458 | __metrics 23:49:15 ERROR - PROCESS | 25458 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:49:16 INFO - TEST-INFO | 25458: exit 0 23:49:16 INFO - Browser initialized. 23:49:16 INFO - Running cycle 1/1 for kraken test... 23:49:16 INFO - TEST-INFO | started process 25575 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaMfhh9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:49:18 INFO - PROCESS | 25575 | 23:49:18 INFO - PROCESS | 25575 | (process:25635): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:49:28 INFO - PROCESS | 25575 | RSS: Main: 163995648 23:49:28 INFO - PROCESS | 25575 | 23:49:30 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35072/tests/kraken/driver.html?testName=audio-beat-detection) 23:49:31 INFO - PROCESS | 25575 | RSS: Main: 168153088 23:49:31 INFO - PROCESS | 25575 | 23:49:34 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35072/tests/kraken/driver.html?testName=audio-dft) 23:49:35 INFO - PROCESS | 25575 | RSS: Main: 168505344 23:49:35 INFO - PROCESS | 25575 | 23:49:37 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35072/tests/kraken/driver.html?testName=audio-fft) 23:49:38 INFO - PROCESS | 25575 | RSS: Main: 163545088 23:49:38 INFO - PROCESS | 25575 | 23:49:40 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35072/tests/kraken/driver.html?testName=audio-oscillator) 23:49:41 INFO - PROCESS | 25575 | RSS: Main: 163438592 23:49:41 INFO - PROCESS | 25575 | 23:49:43 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35072/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:49:44 INFO - PROCESS | 25575 | RSS: Main: 163770368 23:49:44 INFO - PROCESS | 25575 | 23:49:47 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35072/tests/kraken/driver.html?testName=imaging-darkroom) 23:49:48 INFO - PROCESS | 25575 | RSS: Main: 164405248 23:49:48 INFO - PROCESS | 25575 | 23:49:51 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35072/tests/kraken/driver.html?testName=imaging-desaturate) 23:49:52 INFO - PROCESS | 25575 | RSS: Main: 162152448 23:49:52 INFO - PROCESS | 25575 | 23:49:55 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35072/tests/kraken/driver.html?testName=json-parse-financial) 23:49:56 INFO - PROCESS | 25575 | RSS: Main: 163454976 23:49:56 INFO - PROCESS | 25575 | 23:49:57 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35072/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:49:58 INFO - PROCESS | 25575 | RSS: Main: 163590144 23:49:58 INFO - PROCESS | 25575 | 23:50:00 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:50:00 INFO - PROCESS | 25575 | RSS: Main: 163950592 23:50:00 INFO - PROCESS | 25575 | 23:50:03 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:50:03 INFO - PROCESS | 25575 | RSS: Main: 164556800 23:50:03 INFO - PROCESS | 25575 | 23:50:06 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:50:07 INFO - PROCESS | 25575 | RSS: Main: 164376576 23:50:07 INFO - PROCESS | 25575 | 23:50:10 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:50:10 INFO - PROCESS | 25575 | RSS: Main: 164167680 23:50:10 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | Cycle 1(1): loaded http://localhost:35072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35072/tests/kraken/driver.html?testName=ai-astar) 23:50:12 INFO - PROCESS | 25575 | RSS: Main: 165822464 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | __start_tp_report 23:50:12 INFO - PROCESS | 25575 | _x_x_mozilla_page_load 23:50:12 INFO - PROCESS | 25575 | _x_x_mozilla_page_load_details 23:50:12 INFO - PROCESS | 25575 | |i|pagename|runs| 23:50:12 INFO - PROCESS | 25575 | |0;ai-astar;116;117;110;118;119;117;110;117;118;115 23:50:12 INFO - PROCESS | 25575 | |1;audio-beat-detection;165;137;159;213;138;172;156;139;188;135 23:50:12 INFO - PROCESS | 25575 | |2;audio-dft;164;164;163;162;167;175;163;163;164;163 23:50:12 INFO - PROCESS | 25575 | |3;audio-fft;79;78;79;155;80;78;77;77;159;78 23:50:12 INFO - PROCESS | 25575 | |4;audio-oscillator;96;95;168;94;95;94;133;96;97;136 23:50:12 INFO - PROCESS | 25575 | |5;imaging-gaussian-blur;120;118;119;120;119;119;121;120;120;122 23:50:12 INFO - PROCESS | 25575 | |6;imaging-darkroom;148;150;147;149;149;149;148;149;149;149 23:50:12 INFO - PROCESS | 25575 | |7;imaging-desaturate;96;97;97;96;96;96;96;96;96;95 23:50:12 INFO - PROCESS | 25575 | |8;json-parse-financial;70;69;70;68;69;70;69;69;69;72 23:50:12 INFO - PROCESS | 25575 | |9;json-stringify-tinderbox;64;64;64;66;65;64;65;64;64;65 23:50:12 INFO - PROCESS | 25575 | |10;stanford-crypto-aes;91;86;94;92;90;89;89;92;148;87 23:50:12 INFO - PROCESS | 25575 | |11;stanford-crypto-ccm;142;157;146;139;160;149;150;152;160;143 23:50:12 INFO - PROCESS | 25575 | |12;stanford-crypto-pbkdf2;178;184;178;180;180;177;175;180;181;184 23:50:12 INFO - PROCESS | 25575 | |13;stanford-crypto-sha256-iterative;73;72;73;72;71;69;70;75;68;71 23:50:12 INFO - PROCESS | 25575 | __end_tp_report 23:50:12 INFO - PROCESS | 25575 | __start_cc_report 23:50:12 INFO - PROCESS | 25575 | _x_x_mozilla_cycle_collect,821 23:50:12 INFO - PROCESS | 25575 | __end_cc_report 23:50:12 INFO - PROCESS | 25575 | __startTimestamp1463122212927__endTimestamp 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | ------- Summary: start ------- 23:50:12 INFO - PROCESS | 25575 | Number of tests: 14 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#0] ai-astar Cycles:10 Average:115.70 Median:117.00 stddev:3.20 (2.7%) stddev-sans-first:3.39 23:50:12 INFO - PROCESS | 25575 | Values: 116.0 117.0 110.0 118.0 119.0 117.0 110.0 117.0 118.0 115.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#1] audio-beat-detection Cycles:10 Average:160.20 Median:162.00 stddev:25.51 (15.7%) stddev-sans-first:27.00 23:50:12 INFO - PROCESS | 25575 | Values: 165.0 137.0 159.0 213.0 138.0 172.0 156.0 139.0 188.0 135.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:3.82 (2.3%) stddev-sans-first:4.04 23:50:12 INFO - PROCESS | 25575 | Values: 164.0 164.0 163.0 162.0 167.0 175.0 163.0 163.0 164.0 163.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#3] audio-fft Cycles:10 Average:94.00 Median:78.00 stddev:33.23 (42.6%) stddev-sans-first:34.80 23:50:12 INFO - PROCESS | 25575 | Values: 79.0 78.0 79.0 155.0 80.0 78.0 77.0 77.0 159.0 78.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:95.00 stddev:26.01 (27.4%) stddev-sans-first:27.06 23:50:12 INFO - PROCESS | 25575 | Values: 96.0 95.0 168.0 94.0 95.0 94.0 133.0 96.0 97.0 136.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#5] imaging-gaussian-blur Cycles:10 Average:119.80 Median:120.00 stddev:1.14 (0.9%) stddev-sans-first:1.20 23:50:12 INFO - PROCESS | 25575 | Values: 120.0 118.0 119.0 120.0 119.0 119.0 121.0 120.0 120.0 122.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 23:50:12 INFO - PROCESS | 25575 | Values: 148.0 150.0 147.0 149.0 149.0 149.0 148.0 149.0 149.0 149.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:50:12 INFO - PROCESS | 25575 | Values: 96.0 97.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#8] json-parse-financial Cycles:10 Average:69.50 Median:69.50 stddev:1.08 (1.6%) stddev-sans-first:1.13 23:50:12 INFO - PROCESS | 25575 | Values: 70.0 69.0 70.0 68.0 69.0 70.0 69.0 69.0 69.0 72.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 23:50:12 INFO - PROCESS | 25575 | Values: 64.0 64.0 64.0 66.0 65.0 64.0 65.0 64.0 64.0 65.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:90.50 stddev:18.50 (20.4%) stddev-sans-first:19.54 23:50:12 INFO - PROCESS | 25575 | Values: 91.0 86.0 94.0 92.0 90.0 89.0 89.0 92.0 148.0 87.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:151.00 stddev:7.48 (5.0%) stddev-sans-first:7.38 23:50:12 INFO - PROCESS | 25575 | Values: 142.0 157.0 146.0 139.0 160.0 149.0 150.0 152.0 160.0 143.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:180.00 stddev:2.87 (1.6%) stddev-sans-first:2.98 23:50:12 INFO - PROCESS | 25575 | Values: 178.0 184.0 178.0 180.0 180.0 177.0 175.0 180.0 181.0 184.0 23:50:12 INFO - PROCESS | 25575 | 23:50:12 INFO - PROCESS | 25575 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:72.00 stddev:2.07 (2.9%) stddev-sans-first:2.11 23:50:12 INFO - PROCESS | 25575 | Values: 73.0 72.0 73.0 72.0 71.0 69.0 70.0 75.0 68.0 71.0 23:50:12 INFO - PROCESS | 25575 | -------- Summary: end -------- 23:50:12 INFO - PROCESS | 25575 | 23:50:14 INFO - TEST-INFO | 25575: exit 0 23:50:14 INFO - TEST-OK | kraken | took 64908ms 23:50:14 INFO - SUITE-END | took 1180s 23:50:14 INFO - Completed test suite (00:19:40) 23:50:14 INFO - javascript benchmark 23:50:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6377.356305065113, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2548.401125735452, 2565.756253155952, 2591.8574478403684, 2620.6200100985357, 2637.696664883539, 4704.670673052888, 4704.858854234374, 4732.4321081470225, 4772.475027746949, 4821.927671084936, 4642.675012749109, 4654.27858681904, 4722.055588882229, 4805.591524720394, 4813.8796530086765, 4327.978360108203, 4405.493368262642, 4449.688521803475, 4458.242098843204, 4470.888227794315, 488.39989146669063, 4315.331123675835, 4323.783810809463, 4369.279068953619, 4538.659600529957, 4325.286327755916, 4347.826086956518, 4354.673399495035, 4367.323064924942, 4393.53867843875, 4359.6076353128055, 4370.104128653618, 4391.5169331373845, 4419.226635338803, 4451.020775429401, 4301.0, 4344.9137715571105, 4367.0, 4368.453943257093, 4380.255356589388, 4690.9530904690855, 4712.929306060412, 4720.173969555314, 4724.385829842133, 4747.026859493795, 5098.082345177866, 5153.613478989091, 5161.974190129063, 5171.017506673757, 5193.298904647862, 5007.298978143063, 5010.924836127461, 5048.697078175322, 5118.129917913963, 5244.947550524484, 4688.929666055012, 4731.597814185833, 4747.240441529373, 4758.262469317261, 4817.614590832725, 5129.12804823181, 5143.996920600447, 5163.948360516347, 5168.948310516922, 5175.327207463006, 4867.975660121677, 4937.777799998937, 5001.024800164006, 5005.349304590452, 5038.496150385005, 5050.49495050492, 5075.898482030412, 5096.592272618182, 5097.311862898461, 5117.590592752571, 5109.08036553424, 5136.8715782105, 5148.944466384382, 5152.124138896396, 5152.793888244429, 4970.975145124251, 5031.899362012812, 5081.415637201769, 5081.796728130834, 5094.133997220481, 4381.846635367747, 4401.889952751207, 4406.6474682026, 4407.581279778416, 4490.955090449119, 3664.1206110533813, 3794.1083845296334, 3805.46723458716, 3828.4831547741255, 3857.8456861725217, 4252.957470425318, 4348.673849461303, 4372.475302963665, 4399.252127138394, 4412.580804823537, 5093.617978651616, 5122.257272695439, 5172.301739265151, 5189.299444575009, 5235.973820130875, 4348.28253338202, 4427.977860110679, 4445.533219012022, 4459.799309031101, 4508.0, 4591.701539399928, 4601.631869450436, 4629.3518907353, 4643.071385722869, 4646.977664913714, 4728.503507131771, 4787.3776409066595, 4823.3729615149805, 4827.686200396963, 4854.466008739036, 5056.786371270942, 5197.8440646780655, 5199.71401572902, 5203.297554830049, 5267.9464107177955, 4860.756962151949, 4875.878103047452, 4877.048975449895, 4891.33966914462, 4925.359703238556, 5196.194589838581, 5199.870003249949, 5209.426963033988, 5213.6350455467755, 5262.8947421052135, 5020.347354843847, 5094.312267843794, 5200.959808038332, 5291.470852914677, 5292.047431462373, 5000.224965130411, 5033.446320904624, 5045.470225626404, 5072.54347108762, 5106.821261255838, 4961.875953101201, 5029.094762942559, 5063.291139240436, 5073.822416215415, 5080.644354895122, 4739.905201895874, 4871.707697538159, 4914.631402644744, 4919.557239848431, 4976.0, 5086.771095300646, 5091.287219789307, 5103.183490641628, 5128.820491282787, 5166.199239117943, 4992.450830408579, 5005.049040682405, 5011.173156429294, 5023.120953833138, 5025.3718285214345], "value": 4672.915565467666, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.7298472012541, 570.7916610437192, 575.6172145523227, 593.7357875745294, 606.9848253793657, 33632.0, 37639.247215055715, 37702.62297377025, 37739.0, 37854.4321835172, 37516.0, 37898.0, 37905.43141852868, 37922.24155516888, 37986.62013379866, 7637.847243055136, 7787.688492460323, 7796.844063118734, 7807.804804879866, 7821.100573434042, 7684.231576842299, 7782.338501227402, 7796.922030779676, 7798.961005194966, 7817.804554886116, 7833.373330133576, 7842.372610191171, 7865.724699635514, 7867.921320786805, 7888.842223155533, 7796.883046754247, 7804.921950780476, 7836.568988705619, 7837.529748215125, 7853.842923141534, 7741.0, 7782.143964163937, 7819.726309579194, 7830.138684744674, 7875.8031049223655, 29689.851550742325, 35404.468932966025, 35592.64407355919, 35789.284214315565, 35969.64030359689, 3199.392115498048, 3302.4550949093373, 3399.405104106796, 3441.1225137589754, 3462.307538492312, 27120.864395678094, 27549.31126721828, 27570.86214568914, 29528.26179345512, 29672.0, 3258.8913985410595, 3312.9834350828332, 3366.629670736217, 3450.6894379505966, 3464.428369319059, 235538.0, 239919.0, 240033.79983100147, 242328.0, 242645.0, 34010.65989340124, 34827.651723482944, 35202.11994700153, 56305.15542266869, 58326.41673583295, 35395.0, 35644.64355356465, 35763.284734305686, 35877.64122358743, 35956.28087438236, 12959.805602915963, 13067.477300907858, 13095.934520327337, 13116.672083197996, 13124.343782810822, 10726.088282495919, 10727.356358618508, 10788.730281742863, 10831.512581933832, 10853.620123295646, 8473.364497662576, 8475.0, 8491.702790402307, 8521.10528394522, 8531.82936341282, 3383.323335332894, 3405.11467018572, 3405.165734395089, 3412.675795799395, 3431.3823511768123, 854.8760429737653, 916.4547094478775, 936.4240991790136, 946.3360639799081, 1037.0303765978833, 26147.0, 26155.215343539723, 26395.472090558465, 26537.203883883514, 26673.19980400591, 1056.1709058389129, 1062.1078294232882, 1065.781514789482, 1072.0940805019745, 1098.0611577101622, 14241.003129781024, 14323.713525729636, 14379.281035948161, 14392.568222953329, 14651.487197948234, 360080.0, 362150.0, 362921.0, 364010.0, 364558.0, 49615.0, 51547.0, 51584.48415515797, 51600.96798064018, 51670.0, 1283.6341642631992, 1284.5953524639708, 1304.2174695183005, 1305.7649623067748, 1309.423853504436, 26753.732462675904, 26937.461250775268, 27148.457030858877, 27905.44189116247, 27973.580396293666, 41684.16631667289, 42215.366769499095, 42508.14983700371, 43237.567624323354, 43442.0, 323014.0, 325402.0, 325664.0, 326433.0, 326481.0, 25809.6128558068, 26464.470710585294, 27561.173164805088, 27700.168994930184, 28866.422671546872, 41666.79166604148, 41824.16351673011, 42355.576444236394, 43156.78421607872, 43316.0, 36593.26813463769, 36704.26591468209, 37057.629423706494, 37105.81447092747, 37872.62127378691, 36265.818670906476, 36328.81835590805, 36499.45250821186, 36603.45094823633, 37285.440718389786, 34627.826860865534, 35739.64260357363, 37441.251174975805, 38043.0, 41665.0, 32482.675173247964, 33022.83488582542, 33371.16572085717, 33775.32449351048, 33787.83106084454, 24218.546887186825, 24533.87733061323, 24610.630840537047, 24625.50748985046, 24791.50416991686], "value": 16498.660543762187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.2851268128598, 385.4295642449175, 387.1849756263066, 391.92749341371865, 408.5301902811766, 13359.59921202363, 15008.063751067268, 16005.759913601305, 17496.91251543742, 17586.82413175868, 16341.5914602135, 16684.58288542787, 17401.564960875985, 17660.735088973677, 17661.381851635164, 4952.777125029383, 5077.263796749469, 5197.8440646780655, 5238.449962753894, 5357.812476563321, 3410.079278594778, 6558.311377305386, 6962.338577835098, 6970.721171153148, 6987.231404545496, 6805.659717014154, 6924.930750692479, 6947.756828510978, 6968.303169683017, 7081.5396999195145, 6971.233164351917, 6976.5116441849095, 6977.965110174468, 7081.327273908971, 7097.893531597005, 5987.730552125165, 6433.2601750798785, 6441.066045423434, 6510.18622672166, 6525.053867189278, 21617.56764864694, 22906.427339316484, 23635.172768953005, 24003.759962400327, 24004.63993040106, 2011.5675129847007, 2163.3401812447196, 2169.858959167649, 2198.6811912272633, 2228.565429741201, 13876.653083672887, 13893.861061389358, 13964.790528142086, 14677.486287979977, 14808.259587020606, 1817.7000794868832, 1839.3562253211403, 1867.5237814357388, 1935.6612592796205, 1971.4874132725452, 59690.701546492426, 63773.04340434944, 78251.0, 82645.17354826436, 84763.57618211932, 15503.0, 17066.23201955915, 17102.828971710125, 17121.657566848844, 17124.0, 29517.852410737807, 29637.703622963927, 29870.85064574707, 29987.700122998926, 30236.0, 8832.895888013998, 9169.128932751379, 9186.86219706705, 9274.304427167854, 9328.95335523318, 7464.514806537558, 7506.887396689054, 7571.318581327707, 7625.084989801259, 7629.961850190824, 3501.3697534443536, 8657.0, 8711.0417854036, 8758.521172314226, 8920.24177944869, 1876.080720446971, 1929.2283086765126, 1931.1406424141123, 1940.495471177504, 1941.1458958058408, 811.6104269950339, 899.1637776867445, 917.9954100229589, 926.1109335038304, 952.1858810716876, 15800.656944159646, 16013.679726405406, 16097.356105755638, 16152.192390380435, 16519.91740041316, 850.753281548344, 870.1776820904312, 886.7605746448552, 923.1645360948353, 953.5280036382036, 7049.0, 8773.42972706772, 9631.903680963242, 9764.414135151914, 9989.250806189564, 150976.0, 405956.0, 407430.0, 408846.0, 413077.0, 72237.63881180665, 77153.0, 77517.0, 78972.21027789649, 80430.59784701039, 999.9950000249952, 1005.8591797148405, 1009.4952523738131, 1030.386919782728, 1033.0702367868978, 15408.383664653737, 15415.306311216247, 15442.768358474857, 15474.0, 15772.842271577138, 33822.46331525096, 34966.825165874005, 35715.0, 35789.0, 36414.4537831938, 45513.77243113763, 47373.763131184125, 48122.759386202844, 48674.269885952446, 54506.18240726313, 7593.696252149852, 7646.617669116633, 7691.57696326685, 7705.88441173394, 7931.960340198262, 9681.757956051155, 11629.76740465203, 11640.592579260025, 11726.88273117292, 11886.940565297464, 4657.6506761992305, 5516.558675306046, 10374.585016599553, 10478.790424191626, 10561.313514621525, 9968.451735154636, 10111.443870587189, 10257.538410771682, 10377.740556486147, 10601.416922069051, 10385.948070259903, 10492.737681558023, 10519.263651544625, 10637.14902807758, 10938.671839844817, 7532.623368831427, 7635.00744903159, 7692.0, 7716.5755883426955, 7746.225377462209, 10251.0, 10657.307275027099, 10663.093637040938, 10698.251122421667, 10813.080888124598], "value": 9078.959790731331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.1973604649418, 1508.110214973166, 1658.5687721192487, 1800.9914447909164, 1802.161994672477, 6666.366695163963, 7235.204127545966, 7459.328660420561, 7975.162607926164, 7999.160088190736, 7313.085864266966, 7536.4724469287175, 7541.698332066724, 7555.773326800191, 7575.886361704579, 2140.3685912655765, 2175.0755928730305, 2180.0734687757717, 2185.399015270805, 2186.9015894284717, 2111.7149184860077, 2123.2250228666503, 2147.076756994492, 2157.590057888996, 2158.816500597451, 2132.2643687927716, 2138.0378829526776, 2139.6362618354838, 2142.0146732503067, 2155.8059774620283, 2059.588082383522, 2061.7319748432606, 2067.782882797307, 2071.3785864240667, 2076.179908935977, 2037.6841589553494, 2081.4484161697164, 2086.040421406155, 2090.5818836232816, 2091.633964056299, 7383.6677349519405, 7543.094828620546, 7551.848963020709, 7554.508956917783, 7566.432517561205, 4519.7740112994215, 4641.280601506772, 4650.302454631798, 4661.114388266218, 4664.277037059262, 2795.790315726308, 2843.218115018366, 2923.941521169586, 2936.2512559297456, 2938.44169607776, 929.1126973740062, 954.6420092465326, 955.254901177083, 959.194276807485, 961.4567769210374, 10455.320404173704, 10487.79024419515, 10493.895061049368, 10533.736656583571, 10619.469026548642, 4189.560096189917, 4218.620324170839, 4357.54245804186, 4388.4733831940375, 4413.0, 8419.789505262295, 8459.49243045407, 8461.619227134794, 8489.660413583388, 8510.191531804468, 4651.139539185261, 4658.440987081572, 4661.906761864811, 4662.790174442091, 4670.229412146958, 3586.5875424326014, 4078.510578730512, 4235.4917409910395, 4320.762358070337, 4333.458317710286, 3348.4977253411694, 3364.2430453147845, 3381.306832099414, 3423.1099914022534, 3513.771604845677, 994.3834822410151, 996.0836030851634, 1001.6344034427382, 1004.8442491413842, 1016.8474728790741, 815.8123631564773, 820.9096999330069, 844.6705784743955, 927.4667066436825, 932.2868005975434, 5074.898502030012, 5250.711210883362, 5347.598930080259, 5383.300170977826, 5578.86052848682, 907.686848037426, 943.688582767685, 949.8575213717997, 954.9809003820023, 959.9904000960041, 3695.224002959355, 3740.3454395481226, 3791.639794219545, 3808.9428658570146, 3861.9806900965314, 8663.826723465496, 9039.096090390909, 9209.401388909699, 9575.473348965874, 9594.896586892457, 9190.207909457591, 9228.169464748205, 9243.168114869559, 9265.934417541934, 9271.490068046322, 7313.4880558361465, 7345.632718364061, 7481.625918704151, 7494.587797671181, 7508.211637777955, 5463.562914966714, 5497.890042199214, 5671.291088613923, 5674.971625141848, 5806.361300256884, 8774.078721734126, 8836.955815220883, 9095.818083638422, 9144.817103657757, 9153.221976131837, 8054.597270136353, 9031.954840226019, 9077.319201059814, 9105.817883642423, 9139.086091390807, 5958.582899197015, 6448.935510645021, 6598.670066496752, 6687.164104486939, 7000.299970002959, 7331.523450975669, 7373.705051798082, 7397.4082073435075, 7410.333070023724, 7459.291367320096, 6823.522353435411, 6924.0, 6989.440844732308, 7002.2647622000995, 7110.5022648414115, 6703.497237707289, 6762.154730658668, 6830.556013859084, 6873.965630171817, 6955.513114081966, 6208.72060757258, 6296.307406185224, 6410.711517981609, 6451.870962580816, 6492.448141907991, 4639.86080417588, 4697.8355757548325, 4740.2178640523925, 4775.140474714584, 4791.760411979318, 4591.724496530219, 4691.976540117277, 4708.693934894221, 4712.246040633482, 4794.736289504111], "value": 4126.706944516083, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.71075189322033, 315.2843046284935, 316.45569620253184, 317.32093320294564, 320.65529555727596, 4918.581920536754, 4934.629902757299, 4970.850874473763, 4972.179590367585, 4992.729762005887, 4772.78522466489, 4833.589144922677, 4834.854954351375, 4853.223484242522, 4869.318295438633, 3635.927281454363, 3746.1383881707225, 3856.4986551748234, 3865.207632435358, 3871.3031654302213, 3841.9615803842025, 3858.787766672832, 3865.8067096645195, 3873.283442563121, 3889.572147063821, 3816.7709937403706, 3824.9672588401113, 3847.0574709196285, 3852.4799152114524, 3870.612938706135, 3552.289542091566, 3783.243351329745, 3830.012590179664, 3840.9437404713785, 3854.6338097880657, 3320.3857286402094, 3324.202191474053, 3331.0506505646017, 3342.5487559179687, 3356.261622443083, 4588.587027167538, 4673.322368256584, 4677.438707355139, 4688.132695451352, 4691.483936766953, 4292.356146578007, 4352.693072671457, 4361.171377438277, 4370.2133615949115, 4372.5627437256335, 4961.355023846912, 5081.822136225214, 5088.465711100318, 5088.626070960857, 5101.642884998052, 3653.9269214615824, 3664.395374763161, 3665.2669466106618, 3668.5414323209598, 3678.981605091984, 5276.3140791697215, 5318.867028324285, 5331.546818520431, 5342.492463215988, 5369.0, 3564.750467467304, 3620.2759448109955, 3670.1742108025483, 3681.4477828325967, 3692.667659910621, 3730.8321125549414, 3737.140457694691, 3741.401375779862, 3767.7550959187565, 3768.4347347898033, 4271.31658934569, 4280.59334363235, 4290.806913688892, 4305.287668768656, 4312.310030395184, 4889.853304400874, 5081.898362032813, 5144.9742751286, 5148.073346797538, 5154.329937108227, 1274.898008159345, 1287.67808047988, 1297.7209899871573, 1298.727267273864, 1310.927898965566, 830.33158307563, 836.4897412578322, 838.3651878836293, 855.3072011670566, 855.4824331279486, 4389.078293558325, 4493.123840851003, 4499.0, 4520.819167233274, 4530.954690453119, 5177.249298851721, 5215.348081489814, 5247.291615631917, 5259.973700131475, 5292.20616907467, 4500.829784256117, 4508.549145085531, 4518.864434066983, 4526.117407105583, 4542.954570454253, 2662.7337266273216, 2835.191970288458, 2836.929076773056, 2852.857357132135, 2859.9275271773085, 4957.82647607332, 4966.304717339575, 4975.029869175476, 4978.0, 5004.374453193351, 5254.500822421864, 5361.490658387447, 5362.812301569426, 5403.567714582824, 5443.0, 4911.880955593224, 4914.1080683936225, 4927.556519913225, 4940.6788558743565, 4940.802367905316, 5289.9735501323785, 5304.230886521355, 5312.096943519534, 5325.467453254643, 5371.623986321077, 5020.121478741279, 5120.027194833119, 5145.716985565829, 5168.715720635401, 5259.316288882421, 4856.009036295102, 4857.417109946829, 4863.465018847854, 4874.610031197425, 4878.0, 3328.450805617046, 3362.69735723786, 3373.2241584436206, 3383.323335332894, 3407.7273818093995, 4505.887352816206, 4634.328022436795, 4639.443266808004, 4704.858854234379, 4722.693024953367, 4443.933340999823, 4492.505824359383, 4511.278195488706, 4539.024109816405, 4567.200739870443, 4486.66350023743, 4541.43232095988, 4560.361549383023, 4561.657875659273, 4582.9312560310955, 4439.5338489458145, 4453.487848897418, 4461.888452788707, 4471.932921006252, 4483.237849565517, 4811.639127065414, 4897.779599917941, 5042.84871453857, 5150.510701483353, 5183.585313174862, 5001.0, 5016.573591244785, 5039.924401133912, 5040.420351659606, 5050.873728156826], "value": 3787.734641820626, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.1177669132007, 729.0449511140406, 741.2032065529556, 775.2712450296723, 797.6330887791615, 11940.10449216309, 12228.44971976261, 12439.502419903205, 12440.564580239694, 12480.375981200948, 12562.93718531406, 12688.30214338211, 12739.681507962305, 13150.408231629575, 13938.0, 670.0920253057111, 848.1433751910553, 856.9357298202644, 864.7881269089082, 866.0430224601819, 859.4499520307008, 863.9568021598894, 866.6403442571325, 871.0679572857014, 875.3216257400502, 910.7632015675906, 913.1325241021024, 914.1178762494192, 914.6387177065088, 918.361738591679, 903.2051794420901, 906.9546522673905, 908.246155690775, 909.1136142261308, 909.5543183839903, 663.0716996205302, 663.1611012069735, 663.2174034639124, 666.3736088077192, 667.1194023888469, 13254.46982120714, 13452.663683407993, 13454.596362109152, 13462.124961877447, 13533.729325413437, 12492.188632648214, 12687.302198379086, 12823.294718790463, 12909.87090129096, 13124.278164700938, 9916.851247231298, 10021.899781002243, 10226.948865255701, 10237.18102551794, 10240.231982601335, 3348.8995330140133, 3427.725781937439, 3446.8621255149765, 3510.508528805969, 3532.487789270567, 50010.249846252336, 50285.0, 50458.74770626123, 50833.23750143788, 50908.74545627248, 9903.158231550398, 9984.950075249577, 10113.190944724405, 10317.690469285933, 10340.10635764745, 16471.423500177436, 17750.644987100186, 17783.82216177822, 17846.821531784775, 17898.373556925446, 6832.350926661959, 7155.3202445767565, 7213.49505534618, 7307.744228951961, 7530.28462296081, 3973.205358928226, 4022.8189731462153, 4067.166230922713, 4068.959310406917, 4083.959160408358, 2779.291280723443, 2858.714128587125, 2862.5992361069466, 2876.6835648078695, 2884.79806413549, 528.3158309988575, 544.0316237097971, 547.7535109200876, 549.1872029396436, 562.8986782379211, 568.3804652928328, 600.8317671052113, 618.6442795392667, 619.0837560410617, 620.736187120472, 7408.888866667005, 7672.539647621161, 7786.416018798613, 7879.212078792075, 7895.960520197362, 503.6807438977085, 584.9824505264849, 592.599995003371, 593.9792107276224, 604.3080672629815, 3684.428913518409, 3807.162424266657, 3826.5267871869532, 3873.0898238913833, 3874.321993651156, 50761.0, 52269.477305226465, 52353.476465235624, 52377.2143417849, 52469.47530524722, 44092.55907440949, 46994.10022155313, 48287.75856120697, 49314.50685493171, 49736.751316243186, 41492.0, 45636.315455268195, 45689.31466027946, 46274.0, 47056.5294347059, 7796.961015194887, 7936.444448888522, 8057.919420805952, 8135.593220339078, 8145.144759800136, 27784.444311114068, 29261.85369073141, 29612.259693507836, 29633.25916852096, 29871.70128298689, 35342.29315413729, 37161.25677486381, 38916.0, 39035.6096439032, 39753.0, 11699.23954942926, 11828.17202795796, 12017.879821201675, 12034.398280086136, 12158.148929574845, 12418.677755008539, 12497.437615307152, 12566.308853013172, 12880.227186368847, 13303.733925321245, 11493.597724079616, 11496.310221386744, 11715.707107322385, 11769.529218831492, 11853.940730296294, 11846.060738345006, 11865.110116741107, 11950.581729639422, 11953.521859125885, 12144.33206173669, 11438.256513326607, 11461.140414468782, 11531.8270225945, 11610.419479026184, 11682.357470339213, 9371.671991480265, 9957.701268961942, 10026.197904167795, 10026.448545330077, 10067.798644027225, 8320.292775114183, 8367.874481882898, 8411.873821892554, 8605.870911936201, 8695.30437565006], "value": 6148.075332011369, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1640.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 110.0, 118.0, 119.0, 117.0, 110.0, 117.0, 118.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 137.0, 159.0, 213.0, 138.0, 172.0, 156.0, 139.0, 188.0, 135.0], "value": 160.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 162.0, 167.0, 175.0, 163.0, 163.0, 164.0, 163.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 155.0, 80.0, 78.0, 77.0, 77.0, 159.0, 78.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 168.0, 94.0, 95.0, 94.0, 133.0, 96.0, 97.0, 136.0], "value": 110.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 119.0, 120.0, 119.0, 119.0, 121.0, 120.0, 120.0, 122.0], "value": 119.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 147.0, 149.0, 149.0, 149.0, 148.0, 149.0, 149.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 68.0, 69.0, 70.0, 69.0, 69.0, 69.0, 72.0], "value": 69.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 66.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 94.0, 92.0, 90.0, 89.0, 89.0, 92.0, 148.0, 87.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 157.0, 146.0, 139.0, 160.0, 149.0, 150.0, 152.0, 160.0, 143.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 184.0, 178.0, 180.0, 180.0, 177.0, 175.0, 180.0, 181.0, 184.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 72.0, 71.0, 69.0, 70.0, 75.0, 68.0, 71.0], "value": 71.4, "unit": "score"}]}]} 23:50:14 INFO - Return code: 0 23:50:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:50:14 ERROR - # TBPL SUCCESS # 23:50:14 INFO - Running post-action listener: _resource_record_post_action 23:50:14 INFO - ##### 23:50:14 INFO - ##### Finished run-tests step (success) 23:50:14 INFO - ##### 23:50:14 INFO - Running post-run listener: _resource_record_post_run 23:50:15 INFO - Total resource usage - Wall time: 1197s; CPU: 26.0%; Read bytes: 4096; Write bytes: 536182784; Read time: 12; Write time: 893592 23:50:15 INFO - TinderboxPrint: CPU usage
25.8% 23:50:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:50:15 INFO - TinderboxPrint: I/O write bytes / time
536,182,784 / 893,592 23:50:15 INFO - TinderboxPrint: CPU idle
7,083.4 (74.1%) 23:50:15 INFO - TinderboxPrint: CPU user
2,421.5 (25.3%) 23:50:15 INFO - TinderboxPrint: Swap in / out
0 / 0 23:50:15 INFO - install - Wall time: 16s; CPU: 27.0%; Read bytes: 0; Write bytes: 243593216; Read time: 0; Write time: 409004 23:50:15 INFO - run-tests - Wall time: 1181s; CPU: 26.0%; Read bytes: 4096; Write bytes: 292589568; Read time: 12; Write time: 484588 23:50:15 INFO - Running post-run listener: _upload_blobber_files 23:50:15 INFO - Blob upload gear active. 23:50:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:50:15 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 23:50:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:50:15 INFO - (blobuploader) - INFO - Open directory for files ... 23:50:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:50:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:50:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:50:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:50:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:50:16 INFO - (blobuploader) - INFO - Done attempting. 23:50:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:50:17 INFO - (blobuploader) - INFO - Done attempting. 23:50:17 INFO - (blobuploader) - INFO - Iteration through files over. 23:50:17 INFO - Return code: 0 23:50:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:50:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:50:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/89bffa3185f433ce446b9ea71c275d8a83fd2becb66296fcaa52ddc929f98906d86b5d93d58cce42ee04d125f6ec70d8286daecff3047c7339d2b5ed14c96bfb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4529a9b6dfcbc13679da370f52921dcb3a828039ecfaf44d448c90cbcc65da7a4ffe1b8f0b222f72fd9ed07cd05d00c432a36d0d60b9d6218ca8c3dbb71288c5"} 23:50:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:50:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:50:17 INFO - Contents: 23:50:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/89bffa3185f433ce446b9ea71c275d8a83fd2becb66296fcaa52ddc929f98906d86b5d93d58cce42ee04d125f6ec70d8286daecff3047c7339d2b5ed14c96bfb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4529a9b6dfcbc13679da370f52921dcb3a828039ecfaf44d448c90cbcc65da7a4ffe1b8f0b222f72fd9ed07cd05d00c432a36d0d60b9d6218ca8c3dbb71288c5"} 23:50:17 INFO - Running post-run listener: copy_logs_to_upload_dir 23:50:17 INFO - Copying logs to upload dir... 23:50:17 INFO - mkdir: /builds/slave/test/build/upload/logs 23:50:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.135085 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-12 23:50:17.068550) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 23:50:17.072287) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/89bffa3185f433ce446b9ea71c275d8a83fd2becb66296fcaa52ddc929f98906d86b5d93d58cce42ee04d125f6ec70d8286daecff3047c7339d2b5ed14c96bfb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4529a9b6dfcbc13679da370f52921dcb3a828039ecfaf44d448c90cbcc65da7a4ffe1b8f0b222f72fd9ed07cd05d00c432a36d0d60b9d6218ca8c3dbb71288c5"} build_url:https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005455 build_url: 'https://queue.taskcluster.net/v1/task/deZV3iFvRR-7mzIGD1XE8g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/89bffa3185f433ce446b9ea71c275d8a83fd2becb66296fcaa52ddc929f98906d86b5d93d58cce42ee04d125f6ec70d8286daecff3047c7339d2b5ed14c96bfb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4529a9b6dfcbc13679da370f52921dcb3a828039ecfaf44d448c90cbcc65da7a4ffe1b8f0b222f72fd9ed07cd05d00c432a36d0d60b9d6218ca8c3dbb71288c5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 23:50:17.086529) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:50:17.086883) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463120925.433137-1313404942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002126 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:50:17.168761) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 23:50:17.169113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 23:50:17.169450) ========= ========= Total master_lag: 0.17 =========