builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1463584232.89 results: success (0) buildid: 20160518053011 builduid: ccaea75549fb4f6eafcefd5fc72a52a9 revision: 47b83c8478a06374aa9306122a778654b912f713 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:32.890937) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:32.891520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:32.891927) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:32.913225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:32.913739) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 08:10:32-- 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% 224M=0s 2016-05-18 08:10:33 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.221328 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:33.152843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:33.153205) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015635 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:33.216974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 08:10:33.217762) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 47b83c8478a06374aa9306122a778654b912f713 --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 47b83c8478a06374aa9306122a778654b912f713 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 08:10:33,288 truncating revision to first 12 chars 2016-05-18 08:10:33,288 Setting DEBUG logging. 2016-05-18 08:10:33,288 attempt 1/10 2016-05-18 08:10:33,289 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/47b83c8478a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 08:10:34,528 unpacking tar archive at: fx-team-47b83c8478a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.538089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 08:10:34.771362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:34.771755) ========= script_repo_revision: 47b83c8478a06374aa9306122a778654b912f713 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:34.772208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:34.772538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 08:10:34.786056) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-18 08:10:34.786378) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:10:34 INFO - MultiFileLogger online at 20160518 08:10:34 in /builds/slave/test 08:10:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:10:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:10:34 INFO - {'append_to_log': False, 08:10:34 INFO - 'base_work_dir': '/builds/slave/test', 08:10:34 INFO - 'blob_upload_branch': 'Fx-Team', 08:10:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:10:34 INFO - 'branch': 'Fx-Team', 08:10:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:34 INFO - 'config_files': ('talos/linux_config.py',), 08:10:34 INFO - 'default_actions': ('clobber', 08:10:34 INFO - 'read-buildbot-config', 08:10:34 INFO - 'download-and-extract', 08:10:34 INFO - 'populate-webroot', 08:10:34 INFO - 'create-virtualenv', 08:10:34 INFO - 'install', 08:10:34 INFO - 'run-tests'), 08:10:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:34 INFO - 'download_minidump_stackwalk': True, 08:10:34 INFO - 'download_symbols': 'ondemand', 08:10:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:34 INFO - 'installer_path': 'installer.exe', 08:10:34 INFO - 'log_level': 'info', 08:10:34 INFO - 'log_name': 'talos', 08:10:34 INFO - 'log_to_console': True, 08:10:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:10:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:10:34 INFO - 'opt_config_files': (), 08:10:34 INFO - 'pip_index': False, 08:10:34 INFO - 'sps_profile': False, 08:10:34 INFO - 'sps_profile_interval': 0, 08:10:34 INFO - 'suite': 'dromaeojs', 08:10:34 INFO - 'system_bits': '32', 08:10:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:34 INFO - 'title': 'talos-linux64-ix-041', 08:10:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:34 INFO - 'use_talos_json': True, 08:10:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:10:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:34 INFO - 'work_dir': 'build'} 08:10:34 INFO - ##### 08:10:34 INFO - ##### Running clobber step. 08:10:34 INFO - ##### 08:10:34 INFO - Running pre-action listener: _resource_record_pre_action 08:10:34 INFO - Running main action method: clobber 08:10:34 INFO - rmtree: /builds/slave/test/build 08:10:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:10:35 INFO - Running post-action listener: _resource_record_post_action 08:10:35 INFO - ##### 08:10:35 INFO - ##### Finished clobber step (success) 08:10:35 INFO - ##### 08:10:35 INFO - ##### 08:10:35 INFO - ##### Running read-buildbot-config step. 08:10:35 INFO - ##### 08:10:35 INFO - Running pre-action listener: _resource_record_pre_action 08:10:35 INFO - Running main action method: read_buildbot_config 08:10:35 INFO - Using buildbot properties: 08:10:35 INFO - { 08:10:35 INFO - "project": "", 08:10:35 INFO - "product": "firefox", 08:10:35 INFO - "script_repo_revision": "production", 08:10:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:10:35 INFO - "repository": "", 08:10:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:10:35 INFO - "buildid": "20160518053011", 08:10:35 INFO - "pgo_build": "True", 08:10:35 INFO - "basedir": "/builds/slave/test", 08:10:35 INFO - "buildnumber": 695, 08:10:35 INFO - "slavename": "talos-linux64-ix-041", 08:10:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:10:35 INFO - "platform": "ubuntu64_hw", 08:10:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:10:35 INFO - "branch": "fx-team", 08:10:35 INFO - "repo_path": "integration/fx-team", 08:10:35 INFO - "revision": "47b83c8478a06374aa9306122a778654b912f713", 08:10:35 INFO - "stage_platform": "linux64-pgo", 08:10:35 INFO - "builduid": "ccaea75549fb4f6eafcefd5fc72a52a9", 08:10:35 INFO - "slavebuilddir": "test" 08:10:35 INFO - } 08:10:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:10:35 INFO - Running post-action listener: _resource_record_post_action 08:10:35 INFO - ##### 08:10:35 INFO - ##### Finished read-buildbot-config step (success) 08:10:35 INFO - ##### 08:10:35 INFO - ##### 08:10:35 INFO - ##### Running download-and-extract step. 08:10:35 INFO - ##### 08:10:35 INFO - Running pre-action listener: _resource_record_pre_action 08:10:35 INFO - Running main action method: download_and_extract 08:10:35 INFO - mkdir: /builds/slave/test/build/tests 08:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:35 INFO - https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:10:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:10:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:10:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:10:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:10:36 INFO - Downloaded 1361 bytes. 08:10:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:10:36 INFO - Using the following test package requirements: 08:10:36 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:10:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:10:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'jsshell-linux-x86_64.zip'], 08:10:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:10:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:10:36 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:10:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:10:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:10:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:36 INFO - https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:10:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:37 INFO - Downloaded 24063278 bytes. 08:10:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:10:38 INFO - Return code: 0 08:10:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:38 INFO - https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:40 INFO - Downloaded 24063278 bytes. 08:10:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:10:42 INFO - Return code: 0 08:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:42 INFO - https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:10:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:10:43 INFO - Downloaded 10840194 bytes. 08:10:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:10:43 INFO - Return code: 0 08:10: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')]} 08:10:43 INFO - https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:10:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:10:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:10:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:10:45 INFO - Downloaded 58383497 bytes. 08:10:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:10:45 INFO - mkdir: /builds/slave/test/properties 08:10:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:45 INFO - Contents: 08:10:45 INFO - build_url:https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - Running post-action listener: set_extra_try_arguments 08:10:45 INFO - ##### 08:10:45 INFO - ##### Finished download-and-extract step (success) 08:10:45 INFO - ##### 08:10:45 INFO - ##### 08:10:45 INFO - ##### Running populate-webroot step. 08:10:45 INFO - ##### 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: populate_webroot 08:10:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:45 INFO - '--tppagecycles', 08:10:45 INFO - '7'], 08:10:45 INFO - 'tests': ['tsvgm']}}, 08:10:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['damp', 'tps']}, 08:10:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['damp', 'tps']}, 08:10:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['dromaeo_dom']}, 08:10:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tsvgx', 08:10:45 INFO - 'tsvgr_opacity', 08:10:45 INFO - 'tart', 08:10:45 INFO - 'tscrollx', 08:10:45 INFO - 'cart']}, 08:10:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:45 INFO - 'tsvgr_opacity', 08:10:45 INFO - 'tart', 08:10:45 INFO - 'tscrollx', 08:10:45 INFO - 'cart']}, 08:10:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tp5o']}, 08:10:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['tp5o']}, 08:10:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s', 08:10:45 INFO - '--xperf_path', 08:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:45 INFO - 'tests': ['tp5n']}, 08:10:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--xperf_path', 08:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:45 INFO - 'tests': ['tp5n']}}, 08:10:45 INFO - 'talos.zip': {'path': '', 08:10:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - ##### 08:10:45 INFO - ##### Finished populate-webroot step (success) 08:10:45 INFO - ##### 08:10:45 INFO - ##### 08:10:45 INFO - ##### Running create-virtualenv step. 08:10:45 INFO - ##### 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: create_virtualenv 08:10:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:10:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:45 INFO - Using real prefix '/usr' 08:10:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:48 INFO - Installing pip.................done. 08:10:48 INFO - Return code: 0 08:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:10:48 INFO - Using env: {'DISPLAY': ':0', 08:10:48 INFO - 'HOME': '/home/cltbld', 08:10:48 INFO - 'LANG': 'en_US.UTF-8', 08:10:48 INFO - 'LANGUAGE': 'en_US:en', 08:10:48 INFO - 'LOGNAME': 'cltbld', 08:10:48 INFO - 'MAIL': '/var/mail/cltbld', 08:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:48 INFO - 'MOZ_NO_REMOTE': '1', 08:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:48 INFO - 'NO_EM_RESTART': '1', 08:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:48 INFO - 'PWD': '/builds/slave/test', 08:10:48 INFO - 'SHELL': '/bin/bash', 08:10:48 INFO - 'SHLVL': '1', 08:10:48 INFO - 'TERM': 'linux', 08:10:48 INFO - 'TMOUT': '86400', 08:10:48 INFO - 'USER': 'cltbld', 08:10:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:48 INFO - '_': '/tools/buildbot/bin/python'} 08:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:10:48 INFO - Cleaning up... 08:10:48 INFO - Return code: 0 08:10:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:10:48 INFO - Using env: {'DISPLAY': ':0', 08:10:48 INFO - 'HOME': '/home/cltbld', 08:10:48 INFO - 'LANG': 'en_US.UTF-8', 08:10:48 INFO - 'LANGUAGE': 'en_US:en', 08:10:48 INFO - 'LOGNAME': 'cltbld', 08:10:48 INFO - 'MAIL': '/var/mail/cltbld', 08:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:48 INFO - 'MOZ_NO_REMOTE': '1', 08:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:48 INFO - 'NO_EM_RESTART': '1', 08:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:48 INFO - 'PWD': '/builds/slave/test', 08:10:48 INFO - 'SHELL': '/bin/bash', 08:10:48 INFO - 'SHLVL': '1', 08:10:48 INFO - 'TERM': 'linux', 08:10:48 INFO - 'TMOUT': '86400', 08:10:48 INFO - 'USER': 'cltbld', 08:10:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:48 INFO - '_': '/tools/buildbot/bin/python'} 08:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:48 INFO - Downloading/unpacking psutil>=3.1.1 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:51 INFO - Installing collected packages: psutil 08:10:51 INFO - Running setup.py install for psutil 08:10:51 INFO - building 'psutil._psutil_linux' extension 08:10:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:10:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:10:51 INFO - building 'psutil._psutil_posix' extension 08:10:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:10:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:51 INFO - Successfully installed psutil 08:10:51 INFO - Cleaning up... 08:10:51 INFO - Return code: 0 08:10:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:10:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:10:51 INFO - Using env: {'DISPLAY': ':0', 08:10:51 INFO - 'HOME': '/home/cltbld', 08:10:51 INFO - 'LANG': 'en_US.UTF-8', 08:10:51 INFO - 'LANGUAGE': 'en_US:en', 08:10:51 INFO - 'LOGNAME': 'cltbld', 08:10:51 INFO - 'MAIL': '/var/mail/cltbld', 08:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:51 INFO - 'MOZ_NO_REMOTE': '1', 08:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:51 INFO - 'NO_EM_RESTART': '1', 08:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:51 INFO - 'PWD': '/builds/slave/test', 08:10:51 INFO - 'SHELL': '/bin/bash', 08:10:51 INFO - 'SHLVL': '1', 08:10:51 INFO - 'TERM': 'linux', 08:10:51 INFO - 'TMOUT': '86400', 08:10:51 INFO - 'USER': 'cltbld', 08:10:51 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:51 INFO - '_': '/tools/buildbot/bin/python'} 08:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:53 INFO - Installing collected packages: mozsystemmonitor 08:10:53 INFO - Running setup.py install for mozsystemmonitor 08:10:54 INFO - Successfully installed mozsystemmonitor 08:10:54 INFO - Cleaning up... 08:10:54 INFO - Return code: 0 08:10:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:54 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:10:54 INFO - Using env: {'DISPLAY': ':0', 08:10:54 INFO - 'HOME': '/home/cltbld', 08:10:54 INFO - 'LANG': 'en_US.UTF-8', 08:10:54 INFO - 'LANGUAGE': 'en_US:en', 08:10:54 INFO - 'LOGNAME': 'cltbld', 08:10:54 INFO - 'MAIL': '/var/mail/cltbld', 08:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:54 INFO - 'MOZ_NO_REMOTE': '1', 08:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:54 INFO - 'NO_EM_RESTART': '1', 08:10:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:54 INFO - 'PWD': '/builds/slave/test', 08:10:54 INFO - 'SHELL': '/bin/bash', 08:10:54 INFO - 'SHLVL': '1', 08:10:54 INFO - 'TERM': 'linux', 08:10:54 INFO - 'TMOUT': '86400', 08:10:54 INFO - 'USER': 'cltbld', 08:10:54 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:54 INFO - '_': '/tools/buildbot/bin/python'} 08:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:57 INFO - Downloading docopt-0.6.1.tar.gz 08:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:57 INFO - Running setup.py install for blobuploader 08:10:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:58 INFO - Running setup.py install for requests 08:10:58 INFO - Running setup.py install for docopt 08:10:58 INFO - Successfully installed blobuploader requests docopt 08:10:58 INFO - Cleaning up... 08:10:58 INFO - Return code: 0 08:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:58 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:10:58 INFO - Using env: {'DISPLAY': ':0', 08:10:58 INFO - 'HOME': '/home/cltbld', 08:10:58 INFO - 'LANG': 'en_US.UTF-8', 08:10:58 INFO - 'LANGUAGE': 'en_US:en', 08:10:58 INFO - 'LOGNAME': 'cltbld', 08:10:58 INFO - 'MAIL': '/var/mail/cltbld', 08:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:58 INFO - 'MOZ_NO_REMOTE': '1', 08:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:58 INFO - 'NO_EM_RESTART': '1', 08:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:58 INFO - 'PWD': '/builds/slave/test', 08:10:58 INFO - 'SHELL': '/bin/bash', 08:10:58 INFO - 'SHLVL': '1', 08:10:58 INFO - 'TERM': 'linux', 08:10:58 INFO - 'TMOUT': '86400', 08:10:58 INFO - 'USER': 'cltbld', 08:10:58 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:58 INFO - '_': '/tools/buildbot/bin/python'} 08:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:58 INFO - Running setup.py (path:/tmp/pip-xDyeM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:58 INFO - Running setup.py (path:/tmp/pip-fRSnL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:58 INFO - Running setup.py (path:/tmp/pip-M_63l6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:59 INFO - Running setup.py (path:/tmp/pip-CoRQOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:59 INFO - Running setup.py (path:/tmp/pip-PGBY8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:59 INFO - Running setup.py (path:/tmp/pip-8KlWPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:59 INFO - Running setup.py (path:/tmp/pip-s4VwtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:59 INFO - Running setup.py (path:/tmp/pip-Pgcnz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:59 INFO - Running setup.py (path:/tmp/pip-dvHbGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:59 INFO - Running setup.py (path:/tmp/pip-aC1h9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:59 INFO - Running setup.py (path:/tmp/pip-DWzg8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:59 INFO - Running setup.py (path:/tmp/pip-WOBISa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:59 INFO - Running setup.py (path:/tmp/pip-oqAtjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:59 INFO - Running setup.py (path:/tmp/pip-vk74Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:59 INFO - Running setup.py (path:/tmp/pip-QkHr2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:59 INFO - Running setup.py (path:/tmp/pip-lYOJYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:59 INFO - Running setup.py (path:/tmp/pip-7oxB0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:00 INFO - Running setup.py install for manifestparser 08:11:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Running setup.py install for mozcrash 08:11:00 INFO - Running setup.py install for mozdebug 08:11:00 INFO - Running setup.py install for mozdevice 08:11:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Running setup.py install for mozfile 08:11:00 INFO - Running setup.py install for mozhttpd 08:11:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Running setup.py install for mozinfo 08:11:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Running setup.py install for mozInstall 08:11:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:00 INFO - Running setup.py install for mozleak 08:11:00 INFO - Running setup.py install for mozlog 08:11:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for moznetwork 08:11:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for mozprocess 08:11:01 INFO - Running setup.py install for mozprofile 08:11:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for mozrunner 08:11:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for mozscreenshot 08:11:01 INFO - Running setup.py install for moztest 08:11:01 INFO - Running setup.py install for mozversion 08:11:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:01 INFO - Cleaning up... 08:11:01 INFO - Return code: 0 08:11:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:01 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:01 INFO - Using env: {'DISPLAY': ':0', 08:11:01 INFO - 'HOME': '/home/cltbld', 08:11:01 INFO - 'LANG': 'en_US.UTF-8', 08:11:01 INFO - 'LANGUAGE': 'en_US:en', 08:11:01 INFO - 'LOGNAME': 'cltbld', 08:11:01 INFO - 'MAIL': '/var/mail/cltbld', 08:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:01 INFO - 'MOZ_NO_REMOTE': '1', 08:11:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:01 INFO - 'NO_EM_RESTART': '1', 08:11:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:01 INFO - 'PWD': '/builds/slave/test', 08:11:01 INFO - 'SHELL': '/bin/bash', 08:11:01 INFO - 'SHLVL': '1', 08:11:01 INFO - 'TERM': 'linux', 08:11:01 INFO - 'TMOUT': '86400', 08:11:01 INFO - 'USER': 'cltbld', 08:11:01 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:01 INFO - '_': '/tools/buildbot/bin/python'} 08:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:02 INFO - Running setup.py (path:/tmp/pip-tJaJLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:02 INFO - Running setup.py (path:/tmp/pip-4zcGkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:02 INFO - Running setup.py (path:/tmp/pip-P21pfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:02 INFO - Running setup.py (path:/tmp/pip-6kvMer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:02 INFO - Running setup.py (path:/tmp/pip-MWJRTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:02 INFO - Running setup.py (path:/tmp/pip-oeTbag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:02 INFO - Running setup.py (path:/tmp/pip-MwdHcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:02 INFO - Running setup.py (path:/tmp/pip-UDOEaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:02 INFO - Running setup.py (path:/tmp/pip-1j0RvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:02 INFO - Running setup.py (path:/tmp/pip-vkRjuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:02 INFO - Running setup.py (path:/tmp/pip-VZoPxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:02 INFO - Running setup.py (path:/tmp/pip-yc6bbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:02 INFO - Running setup.py (path:/tmp/pip-CGMAm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:02 INFO - Running setup.py (path:/tmp/pip-OTkh6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:03 INFO - Running setup.py (path:/tmp/pip-i4dGZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:03 INFO - Running setup.py (path:/tmp/pip-DIB081-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:03 INFO - Running setup.py (path:/tmp/pip-gq8eSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11:05 INFO - Downloading blessings-1.6.tar.gz 08:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:05 INFO - Installing collected packages: blessings 08:11:05 INFO - Running setup.py install for blessings 08:11:05 INFO - Successfully installed blessings 08:11:05 INFO - Cleaning up... 08:11:05 INFO - Return code: 0 08:11:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:05 INFO - Reading from file tmpfile_stdout 08:11:05 INFO - Current package versions: 08:11:05 INFO - argparse == 1.2.1 08:11:05 INFO - blessings == 1.6 08:11:05 INFO - blobuploader == 1.2.4 08:11:05 INFO - docopt == 0.6.1 08:11:05 INFO - manifestparser == 1.1 08:11:05 INFO - mozInstall == 1.12 08:11:05 INFO - mozcrash == 0.17 08:11:05 INFO - mozdebug == 0.1 08:11:05 INFO - mozdevice == 0.48 08:11:05 INFO - mozfile == 1.2 08:11:05 INFO - mozhttpd == 0.7 08:11:05 INFO - mozinfo == 0.9 08:11:05 INFO - mozleak == 0.1 08:11:05 INFO - mozlog == 3.2 08:11:05 INFO - moznetwork == 0.27 08:11:05 INFO - mozprocess == 0.22 08:11:05 INFO - mozprofile == 0.28 08:11:05 INFO - mozrunner == 6.11 08:11:05 INFO - mozscreenshot == 0.1 08:11:05 INFO - mozsystemmonitor == 0.3 08:11:05 INFO - moztest == 0.7 08:11:05 INFO - mozversion == 1.4 08:11:05 INFO - psutil == 3.1.1 08:11:05 INFO - requests == 1.2.3 08:11:05 INFO - wsgiref == 0.1.2 08:11:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:05 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:05 INFO - Using env: {'DISPLAY': ':0', 08:11:05 INFO - 'HOME': '/home/cltbld', 08:11:05 INFO - 'LANG': 'en_US.UTF-8', 08:11:05 INFO - 'LANGUAGE': 'en_US:en', 08:11:05 INFO - 'LOGNAME': 'cltbld', 08:11:05 INFO - 'MAIL': '/var/mail/cltbld', 08:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:05 INFO - 'MOZ_NO_REMOTE': '1', 08:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:05 INFO - 'NO_EM_RESTART': '1', 08:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:05 INFO - 'PWD': '/builds/slave/test', 08:11:05 INFO - 'SHELL': '/bin/bash', 08:11:05 INFO - 'SHLVL': '1', 08:11:05 INFO - 'TERM': 'linux', 08:11:05 INFO - 'TMOUT': '86400', 08:11:05 INFO - 'USER': 'cltbld', 08:11:05 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:05 INFO - '_': '/tools/buildbot/bin/python'} 08:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:11:05 INFO - Cleaning up... 08:11:05 INFO - Return code: 0 08:11:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:05 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:11:05 INFO - Using env: {'DISPLAY': ':0', 08:11:05 INFO - 'HOME': '/home/cltbld', 08:11:05 INFO - 'LANG': 'en_US.UTF-8', 08:11:05 INFO - 'LANGUAGE': 'en_US:en', 08:11:05 INFO - 'LOGNAME': 'cltbld', 08:11:05 INFO - 'MAIL': '/var/mail/cltbld', 08:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:05 INFO - 'MOZ_NO_REMOTE': '1', 08:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:05 INFO - 'NO_EM_RESTART': '1', 08:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:05 INFO - 'PWD': '/builds/slave/test', 08:11:05 INFO - 'SHELL': '/bin/bash', 08:11:05 INFO - 'SHLVL': '1', 08:11:05 INFO - 'TERM': 'linux', 08:11:05 INFO - 'TMOUT': '86400', 08:11:05 INFO - 'USER': 'cltbld', 08:11:05 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:05 INFO - '_': '/tools/buildbot/bin/python'} 08:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:06 INFO - Downloading/unpacking jsonschema 08:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:07 INFO - Downloading/unpacking functools32 (from jsonschema) 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:08 INFO - warning: no files found matching '*.txt' 08:11:08 INFO - no previously-included directories found matching 'build' 08:11:08 INFO - no previously-included directories found matching 'dist' 08:11:08 INFO - no previously-included directories found matching '.git*' 08:11:08 INFO - Installing collected packages: jsonschema, functools32 08:11:08 INFO - Running setup.py install for functools32 08:11:08 INFO - warning: no files found matching '*.txt' 08:11:08 INFO - no previously-included directories found matching 'build' 08:11:08 INFO - no previously-included directories found matching 'dist' 08:11:08 INFO - no previously-included directories found matching '.git*' 08:11:08 INFO - Successfully installed jsonschema functools32 08:11:08 INFO - Cleaning up... 08:11:08 INFO - Return code: 0 08:11:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:08 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 0x7fb6af676f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:11:08 INFO - Using env: {'DISPLAY': ':0', 08:11:08 INFO - 'HOME': '/home/cltbld', 08:11:08 INFO - 'LANG': 'en_US.UTF-8', 08:11:08 INFO - 'LANGUAGE': 'en_US:en', 08:11:08 INFO - 'LOGNAME': 'cltbld', 08:11:08 INFO - 'MAIL': '/var/mail/cltbld', 08:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:08 INFO - 'MOZ_NO_REMOTE': '1', 08:11:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:08 INFO - 'NO_EM_RESTART': '1', 08:11:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:08 INFO - 'PWD': '/builds/slave/test', 08:11:08 INFO - 'SHELL': '/bin/bash', 08:11:08 INFO - 'SHLVL': '1', 08:11:08 INFO - 'TERM': 'linux', 08:11:08 INFO - 'TMOUT': '86400', 08:11:08 INFO - 'USER': 'cltbld', 08:11:08 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:08 INFO - '_': '/tools/buildbot/bin/python'} 08:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:08 INFO - Downloading/unpacking flake8 08:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:11:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:11:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:11:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:11:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:11:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:11:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:11:11 INFO - no previously-included directories found matching 'docs/_build' 08:11:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:11:11 INFO - Running setup.py install for pep8 08:11:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:11:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:11:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:11:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:11:11 INFO - no previously-included directories found matching 'docs/_build' 08:11:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:11:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:11:11 INFO - Cleaning up... 08:11:11 INFO - Return code: 0 08:11:11 INFO - Running post-action listener: _resource_record_post_action 08:11:11 INFO - Running post-action listener: _start_resource_monitoring 08:11:11 INFO - Starting resource monitoring. 08:11:11 INFO - ##### 08:11:11 INFO - ##### Finished create-virtualenv step (success) 08:11:11 INFO - ##### 08:11:11 INFO - ##### 08:11:11 INFO - ##### Running install step. 08:11:11 INFO - ##### 08:11:11 INFO - Running pre-action listener: _resource_record_pre_action 08:11:11 INFO - Running main action method: install 08:11:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:11 INFO - Reading from file tmpfile_stdout 08:11:11 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:11 INFO - Reading from file tmpfile_stdout 08:11:11 INFO - Output received: 08:11:11 INFO - Usage: mozinstall [options] installer 08:11:11 INFO - Options: 08:11:11 INFO - -h, --help show this help message and exit 08:11:11 INFO - -d DEST, --destination=DEST 08:11:11 INFO - Directory to install application into. [default: 08:11:11 INFO - "/builds/slave/test"] 08:11:11 INFO - --app=APP Application being installed. [default: firefox] 08:11:11 INFO - mkdir: /builds/slave/test/build/application 08:11:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:11:28 INFO - Reading from file tmpfile_stdout 08:11:28 INFO - Output received: 08:11:28 INFO - /builds/slave/test/build/application/firefox/firefox 08:11:28 INFO - Running post-action listener: _resource_record_post_action 08:11:28 INFO - ##### 08:11:28 INFO - ##### Finished install step (success) 08:11:28 INFO - ##### 08:11:28 INFO - ##### 08:11:28 INFO - ##### Running run-tests step. 08:11:28 INFO - ##### 08:11:28 INFO - Running pre-action listener: _resource_record_pre_action 08:11:28 INFO - Running main action method: run_tests 08:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:28 INFO - Python 2.7.3 08:11:28 INFO - Return code: 0 08:11:28 INFO - grabbing minidump binary from tooltool 08:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:28 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 0x2d579c0>, '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 0x2d588c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d58d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:11:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:11:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:11:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:28 INFO - Return code: 0 08:11:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:11:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:11:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:11:29 INFO - Return code: 0 08:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:11:29 INFO - Using env: {'DISPLAY': ':0', 08:11:29 INFO - 'HOME': '/home/cltbld', 08:11:29 INFO - 'LANG': 'en_US.UTF-8', 08:11:29 INFO - 'LANGUAGE': 'en_US:en', 08:11:29 INFO - 'LOGNAME': 'cltbld', 08:11:29 INFO - 'MAIL': '/var/mail/cltbld', 08:11:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:29 INFO - 'MOZ_NO_REMOTE': '1', 08:11:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:29 INFO - 'NO_EM_RESTART': '1', 08:11:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:29 INFO - 'PWD': '/builds/slave/test', 08:11:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:29 INFO - 'SHELL': '/bin/bash', 08:11:29 INFO - 'SHLVL': '1', 08:11:29 INFO - 'TERM': 'linux', 08:11:29 INFO - 'TMOUT': '86400', 08:11:29 INFO - 'USER': 'cltbld', 08:11:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417', 08:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:29 INFO - '_': '/tools/buildbot/bin/python'} 08:11:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:11:29 INFO - mozversion application_buildid: 20160518053011 08:11:29 INFO - mozversion application_changeset: 47b83c8478a06374aa9306122a778654b912f713 08:11:29 INFO - mozversion application_display_name: Nightly 08:11:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:29 INFO - mozversion application_name: Firefox 08:11:29 INFO - mozversion application_remotingname: firefox 08:11:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:11:29 INFO - mozversion application_vendor: Mozilla 08:11:29 INFO - mozversion application_version: 49.0a1 08:11:29 INFO - mozversion platform_buildid: 20160518053011 08:11:29 INFO - mozversion platform_changeset: 47b83c8478a06374aa9306122a778654b912f713 08:11:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:11:29 INFO - mozversion platform_version: 49.0a1 08:11:29 INFO - using testdate: 1463584289 08:11:29 INFO - actual date: 1463584289 08:11:29 INFO - starting webserver on 'localhost:39797' 08:11:29 INFO - SUITE-START | Running 2 tests 08:11:29 INFO - TEST-START | dromaeo_css 08:11:29 INFO - operating with platform_type : linux_ 08:11:29 INFO - Initialising browser for dromaeo_css test... 08:11:29 INFO - TEST-INFO | started process 4390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNfdiXN/profile http://localhost:39797/getInfo.html) 08:11:33 INFO - PROCESS | 4390 | __metrics Screen width/height:1600/1200 08:11:33 INFO - PROCESS | 4390 | colorDepth:24 08:11:33 INFO - PROCESS | 4390 | Browser inner width/height: 1024/697 08:11:33 INFO - PROCESS | 4390 | __metrics 08:11:35 ERROR - PROCESS | 4390 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:11:35 INFO - TEST-INFO | 4390: exit 0 08:11:35 INFO - Browser initialized. 08:11:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:35 INFO - TEST-INFO | started process 4471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNfdiXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:47 INFO - PROCESS | 4471 | RSS: Main: 152358912 08:11:47 INFO - PROCESS | 4471 | 08:12:38 INFO - PROCESS | 4471 | 1463584358979 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:14:47 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39797/tests/dromaeo/cssquery-ext.html) 08:14:48 INFO - PROCESS | 4471 | RSS: Main: 256069632 08:14:48 INFO - PROCESS | 4471 | 08:17:50 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-ext.html (next: http://localhost:39797/tests/dromaeo/cssquery-jquery.html) 08:17:51 INFO - PROCESS | 4471 | RSS: Main: 236478464 08:17:51 INFO - PROCESS | 4471 | 08:20:53 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39797/tests/dromaeo/cssquery-mootools.html) 08:20:53 INFO - PROCESS | 4471 | RSS: Main: 239677440 08:20:53 INFO - PROCESS | 4471 | 08:23:55 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39797/tests/dromaeo/cssquery-prototype.html) 08:23:56 INFO - PROCESS | 4471 | RSS: Main: 235483136 08:23:56 INFO - PROCESS | 4471 | 08:26:59 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39797/tests/dromaeo/cssquery-yui.html) 08:26:59 INFO - PROCESS | 4471 | RSS: Main: 221237248 08:26:59 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:39797/tests/dromaeo/cssquery-yui.html (next: http://localhost:39797/tests/dromaeo/cssquery-dojo.html) 08:30:02 INFO - PROCESS | 4471 | RSS: Main: 272101376 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | __start_tp_report 08:30:02 INFO - PROCESS | 4471 | _x_x_mozilla_page_load 08:30:02 INFO - PROCESS | 4471 | _x_x_mozilla_page_load_details 08:30:02 INFO - PROCESS | 4471 | |i|pagename|runs| 08:30:02 INFO - PROCESS | 4471 | |0;dojo.html;3166.6358368787587;3194;3224.3712476067167;3237.1745204972726;3254.381667483178;5399.730013499319;5448.182772584114;5450.781968721247;5511.559075273974;5517.668939863606;5456.317960254968;5486.177073438977;5498.0653288940875;5531.059719847625;5549.889002219953;4901.215805471125;4917.237828136645;5027.974860125694;5096.337476128098;5110.054639891613;5023.974880125595;5058.494150584949;5100.9489905100845;5106.7701953412;5114.641975061747;4903.2645103234445;4925.38432695913;4969.080720066763;4993.275974983625;4993.525615066563;4962.1812400953795;4993.400791904958;4995.700257984533;5008.273800298973;5055.216441451581;4854.611631069506;4943.950560494385;4961.131801934683;4972.403311602595;5028.949710502885;5362.249285100089;5437.782488700447;5449.482299181611;5451.64564303319;5522.585806064561;5940.049592065292;5960.97019514904;5978.342382337939;5978.701064946736;5980.850478738066;5738.340090889559;5869.765209391662;5940.46535811779;5960.0761881908375;5995.550333724988;5522.30971128609;5620.91568626471;5624.943750562483;5626.352969408488;5626.915596266059;6090.208272924491;6103.877922441527;6105.633661980295;6107.6640784757265;6140.048292514667;5685;5685.431456854364;5768.250127483484;5900.498457631149;5903.468687818031;5887.852803679943;5898.587098903122;5900.4099590041515;5902.026165682701;5946.405359464019;5804.332501762267;5831.562632802556;5856.121581762684;5868.970655146697;5874.177615133799;5702.002149623882;5824.359320474744;5859.150423188614;5862.14998825168;5897.911531327033;4467.039586488855;4486.304622783474;4494.348319493655;4494.483134439582;4515.0518391137575;3977.244323578511;3994.8601798936897;3998.460207871958;4027.7180597357906;4030;5469.835904922858;5518.586106042063;5527.751251193706;5532.640378375393;5558.138488534284;5932.822015339547;5937.198478205473;5937.465628093406;5941.55438342126;5952.791652292236;5610.13042978339;5617.21359009739;5620.718964051863;5622.381538030813;5624.775008999594;4960.330355402047;5031.622628302892;5058.46886076964;5075.644704870623;5078.97460512695;6121.326654068049;6125.84685382869;6127.908081378694;6135.07973803933;6135.447809697149;5707.258056452634;5906.586538942232;5912.793052243151;5918.201042859331;5924.11138329254;6071.2714474263375;6112.448802283729;6127.3259941407305;6135.662538560422;6179.598326108789;5786.276715410574;5818.9127163093435;5823.767049317979;5825.446582574648;5833.416658334133;5710.114932185518;5838.036723940672;5884.146798714077;5897.3807750185615;5902.291724993029;5724.713764311685;5791.276090488689;5793.217915581343;5803.274590676166;5840.065589505659;5781.508571771446;5786.566007549535;5798.56510761703;5800.361960184292;5802.361740208658;5437.102878025082;5575.8884822304135;5616.550675945996;5692.943070569241;5697.6296540724725;5794.362620111869;5830.9416905832095;5833.329167145663;5837.5329973601165;5842.123681447817;5636.182753500638;5639.54883609302;5651.208830763614;5651.2935883014625;5655.943440565542 08:30:02 INFO - PROCESS | 4471 | |1;ext.html;611.2939554814188;637.407211293497;644.4844124700238;654.3489228217923;657.9407853293203;34689;42846;43786.34320485191;43796.12407751851;47901.52098479014;45149.54850451495;45461.318080228826;45534;45548.544514554844;45619.315710264375;8862.33532485063;9001.234895033896;9001.639934402616;9004.369694121415;9005.729828105135;8752.518611476366;8913.643454261823;8925.330600204978;8929.598168082419;8936.061713520085;8934.508602026886;8952.686655967042;8959.37284390093;8972.730818075468;8972.910270897273;8908.643654253821;8912.509811960339;8950.06024367445;8957.104289571023;8958.596863141142;8829.249513791336;8855.867161992575;8868.15752503511;8874.733757987271;8898.777530561723;42203;42414.36378454325;42593.78703106465;42670;42920.356194657106;3500.177458297298;3629.2922880038404;3880.553736320303;3937.6652984496354;4339.848105316299;33049.669503304896;33086.50370244448;33426.49860252098;33493.49759753581;34305.6569434305;4475.239209334421;4505.938289533872;4624.28323609841;4639.443266808004;4753.691010084333;357912;360238;361519;364147;365859;34924.30151396958;35993.8200309002;36044.279114417564;37688.43467347992;60782.39217607856;37489.81255093762;37640.811795941394;37762.81118594445;37794;37886.62113378886;15022.474213402696;15117.395304187708;15129.940904136825;15131.54605361841;15135.924320378328;12954.417051232716;12998.285094319903;13027;13067.477300908047;13129.212247265195;8584.8712269316;8640.222379985706;8645.870311945326;8710.738677839676;8712.738617841349;3321.534985102088;3325.567676202078;3367.7979321240314;3379.172102834821;3388.661133886641;1132.9716757080973;1135.1940122513108;1150.7065698246893;1169.602335206034;1177;32189.35621287608;32534.511982320284;33162.66837331596;33452.832735836164;33663.4950475743;1178.988210117905;1194.6416075177585;1198.1313547677864;1201.567435723139;1336.525533435636;17601.559961001076;17628.559286017953;17629.647407051787;17682.469525914243;17723.73414398785;554970;558204;561200;561537;561759;59270.703646482354;59578.702106490055;59759.70120149371;59824;59842;1311.141202512356;1382;1390.3395886953615;1408.3662351941666;1429.428228708504;32530.674693252764;33886.152846178054;34016.489752654226;34231.82884085563;34291.314173716884;49585.50414495809;50261;50394.49605503898;50446.49553504418;50575.49424505855;403522;403839;405701;406338;407329;24923.376915577257;25306.1142859999;26769;27829;28207.294817629725;48966.75516622537;49214.753926230136;49567.25649115194;49672.50327496679;49770;41186;41532.3770143442;42235.577644223165;42269.66471315204;42709;40144.79927600343;40835;42098.78950605227;42156.57843421526;42429.363559546015;41583.37624935689;42050.57949420466;42907.7854610725;42986.785066074466;43009.56990430056;34938.82530587432;35951.460728088576;36075.63924360723;36542.63457365392;36984.445233320985;28305.292367690974;28459.146225613265;29117.563236551894;29173.85413072921;29233.85383073071 08:30:02 INFO - PROCESS | 4471 | |2;jquery.html;405.5174342532386;411.40757309474355;412.8967758060485;415.52837529404115;418.1574128131815;13962.93018534906;17873.553161170978;19370.031498425076;19375.806241937593;19801.504962375948;14703.92648036761;18641;18783;18858.33995810147;19659.705104423447;5614.073677843161;5780.3063632363965;5810.099434587646;5813.82558523245;5816.912746308809;5716.942830571704;5722.427757224286;5753.568482363839;5760.164776107462;5765.2216950711745;4134.689898257628;4346.456692913386;5429.782808687648;6337.271213810424;6337.968310158442;6302.6218426894075;6353.047042943549;6365.58623689461;6411.102445657589;6424.421802037816;5447.210154527571;5564.10974244123;5621.718914054321;5648.774049038034;5651.434856514356;21778.782212177834;22757.317280481613;22799.202027928943;23756.524869502686;23804.642930356058;2397.4485868250226;2412.975870241293;2447.681801365811;2525.8105642076735;2917.212352664769;9691.418514889128;10549.736256593647;10711.357318560833;10717.517711702993;12111.576094836639;1860.48836569943;1876.0713446843763;1921.4812000759716;1941.728158057873;1980.554375265568;69151;71157.64421177913;72356.63821680858;80485.19514804894;81125.18874811294;10153.847692284622;10243.282970192015;10427.21795865298;10590.311629744043;10905.509252083675;13250.138740981805;13331.733365332833;13378.598642040753;13382.799258011137;13413.865861341457;7921.524708517507;8145.551994640233;8249.8350032999;8315.875261871077;8342.165783421731;8174.713885013996;8224.83550329002;8228.25945664893;8316.293115085164;8359.707410240613;4362.236608593484;4389.670774691911;4453.75504347264;4535.705179163409;4661;1926.80731926809;2024.2712623455545;2024.3825633181727;2051.712760213571;2079.8440116991287;982.258394911837;1104.5195340027115;1107.6234080412698;1113.8106521891298;1128.9548418063187;19474.955129711176;19804.60390792205;19854.20583176657;19982.30061947825;20163.596728065357;1028.9331193472551;1038.1642777564134;1051.726551096705;1054.7363159210197;1083.0793825248477;10587.782405023521;11125.72185695348;11174.106071514421;11210.159238057178;11221.158413119048;475233;477571;481194;488643;497717;84948.57525712455;90532.54733726289;91356;91637.54181229185;93253;1067.1836045425257;1067.4395942130443;1076.079951641351;1086.0822604898847;1097.912167026652;17151.142442877557;17323.826761732726;17344.30622775075;18835.529111771768;18964.905175474036;41494.79252603718;41993.79003104965;42075.94810129771;42764.57235427606;44716;58343.70828145832;58384.4161558379;58513.4148658508;59616.10575841279;60429.697851510464;8938.1508756668;9102.908970910206;9143.222826059859;9262.72211833646;9264.629414823332;8196.795080123045;10306.587736490457;10443.321184123008;12730.981521478074;14472.493462728755;11531.481083351437;11777.705557361134;12282.447289872156;12574.37128143571;12619.936900315439;12146;12511.061670374576;12591.685207869876;12672.366381681064;12780.936095319465;12283.69290767738;12469.438875250817;12843.229406235656;12904.935475322563;12925.095243333253;8998.460092394478;9099.272058235458;9155.816883662423;9156.771080723036;9275.768105797139;12053.63839084829;12702.491900323883;12728.6817829555;12760.234385936503;12807.487700491876 08:30:02 INFO - PROCESS | 4471 | |3;mootools.html;1352.2630166559225;1567.396552327354;1574.6929348776987;1765.3203516646088;1859.748933893924;8494.872576911344;8811.206991370775;8821.955890220548;9129.634814607407;9134.223590994774;8856.424332418388;9067.546622668873;9069.183773460387;9079.727608171748;9082.318826088052;2068.0383621615947;2248.910043598254;2333.7899589036983;2344.742078371378;2370.5377451396985;2380.9642855357183;2390.6294524348755;2390.8087353011715;2390.8565486070893;2391.35433432973;2237.876916769577;2258.0290475095703;2258.5821622999715;2261.5589959957815;2265.3090807303765;1995.860289779716;2243.5288589396246;2281.908723651052;2296.3570200343765;2303.4586872085047;2122.862013969087;2126.170793390579;2139.6469582518866;2140.2509121807398;2141.379000089972;8825.117488251188;8838.29293656506;8850.955745221232;8857.867131993025;8880.33397495184;5204.34945631796;5333.146696528575;5337.146056630959;5339.67961922282;5339.9733001335135;3410.9829450852835;3457.8098204598737;3524.312759011994;3537.7617833758236;3548.432250839879;1074.5003573338356;1082.8484012238293;1113.1873732175477;1134.1437214902696;1167.6847251242118;12100.152989290753;12184.451699673536;12185.634430967086;12195.573154939624;12196.268223906594;4402.801873915681;4523.547645235451;4533.38799262102;4621.768911554409;4639.814407423665;9881.357711748713;9883.851742223873;9892.901070989343;9902;9926.751831204278;5213.296205012351;5251.527362537314;5254.947450525446;5259.552938000236;5269.815556455505;4255.744655320691;4821.54195351441;4935.679180853233;4936.506349365107;5070.163423035158;3826.674732647756;3841.9818748031794;3906.160175562268;3920.745151565139;3955.8417663293144;1105.5412004018242;1117.9776404471866;1134.3364131982717;1145.3700464744356;1155.722626569634;951.8667386565886;993.0813997052697;995.4326034160604;1007.3552926127285;1019.225388704575;6043.607165534227;6059.515418722442;6069.36271691466;6221.720022598994;6305.274893387227;942.4251206763869;1015.096564057989;1026.0867827633413;1048.339546085961;1066.7973085113804;4021.959780402217;4130.153318569686;4138.172365526907;4152.2525945330135;4163.39630753539;11049.558017679203;11408.771824563462;11498.885011149781;11526.88473115275;11540.826887596693;11272.492737826817;11274.943625281821;11329.603463878724;11340.26288291275;11388.088952883749;8098.959505202554;8361.790955226168;8496.150384961455;8510.191531804468;8553.700620478254;6035.0343944970355;6107.236595425572;6116.418940200674;6124.755009799558;6196.81409557714;10494.265401421828;10722.785544288914;10785.89214107849;10801.621943231949;10816.729581760204;10404.68785936423;10449.738756531147;10761.838572421577;10773.51519181623;10789.62236321725;7361.411087113125;7875;8092.069412017693;8095.23095305945;8281.047679516932;8950.552472376225;8961.103889610988;8980.146886045815;9017.639294428149;9036.18674319288;7950.52296862167;8157.2250636189465;8166.224208700164;8225.259726624632;8316.708915187939;7821.413393995359;7849.646765895434;7929.960350198212;7950.085740139958;8017.839643206987;7008.053912721717;7030.367266945795;7074.858502830018;7102.218755936739;7108.822279442848;5102.08162530748;5128.666636668605;5145.562627176732;5149.794008239778;5203.479652034766;5102.336696229467;5120.283160357479;5138.203578445347;5189.429162792015;5217.739113044408 08:30:02 INFO - PROCESS | 4471 | |4;prototype.html;308.35092131064107;311.59959452104044;314.6193106341326;316.6627541668122;319.331001551749;5262.657927234727;5322.89354212916;5395.973020134898;5407.756650950707;5451.454854514552;5283.494204151818;5285.233641122035;5288.497592728685;5318.734063296839;5336.279602253695;3986.880393588197;4010.1979604079156;4117.6705863531;4127.29835927893;4142.689298302625;4091.9386209206887;4127.318992366256;4146.917061658765;4152.418661387408;4157.833686652531;3960.584138665442;4105.220008198447;4151.83392664293;4151.958480415203;4155.355919832431;4047.251258517169;4094.61101195386;4109.157622687342;4124.05146816231;4148.896277593054;3618.9095272618133;3625.6374362563797;3627.1838836261895;3640.290143422034;3649.9013067486303;5107.948920510785;5109.284700141983;5116.488351164891;5142.51146141116;5157.4068982067165;4497.930951762193;4711.693739906929;4721.244600863845;4759.595434388081;4766.428028636586;5487.204355368489;5596.944030559683;5608.383077861432;5613;5633.267675202238;4010.939835902464;4011.919761604752;4024.7987600619854;4025.9194816103513;4043.110515686544;5710.428957104298;5831.650100993911;5839.30660108568;5851.882962340772;5855.619384739978;4101.118259574206;4101.323281658552;4113.794310284474;4114.835406583763;4114.917701645951;4110.157417729359;4128.91742165155;4136.689748268892;4140.819866338079;4148.377743338523;4533.297338912474;4685.672002959828;4690.413698287714;4703.588923322989;4762.14281429346;5528.308961379827;5548.5838562108;5557.166425036196;5564.19319198722;5603;1405.8331584784605;1408.4436647524171;1410.3230449384353;1411.5483045425572;1416;904.0959040959041;913.1600721755335;927.5547737086237;971.3928794503436;982.3369225772575;5289.68261904287;5356.812511562077;5373.596980226499;5393.191021346829;5417.160340147283;5675;5677.716114194356;5716.885662286731;5721.141828725725;5722.884037612625;5193.844184674465;5443.183522471661;5456.372517160498;5510.448955104457;5516.262377351121;3039.6200474940633;3044.9086527404215;3062.2344413896526;3082.830444325539;3095.9845200773852;5719.885602287931;5979.043353063489;5987.64074155552;6036.879262414727;6038.939610603926;5844.386339434383;5844.649321040752;5850.3564607893095;5866.090755932837;5873.706314684334;5878.764849406061;5926.111083337534;5953.196318497034;6026.668533230539;6035.66803825785;5640.633358831663;5732.398098199629;5819.330776960704;5839.824805255849;5848.912266316093;5633.915491267552;5695.686737229492;5710.48672101885;5729.684867332337;5769.307683078057;5745.712714364348;5957.970210148921;6013.361916040907;6017.608855424383;6032.00471922128;3936.913435878989;3947.190825880745;3967.2065586882163;3979.3036218662196;3979.4826672532386;5766.625169363978;5773.3072031356505;5781.942180578309;5785.797497087582;5816.5458635341165;5434.64674796137;5456.699881506555;5633.464820842013;5636.85907852307;5682.6306290091015;5606.074997625346;5635.098384258499;5637.379888212211;5650.63270887391;5680.68756218412;5281.392639846466;5419.160030195326;5425.972870135624;5577.135543990688;5627.5497960162265;5700.458456446631;5709.25779648643;5728.966216992908;5744.138379243147;5745.1382292656435;5547.472990065938;5615.989121957923;5616.831495055155;5632.7746890123935;5680.4319568042865 08:30:02 INFO - PROCESS | 4471 | |5;yui.html;771.2672960687348;778.8987431633888;814.3403842887262;816.2286639126024;823.7693445835168;14905.360410354851;15577.454789082385;15732.764008539882;15855.207239638015;15866.761998570031;14484.927575362135;14487.058341207814;14510.927445362759;14925;15756.527304180865;873.3973558244791;874.2132081127005;880.8194320164383;881.0748713850431;881.3257857738835;706.8939659051158;833.3999520345342;878.3807415771865;880.2122100719852;882.2016075451727;912.0924679943465;921.7833809054866;922.5848368234322;924.7641851327902;925.6112432778249;735.6799792090458;917.8302014127341;918.5177781664613;919.0120620333169;919.8022425178585;631.3497097989078;658.8418779492887;663.8141320430287;665.7969319357594;666.4068978609042;15165.165915874622;15294.311755971008;15297.08217506942;15318.387264509407;15322;13419;13739.381727822272;13941.233232172226;14001.859981400157;14033.71932561343;13038.41327140281;13055.934720326433;13095;13298.468061277537;13550.796738048937;4452.955470445287;4471.664625153094;4564.041551274237;4600.769961501945;4618.307253911907;60120.39879601192;60205.6989715053;60261.397386026016;60308;60398.39601603972;10718.571257149626;11223.38271395081;11301.434928253719;11437.828432573519;11748.060155187564;19711.80288197128;19726.40820775379;19820.40538783839;19822.108005139802;19822.108005139802;7846.254605812439;8018.51888886657;8160.143184965612;8198.508089514648;8473.872891906627;4832.251001094836;4850.272459131159;4860.538248866352;4951.925721114186;4999.10016197088;3359.3785149747373;3479.59984601774;3481.878134265339;3484.425069863444;3488.755787094879;617.6016469377226;623.1058431151313;625.3152797686525;636.7643971730489;651.6546230497844;664.5481072870398;705.5837056136904;750.9286617771303;755.8072691463642;765.2309429023795;9785.657501987538;9858.901410985942;10039.598416063276;10131.290809643402;10162.593496260215;679.9388055075067;698.4238003647012;738.4978214813979;751.9699212031458;762.9237076292437;4599.310103484438;4647.651426143053;4675.672702910763;4692.41344831896;4708.741019243906;68804.65597671979;68957.31042689508;69346.65326673334;69443.65278173577;70500;60939.69530152321;61372.693136534035;61518;62790.37209627937;63149.63228125569;55710.442895572145;56724;56768;56955.860882782115;56978.71510642503;8973.37186396972;9074.45553266806;9227.953860230657;9231.723048308562;9417.387869788441;37503.249935000604;37632.623673762915;38438.23123537569;38612.227755445296;38690.4196437048;46217.075658487316;50004.499954999985;50756.492435075175;51110;51980;10833.079188269085;11054.060404865677;11329.546818127183;12249.632511024683;12282.877171228174;14457.951176810264;14617.415303387745;15120.168390738614;15158.621034474227;15236.77144842806;10496.317739346918;11849.703757406134;13853.16880987144;13854.445822167001;14559.12645241289;13342.599722008355;13787.586372408843;13983.370748316147;14088.647783805487;14448.855511444752;13951.37218825135;13992.720145597235;14008.509702160376;14086.22525761093;14231.928840355731;10003.998800360008;10381.740456488647;10633.255672102878;10855.09472606233;11061.336319820837;10828.783424331627;10995.725106872073;11060;11064.336139831636;11455.198136130392 08:30:02 INFO - PROCESS | 4471 | __end_tp_report 08:30:02 INFO - PROCESS | 4471 | __start_cc_report 08:30:02 INFO - PROCESS | 4471 | _x_x_mozilla_cycle_collect,885 08:30:02 INFO - PROCESS | 4471 | __end_cc_report 08:30:02 INFO - PROCESS | 4471 | __startTimestamp1463585402259__endTimestamp 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | ------- Summary: start ------- 08:30:02 INFO - PROCESS | 4471 | Number of tests: 6 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#0] dojo.html Cycles:165 Average:5474.59 Median:5655.94 stddev:627.16 (11.1%) stddev-sans-first:602.39 08:30:02 INFO - PROCESS | 4471 | Values: 3166.6 3194.0 3224.4 3237.2 3254.4 5399.7 5448.2 5450.8 5511.6 5517.7 5456.3 5486.2 5498.1 5531.1 5549.9 4901.2 4917.2 5028.0 5096.3 5110.1 5024.0 5058.5 5100.9 5106.8 5114.6 4903.3 4925.4 4969.1 4993.3 4993.5 4962.2 4993.4 4995.7 5008.3 5055.2 4854.6 4944.0 4961.1 4972.4 5028.9 5362.2 5437.8 5449.5 5451.6 5522.6 5940.0 5961.0 5978.3 5978.7 5980.9 5738.3 5869.8 5940.5 5960.1 5995.6 5522.3 5620.9 5624.9 5626.4 5626.9 6090.2 6103.9 6105.6 6107.7 6140.0 5685.0 5685.4 5768.3 5900.5 5903.5 5887.9 5898.6 5900.4 5902.0 5946.4 5804.3 5831.6 5856.1 5869.0 5874.2 5702.0 5824.4 5859.2 5862.1 5897.9 4467.0 4486.3 4494.3 4494.5 4515.1 3977.2 3994.9 3998.5 4027.7 4030.0 5469.8 5518.6 5527.8 5532.6 5558.1 5932.8 5937.2 5937.5 5941.6 5952.8 5610.1 5617.2 5620.7 5622.4 5624.8 4960.3 5031.6 5058.5 5075.6 5079.0 6121.3 6125.8 6127.9 6135.1 6135.4 5707.3 5906.6 5912.8 5918.2 5924.1 6071.3 6112.4 6127.3 6135.7 6179.6 5786.3 5818.9 5823.8 5825.4 5833.4 5710.1 5838.0 5884.1 5897.4 5902.3 5724.7 5791.3 5793.2 5803.3 5840.1 5781.5 5786.6 5798.6 5800.4 5802.4 5437.1 5575.9 5616.6 5692.9 5697.6 5794.4 5830.9 5833.3 5837.5 5842.1 5636.2 5639.5 5651.2 5651.3 5655.9 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#1] ext.html Cycles:180 Average:59131.67 Median:41010.50 stddev:119671.39 (291.8%) stddev-sans-first:119926.44 08:30:02 INFO - PROCESS | 4471 | Values: 611.3 637.4 644.5 654.3 657.9 34689.0 42846.0 43786.3 43796.1 47901.5 45149.5 45461.3 45534.0 45548.5 45619.3 8862.3 9001.2 9001.6 9004.4 9005.7 8752.5 8913.6 8925.3 8929.6 8936.1 8934.5 8952.7 8959.4 8972.7 8972.9 8908.6 8912.5 8950.1 8957.1 8958.6 8829.2 8855.9 8868.2 8874.7 8898.8 42203.0 42414.4 42593.8 42670.0 42920.4 3500.2 3629.3 3880.6 3937.7 4339.8 33049.7 33086.5 33426.5 33493.5 34305.7 4475.2 4505.9 4624.3 4639.4 4753.7 357912.0 360238.0 361519.0 364147.0 365859.0 34924.3 35993.8 36044.3 37688.4 60782.4 37489.8 37640.8 37762.8 37794.0 37886.6 15022.5 15117.4 15129.9 15131.5 15135.9 12954.4 12998.3 13027.0 13067.5 13129.2 8584.9 8640.2 8645.9 8710.7 8712.7 3321.5 3325.6 3367.8 3379.2 3388.7 1133.0 1135.2 1150.7 1169.6 1177.0 32189.4 32534.5 33162.7 33452.8 33663.5 1179.0 1194.6 1198.1 1201.6 1336.5 17601.6 17628.6 17629.6 17682.5 17723.7 554970.0 558204.0 561200.0 561537.0 561759.0 59270.7 59578.7 59759.7 59824.0 59842.0 1311.1 1382.0 1390.3 1408.4 1429.4 32530.7 33886.2 34016.5 34231.8 34291.3 49585.5 50261.0 50394.5 50446.5 50575.5 403522.0 403839.0 405701.0 406338.0 407329.0 24923.4 25306.1 26769.0 27829.0 28207.3 48966.8 49214.8 49567.3 49672.5 49770.0 41186.0 41532.4 42235.6 42269.7 42709.0 40144.8 40835.0 42098.8 42156.6 42429.4 41583.4 42050.6 42907.8 42986.8 43009.6 34938.8 35951.5 36075.6 36542.6 36984.4 28305.3 28459.1 29117.6 29173.9 29233.9 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#2] jquery.html Cycles:180 Average:28690.91 Median:23780.58 stddev:79727.28 (335.3%) stddev-sans-first:79922.65 08:30:02 INFO - PROCESS | 4471 | Values: 405.5 411.4 412.9 415.5 418.2 13962.9 17873.6 19370.0 19375.8 19801.5 14703.9 18641.0 18783.0 18858.3 19659.7 5614.1 5780.3 5810.1 5813.8 5816.9 5716.9 5722.4 5753.6 5760.2 5765.2 4134.7 4346.5 5429.8 6337.3 6338.0 6302.6 6353.0 6365.6 6411.1 6424.4 5447.2 5564.1 5621.7 5648.8 5651.4 21778.8 22757.3 22799.2 23756.5 23804.6 2397.4 2413.0 2447.7 2525.8 2917.2 9691.4 10549.7 10711.4 10717.5 12111.6 1860.5 1876.1 1921.5 1941.7 1980.6 69151.0 71157.6 72356.6 80485.2 81125.2 10153.8 10243.3 10427.2 10590.3 10905.5 13250.1 13331.7 13378.6 13382.8 13413.9 7921.5 8145.6 8249.8 8315.9 8342.2 8174.7 8224.8 8228.3 8316.3 8359.7 4362.2 4389.7 4453.8 4535.7 4661.0 1926.8 2024.3 2024.4 2051.7 2079.8 982.3 1104.5 1107.6 1113.8 1129.0 19475.0 19804.6 19854.2 19982.3 20163.6 1028.9 1038.2 1051.7 1054.7 1083.1 10587.8 11125.7 11174.1 11210.2 11221.2 475233.0 477571.0 481194.0 488643.0 497717.0 84948.6 90532.5 91356.0 91637.5 93253.0 1067.2 1067.4 1076.1 1086.1 1097.9 17151.1 17323.8 17344.3 18835.5 18964.9 41494.8 41993.8 42075.9 42764.6 44716.0 58343.7 58384.4 58513.4 59616.1 60429.7 8938.2 9102.9 9143.2 9262.7 9264.6 8196.8 10306.6 10443.3 12731.0 14472.5 11531.5 11777.7 12282.4 12574.4 12619.9 12146.0 12511.1 12591.7 12672.4 12780.9 12283.7 12469.4 12843.2 12904.9 12925.1 8998.5 9099.3 9155.8 9156.8 9275.8 12053.6 12702.5 12728.7 12760.2 12807.5 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#3] mootools.html Cycles:180 Average:5903.43 Median:4630.79 stddev:3406.25 (73.6%) stddev-sans-first:3398.63 08:30:02 INFO - PROCESS | 4471 | Values: 1352.3 1567.4 1574.7 1765.3 1859.7 8494.9 8811.2 8822.0 9129.6 9134.2 8856.4 9067.5 9069.2 9079.7 9082.3 2068.0 2248.9 2333.8 2344.7 2370.5 2381.0 2390.6 2390.8 2390.9 2391.4 2237.9 2258.0 2258.6 2261.6 2265.3 1995.9 2243.5 2281.9 2296.4 2303.5 2122.9 2126.2 2139.6 2140.3 2141.4 8825.1 8838.3 8851.0 8857.9 8880.3 5204.3 5333.1 5337.1 5339.7 5340.0 3411.0 3457.8 3524.3 3537.8 3548.4 1074.5 1082.8 1113.2 1134.1 1167.7 12100.2 12184.5 12185.6 12195.6 12196.3 4402.8 4523.5 4533.4 4621.8 4639.8 9881.4 9883.9 9892.9 9902.0 9926.8 5213.3 5251.5 5254.9 5259.6 5269.8 4255.7 4821.5 4935.7 4936.5 5070.2 3826.7 3842.0 3906.2 3920.7 3955.8 1105.5 1118.0 1134.3 1145.4 1155.7 951.9 993.1 995.4 1007.4 1019.2 6043.6 6059.5 6069.4 6221.7 6305.3 942.4 1015.1 1026.1 1048.3 1066.8 4022.0 4130.2 4138.2 4152.3 4163.4 11049.6 11408.8 11498.9 11526.9 11540.8 11272.5 11274.9 11329.6 11340.3 11388.1 8099.0 8361.8 8496.2 8510.2 8553.7 6035.0 6107.2 6116.4 6124.8 6196.8 10494.3 10722.8 10785.9 10801.6 10816.7 10404.7 10449.7 10761.8 10773.5 10789.6 7361.4 7875.0 8092.1 8095.2 8281.0 8950.6 8961.1 8980.1 9017.6 9036.2 7950.5 8157.2 8166.2 8225.3 8316.7 7821.4 7849.6 7930.0 7950.1 8017.8 7008.1 7030.4 7074.9 7102.2 7108.8 5102.1 5128.7 5145.6 5149.8 5203.5 5102.3 5120.3 5138.2 5189.4 5217.7 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#4] prototype.html Cycles:180 Average:4712.49 Median:5418.16 stddev:1403.39 (25.9%) stddev-sans-first:1367.85 08:30:02 INFO - PROCESS | 4471 | Values: 308.4 311.6 314.6 316.7 319.3 5262.7 5322.9 5396.0 5407.8 5451.5 5283.5 5285.2 5288.5 5318.7 5336.3 3986.9 4010.2 4117.7 4127.3 4142.7 4091.9 4127.3 4146.9 4152.4 4157.8 3960.6 4105.2 4151.8 4152.0 4155.4 4047.3 4094.6 4109.2 4124.1 4148.9 3618.9 3625.6 3627.2 3640.3 3649.9 5107.9 5109.3 5116.5 5142.5 5157.4 4497.9 4711.7 4721.2 4759.6 4766.4 5487.2 5596.9 5608.4 5613.0 5633.3 4010.9 4011.9 4024.8 4025.9 4043.1 5710.4 5831.7 5839.3 5851.9 5855.6 4101.1 4101.3 4113.8 4114.8 4114.9 4110.2 4128.9 4136.7 4140.8 4148.4 4533.3 4685.7 4690.4 4703.6 4762.1 5528.3 5548.6 5557.2 5564.2 5603.0 1405.8 1408.4 1410.3 1411.5 1416.0 904.1 913.2 927.6 971.4 982.3 5289.7 5356.8 5373.6 5393.2 5417.2 5675.0 5677.7 5716.9 5721.1 5722.9 5193.8 5443.2 5456.4 5510.4 5516.3 3039.6 3044.9 3062.2 3082.8 3096.0 5719.9 5979.0 5987.6 6036.9 6038.9 5844.4 5844.6 5850.4 5866.1 5873.7 5878.8 5926.1 5953.2 6026.7 6035.7 5640.6 5732.4 5819.3 5839.8 5848.9 5633.9 5695.7 5710.5 5729.7 5769.3 5745.7 5958.0 6013.4 6017.6 6032.0 3936.9 3947.2 3967.2 3979.3 3979.5 5766.6 5773.3 5781.9 5785.8 5816.5 5434.6 5456.7 5633.5 5636.9 5682.6 5606.1 5635.1 5637.4 5650.6 5680.7 5281.4 5419.2 5426.0 5577.1 5627.5 5700.5 5709.3 5729.0 5744.1 5745.1 5547.5 5616.0 5616.8 5632.8 5680.4 08:30:02 INFO - PROCESS | 4471 | 08:30:02 INFO - PROCESS | 4471 | [#5] yui.html Cycles:180 Average:16145.26 Median:4661.66 stddev:19160.07 (411.0%) stddev-sans-first:19179.04 08:30:02 INFO - PROCESS | 4471 | Values: 771.3 778.9 814.3 816.2 823.8 14905.4 15577.5 15732.8 15855.2 15866.8 14484.9 14487.1 14510.9 14925.0 15756.5 873.4 874.2 880.8 881.1 881.3 706.9 833.4 878.4 880.2 882.2 912.1 921.8 922.6 924.8 925.6 735.7 917.8 918.5 919.0 919.8 631.3 658.8 663.8 665.8 666.4 15165.2 15294.3 15297.1 15318.4 15322.0 13419.0 13739.4 13941.2 14001.9 14033.7 13038.4 13055.9 13095.0 13298.5 13550.8 4453.0 4471.7 4564.0 4600.8 4618.3 60120.4 60205.7 60261.4 60308.0 60398.4 10718.6 11223.4 11301.4 11437.8 11748.1 19711.8 19726.4 19820.4 19822.1 19822.1 7846.3 8018.5 8160.1 8198.5 8473.9 4832.3 4850.3 4860.5 4951.9 4999.1 3359.4 3479.6 3481.9 3484.4 3488.8 617.6 623.1 625.3 636.8 651.7 664.5 705.6 750.9 755.8 765.2 9785.7 9858.9 10039.6 10131.3 10162.6 679.9 698.4 738.5 752.0 762.9 4599.3 4647.7 4675.7 4692.4 4708.7 68804.7 68957.3 69346.7 69443.7 70500.0 60939.7 61372.7 61518.0 62790.4 63149.6 55710.4 56724.0 56768.0 56955.9 56978.7 8973.4 9074.5 9228.0 9231.7 9417.4 37503.2 37632.6 38438.2 38612.2 38690.4 46217.1 50004.5 50756.5 51110.0 51980.0 10833.1 11054.1 11329.5 12249.6 12282.9 14458.0 14617.4 15120.2 15158.6 15236.8 10496.3 11849.7 13853.2 13854.4 14559.1 13342.6 13787.6 13983.4 14088.6 14448.9 13951.4 13992.7 14008.5 14086.2 14231.9 10004.0 10381.7 10633.3 10855.1 11061.3 10828.8 10995.7 11060.0 11064.3 11455.2 08:30:02 INFO - PROCESS | 4471 | -------- Summary: end -------- 08:30:02 INFO - PROCESS | 4471 | 08:30:03 INFO - TEST-INFO | 4471: exit 0 08:30:03 INFO - TEST-OK | dromaeo_css | took 1113525ms 08:30:03 INFO - TEST-START | kraken 08:30:03 INFO - operating with platform_type : linux_ 08:30:03 INFO - Initialising browser for kraken test... 08:30:03 INFO - TEST-INFO | started process 4661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpplcxGX/profile http://localhost:39797/getInfo.html) 08:30:07 INFO - PROCESS | 4661 | __metrics Screen width/height:1600/1200 08:30:07 INFO - PROCESS | 4661 | colorDepth:24 08:30:07 INFO - PROCESS | 4661 | Browser inner width/height: 1024/697 08:30:07 INFO - PROCESS | 4661 | __metrics 08:30:08 ERROR - PROCESS | 4661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:30:09 INFO - TEST-INFO | 4661: exit 0 08:30:09 INFO - Browser initialized. 08:30:09 INFO - Running cycle 1/1 for kraken test... 08:30:09 INFO - TEST-INFO | started process 4747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpplcxGX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:20 INFO - PROCESS | 4747 | RSS: Main: 152907776 08:30:20 INFO - PROCESS | 4747 | 08:30:23 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39797/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:24 INFO - PROCESS | 4747 | RSS: Main: 208093184 08:30:24 INFO - PROCESS | 4747 | 08:30:27 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39797/tests/kraken/driver.html?testName=audio-dft) 08:30:27 INFO - PROCESS | 4747 | RSS: Main: 197828608 08:30:27 INFO - PROCESS | 4747 | 08:30:30 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39797/tests/kraken/driver.html?testName=audio-fft) 08:30:31 INFO - PROCESS | 4747 | RSS: Main: 265396224 08:30:31 INFO - PROCESS | 4747 | 08:30:33 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39797/tests/kraken/driver.html?testName=audio-oscillator) 08:30:34 INFO - PROCESS | 4747 | RSS: Main: 174460928 08:30:34 INFO - PROCESS | 4747 | 08:30:36 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39797/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:30:37 INFO - PROCESS | 4747 | RSS: Main: 179056640 08:30:37 INFO - PROCESS | 4747 | 08:30:40 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39797/tests/kraken/driver.html?testName=imaging-darkroom) 08:30:40 INFO - PROCESS | 4747 | RSS: Main: 215216128 08:30:40 INFO - PROCESS | 4747 | 08:30:44 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39797/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:45 INFO - PROCESS | 4747 | RSS: Main: 213073920 08:30:45 INFO - PROCESS | 4747 | 08:30:48 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39797/tests/kraken/driver.html?testName=json-parse-financial) 08:30:48 INFO - PROCESS | 4747 | RSS: Main: 214286336 08:30:48 INFO - PROCESS | 4747 | 08:30:50 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39797/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:50 INFO - PROCESS | 4747 | RSS: Main: 202780672 08:30:50 INFO - PROCESS | 4747 | 08:30:52 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:53 INFO - PROCESS | 4747 | RSS: Main: 206905344 08:30:53 INFO - PROCESS | 4747 | 08:30:55 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:56 INFO - PROCESS | 4747 | RSS: Main: 217853952 08:30:56 INFO - PROCESS | 4747 | 08:30:58 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:59 INFO - PROCESS | 4747 | RSS: Main: 222404608 08:30:59 INFO - PROCESS | 4747 | 08:31:01 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:02 INFO - PROCESS | 4747 | RSS: Main: 217669632 08:31:02 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | Cycle 1(1): loaded http://localhost:39797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39797/tests/kraken/driver.html?testName=ai-astar) 08:31:04 INFO - PROCESS | 4747 | RSS: Main: 263409664 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | __start_tp_report 08:31:04 INFO - PROCESS | 4747 | _x_x_mozilla_page_load 08:31:04 INFO - PROCESS | 4747 | _x_x_mozilla_page_load_details 08:31:04 INFO - PROCESS | 4747 | |i|pagename|runs| 08:31:04 INFO - PROCESS | 4747 | |0;ai-astar;109;106;110;110;110;110;109;111;110;110 08:31:04 INFO - PROCESS | 4747 | |1;audio-beat-detection;160;123;152;215;126;156;159;124;174;129 08:31:04 INFO - PROCESS | 4747 | |2;audio-dft;161;160;160;161;160;159;160;160;161;160 08:31:04 INFO - PROCESS | 4747 | |3;audio-fft;74;74;76;149;76;76;75;76;132;75 08:31:04 INFO - PROCESS | 4747 | |4;audio-oscillator;91;91;153;92;91;91;134;91;91;126 08:31:04 INFO - PROCESS | 4747 | |5;imaging-gaussian-blur;117;116;117;116;117;116;117;116;117;116 08:31:04 INFO - PROCESS | 4747 | |6;imaging-darkroom;146;145;146;145;145;146;145;145;145;145 08:31:04 INFO - PROCESS | 4747 | |7;imaging-desaturate;94;94;94;94;95;95;95;94;95;95 08:31:04 INFO - PROCESS | 4747 | |8;json-parse-financial;54;54;54;54;54;53;55;106;54;52 08:31:04 INFO - PROCESS | 4747 | |9;json-stringify-tinderbox;59;59;59;59;59;59;60;59;59;59 08:31:04 INFO - PROCESS | 4747 | |10;stanford-crypto-aes;77;77;78;78;78;85;80;78;79;78 08:31:04 INFO - PROCESS | 4747 | |11;stanford-crypto-ccm;130;127;120;116;118;116;124;116;187;129 08:31:04 INFO - PROCESS | 4747 | |12;stanford-crypto-pbkdf2;159;164;157;166;155;157;155;159;157;156 08:31:04 INFO - PROCESS | 4747 | |13;stanford-crypto-sha256-iterative;65;62;62;62;64;62;62;64;61;63 08:31:04 INFO - PROCESS | 4747 | __end_tp_report 08:31:04 INFO - PROCESS | 4747 | __start_cc_report 08:31:04 INFO - PROCESS | 4747 | _x_x_mozilla_cycle_collect,1863 08:31:04 INFO - PROCESS | 4747 | __end_cc_report 08:31:04 INFO - PROCESS | 4747 | __startTimestamp1463585464556__endTimestamp 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | ------- Summary: start ------- 08:31:04 INFO - PROCESS | 4747 | Number of tests: 14 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.35 (1.2%) stddev-sans-first:1.42 08:31:04 INFO - PROCESS | 4747 | Values: 109.0 106.0 110.0 110.0 110.0 110.0 109.0 111.0 110.0 110.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#1] audio-beat-detection Cycles:10 Average:151.80 Median:157.50 stddev:28.70 (18.2%) stddev-sans-first:30.28 08:31:04 INFO - PROCESS | 4747 | Values: 160.0 123.0 152.0 215.0 126.0 156.0 159.0 124.0 174.0 129.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 08:31:04 INFO - PROCESS | 4747 | Values: 161.0 160.0 160.0 161.0 160.0 159.0 160.0 160.0 161.0 160.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#3] audio-fft Cycles:10 Average:88.30 Median:75.50 stddev:27.81 (36.8%) stddev-sans-first:29.01 08:31:04 INFO - PROCESS | 4747 | Values: 74.0 74.0 76.0 149.0 76.0 76.0 75.0 76.0 132.0 75.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:91.00 stddev:23.41 (25.7%) stddev-sans-first:24.26 08:31:04 INFO - PROCESS | 4747 | Values: 91.0 91.0 153.0 92.0 91.0 91.0 134.0 91.0 91.0 126.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:31:04 INFO - PROCESS | 4747 | Values: 117.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 08:31:04 INFO - PROCESS | 4747 | Values: 146.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 08:31:04 INFO - PROCESS | 4747 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:54.00 stddev:16.53 (30.6%) stddev-sans-first:17.44 08:31:04 INFO - PROCESS | 4747 | Values: 54.0 54.0 54.0 54.0 54.0 53.0 55.0 106.0 54.0 52.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 08:31:04 INFO - PROCESS | 4747 | Values: 59.0 59.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 59.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.00 stddev:2.35 (3.0%) stddev-sans-first:2.40 08:31:04 INFO - PROCESS | 4747 | Values: 77.0 77.0 78.0 78.0 78.0 85.0 80.0 78.0 79.0 78.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:125.50 stddev:21.34 (17.0%) stddev-sans-first:22.62 08:31:04 INFO - PROCESS | 4747 | Values: 130.0 127.0 120.0 116.0 118.0 116.0 124.0 116.0 187.0 129.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.50 Median:158.00 stddev:3.72 (2.4%) stddev-sans-first:3.94 08:31:04 INFO - PROCESS | 4747 | Values: 159.0 164.0 157.0 166.0 155.0 157.0 155.0 159.0 157.0 156.0 08:31:04 INFO - PROCESS | 4747 | 08:31:04 INFO - PROCESS | 4747 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:1.25 (2.0%) stddev-sans-first:1.01 08:31:04 INFO - PROCESS | 4747 | Values: 65.0 62.0 62.0 62.0 64.0 62.0 62.0 64.0 61.0 63.0 08:31:04 INFO - PROCESS | 4747 | -------- Summary: end -------- 08:31:04 INFO - PROCESS | 4747 | 08:31:05 INFO - TEST-INFO | 4747: exit 0 08:31:05 INFO - TEST-OK | kraken | took 62470ms 08:31:05 INFO - SUITE-END | took 1175s 08:31:05 INFO - Completed test suite (00:19:36) 08:31:05 INFO - javascript benchmark 08:31:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3166.6358368787587, 3194.0, 3224.3712476067167, 3237.1745204972726, 3254.381667483178, 5399.730013499319, 5448.182772584114, 5450.781968721247, 5511.559075273974, 5517.668939863606, 5456.317960254968, 5486.177073438977, 5498.0653288940875, 5531.059719847625, 5549.889002219953, 4901.215805471125, 4917.237828136645, 5027.974860125694, 5096.337476128098, 5110.054639891613, 5023.974880125595, 5058.494150584949, 5100.9489905100845, 5106.7701953412, 5114.641975061747, 4903.2645103234445, 4925.38432695913, 4969.080720066763, 4993.275974983625, 4993.525615066563, 4962.1812400953795, 4993.400791904958, 4995.700257984533, 5008.273800298973, 5055.216441451581, 4854.611631069506, 4943.950560494385, 4961.131801934683, 4972.403311602595, 5028.949710502885, 5362.249285100089, 5437.782488700447, 5449.482299181611, 5451.64564303319, 5522.585806064561, 5940.049592065292, 5960.97019514904, 5978.342382337939, 5978.701064946736, 5980.850478738066, 5738.340090889559, 5869.765209391662, 5940.46535811779, 5960.0761881908375, 5995.550333724988, 5522.30971128609, 5620.91568626471, 5624.943750562483, 5626.352969408488, 5626.915596266059, 6090.208272924491, 6103.877922441527, 6105.633661980295, 6107.6640784757265, 6140.048292514667, 5685.0, 5685.431456854364, 5768.250127483484, 5900.498457631149, 5903.468687818031, 5887.852803679943, 5898.587098903122, 5900.4099590041515, 5902.026165682701, 5946.405359464019, 5804.332501762267, 5831.562632802556, 5856.121581762684, 5868.970655146697, 5874.177615133799, 5702.002149623882, 5824.359320474744, 5859.150423188614, 5862.14998825168, 5897.911531327033, 4467.039586488855, 4486.304622783474, 4494.348319493655, 4494.483134439582, 4515.0518391137575, 3977.244323578511, 3994.8601798936897, 3998.460207871958, 4027.7180597357906, 4030.0, 5469.835904922858, 5518.586106042063, 5527.751251193706, 5532.640378375393, 5558.138488534284, 5932.822015339547, 5937.198478205473, 5937.465628093406, 5941.55438342126, 5952.791652292236, 5610.13042978339, 5617.21359009739, 5620.718964051863, 5622.381538030813, 5624.775008999594, 4960.330355402047, 5031.622628302892, 5058.46886076964, 5075.644704870623, 5078.97460512695, 6121.326654068049, 6125.84685382869, 6127.908081378694, 6135.07973803933, 6135.447809697149, 5707.258056452634, 5906.586538942232, 5912.793052243151, 5918.201042859331, 5924.11138329254, 6071.2714474263375, 6112.448802283729, 6127.3259941407305, 6135.662538560422, 6179.598326108789, 5786.276715410574, 5818.9127163093435, 5823.767049317979, 5825.446582574648, 5833.416658334133, 5710.114932185518, 5838.036723940672, 5884.146798714077, 5897.3807750185615, 5902.291724993029, 5724.713764311685, 5791.276090488689, 5793.217915581343, 5803.274590676166, 5840.065589505659, 5781.508571771446, 5786.566007549535, 5798.56510761703, 5800.361960184292, 5802.361740208658, 5437.102878025082, 5575.8884822304135, 5616.550675945996, 5692.943070569241, 5697.6296540724725, 5794.362620111869, 5830.9416905832095, 5833.329167145663, 5837.5329973601165, 5842.123681447817, 5636.182753500638, 5639.54883609302, 5651.208830763614, 5651.2935883014625, 5655.943440565542], "value": 5432.132069117915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.2939554814188, 637.407211293497, 644.4844124700238, 654.3489228217923, 657.9407853293203, 34689.0, 42846.0, 43786.34320485191, 43796.12407751851, 47901.52098479014, 45149.54850451495, 45461.318080228826, 45534.0, 45548.544514554844, 45619.315710264375, 8862.33532485063, 9001.234895033896, 9001.639934402616, 9004.369694121415, 9005.729828105135, 8752.518611476366, 8913.643454261823, 8925.330600204978, 8929.598168082419, 8936.061713520085, 8934.508602026886, 8952.686655967042, 8959.37284390093, 8972.730818075468, 8972.910270897273, 8908.643654253821, 8912.509811960339, 8950.06024367445, 8957.104289571023, 8958.596863141142, 8829.249513791336, 8855.867161992575, 8868.15752503511, 8874.733757987271, 8898.777530561723, 42203.0, 42414.36378454325, 42593.78703106465, 42670.0, 42920.356194657106, 3500.177458297298, 3629.2922880038404, 3880.553736320303, 3937.6652984496354, 4339.848105316299, 33049.669503304896, 33086.50370244448, 33426.49860252098, 33493.49759753581, 34305.6569434305, 4475.239209334421, 4505.938289533872, 4624.28323609841, 4639.443266808004, 4753.691010084333, 357912.0, 360238.0, 361519.0, 364147.0, 365859.0, 34924.30151396958, 35993.8200309002, 36044.279114417564, 37688.43467347992, 60782.39217607856, 37489.81255093762, 37640.811795941394, 37762.81118594445, 37794.0, 37886.62113378886, 15022.474213402696, 15117.395304187708, 15129.940904136825, 15131.54605361841, 15135.924320378328, 12954.417051232716, 12998.285094319903, 13027.0, 13067.477300908047, 13129.212247265195, 8584.8712269316, 8640.222379985706, 8645.870311945326, 8710.738677839676, 8712.738617841349, 3321.534985102088, 3325.567676202078, 3367.7979321240314, 3379.172102834821, 3388.661133886641, 1132.9716757080973, 1135.1940122513108, 1150.7065698246893, 1169.602335206034, 1177.0, 32189.35621287608, 32534.511982320284, 33162.66837331596, 33452.832735836164, 33663.4950475743, 1178.988210117905, 1194.6416075177585, 1198.1313547677864, 1201.567435723139, 1336.525533435636, 17601.559961001076, 17628.559286017953, 17629.647407051787, 17682.469525914243, 17723.73414398785, 554970.0, 558204.0, 561200.0, 561537.0, 561759.0, 59270.703646482354, 59578.702106490055, 59759.70120149371, 59824.0, 59842.0, 1311.141202512356, 1382.0, 1390.3395886953615, 1408.3662351941666, 1429.428228708504, 32530.674693252764, 33886.152846178054, 34016.489752654226, 34231.82884085563, 34291.314173716884, 49585.50414495809, 50261.0, 50394.49605503898, 50446.49553504418, 50575.49424505855, 403522.0, 403839.0, 405701.0, 406338.0, 407329.0, 24923.376915577257, 25306.1142859999, 26769.0, 27829.0, 28207.294817629725, 48966.75516622537, 49214.753926230136, 49567.25649115194, 49672.50327496679, 49770.0, 41186.0, 41532.3770143442, 42235.577644223165, 42269.66471315204, 42709.0, 40144.79927600343, 40835.0, 42098.78950605227, 42156.57843421526, 42429.363559546015, 41583.37624935689, 42050.57949420466, 42907.7854610725, 42986.785066074466, 43009.56990430056, 34938.82530587432, 35951.460728088576, 36075.63924360723, 36542.63457365392, 36984.445233320985, 28305.292367690974, 28459.146225613265, 29117.563236551894, 29173.85413072921, 29233.85383073071], "value": 19179.03197148349, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.5174342532386, 411.40757309474355, 412.8967758060485, 415.52837529404115, 418.1574128131815, 13962.93018534906, 17873.553161170978, 19370.031498425076, 19375.806241937593, 19801.504962375948, 14703.92648036761, 18641.0, 18783.0, 18858.33995810147, 19659.705104423447, 5614.073677843161, 5780.3063632363965, 5810.099434587646, 5813.82558523245, 5816.912746308809, 5716.942830571704, 5722.427757224286, 5753.568482363839, 5760.164776107462, 5765.2216950711745, 4134.689898257628, 4346.456692913386, 5429.782808687648, 6337.271213810424, 6337.968310158442, 6302.6218426894075, 6353.047042943549, 6365.58623689461, 6411.102445657589, 6424.421802037816, 5447.210154527571, 5564.10974244123, 5621.718914054321, 5648.774049038034, 5651.434856514356, 21778.782212177834, 22757.317280481613, 22799.202027928943, 23756.524869502686, 23804.642930356058, 2397.4485868250226, 2412.975870241293, 2447.681801365811, 2525.8105642076735, 2917.212352664769, 9691.418514889128, 10549.736256593647, 10711.357318560833, 10717.517711702993, 12111.576094836639, 1860.48836569943, 1876.0713446843763, 1921.4812000759716, 1941.728158057873, 1980.554375265568, 69151.0, 71157.64421177913, 72356.63821680858, 80485.19514804894, 81125.18874811294, 10153.847692284622, 10243.282970192015, 10427.21795865298, 10590.311629744043, 10905.509252083675, 13250.138740981805, 13331.733365332833, 13378.598642040753, 13382.799258011137, 13413.865861341457, 7921.524708517507, 8145.551994640233, 8249.8350032999, 8315.875261871077, 8342.165783421731, 8174.713885013996, 8224.83550329002, 8228.25945664893, 8316.293115085164, 8359.707410240613, 4362.236608593484, 4389.670774691911, 4453.75504347264, 4535.705179163409, 4661.0, 1926.80731926809, 2024.2712623455545, 2024.3825633181727, 2051.712760213571, 2079.8440116991287, 982.258394911837, 1104.5195340027115, 1107.6234080412698, 1113.8106521891298, 1128.9548418063187, 19474.955129711176, 19804.60390792205, 19854.20583176657, 19982.30061947825, 20163.596728065357, 1028.9331193472551, 1038.1642777564134, 1051.726551096705, 1054.7363159210197, 1083.0793825248477, 10587.782405023521, 11125.72185695348, 11174.106071514421, 11210.159238057178, 11221.158413119048, 475233.0, 477571.0, 481194.0, 488643.0, 497717.0, 84948.57525712455, 90532.54733726289, 91356.0, 91637.54181229185, 93253.0, 1067.1836045425257, 1067.4395942130443, 1076.079951641351, 1086.0822604898847, 1097.912167026652, 17151.142442877557, 17323.826761732726, 17344.30622775075, 18835.529111771768, 18964.905175474036, 41494.79252603718, 41993.79003104965, 42075.94810129771, 42764.57235427606, 44716.0, 58343.70828145832, 58384.4161558379, 58513.4148658508, 59616.10575841279, 60429.697851510464, 8938.1508756668, 9102.908970910206, 9143.222826059859, 9262.72211833646, 9264.629414823332, 8196.795080123045, 10306.587736490457, 10443.321184123008, 12730.981521478074, 14472.493462728755, 11531.481083351437, 11777.705557361134, 12282.447289872156, 12574.37128143571, 12619.936900315439, 12146.0, 12511.061670374576, 12591.685207869876, 12672.366381681064, 12780.936095319465, 12283.69290767738, 12469.438875250817, 12843.229406235656, 12904.935475322563, 12925.095243333253, 8998.460092394478, 9099.272058235458, 9155.816883662423, 9156.771080723036, 9275.768105797139, 12053.63839084829, 12702.491900323883, 12728.6817829555, 12760.234385936503, 12807.487700491876], "value": 9323.809079807661, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.2630166559225, 1567.396552327354, 1574.6929348776987, 1765.3203516646088, 1859.748933893924, 8494.872576911344, 8811.206991370775, 8821.955890220548, 9129.634814607407, 9134.223590994774, 8856.424332418388, 9067.546622668873, 9069.183773460387, 9079.727608171748, 9082.318826088052, 2068.0383621615947, 2248.910043598254, 2333.7899589036983, 2344.742078371378, 2370.5377451396985, 2380.9642855357183, 2390.6294524348755, 2390.8087353011715, 2390.8565486070893, 2391.35433432973, 2237.876916769577, 2258.0290475095703, 2258.5821622999715, 2261.5589959957815, 2265.3090807303765, 1995.860289779716, 2243.5288589396246, 2281.908723651052, 2296.3570200343765, 2303.4586872085047, 2122.862013969087, 2126.170793390579, 2139.6469582518866, 2140.2509121807398, 2141.379000089972, 8825.117488251188, 8838.29293656506, 8850.955745221232, 8857.867131993025, 8880.33397495184, 5204.34945631796, 5333.146696528575, 5337.146056630959, 5339.67961922282, 5339.9733001335135, 3410.9829450852835, 3457.8098204598737, 3524.312759011994, 3537.7617833758236, 3548.432250839879, 1074.5003573338356, 1082.8484012238293, 1113.1873732175477, 1134.1437214902696, 1167.6847251242118, 12100.152989290753, 12184.451699673536, 12185.634430967086, 12195.573154939624, 12196.268223906594, 4402.801873915681, 4523.547645235451, 4533.38799262102, 4621.768911554409, 4639.814407423665, 9881.357711748713, 9883.851742223873, 9892.901070989343, 9902.0, 9926.751831204278, 5213.296205012351, 5251.527362537314, 5254.947450525446, 5259.552938000236, 5269.815556455505, 4255.744655320691, 4821.54195351441, 4935.679180853233, 4936.506349365107, 5070.163423035158, 3826.674732647756, 3841.9818748031794, 3906.160175562268, 3920.745151565139, 3955.8417663293144, 1105.5412004018242, 1117.9776404471866, 1134.3364131982717, 1145.3700464744356, 1155.722626569634, 951.8667386565886, 993.0813997052697, 995.4326034160604, 1007.3552926127285, 1019.225388704575, 6043.607165534227, 6059.515418722442, 6069.36271691466, 6221.720022598994, 6305.274893387227, 942.4251206763869, 1015.096564057989, 1026.0867827633413, 1048.339546085961, 1066.7973085113804, 4021.959780402217, 4130.153318569686, 4138.172365526907, 4152.2525945330135, 4163.39630753539, 11049.558017679203, 11408.771824563462, 11498.885011149781, 11526.88473115275, 11540.826887596693, 11272.492737826817, 11274.943625281821, 11329.603463878724, 11340.26288291275, 11388.088952883749, 8098.959505202554, 8361.790955226168, 8496.150384961455, 8510.191531804468, 8553.700620478254, 6035.0343944970355, 6107.236595425572, 6116.418940200674, 6124.755009799558, 6196.81409557714, 10494.265401421828, 10722.785544288914, 10785.89214107849, 10801.621943231949, 10816.729581760204, 10404.68785936423, 10449.738756531147, 10761.838572421577, 10773.51519181623, 10789.62236321725, 7361.411087113125, 7875.0, 8092.069412017693, 8095.23095305945, 8281.047679516932, 8950.552472376225, 8961.103889610988, 8980.146886045815, 9017.639294428149, 9036.18674319288, 7950.52296862167, 8157.2250636189465, 8166.224208700164, 8225.259726624632, 8316.708915187939, 7821.413393995359, 7849.646765895434, 7929.960350198212, 7950.085740139958, 8017.839643206987, 7008.053912721717, 7030.367266945795, 7074.858502830018, 7102.218755936739, 7108.822279442848, 5102.08162530748, 5128.666636668605, 5145.562627176732, 5149.794008239778, 5203.479652034766, 5102.336696229467, 5120.283160357479, 5138.203578445347, 5189.429162792015, 5217.739113044408], "value": 4686.780858259534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.35092131064107, 311.59959452104044, 314.6193106341326, 316.6627541668122, 319.331001551749, 5262.657927234727, 5322.89354212916, 5395.973020134898, 5407.756650950707, 5451.454854514552, 5283.494204151818, 5285.233641122035, 5288.497592728685, 5318.734063296839, 5336.279602253695, 3986.880393588197, 4010.1979604079156, 4117.6705863531, 4127.29835927893, 4142.689298302625, 4091.9386209206887, 4127.318992366256, 4146.917061658765, 4152.418661387408, 4157.833686652531, 3960.584138665442, 4105.220008198447, 4151.83392664293, 4151.958480415203, 4155.355919832431, 4047.251258517169, 4094.61101195386, 4109.157622687342, 4124.05146816231, 4148.896277593054, 3618.9095272618133, 3625.6374362563797, 3627.1838836261895, 3640.290143422034, 3649.9013067486303, 5107.948920510785, 5109.284700141983, 5116.488351164891, 5142.51146141116, 5157.4068982067165, 4497.930951762193, 4711.693739906929, 4721.244600863845, 4759.595434388081, 4766.428028636586, 5487.204355368489, 5596.944030559683, 5608.383077861432, 5613.0, 5633.267675202238, 4010.939835902464, 4011.919761604752, 4024.7987600619854, 4025.9194816103513, 4043.110515686544, 5710.428957104298, 5831.650100993911, 5839.30660108568, 5851.882962340772, 5855.619384739978, 4101.118259574206, 4101.323281658552, 4113.794310284474, 4114.835406583763, 4114.917701645951, 4110.157417729359, 4128.91742165155, 4136.689748268892, 4140.819866338079, 4148.377743338523, 4533.297338912474, 4685.672002959828, 4690.413698287714, 4703.588923322989, 4762.14281429346, 5528.308961379827, 5548.5838562108, 5557.166425036196, 5564.19319198722, 5603.0, 1405.8331584784605, 1408.4436647524171, 1410.3230449384353, 1411.5483045425572, 1416.0, 904.0959040959041, 913.1600721755335, 927.5547737086237, 971.3928794503436, 982.3369225772575, 5289.68261904287, 5356.812511562077, 5373.596980226499, 5393.191021346829, 5417.160340147283, 5675.0, 5677.716114194356, 5716.885662286731, 5721.141828725725, 5722.884037612625, 5193.844184674465, 5443.183522471661, 5456.372517160498, 5510.448955104457, 5516.262377351121, 3039.6200474940633, 3044.9086527404215, 3062.2344413896526, 3082.830444325539, 3095.9845200773852, 5719.885602287931, 5979.043353063489, 5987.64074155552, 6036.879262414727, 6038.939610603926, 5844.386339434383, 5844.649321040752, 5850.3564607893095, 5866.090755932837, 5873.706314684334, 5878.764849406061, 5926.111083337534, 5953.196318497034, 6026.668533230539, 6035.66803825785, 5640.633358831663, 5732.398098199629, 5819.330776960704, 5839.824805255849, 5848.912266316093, 5633.915491267552, 5695.686737229492, 5710.48672101885, 5729.684867332337, 5769.307683078057, 5745.712714364348, 5957.970210148921, 6013.361916040907, 6017.608855424383, 6032.00471922128, 3936.913435878989, 3947.190825880745, 3967.2065586882163, 3979.3036218662196, 3979.4826672532386, 5766.625169363978, 5773.3072031356505, 5781.942180578309, 5785.797497087582, 5816.5458635341165, 5434.64674796137, 5456.699881506555, 5633.464820842013, 5636.85907852307, 5682.6306290091015, 5606.074997625346, 5635.098384258499, 5637.379888212211, 5650.63270887391, 5680.68756218412, 5281.392639846466, 5419.160030195326, 5425.972870135624, 5577.135543990688, 5627.5497960162265, 5700.458456446631, 5709.25779648643, 5728.966216992908, 5744.138379243147, 5745.1382292656435, 5547.472990065938, 5615.989121957923, 5616.831495055155, 5632.7746890123935, 5680.4319568042865], "value": 4257.931080138719, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.2672960687348, 778.8987431633888, 814.3403842887262, 816.2286639126024, 823.7693445835168, 14905.360410354851, 15577.454789082385, 15732.764008539882, 15855.207239638015, 15866.761998570031, 14484.927575362135, 14487.058341207814, 14510.927445362759, 14925.0, 15756.527304180865, 873.3973558244791, 874.2132081127005, 880.8194320164383, 881.0748713850431, 881.3257857738835, 706.8939659051158, 833.3999520345342, 878.3807415771865, 880.2122100719852, 882.2016075451727, 912.0924679943465, 921.7833809054866, 922.5848368234322, 924.7641851327902, 925.6112432778249, 735.6799792090458, 917.8302014127341, 918.5177781664613, 919.0120620333169, 919.8022425178585, 631.3497097989078, 658.8418779492887, 663.8141320430287, 665.7969319357594, 666.4068978609042, 15165.165915874622, 15294.311755971008, 15297.08217506942, 15318.387264509407, 15322.0, 13419.0, 13739.381727822272, 13941.233232172226, 14001.859981400157, 14033.71932561343, 13038.41327140281, 13055.934720326433, 13095.0, 13298.468061277537, 13550.796738048937, 4452.955470445287, 4471.664625153094, 4564.041551274237, 4600.769961501945, 4618.307253911907, 60120.39879601192, 60205.6989715053, 60261.397386026016, 60308.0, 60398.39601603972, 10718.571257149626, 11223.38271395081, 11301.434928253719, 11437.828432573519, 11748.060155187564, 19711.80288197128, 19726.40820775379, 19820.40538783839, 19822.108005139802, 19822.108005139802, 7846.254605812439, 8018.51888886657, 8160.143184965612, 8198.508089514648, 8473.872891906627, 4832.251001094836, 4850.272459131159, 4860.538248866352, 4951.925721114186, 4999.10016197088, 3359.3785149747373, 3479.59984601774, 3481.878134265339, 3484.425069863444, 3488.755787094879, 617.6016469377226, 623.1058431151313, 625.3152797686525, 636.7643971730489, 651.6546230497844, 664.5481072870398, 705.5837056136904, 750.9286617771303, 755.8072691463642, 765.2309429023795, 9785.657501987538, 9858.901410985942, 10039.598416063276, 10131.290809643402, 10162.593496260215, 679.9388055075067, 698.4238003647012, 738.4978214813979, 751.9699212031458, 762.9237076292437, 4599.310103484438, 4647.651426143053, 4675.672702910763, 4692.41344831896, 4708.741019243906, 68804.65597671979, 68957.31042689508, 69346.65326673334, 69443.65278173577, 70500.0, 60939.69530152321, 61372.693136534035, 61518.0, 62790.37209627937, 63149.63228125569, 55710.442895572145, 56724.0, 56768.0, 56955.860882782115, 56978.71510642503, 8973.37186396972, 9074.45553266806, 9227.953860230657, 9231.723048308562, 9417.387869788441, 37503.249935000604, 37632.623673762915, 38438.23123537569, 38612.227755445296, 38690.4196437048, 46217.075658487316, 50004.499954999985, 50756.492435075175, 51110.0, 51980.0, 10833.079188269085, 11054.060404865677, 11329.546818127183, 12249.632511024683, 12282.877171228174, 14457.951176810264, 14617.415303387745, 15120.168390738614, 15158.621034474227, 15236.77144842806, 10496.317739346918, 11849.703757406134, 13853.16880987144, 13854.445822167001, 14559.12645241289, 13342.599722008355, 13787.586372408843, 13983.370748316147, 14088.647783805487, 14448.855511444752, 13951.37218825135, 13992.720145597235, 14008.509702160376, 14086.22525761093, 14231.928840355731, 10003.998800360008, 10381.740456488647, 10633.255672102878, 10855.09472606233, 11061.336319820837, 10828.783424331627, 10995.725106872073, 11060.0, 11064.336139831636, 11455.198136130392], "value": 7155.813263357717, "unit": "score"}], "name": "dromaeo_css", "value": 7194.945344944208, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 110.0, 110.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 152.0, 215.0, 126.0, 156.0, 159.0, 124.0, 174.0, 129.0], "value": 151.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 159.0, 160.0, 160.0, 161.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 149.0, 76.0, 76.0, 75.0, 76.0, 132.0, 75.0], "value": 88.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 153.0, 92.0, 91.0, 91.0, 134.0, 91.0, 91.0, 126.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0, 106.0, 54.0, 52.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 78.0, 78.0, 78.0, 85.0, 80.0, 78.0, 79.0, 78.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 127.0, 120.0, 116.0, 118.0, 116.0, 124.0, 116.0, 187.0, 129.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 157.0, 166.0, 155.0, 157.0, 155.0, 159.0, 157.0, 156.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 62.0, 62.0, 64.0, 62.0, 62.0, 64.0, 61.0, 63.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1517.6, "alertThreshold": 2.0}]} 08:31:05 INFO - Return code: 0 08:31:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:31:05 ERROR - # TBPL SUCCESS # 08:31:05 INFO - Running post-action listener: _resource_record_post_action 08:31:05 INFO - ##### 08:31:05 INFO - ##### Finished run-tests step (success) 08:31:05 INFO - ##### 08:31:05 INFO - Running post-run listener: _resource_record_post_run 08:31:05 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 45056; Write bytes: 288624640; Read time: 76; Write time: 311632 08:31:05 INFO - TinderboxPrint: CPU usage
13.1% 08:31:05 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 76 08:31:05 INFO - TinderboxPrint: I/O write bytes / time
288,624,640 / 311,632 08:31:05 INFO - TinderboxPrint: CPU idle
8,282.8 (86.9%) 08:31:05 INFO - TinderboxPrint: CPU user
1,202.1 (12.6%) 08:31:05 INFO - TinderboxPrint: Swap in / out
0 / 0 08:31:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1347584; Read time: 0; Write time: 352 08:31:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 45056; Write bytes: 287277056; Read time: 76; Write time: 311280 08:31:06 INFO - Running post-run listener: _upload_blobber_files 08:31:06 INFO - Blob upload gear active. 08:31:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:31:06 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:11 INFO - (blobuploader) - INFO - Done attempting. 08:31:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:12 INFO - (blobuploader) - INFO - Done attempting. 08:31:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:12 INFO - (blobuploader) - INFO - Done attempting. 08:31:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:12 INFO - Return code: 0 08:31:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/765b23033eaadfc41670ceeba8c4ada5098fd9becda17242cbebf2bb3315e0d12b7a06a98edd311f99d662ad8681bfc3b43ce7b8983980061ec170a1371bc422", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa2fdb026f3b96ce072b3bbdfc698cdba9fd33da6c37014a662aa38fb1b415613b71a99cbb3e2ad68d5d39fce70e198ace9f725dc25e115248dccde7bdfe70d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3382aa81dbbcd4ff8664cb667dc0981cb1cbdd99b0547edaf17c174d3298dbda8d4d350eb219b68abf0fc1242e458dbfaebc2f946469951e32786d1422a511fa"} 08:31:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:12 INFO - Contents: 08:31:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/765b23033eaadfc41670ceeba8c4ada5098fd9becda17242cbebf2bb3315e0d12b7a06a98edd311f99d662ad8681bfc3b43ce7b8983980061ec170a1371bc422", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa2fdb026f3b96ce072b3bbdfc698cdba9fd33da6c37014a662aa38fb1b415613b71a99cbb3e2ad68d5d39fce70e198ace9f725dc25e115248dccde7bdfe70d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3382aa81dbbcd4ff8664cb667dc0981cb1cbdd99b0547edaf17c174d3298dbda8d4d350eb219b68abf0fc1242e458dbfaebc2f946469951e32786d1422a511fa"} 08:31:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:12 INFO - Copying logs to upload dir... 08:31:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.152813 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-18 08:31:12.956266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 08:31:12.959947) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/765b23033eaadfc41670ceeba8c4ada5098fd9becda17242cbebf2bb3315e0d12b7a06a98edd311f99d662ad8681bfc3b43ce7b8983980061ec170a1371bc422", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa2fdb026f3b96ce072b3bbdfc698cdba9fd33da6c37014a662aa38fb1b415613b71a99cbb3e2ad68d5d39fce70e198ace9f725dc25e115248dccde7bdfe70d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3382aa81dbbcd4ff8664cb667dc0981cb1cbdd99b0547edaf17c174d3298dbda8d4d350eb219b68abf0fc1242e458dbfaebc2f946469951e32786d1422a511fa"} build_url:https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/C9-9u3h3RbO9FltpLrrAhw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/765b23033eaadfc41670ceeba8c4ada5098fd9becda17242cbebf2bb3315e0d12b7a06a98edd311f99d662ad8681bfc3b43ce7b8983980061ec170a1371bc422", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa2fdb026f3b96ce072b3bbdfc698cdba9fd33da6c37014a662aa38fb1b415613b71a99cbb3e2ad68d5d39fce70e198ace9f725dc25e115248dccde7bdfe70d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3382aa81dbbcd4ff8664cb667dc0981cb1cbdd99b0547edaf17c174d3298dbda8d4d350eb219b68abf0fc1242e458dbfaebc2f946469951e32786d1422a511fa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 08:31:12.987281) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:31:12.987880) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1463584232.234158-1865645417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 08:31:13.071371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 08:31:13.071729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 08:31:13.072025) ========= ========= Total master_lag: 0.19 =========