builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1461780964.5 results: success (0) buildid: 20160427083245 builduid: 1d1707dbb3e843a893307cb4d9081e61 revision: 15655faddab77636800a0951579b5ac3a292d09d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.501738) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.502274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.502785) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.516763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.517248) ========= 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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-27 11:16:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-04-27 11:16:04 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107626 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.639735) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.640394) ========= 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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016474 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.693302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:04.694102) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 15655faddab77636800a0951579b5ac3a292d09d --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 15655faddab77636800a0951579b5ac3a292d09d --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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-27 11:16:05,076 truncating revision to first 12 chars 2016-04-27 11:16:05,076 Setting DEBUG logging. 2016-04-27 11:16:05,076 attempt 1/10 2016-04-27 11:16:05,076 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/15655faddab7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 11:16:05,320 unpacking tar archive at: fx-team-15655faddab7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.539727 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:05.247923) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:05.248294) ========= script_repo_revision: 15655faddab77636800a0951579b5ac3a292d09d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:05.248769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:05.249076) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 11:16:05.257585) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-04-27 11:16:05.257904) ========= /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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:16:05 INFO - MultiFileLogger online at 20160427 11:16:05 in /builds/slave/test 11:16:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:16:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:16:05 INFO - {'append_to_log': False, 11:16:05 INFO - 'base_work_dir': '/builds/slave/test', 11:16:05 INFO - 'blob_upload_branch': 'Fx-Team', 11:16:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:16:05 INFO - 'branch': 'Fx-Team', 11:16:05 INFO - 'buildbot_json_path': 'buildprops.json', 11:16:05 INFO - 'config_files': ('talos/linux_config.py',), 11:16:05 INFO - 'default_actions': ('clobber', 11:16:05 INFO - 'read-buildbot-config', 11:16:05 INFO - 'download-and-extract', 11:16:05 INFO - 'populate-webroot', 11:16:05 INFO - 'create-virtualenv', 11:16:05 INFO - 'install', 11:16:05 INFO - 'run-tests'), 11:16:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:16:05 INFO - 'download_minidump_stackwalk': True, 11:16:05 INFO - 'download_symbols': 'ondemand', 11:16:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:16:05 INFO - 'tooltool.py': '/tools/tooltool.py', 11:16:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:16:05 INFO - '/tools/misc-python/virtualenv.py')}, 11:16:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:16:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:16:05 INFO - 'installer_path': 'installer.exe', 11:16:05 INFO - 'log_level': 'info', 11:16:05 INFO - 'log_name': 'talos', 11:16:05 INFO - 'log_to_console': True, 11:16:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:16:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:16:05 INFO - 'opt_config_files': (), 11:16:05 INFO - 'pip_index': False, 11:16:05 INFO - 'sps_profile': False, 11:16:05 INFO - 'sps_profile_interval': 0, 11:16:05 INFO - 'suite': 'dromaeojs', 11:16:05 INFO - 'system_bits': '32', 11:16:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:16:05 INFO - 'title': 'talos-linux64-ix-009', 11:16:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:16:05 INFO - 'use_talos_json': True, 11:16:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:16:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:16:05 INFO - 'work_dir': 'build'} 11:16:05 INFO - ##### 11:16:05 INFO - ##### Running clobber step. 11:16:05 INFO - ##### 11:16:05 INFO - Running pre-action listener: _resource_record_pre_action 11:16:05 INFO - Running main action method: clobber 11:16:05 INFO - rmtree: /builds/slave/test/build 11:16:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:16:06 INFO - Running post-action listener: _resource_record_post_action 11:16:06 INFO - ##### 11:16:06 INFO - ##### Running read-buildbot-config step. 11:16:06 INFO - ##### 11:16:06 INFO - Running pre-action listener: _resource_record_pre_action 11:16:06 INFO - Running main action method: read_buildbot_config 11:16:06 INFO - Using buildbot properties: 11:16:06 INFO - { 11:16:06 INFO - "project": "", 11:16:06 INFO - "product": "firefox", 11:16:06 INFO - "script_repo_revision": "production", 11:16:06 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 11:16:06 INFO - "repository": "", 11:16:06 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 11:16:06 INFO - "buildid": "20160427083245", 11:16:06 INFO - "pgo_build": "True", 11:16:06 INFO - "basedir": "/builds/slave/test", 11:16:06 INFO - "buildnumber": 1116, 11:16:06 INFO - "slavename": "talos-linux64-ix-009", 11:16:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:16:06 INFO - "platform": "ubuntu64_hw", 11:16:06 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 11:16:06 INFO - "branch": "fx-team", 11:16:06 INFO - "repo_path": "integration/fx-team", 11:16:06 INFO - "revision": "15655faddab77636800a0951579b5ac3a292d09d", 11:16:06 INFO - "stage_platform": "linux64-pgo", 11:16:06 INFO - "builduid": "1d1707dbb3e843a893307cb4d9081e61", 11:16:06 INFO - "slavebuilddir": "test" 11:16:06 INFO - } 11:16:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 11:16:06 INFO - Running post-action listener: _resource_record_post_action 11:16:06 INFO - ##### 11:16:06 INFO - ##### Running download-and-extract step. 11:16:06 INFO - ##### 11:16:06 INFO - Running pre-action listener: _resource_record_pre_action 11:16:06 INFO - Running main action method: download_and_extract 11:16:06 INFO - mkdir: /builds/slave/test/build/tests 11:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:06 INFO - https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:16:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:16:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:16:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:16:06 INFO - Downloaded 1361 bytes. 11:16:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:16:06 INFO - Using the following test package requirements: 11:16:06 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:16:06 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:16:06 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:16:06 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'jsshell-linux-x86_64.zip'], 11:16:06 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:16:06 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:16:06 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:16:06 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:16:06 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:16:06 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:16:06 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:16:06 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:06 INFO - https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:16:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:16:08 INFO - Downloaded 23867116 bytes. 11:16:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:16:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:16:09 INFO - Return code: 0 11:16:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:09 INFO - https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:16:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:16:10 INFO - Downloaded 23867116 bytes. 11:16:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:16:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:16:11 INFO - Return code: 0 11:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:11 INFO - https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:16:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:16:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:16:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:16:12 INFO - Downloaded 10840145 bytes. 11:16:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:16:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:16:13 INFO - Return code: 0 11:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:13 INFO - https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:16:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:16:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:16:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:16:14 INFO - Downloaded 58389820 bytes. 11:16:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:16:14 INFO - mkdir: /builds/slave/test/properties 11:16:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:16:14 INFO - Writing to file /builds/slave/test/properties/build_url 11:16:14 INFO - Contents: 11:16:14 INFO - build_url:https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:16:15 INFO - Running post-action listener: _resource_record_post_action 11:16:15 INFO - Running post-action listener: set_extra_try_arguments 11:16:15 INFO - ##### 11:16:15 INFO - ##### Running populate-webroot step. 11:16:15 INFO - ##### 11:16:15 INFO - Running pre-action listener: _resource_record_pre_action 11:16:15 INFO - Running main action method: populate_webroot 11:16:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:16:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:16:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:16:15 INFO - '--tppagecycles', 11:16:15 INFO - '7'], 11:16:15 INFO - 'tests': ['tsvgm']}}, 11:16:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:16:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:16:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:16:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:16:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['damp', 'tps']}, 11:16:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'tests': ['damp', 'tps']}, 11:16:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['dromaeo_dom']}, 11:16:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:16:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'other-e10s': {'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['a11yr', 11:16:15 INFO - 'ts_paint', 11:16:15 INFO - 'tpaint', 11:16:15 INFO - 'sessionrestore', 11:16:15 INFO - 'sessionrestore_no_auto_restore', 11:16:15 INFO - 'tabpaint']}, 11:16:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['tsvgx', 11:16:15 INFO - 'tsvgr_opacity', 11:16:15 INFO - 'tart', 11:16:15 INFO - 'tscrollx', 11:16:15 INFO - 'cart']}, 11:16:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:16:15 INFO - 'tsvgr_opacity', 11:16:15 INFO - 'tart', 11:16:15 INFO - 'tscrollx', 11:16:15 INFO - 'cart']}, 11:16:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'talos_options': ['--disable-e10s'], 11:16:15 INFO - 'tests': ['tp5o']}, 11:16:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'tests': ['tp5o']}, 11:16:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'talos_options': ['--disable-e10s', 11:16:15 INFO - '--xperf_path', 11:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:15 INFO - 'tests': ['tp5n']}, 11:16:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:15 INFO - 'talos_options': ['--xperf_path', 11:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:15 INFO - 'tests': ['tp5n']}}, 11:16:15 INFO - 'talos.zip': {'path': '', 11:16:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:16:15 INFO - Running post-action listener: _resource_record_post_action 11:16:15 INFO - ##### 11:16:15 INFO - ##### Running create-virtualenv step. 11:16:15 INFO - ##### 11:16:15 INFO - Running pre-action listener: _resource_record_pre_action 11:16:15 INFO - Running main action method: create_virtualenv 11:16:15 INFO - Creating virtualenv /builds/slave/test/build/venv 11:16:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:16:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:16:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:16:15 INFO - Using real prefix '/usr' 11:16:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:16:16 INFO - Installing distribute.............................................................................................................................................................................................done. 11:16:18 INFO - Installing pip.................done. 11:16:18 INFO - Return code: 0 11:16:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:18 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:16:18 INFO - Using env: {'DISPLAY': ':0', 11:16:18 INFO - 'HOME': '/home/cltbld', 11:16:18 INFO - 'LANG': 'en_US.UTF-8', 11:16:18 INFO - 'LANGUAGE': 'en_US:en', 11:16:18 INFO - 'LOGNAME': 'cltbld', 11:16:18 INFO - 'MAIL': '/var/mail/cltbld', 11:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:18 INFO - 'MOZ_NO_REMOTE': '1', 11:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:18 INFO - 'NO_EM_RESTART': '1', 11:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:18 INFO - 'PWD': '/builds/slave/test', 11:16:18 INFO - 'SHELL': '/bin/bash', 11:16:18 INFO - 'SHLVL': '1', 11:16:18 INFO - 'TERM': 'linux', 11:16:18 INFO - 'TMOUT': '86400', 11:16:18 INFO - 'USER': 'cltbld', 11:16:18 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:18 INFO - '_': '/tools/buildbot/bin/python'} 11:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:16:18 INFO - Cleaning up... 11:16:18 INFO - Return code: 0 11:16:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:16:18 INFO - Using env: {'DISPLAY': ':0', 11:16:18 INFO - 'HOME': '/home/cltbld', 11:16:18 INFO - 'LANG': 'en_US.UTF-8', 11:16:18 INFO - 'LANGUAGE': 'en_US:en', 11:16:18 INFO - 'LOGNAME': 'cltbld', 11:16:18 INFO - 'MAIL': '/var/mail/cltbld', 11:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:18 INFO - 'MOZ_NO_REMOTE': '1', 11:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:18 INFO - 'NO_EM_RESTART': '1', 11:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:18 INFO - 'PWD': '/builds/slave/test', 11:16:18 INFO - 'SHELL': '/bin/bash', 11:16:18 INFO - 'SHLVL': '1', 11:16:18 INFO - 'TERM': 'linux', 11:16:18 INFO - 'TMOUT': '86400', 11:16:18 INFO - 'USER': 'cltbld', 11:16:18 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:18 INFO - '_': '/tools/buildbot/bin/python'} 11:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:18 INFO - Downloading/unpacking psutil>=0.7.1 11:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:16:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:20 INFO - Installing collected packages: psutil 11:16:20 INFO - Running setup.py install for psutil 11:16:20 INFO - building 'psutil._psutil_linux' extension 11:16:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:16:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:16:21 INFO - building 'psutil._psutil_posix' extension 11:16:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:16:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:16:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:21 INFO - Successfully installed psutil 11:16:21 INFO - Cleaning up... 11:16:21 INFO - Return code: 0 11:16:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 11:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 11:16:21 INFO - Using env: {'DISPLAY': ':0', 11:16:21 INFO - 'HOME': '/home/cltbld', 11:16:21 INFO - 'LANG': 'en_US.UTF-8', 11:16:21 INFO - 'LANGUAGE': 'en_US:en', 11:16:21 INFO - 'LOGNAME': 'cltbld', 11:16:21 INFO - 'MAIL': '/var/mail/cltbld', 11:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:21 INFO - 'MOZ_NO_REMOTE': '1', 11:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:21 INFO - 'NO_EM_RESTART': '1', 11:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:21 INFO - 'PWD': '/builds/slave/test', 11:16:21 INFO - 'SHELL': '/bin/bash', 11:16:21 INFO - 'SHLVL': '1', 11:16:21 INFO - 'TERM': 'linux', 11:16:21 INFO - 'TMOUT': '86400', 11:16:21 INFO - 'USER': 'cltbld', 11:16:21 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:21 INFO - '_': '/tools/buildbot/bin/python'} 11:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:16:23 INFO - Installing collected packages: mozsystemmonitor 11:16:23 INFO - Running setup.py install for mozsystemmonitor 11:16:23 INFO - Successfully installed mozsystemmonitor 11:16:23 INFO - Cleaning up... 11:16:23 INFO - Return code: 0 11:16:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:16:23 INFO - Using env: {'DISPLAY': ':0', 11:16:23 INFO - 'HOME': '/home/cltbld', 11:16:23 INFO - 'LANG': 'en_US.UTF-8', 11:16:23 INFO - 'LANGUAGE': 'en_US:en', 11:16:23 INFO - 'LOGNAME': 'cltbld', 11:16:23 INFO - 'MAIL': '/var/mail/cltbld', 11:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:23 INFO - 'MOZ_NO_REMOTE': '1', 11:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:23 INFO - 'NO_EM_RESTART': '1', 11:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:23 INFO - 'PWD': '/builds/slave/test', 11:16:23 INFO - 'SHELL': '/bin/bash', 11:16:23 INFO - 'SHLVL': '1', 11:16:23 INFO - 'TERM': 'linux', 11:16:23 INFO - 'TMOUT': '86400', 11:16:23 INFO - 'USER': 'cltbld', 11:16:23 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:23 INFO - '_': '/tools/buildbot/bin/python'} 11:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:23 INFO - Downloading/unpacking blobuploader==1.2.4 11:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:25 INFO - Downloading blobuploader-1.2.4.tar.gz 11:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:16:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:16:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:26 INFO - Downloading docopt-0.6.1.tar.gz 11:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:16:26 INFO - Installing collected packages: blobuploader, requests, docopt 11:16:26 INFO - Running setup.py install for blobuploader 11:16:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:16:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:16:26 INFO - Running setup.py install for requests 11:16:27 INFO - Running setup.py install for docopt 11:16:27 INFO - Successfully installed blobuploader requests docopt 11:16:27 INFO - Cleaning up... 11:16:27 INFO - Return code: 0 11:16:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:27 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:16:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:16:27 INFO - Using env: {'DISPLAY': ':0', 11:16:27 INFO - 'HOME': '/home/cltbld', 11:16:27 INFO - 'LANG': 'en_US.UTF-8', 11:16:27 INFO - 'LANGUAGE': 'en_US:en', 11:16:27 INFO - 'LOGNAME': 'cltbld', 11:16:27 INFO - 'MAIL': '/var/mail/cltbld', 11:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:27 INFO - 'MOZ_NO_REMOTE': '1', 11:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:27 INFO - 'NO_EM_RESTART': '1', 11:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:27 INFO - 'PWD': '/builds/slave/test', 11:16:27 INFO - 'SHELL': '/bin/bash', 11:16:27 INFO - 'SHLVL': '1', 11:16:27 INFO - 'TERM': 'linux', 11:16:27 INFO - 'TMOUT': '86400', 11:16:27 INFO - 'USER': 'cltbld', 11:16:27 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:27 INFO - '_': '/tools/buildbot/bin/python'} 11:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:27 INFO - Running setup.py (path:/tmp/pip-5eW8wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:27 INFO - Running setup.py (path:/tmp/pip-y9ifxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:27 INFO - Running setup.py (path:/tmp/pip-ztqz8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:27 INFO - Running setup.py (path:/tmp/pip-44RWHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:27 INFO - Running setup.py (path:/tmp/pip-whWDAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:27 INFO - Running setup.py (path:/tmp/pip-F0cHNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:27 INFO - Running setup.py (path:/tmp/pip-Whrzc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:27 INFO - Running setup.py (path:/tmp/pip-qaS8Xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:28 INFO - Running setup.py (path:/tmp/pip-Kj6d3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:28 INFO - Running setup.py (path:/tmp/pip-JsA6Ua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:28 INFO - Running setup.py (path:/tmp/pip-hgB61y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:28 INFO - Running setup.py (path:/tmp/pip-axOYPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:28 INFO - Running setup.py (path:/tmp/pip-J9IBHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:28 INFO - Running setup.py (path:/tmp/pip-MkxIZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:28 INFO - Running setup.py (path:/tmp/pip-o3ekPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:28 INFO - Running setup.py (path:/tmp/pip-l9iu_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:28 INFO - Running setup.py (path:/tmp/pip-29kmTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:16:28 INFO - Running setup.py install for manifestparser 11:16:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:16:28 INFO - Running setup.py install for mozcrash 11:16:28 INFO - Running setup.py install for mozdebug 11:16:28 INFO - Running setup.py install for mozdevice 11:16:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozfile 11:16:29 INFO - Running setup.py install for mozhttpd 11:16:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozinfo 11:16:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozInstall 11:16:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozleak 11:16:29 INFO - Running setup.py install for mozlog 11:16:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for moznetwork 11:16:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozprocess 11:16:29 INFO - Running setup.py install for mozprofile 11:16:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozrunner 11:16:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozscreenshot 11:16:30 INFO - Running setup.py install for moztest 11:16:30 INFO - Running setup.py install for mozversion 11:16:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:16:30 INFO - Cleaning up... 11:16:30 INFO - Return code: 0 11:16:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:30 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:16:30 INFO - Using env: {'DISPLAY': ':0', 11:16:30 INFO - 'HOME': '/home/cltbld', 11:16:30 INFO - 'LANG': 'en_US.UTF-8', 11:16:30 INFO - 'LANGUAGE': 'en_US:en', 11:16:30 INFO - 'LOGNAME': 'cltbld', 11:16:30 INFO - 'MAIL': '/var/mail/cltbld', 11:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:30 INFO - 'MOZ_NO_REMOTE': '1', 11:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:30 INFO - 'NO_EM_RESTART': '1', 11:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:30 INFO - 'PWD': '/builds/slave/test', 11:16:30 INFO - 'SHELL': '/bin/bash', 11:16:30 INFO - 'SHLVL': '1', 11:16:30 INFO - 'TERM': 'linux', 11:16:30 INFO - 'TMOUT': '86400', 11:16:30 INFO - 'USER': 'cltbld', 11:16:30 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:30 INFO - '_': '/tools/buildbot/bin/python'} 11:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:30 INFO - Running setup.py (path:/tmp/pip-qJdMI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:30 INFO - Running setup.py (path:/tmp/pip-Rh2JOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:30 INFO - Running setup.py (path:/tmp/pip-ojrKcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:30 INFO - Running setup.py (path:/tmp/pip-iwQGMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:30 INFO - Running setup.py (path:/tmp/pip-PZk6k0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:31 INFO - Running setup.py (path:/tmp/pip-W_h_G_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:31 INFO - Running setup.py (path:/tmp/pip-E1bghK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:31 INFO - Running setup.py (path:/tmp/pip-Gh7LyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:31 INFO - Running setup.py (path:/tmp/pip-smF0EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:31 INFO - Running setup.py (path:/tmp/pip-Rkv9Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:31 INFO - Running setup.py (path:/tmp/pip-L4ndWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:31 INFO - Running setup.py (path:/tmp/pip-SUYEQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:31 INFO - Running setup.py (path:/tmp/pip-nZUn8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:31 INFO - Running setup.py (path:/tmp/pip-IxtZry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:31 INFO - Running setup.py (path:/tmp/pip-Uc4ktz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:31 INFO - Running setup.py (path:/tmp/pip-8s07sE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:31 INFO - Running setup.py (path:/tmp/pip-WxAb9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:16:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:33 INFO - Downloading blessings-1.6.tar.gz 11:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:16:34 INFO - Installing collected packages: blessings 11:16:34 INFO - Running setup.py install for blessings 11:16:34 INFO - Successfully installed blessings 11:16:34 INFO - Cleaning up... 11:16:34 INFO - Return code: 0 11:16:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:34 INFO - Reading from file tmpfile_stdout 11:16:34 INFO - Current package versions: 11:16:34 INFO - argparse == 1.2.1 11:16:34 INFO - blessings == 1.6 11:16:34 INFO - blobuploader == 1.2.4 11:16:34 INFO - docopt == 0.6.1 11:16:34 INFO - manifestparser == 1.1 11:16:34 INFO - mozInstall == 1.12 11:16:34 INFO - mozcrash == 0.17 11:16:34 INFO - mozdebug == 0.1 11:16:34 INFO - mozdevice == 0.48 11:16:34 INFO - mozfile == 1.2 11:16:34 INFO - mozhttpd == 0.7 11:16:34 INFO - mozinfo == 0.9 11:16:34 INFO - mozleak == 0.1 11:16:34 INFO - mozlog == 3.1 11:16:34 INFO - moznetwork == 0.27 11:16:34 INFO - mozprocess == 0.22 11:16:34 INFO - mozprofile == 0.28 11:16:34 INFO - mozrunner == 6.11 11:16:34 INFO - mozscreenshot == 0.1 11:16:34 INFO - mozsystemmonitor == 0.0 11:16:34 INFO - moztest == 0.7 11:16:34 INFO - mozversion == 1.4 11:16:34 INFO - psutil == 3.1.1 11:16:34 INFO - requests == 1.2.3 11:16:34 INFO - wsgiref == 0.1.2 11:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:34 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:16:34 INFO - Using env: {'DISPLAY': ':0', 11:16:34 INFO - 'HOME': '/home/cltbld', 11:16:34 INFO - 'LANG': 'en_US.UTF-8', 11:16:34 INFO - 'LANGUAGE': 'en_US:en', 11:16:34 INFO - 'LOGNAME': 'cltbld', 11:16:34 INFO - 'MAIL': '/var/mail/cltbld', 11:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:34 INFO - 'MOZ_NO_REMOTE': '1', 11:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:34 INFO - 'NO_EM_RESTART': '1', 11:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:34 INFO - 'PWD': '/builds/slave/test', 11:16:34 INFO - 'SHELL': '/bin/bash', 11:16:34 INFO - 'SHLVL': '1', 11:16:34 INFO - 'TERM': 'linux', 11:16:34 INFO - 'TMOUT': '86400', 11:16:34 INFO - 'USER': 'cltbld', 11:16:34 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:34 INFO - '_': '/tools/buildbot/bin/python'} 11:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:16:34 INFO - Cleaning up... 11:16:34 INFO - Return code: 0 11:16:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:34 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:16:34 INFO - Using env: {'DISPLAY': ':0', 11:16:34 INFO - 'HOME': '/home/cltbld', 11:16:34 INFO - 'LANG': 'en_US.UTF-8', 11:16:34 INFO - 'LANGUAGE': 'en_US:en', 11:16:34 INFO - 'LOGNAME': 'cltbld', 11:16:34 INFO - 'MAIL': '/var/mail/cltbld', 11:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:34 INFO - 'MOZ_NO_REMOTE': '1', 11:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:34 INFO - 'NO_EM_RESTART': '1', 11:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:34 INFO - 'PWD': '/builds/slave/test', 11:16:34 INFO - 'SHELL': '/bin/bash', 11:16:34 INFO - 'SHLVL': '1', 11:16:34 INFO - 'TERM': 'linux', 11:16:34 INFO - 'TMOUT': '86400', 11:16:34 INFO - 'USER': 'cltbld', 11:16:34 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:34 INFO - '_': '/tools/buildbot/bin/python'} 11:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:34 INFO - Downloading/unpacking jsonschema 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:16:36 INFO - Downloading/unpacking functools32 (from jsonschema) 11:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:36 INFO - Downloading functools32-3.2.3-2.tar.gz 11:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:16:37 INFO - warning: no files found matching '*.txt' 11:16:37 INFO - no previously-included directories found matching 'build' 11:16:37 INFO - no previously-included directories found matching 'dist' 11:16:37 INFO - no previously-included directories found matching '.git*' 11:16:37 INFO - Installing collected packages: jsonschema, functools32 11:16:37 INFO - Running setup.py install for functools32 11:16:37 INFO - warning: no files found matching '*.txt' 11:16:37 INFO - no previously-included directories found matching 'build' 11:16:37 INFO - no previously-included directories found matching 'dist' 11:16:37 INFO - no previously-included directories found matching '.git*' 11:16:37 INFO - Successfully installed jsonschema functools32 11:16:37 INFO - Cleaning up... 11:16:37 INFO - Return code: 0 11:16:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 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 0x7f5e4a468f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28419a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, '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': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:16:37 INFO - Using env: {'DISPLAY': ':0', 11:16:37 INFO - 'HOME': '/home/cltbld', 11:16:37 INFO - 'LANG': 'en_US.UTF-8', 11:16:37 INFO - 'LANGUAGE': 'en_US:en', 11:16:37 INFO - 'LOGNAME': 'cltbld', 11:16:37 INFO - 'MAIL': '/var/mail/cltbld', 11:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:37 INFO - 'MOZ_NO_REMOTE': '1', 11:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:37 INFO - 'NO_EM_RESTART': '1', 11:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:37 INFO - 'PWD': '/builds/slave/test', 11:16:37 INFO - 'SHELL': '/bin/bash', 11:16:37 INFO - 'SHLVL': '1', 11:16:37 INFO - 'TERM': 'linux', 11:16:37 INFO - 'TMOUT': '86400', 11:16:37 INFO - 'USER': 'cltbld', 11:16:37 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:37 INFO - '_': '/tools/buildbot/bin/python'} 11:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:37 INFO - Downloading/unpacking flake8 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:16:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:16:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:16:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:40 INFO - no previously-included directories found matching 'docs/_build' 11:16:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:16:40 INFO - Running setup.py install for pep8 11:16:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:40 INFO - no previously-included directories found matching 'docs/_build' 11:16:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:16:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:16:40 INFO - Cleaning up... 11:16:40 INFO - Return code: 0 11:16:40 INFO - Running post-action listener: _resource_record_post_action 11:16:40 INFO - Running post-action listener: _start_resource_monitoring 11:16:40 INFO - Starting resource monitoring. 11:16:40 INFO - ##### 11:16:40 INFO - ##### Running install step. 11:16:40 INFO - ##### 11:16:40 INFO - Running pre-action listener: _resource_record_pre_action 11:16:40 INFO - Running main action method: install 11:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:40 INFO - Reading from file tmpfile_stdout 11:16:40 INFO - Detecting whether we're running mozinstall >=1.0... 11:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:16:40 INFO - Reading from file tmpfile_stdout 11:16:40 INFO - Output received: 11:16:40 INFO - Usage: mozinstall [options] installer 11:16:40 INFO - Options: 11:16:40 INFO - -h, --help show this help message and exit 11:16:40 INFO - -d DEST, --destination=DEST 11:16:40 INFO - Directory to install application into. [default: 11:16:40 INFO - "/builds/slave/test"] 11:16:40 INFO - --app=APP Application being installed. [default: firefox] 11:16:40 INFO - mkdir: /builds/slave/test/build/application 11:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:16:57 INFO - Reading from file tmpfile_stdout 11:16:57 INFO - Output received: 11:16:57 INFO - /builds/slave/test/build/application/firefox/firefox 11:16:57 INFO - Running post-action listener: _resource_record_post_action 11:16:57 INFO - ##### 11:16:57 INFO - ##### Running run-tests step. 11:16:57 INFO - ##### 11:16:57 INFO - Running pre-action listener: _resource_record_pre_action 11:16:57 INFO - Running main action method: run_tests 11:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:57 INFO - Python 2.7.3 11:16:57 INFO - Return code: 0 11:16:57 INFO - grabbing minidump binary from tooltool 11:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d0810>, '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 0x28d1710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d1ba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:16:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:16:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:57 INFO - Return code: 0 11:16:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:16:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:16:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:16:58 INFO - Return code: 0 11:16:58 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/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:16:58 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/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:16:58 INFO - Using env: {'DISPLAY': ':0', 11:16:58 INFO - 'HOME': '/home/cltbld', 11:16:58 INFO - 'LANG': 'en_US.UTF-8', 11:16:58 INFO - 'LANGUAGE': 'en_US:en', 11:16:58 INFO - 'LOGNAME': 'cltbld', 11:16:58 INFO - 'MAIL': '/var/mail/cltbld', 11:16:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:58 INFO - 'MOZ_NO_REMOTE': '1', 11:16:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:58 INFO - 'NO_EM_RESTART': '1', 11:16:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:58 INFO - 'PWD': '/builds/slave/test', 11:16:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:58 INFO - 'SHELL': '/bin/bash', 11:16:58 INFO - 'SHLVL': '1', 11:16:58 INFO - 'TERM': 'linux', 11:16:58 INFO - 'TMOUT': '86400', 11:16:58 INFO - 'USER': 'cltbld', 11:16:58 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883', 11:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:58 INFO - '_': '/tools/buildbot/bin/python'} 11:16:58 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/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:16:58 INFO - mozversion application_buildid: 20160427083245 11:16:58 INFO - mozversion application_changeset: 15655faddab77636800a0951579b5ac3a292d09d 11:16:58 INFO - mozversion application_display_name: Nightly 11:16:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:58 INFO - mozversion application_name: Firefox 11:16:58 INFO - mozversion application_remotingname: firefox 11:16:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:16:58 INFO - mozversion application_vendor: Mozilla 11:16:58 INFO - mozversion application_version: 49.0a1 11:16:58 INFO - mozversion platform_buildid: 20160427083245 11:16:58 INFO - mozversion platform_changeset: 15655faddab77636800a0951579b5ac3a292d09d 11:16:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:16:58 INFO - mozversion platform_version: 49.0a1 11:16:58 INFO - using testdate: 1461781018 11:16:58 INFO - actual date: 1461781018 11:16:58 INFO - starting webserver on 'localhost:34553' 11:16:58 INFO - SUITE-START | Running 2 tests 11:16:58 INFO - TEST-START | dromaeo_css 11:16:58 INFO - operating with platform_type : linux_ 11:16:58 INFO - Initialising browser for dromaeo_css test... 11:16:58 INFO - TEST-INFO | started process 6673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIwhCAX/profile http://localhost:34553/getInfo.html) 11:17:02 INFO - PROCESS | 6673 | __metrics Screen width/height:1600/1200 11:17:02 INFO - PROCESS | 6673 | colorDepth:24 11:17:02 INFO - PROCESS | 6673 | Browser inner width/height: 1024/697 11:17:02 INFO - PROCESS | 6673 | __metrics 11:17:03 ERROR - PROCESS | 6673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:17:04 INFO - TEST-INFO | 6673: exit 0 11:17:04 INFO - Browser initialized. 11:17:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:17:04 INFO - TEST-INFO | started process 6758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIwhCAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:17:16 INFO - PROCESS | 6758 | RSS: Main: 154546176 11:17:16 INFO - PROCESS | 6758 | 11:18:07 INFO - PROCESS | 6758 | 1461781087858 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:20:15 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34553/tests/dromaeo/cssquery-ext.html) 11:20:16 INFO - PROCESS | 6758 | RSS: Main: 249221120 11:20:16 INFO - PROCESS | 6758 | 11:23:18 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-ext.html (next: http://localhost:34553/tests/dromaeo/cssquery-jquery.html) 11:23:19 INFO - PROCESS | 6758 | RSS: Main: 238886912 11:23:19 INFO - PROCESS | 6758 | 11:26:20 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34553/tests/dromaeo/cssquery-mootools.html) 11:26:21 INFO - PROCESS | 6758 | RSS: Main: 236015616 11:26:21 INFO - PROCESS | 6758 | 11:29:23 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34553/tests/dromaeo/cssquery-prototype.html) 11:29:24 INFO - PROCESS | 6758 | RSS: Main: 231510016 11:29:24 INFO - PROCESS | 6758 | 11:32:26 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34553/tests/dromaeo/cssquery-yui.html) 11:32:27 INFO - PROCESS | 6758 | RSS: Main: 212422656 11:32:27 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | Cycle 1(1): loaded http://localhost:34553/tests/dromaeo/cssquery-yui.html (next: http://localhost:34553/tests/dromaeo/cssquery-dojo.html) 11:35:30 INFO - PROCESS | 6758 | RSS: Main: 265113600 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | __start_tp_report 11:35:30 INFO - PROCESS | 6758 | _x_x_mozilla_page_load 11:35:30 INFO - PROCESS | 6758 | _x_x_mozilla_page_load_details 11:35:30 INFO - PROCESS | 6758 | |i|pagename|runs| 11:35:30 INFO - PROCESS | 6758 | |0;dojo.html;3149.527570864371;3199.8240096794666;3235.7411407087434;3238.2713889374877;3271.4929185976166;5323.840284791462;5409.702466364349;5443.428440013801;5449.100898351777;5536.667799932002;5452.454754524555;5475.260839786628;5478.726063696819;5550.306211723535;5585.022621041322;4873.147199240137;4905.4849240829735;4928.162212423897;4937.950620493803;5080.237964305346;4988.102141614508;5001.8249361272365;5055.949440505603;5060.924086138711;5065.848024559251;4750.16872047392;4823.5176482351835;4831.05794370098;4875.829345972874;4904.558589726942;4924;4926.802927882881;4937.432195297551;4939.629527785394;4980.05378977993;4865.635077369211;4899.363082799213;4913.459519452857;4914.311996320518;4946.629002824802;5201.713905735183;5335.733213339317;5378.973105134489;5438.483344082306;5444.564434845203;5676;5690.032694441955;5700.344460387067;5701.059325211335;5703.3441154267375;5583.190437386556;5738.081906894919;5739.053056245714;5742.397048309909;5751.884962300773;5429.891402171974;5455.536279416255;5493.560515158778;5507.421720719307;5508.889822203613;5885.382034886361;5886.411358864165;5888.646681199141;5890.705464726747;5891.322497912709;5523.475269849358;5674.1488776683;5676.2337084493065;5680.573956953245;5685.317761868602;5767.077267637158;5771.59598828078;5772.538196944234;5792.536597072224;5816.069428891357;5733.168690539848;5743.62666426681;5747.8563035924435;5748.885022299614;5752.539796816244;5547.55619550435;5699.059655156935;5741.799037033684;5744.770209191669;5785.681787501728;4338.566143385699;4349.826006959749;4358.411614432011;4384.232759267115;4399.988002759319;3895.454636350913;3914.64768170866;3918.0792513759243;3947.111899822517;3947.210557888434;5294.788208471618;5472.589555783332;5476.288082549243;5483;5583.302087239095;5758.913616295759;5778.566607504454;5783.65298082108;5796.88406231873;5814.912776308359;5476.3702174250475;5485.753141108659;5488.039593071277;5488.1493368527945;5490.203920431515;4695.248760198352;4783.402074740658;4837.9032419351415;4858.8785280367565;4884.3650325458175;6001.459868611846;6013.458788709037;6041.818745437644;6045.637261764308;6046.183765191643;5837.708114594338;5870.970645146747;5872.647641141545;5872.823815285548;5874.970625146847;5883.55873309512;5947.791827286024;5972.58191926561;6023.698815059141;6035.818925432244;5551.9722401387735;5606.6636001840025;5607.186957891165;5611.298587676541;5628.493435590653;5537.086380747292;5716.42835716425;5729.026065568946;5730.140478928194;5738.598298119258;5576.0799468088935;5598.664080155204;5609.27079479665;5636.633618814764;5664.745086471201;5604.663720176803;5613.747381367766;5621.353544342453;5632.070708333243;5633.4366563344975;5443.7278136092245;5498.3127109111365;5534.695591742493;5549.556035517229;5563.02647036775;5635.323761148525;5646.096624540054;5649.37856835756;5653.547716182778;5701.800436984686;5504.862378440571;5508.697021663686;5509.449055094618;5512.751926163252;5559.777608895599 11:35:30 INFO - PROCESS | 6758 | |1;ext.html;599.3167788720856;643.2955913274965;643.2988043033093;645.3772109913932;664.8836453620618;28433;29035.27411814703;29078.709212907863;29082.41835163298;36009.27981440369;37571;38172.85481435561;38185.42721859174;38240.80879595598;38278;8017.438779285452;8320.625571849281;8321.66713331466;8322.958385208065;8323.417360784748;8123.796905077391;8190.959045204795;8267.793305167359;8272.17278272174;8273.669053237863;8250.051244106944;8532.402731808776;8538.103499132594;8541.017782954978;8550.572471376438;8428.957855210747;8519.48883067018;8522.87215691762;8527.488350698946;8528.232459078712;8400.41197116202;8512.404131710782;8520;8521.10528394522;8523.06246312905;37137.44293835595;37601.435978460344;37623.247535049144;37640;37655;3467.809270490122;3490.528778614905;3591.3356029134693;3787.16682329887;3977.5823538528616;28663.57004644932;28973.42053158946;29087.800341962367;29110.41779164426;29814.254643633867;3684.4657524658774;3684.9447258291148;3709.629037096269;3808.2383523295453;3835.8657446989223;234541;236118;236690;237000;237557.81221093956;51156.23265651018;52303.73848130735;52365.73817130966;52374.21438678423;52401;55016.44983550193;55060.44939550553;55090.72454637701;55111;55245.44754552403;15914.204289785695;15945.123018234108;16006.359745610045;16032.278547465392;16080.839191607934;12018.519259229533;12034.157608967289;12039.578614748441;12040.518379264731;12058.397080145958;14523.636909077357;15668.294926728096;15733.797268382436;15770.605734856721;15806.841931580768;5125.873217337421;5995.250593675791;6168.19813424252;6258.499320054476;6331.303556608769;1100.4662738571785;1100.5267734873921;1122.1023181454802;1137.4445346387665;1185.0815617896028;28155;28496.145115646563;28670.426591468055;28882.85558572236;29190.124296271148;1115.9051480624075;1165.7668466306773;1216.7444836584239;1222.2177806204036;1243.3410292544966;15340.309686064154;16112.75830862538;16124.27440765145;16344.264508097163;16378.935369200964;322610;325111;325976;326574;326720;50848;51031.87617485925;51121;51185;51215.23177152346;1265.544404014554;1277.5847849448967;1384.3908680180689;1410.4146779086768;1417.957461276184;27400.040998565753;28058.43883122367;30590.84704576463;31329.216769580944;31539.84230078835;43425;43575.9106022352;43621;43700.125997479234;43755;215518;216255;216520;225557;226543;35355.29289414146;37368.252634946606;37589;37757.62242377651;37783;42438;42831.1433771329;43234.56765432305;43790.34314485349;44626.3306050416;36628.81685591555;37056.814715926244;37072.81463592665;37165;37173.814130929175;36431.016520315316;36725.81637091797;36755.63244367629;37141.442878356305;37359.626403735616;37169.44245836369;37258.81370593238;37627.24745505129;38004.619953801215;38434.42348364721;32307;32846.34307313793;32856.83571582127;33422.665773341956;33432.49851252282;25740.742592573835;26160.215193544223;26253.474930501667;26579.867100664374;26816.865915670296 11:35:30 INFO - PROCESS | 6758 | |2;jquery.html;392.4113829256116;393.7952264822292;394.3434182086822;403.54601073791986;415.86068666996556;16853;17338.82661173388;17867.910660446694;17894.821051789477;17936;16767.748483772753;17127.57181070474;17623;18307.63384732308;18337;5497.367802702691;5535.114381698929;5641.492265696086;5649.802256921009;5650.830475085754;4783.880402989918;5391.973040134794;5556.611037227396;5620.971895140518;5623.100303951369;5431.674099554019;5541.445855414447;5548.833534993957;5550.555955523569;5570.8607284818;5443.374011988632;5548.611597188198;5585.74864131111;5606.55147588192;5611.046122159243;4896.46138924718;4968.279599458058;4992.900141997176;5004.599632029429;5022.648414610979;21008.159673613038;21222.363329099997;21430.785692143036;21651.783482165134;21728.34814955538;2342.0865862313713;2463.507298540281;2464.051340234023;2486.5026994601153;2583.7803786678155;13481.730365392736;13629.386677599532;13720.65698357549;13764.93117534416;14199.148051116967;1918.3765276285262;1939.4763413878175;2040.1737296394836;2079.5736873940805;2085.447356450542;65081;66582.66708666425;66946.66526667336;67037.65924681528;67876;9932.205423566098;10008.849867251996;10049.849252261221;10186.694399168036;10446.895531044593;14291.785623215661;14307.92846035763;14308.427662893368;14357.64105897361;14392.280385980868;7872.881906771403;8036.879446808302;8049.9597502013285;8225.917740822515;8252.587370631445;8125.4718443301;8138.877916831252;8146.511209327459;8290.751277461686;8299.211574900375;6379.393957574023;6544.214694236627;6560.606363618198;6563;6686.765963191362;1786.6783978883925;1856.916438760259;1858.3402891973155;1880.4586922582084;1929.6623090959026;882.580774132282;964.3490643815537;1088.7169335972703;1097.720081379243;1100.1583788401879;17845.910770446066;18291.81708182928;18705.81294187041;19098.618027639368;19289.8071019288;1019.0828254570946;1042.9739256518649;1053.6312290698347;1069.1233188785277;1069.1713921710736;10388.636397726195;10947.616833410792;11009.174311926638;11066;11150.665480035612;438409;439549;443277;484819;486964;46625.5337446628;58057;67480.66259668769;67969.3203067973;71621.6418917902;462.28576848768733;473.4933621025539;474.8456751555758;475.9190937540696;476.99761501192273;8250.917490825255;10302.690919272434;10415.895841041493;10572.154227661615;10757.731056723644;32979.186144762265;34761.304773904885;34850.82574587111;35187.64812351844;36682.266354672225;45351.31973020342;45806.770966144955;46935;47155.76422117867;48133;8021.197880211932;8078.030636323679;8235.258826705625;8338.66645334204;8423.536705481258;9456.952715236379;11219.71950701239;12189.451474683838;12607;12690.175138615961;6272.529560282906;8636;11290.362897379999;11337.489812958273;11373.14701397382;10304.536295866554;10983.780324393307;10987.560497580007;11124.555017799521;11182.944085279521;11008.834867476835;11022.889771102185;11211.103111750877;11329.093672506344;11420;8144.592770361577;8348.6243119061;8382.664693412195;8436.45163064399;8800.38397312182;11124.721881953015;11184.440777961232;11515.942420287845;11702.70743231426;11717 11:35:30 INFO - PROCESS | 6758 | |3;mootools.html;1375.7317323121995;1487.2415068315158;1491.7911492391063;1648.769172315876;1805.2327760701708;8078.192180781919;8420.410571260007;8431.241188293052;8431.62057707403;8441.662333506652;8183.631736571853;8352.91647083529;8377.664893404271;8380.161983801618;8385.790355241123;1926.7687877454655;1988.5128143604836;1991.1239054815908;2207.459172502739;2256.7291924968945;2223.110755697726;2293.54129174165;2295.357299956015;2295.5753185660624;2295.747467778543;2218.2901471529117;2226.065052677871;2236.5079682469914;2236.709227800384;2275.6358982562792;1940.5439721665398;2154.676798480225;2187.7593464718866;2201.7798220178015;2203.7796220377913;2250.932472025842;2261.129465155927;2268.534950335177;2268.7164104486938;2272.9545409091725;8309.667613295462;8451.323894088458;8478.406511544194;8480.279176270025;8480.872786908201;4827.372441582607;4969.105560999019;4971.502849715036;4979.153543897546;4983.850484485435;3011.111722041981;3128.1710346758127;3138.168385377877;3143.5599016137758;3147.6380216275193;966.7196513011219;976.5995941663955;984.4929861121528;1000.0949141027381;1019.1948360795037;11654.533818647244;11716.23844450108;11759.94120029403;11765.176437649454;11806;4394.055278115156;4410.095930334338;4430.579094985971;4515.367848501212;4556.65825063115;9822.852657210147;9827.213822894151;9828.21374290055;9840.409575425354;9846.704598862045;5085.2372144177925;5129.461406552333;5130.769115389817;5131.512506311894;5134.48655134491;4197.601227883346;4557.156925968707;4637.211674015425;4653.581177694024;4771.212749896228;3619.7104231661406;3672.540932383452;3680.8895733128047;3699.445083237482;3742.419924911643;1222.9938850305691;1236.9814452783216;1240.0451652227734;1242.993785031087;1249.419020155614;937.1425145991478;998.8751406074241;1012.1346248957183;1015.5125539740966;1015.7031082902698;5920.141579470953;6050.45545900871;6055.8486037848525;6113.602615829956;6265.373462653698;414.17785695394616;420.258244198989;425.1200015966919;428.2441490768796;429.26381256145237;3921.2353591049473;4011.5186177658284;4030.2946984277633;4084.9183016340103;4175.457190565267;10012.148967337858;10032.247581431422;10431.895681043245;10449.05958463742;10589.629362972259;10235.846462303072;10482.68551943443;10536.15710743154;10546.472676366151;10582.365058096539;7724.7682569523;7781.221877812173;7821.139674635786;7956.602169891482;7985.600719964094;5866.50134738552;6038.3357830637715;6129.233845769279;6162.075688646739;6173.69131543412;9664.420134791653;9664.661736839176;9913.157381622643;9990.400575965465;10019.799604007732;9830.966397655231;9891.653792117242;10217.489125543545;10384.065434110966;10396.064354208158;6886.896696549656;7343.045404097433;7504.287092726182;7584.203658615766;7621.12357078943;8250.504969701837;8320.459170153921;8368.330533557179;8382.203690649378;8404.87392689098;7344.449166312441;7362.116546014512;7464.738734144279;7509.774706758806;7591.848163036819;7157.284271572801;7287.489875708649;7326.047613810384;7353.669084891086;7480.625968701434;6681.665916704243;6707.698153583003;6749.966250168718;6752;6757.054012438361;4987.476314986874;5103.137354865522;5192.247124166899;5217.243499692448;5280.81517146898;5209.453007434165;5236.659617124875;5258.526732594085;5289.3652761668845;5362.0616392132 11:35:30 INFO - PROCESS | 6758 | |4;prototype.html;307.12622588735053;309.11746962422285;311.5202588014458;312.0638085742772;313.3951473655843;5201.713905735183;5263.210518422234;5266.7893284268675;5291.814786482474;5300.390455097665;5191.766370513326;5202.271681964528;5213.661112027716;5239.79040838366;5250.422453530108;3892.034522566107;3970.8808735737975;4072.063425412157;4092.0792821615196;4103.425520427142;4054.6148115929086;4064.9024763314037;4071.5391916870717;4093.815778289984;4095.1604920991126;3871.30290572504;4026.637602615764;4051.756894586334;4053.9391909121387;4058.776767277813;3748.4189950557707;3967.603239676024;3976.4234186043004;4060.573639767841;4080.5919408059253;3555.3422616815974;3581.7850928944345;3592.8023958682525;3598.9845686415692;3610.3031575159634;5054.292399064134;5054.646174767768;5074.31496747938;5080.79676812927;5101.362329708786;4562.634989200889;4577.748223847687;4596.666966579688;4617.145828021827;4671.976640116778;5358.33020872391;5384.865378365533;5397.595180361489;5401.621886467949;5454.400015998237;3901.3367727486398;3918.8236529356245;3936.6850651947775;3947.644711975936;3948.822302996372;5432.565394768409;5605.32736071674;5641.943580564224;5680.204771332017;5684.602077854552;3930.5479869815335;3933.370660694275;3943.664788492953;3964.4053391991433;3971.4439978403047;3994.720369574102;3994.960050399459;3997.5402828675074;4010.0776821331256;4027.2549578328103;4444.911101777946;4562.406887104655;4564.338170965191;4580.725156490621;4585.839495617637;5410.810621628224;5435.315052333789;5452.863678408072;5456.345238571317;5497.505224529811;1465.4431316099815;1475.9409623614936;1480.0897448069575;1480.363443719211;1490.2995592071925;934.6915517879083;974.8099120671401;997.1275134257525;999.9200063994863;1012.2863381316155;5215.843524694265;5261.631685781958;5325.920111198335;5343.171808369631;5349.1976203569775;5642.294713160855;5651.8304450866535;5714.5999780015;5741.282339707536;5747.827565173052;5114.28400023997;5208.192730126836;5300.628955973045;5373.731313434313;5400.351957765093;3195.3769015041844;3221.9689699296;3233.1270556949494;3236.64396916339;3249.8537565809597;6098.176746139215;6206.286277078104;6272.686365681698;6274.466670333073;6276;6189.783357582463;6194.163787888578;6212.502999760009;6215.192025036807;6216.906746398808;6220.657863817533;6233.688315584202;6257;6275.937240627718;6282.434580887742;5911.881762364643;6092.69536523181;6115.755369785159;6116.17431646722;6118.632882027093;5980.491658209101;5981.970090149522;5983.102534619842;5993.9700301498215;6026.216591843032;6014.458698717136;6230.532710046674;6245.500359971101;6251.187345645037;6254.843628909131;4172.183775951282;4200.936985945152;4204.411382406404;4211.326187809936;4220.472440944882;6037.068138195736;6038.3357830637715;6040.063790112539;6045.516358691382;6059.182010428712;5789;5877.853053673693;5882.23530940975;5937.940620593911;5944.940550594439;5844.327902291121;5863.120531920075;5880.705964701662;5882.558808089325;5890.087036509348;5187.351581052369;5792.275965504312;5814.622049566765;5821.893840170354;5845.064789633639;5087.92368114486;5405.864853378697;5440.29276194108;5493.395726470005;5496.900619875961;5402.378726446509;5432.945670543244;5448.1203984625245;5458.4814442627885;5480.424555421623 11:35:30 INFO - PROCESS | 6758 | |5;yui.html;745.5154149802631;791.9524828510289;801.4069588504507;816.5549775372418;851.795569063425;14320.21238831865;14426.78359824601;14583.052101613388;14619.780703289445;15109.471168509104;12392.81410778839;12447.253164810118;12501.624951251477;12511;12514.499420023189;680.4726337088746;788.3811208201535;817.7383237364046;821.1501096365263;824.6907409721355;822.4448497264352;823.6952327638752;824.3775949158377;825.6078362777693;825.6119623776815;713.2439614009166;861.7759382560516;863.6415887406719;863.9740807775777;864.2308345572445;835.1773503099472;863.8315528471951;863.8574635185187;863.8920134983096;864.416518849777;633.9904901426482;634.7207228819304;635.0823060677324;635.6345101566618;636.0427556527443;13594.184348939096;13678.589642310746;13724.039317247796;13752.31238438074;13772.380242889094;13959.65100872476;14085.436582536686;14236.217008064552;14273.357698903574;14314.284285785774;11325.943370283178;11327.943360283145;11367.147463940237;11500.25248358854;11632.534698612046;3806.3148633246005;3848.1534062506203;3921.0393453603765;3952.3281042222893;4024.9597504024878;51910.22134667983;52242;54097;54160.45839541594;54400.45599544033;10480.790384192274;10759.83860242097;10881.83677244842;11048.281861678965;11119.555217791198;18252;18353.7246941296;18398.448046558624;18434.723479147822;18444;7992.200779921961;8002.3598112150885;8066.395020373496;8540.231379175904;8547.871781923277;4390.100029493946;4498.752568608758;4577.3362862384765;4636.953630463652;4732.1718699227495;3150.385674793439;3208.598925134358;3222.2910959588853;3222.6132864056462;3225.193701574606;532.0282973156344;552.7457369609943;574.052812858778;577.4831525784399;627.9874402512015;691.4710246661308;711.619283683227;712.8467379513429;731.9487635865545;753.3822265742039;8252.711155109542;8459;8527.530985795716;8618.827623447622;8624.051354351017;684.1516519515864;732.5897497401472;736.5433431272645;746.0823187479323;755.8677231484468;4310.116426132698;4342.066455712037;4381.047381546135;4391.099824536026;4401.207782599163;54215.45784542183;54639.18041229385;57310.42689573134;64791;64879.67560162169;48384.27423588649;48942.75528622334;51197;51629.48370516247;51799.22301165485;46737.53262467332;46969.76515117471;47483;47948.28077578909;48123.27815082776;9221.49281789508;9675.564599592895;9777.266704997011;9913.5129730541;9929.602815887283;32783;32861.34277314488;33515.66484335125;33850.49224261687;33884.32231355408;39761;42719.786401067795;42763.35854962116;42883.35674964941;43276.13447731091;11492.94253528727;11718.062554995751;11763.647090587296;11782.882171178522;12346.259224446203;12396.876031239934;12443;12459.252444853339;12579.182353147016;13228.40472178735;11728.354940478355;11764.411779410824;11957.162998590014;11973.880261197277;13110.541131060367;12977.221366718028;13041.869581304445;13336.333183340988;13504.45982160742;13511.662208444868;12802.103852730592;13033.283169425393;13035.283059431822;13047.739045219232;13048;9986.900130998887;10163.390196588229;10303.587856485656;10305.227107966783;10343.27597068198;10235.692929212135;10368.688939331832;10383.117435017806;10436.530356134144;10476.842847357448 11:35:30 INFO - PROCESS | 6758 | __end_tp_report 11:35:30 INFO - PROCESS | 6758 | __start_cc_report 11:35:30 INFO - PROCESS | 6758 | _x_x_mozilla_cycle_collect,859 11:35:30 INFO - PROCESS | 6758 | __end_cc_report 11:35:30 INFO - PROCESS | 6758 | __startTimestamp1461782130269__endTimestamp 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | ------- Summary: start ------- 11:35:30 INFO - PROCESS | 6758 | Number of tests: 6 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#0] dojo.html Cycles:165 Average:5356.61 Median:5549.56 stddev:598.47 (10.8%) stddev-sans-first:574.71 11:35:30 INFO - PROCESS | 6758 | Values: 3149.5 3199.8 3235.7 3238.3 3271.5 5323.8 5409.7 5443.4 5449.1 5536.7 5452.5 5475.3 5478.7 5550.3 5585.0 4873.1 4905.5 4928.2 4938.0 5080.2 4988.1 5001.8 5055.9 5060.9 5065.8 4750.2 4823.5 4831.1 4875.8 4904.6 4924.0 4926.8 4937.4 4939.6 4980.1 4865.6 4899.4 4913.5 4914.3 4946.6 5201.7 5335.7 5379.0 5438.5 5444.6 5676.0 5690.0 5700.3 5701.1 5703.3 5583.2 5738.1 5739.1 5742.4 5751.9 5429.9 5455.5 5493.6 5507.4 5508.9 5885.4 5886.4 5888.6 5890.7 5891.3 5523.5 5674.1 5676.2 5680.6 5685.3 5767.1 5771.6 5772.5 5792.5 5816.1 5733.2 5743.6 5747.9 5748.9 5752.5 5547.6 5699.1 5741.8 5744.8 5785.7 4338.6 4349.8 4358.4 4384.2 4400.0 3895.5 3914.6 3918.1 3947.1 3947.2 5294.8 5472.6 5476.3 5483.0 5583.3 5758.9 5778.6 5783.7 5796.9 5814.9 5476.4 5485.8 5488.0 5488.1 5490.2 4695.2 4783.4 4837.9 4858.9 4884.4 6001.5 6013.5 6041.8 6045.6 6046.2 5837.7 5871.0 5872.6 5872.8 5875.0 5883.6 5947.8 5972.6 6023.7 6035.8 5552.0 5606.7 5607.2 5611.3 5628.5 5537.1 5716.4 5729.0 5730.1 5738.6 5576.1 5598.7 5609.3 5636.6 5664.7 5604.7 5613.7 5621.4 5632.1 5633.4 5443.7 5498.3 5534.7 5549.6 5563.0 5635.3 5646.1 5649.4 5653.5 5701.8 5504.9 5508.7 5509.4 5512.8 5559.8 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#1] ext.html Cycles:180 Average:43000.55 Median:37064.81 stddev:69069.51 (186.3%) stddev-sans-first:69189.90 11:35:30 INFO - PROCESS | 6758 | Values: 599.3 643.3 643.3 645.4 664.9 28433.0 29035.3 29078.7 29082.4 36009.3 37571.0 38172.9 38185.4 38240.8 38278.0 8017.4 8320.6 8321.7 8323.0 8323.4 8123.8 8191.0 8267.8 8272.2 8273.7 8250.1 8532.4 8538.1 8541.0 8550.6 8429.0 8519.5 8522.9 8527.5 8528.2 8400.4 8512.4 8520.0 8521.1 8523.1 37137.4 37601.4 37623.2 37640.0 37655.0 3467.8 3490.5 3591.3 3787.2 3977.6 28663.6 28973.4 29087.8 29110.4 29814.3 3684.5 3684.9 3709.6 3808.2 3835.9 234541.0 236118.0 236690.0 237000.0 237557.8 51156.2 52303.7 52365.7 52374.2 52401.0 55016.4 55060.4 55090.7 55111.0 55245.4 15914.2 15945.1 16006.4 16032.3 16080.8 12018.5 12034.2 12039.6 12040.5 12058.4 14523.6 15668.3 15733.8 15770.6 15806.8 5125.9 5995.3 6168.2 6258.5 6331.3 1100.5 1100.5 1122.1 1137.4 1185.1 28155.0 28496.1 28670.4 28882.9 29190.1 1115.9 1165.8 1216.7 1222.2 1243.3 15340.3 16112.8 16124.3 16344.3 16378.9 322610.0 325111.0 325976.0 326574.0 326720.0 50848.0 51031.9 51121.0 51185.0 51215.2 1265.5 1277.6 1384.4 1410.4 1418.0 27400.0 28058.4 30590.8 31329.2 31539.8 43425.0 43575.9 43621.0 43700.1 43755.0 215518.0 216255.0 216520.0 225557.0 226543.0 35355.3 37368.3 37589.0 37757.6 37783.0 42438.0 42831.1 43234.6 43790.3 44626.3 36628.8 37056.8 37072.8 37165.0 37173.8 36431.0 36725.8 36755.6 37141.4 37359.6 37169.4 37258.8 37627.2 38004.6 38434.4 32307.0 32846.3 32856.8 33422.7 33432.5 25740.7 26160.2 26253.5 26579.9 26816.9 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#2] jquery.html Cycles:180 Average:25957.90 Median:35019.24 stddev:75044.27 (214.3%) stddev-sans-first:75230.23 11:35:30 INFO - PROCESS | 6758 | Values: 392.4 393.8 394.3 403.5 415.9 16853.0 17338.8 17867.9 17894.8 17936.0 16767.7 17127.6 17623.0 18307.6 18337.0 5497.4 5535.1 5641.5 5649.8 5650.8 4783.9 5392.0 5556.6 5621.0 5623.1 5431.7 5541.4 5548.8 5550.6 5570.9 5443.4 5548.6 5585.7 5606.6 5611.0 4896.5 4968.3 4992.9 5004.6 5022.6 21008.2 21222.4 21430.8 21651.8 21728.3 2342.1 2463.5 2464.1 2486.5 2583.8 13481.7 13629.4 13720.7 13764.9 14199.1 1918.4 1939.5 2040.2 2079.6 2085.4 65081.0 66582.7 66946.7 67037.7 67876.0 9932.2 10008.8 10049.8 10186.7 10446.9 14291.8 14307.9 14308.4 14357.6 14392.3 7872.9 8036.9 8050.0 8225.9 8252.6 8125.5 8138.9 8146.5 8290.8 8299.2 6379.4 6544.2 6560.6 6563.0 6686.8 1786.7 1856.9 1858.3 1880.5 1929.7 882.6 964.3 1088.7 1097.7 1100.2 17845.9 18291.8 18705.8 19098.6 19289.8 1019.1 1043.0 1053.6 1069.1 1069.2 10388.6 10947.6 11009.2 11066.0 11150.7 438409.0 439549.0 443277.0 484819.0 486964.0 46625.5 58057.0 67480.7 67969.3 71621.6 462.3 473.5 474.8 475.9 477.0 8250.9 10302.7 10415.9 10572.2 10757.7 32979.2 34761.3 34850.8 35187.6 36682.3 45351.3 45806.8 46935.0 47155.8 48133.0 8021.2 8078.0 8235.3 8338.7 8423.5 9457.0 11219.7 12189.5 12607.0 12690.2 6272.5 8636.0 11290.4 11337.5 11373.1 10304.5 10983.8 10987.6 11124.6 11182.9 11008.8 11022.9 11211.1 11329.1 11420.0 8144.6 8348.6 8382.7 8436.5 8800.4 11124.7 11184.4 11515.9 11702.7 11717.0 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#3] mootools.html Cycles:180 Average:5591.62 Median:4970.30 stddev:3196.75 (64.3%) stddev-sans-first:3190.02 11:35:30 INFO - PROCESS | 6758 | Values: 1375.7 1487.2 1491.8 1648.8 1805.2 8078.2 8420.4 8431.2 8431.6 8441.7 8183.6 8352.9 8377.7 8380.2 8385.8 1926.8 1988.5 1991.1 2207.5 2256.7 2223.1 2293.5 2295.4 2295.6 2295.7 2218.3 2226.1 2236.5 2236.7 2275.6 1940.5 2154.7 2187.8 2201.8 2203.8 2250.9 2261.1 2268.5 2268.7 2273.0 8309.7 8451.3 8478.4 8480.3 8480.9 4827.4 4969.1 4971.5 4979.2 4983.9 3011.1 3128.2 3138.2 3143.6 3147.6 966.7 976.6 984.5 1000.1 1019.2 11654.5 11716.2 11759.9 11765.2 11806.0 4394.1 4410.1 4430.6 4515.4 4556.7 9822.9 9827.2 9828.2 9840.4 9846.7 5085.2 5129.5 5130.8 5131.5 5134.5 4197.6 4557.2 4637.2 4653.6 4771.2 3619.7 3672.5 3680.9 3699.4 3742.4 1223.0 1237.0 1240.0 1243.0 1249.4 937.1 998.9 1012.1 1015.5 1015.7 5920.1 6050.5 6055.8 6113.6 6265.4 414.2 420.3 425.1 428.2 429.3 3921.2 4011.5 4030.3 4084.9 4175.5 10012.1 10032.2 10431.9 10449.1 10589.6 10235.8 10482.7 10536.2 10546.5 10582.4 7724.8 7781.2 7821.1 7956.6 7985.6 5866.5 6038.3 6129.2 6162.1 6173.7 9664.4 9664.7 9913.2 9990.4 10019.8 9831.0 9891.7 10217.5 10384.1 10396.1 6886.9 7343.0 7504.3 7584.2 7621.1 8250.5 8320.5 8368.3 8382.2 8404.9 7344.4 7362.1 7464.7 7509.8 7591.8 7157.3 7287.5 7326.0 7353.7 7480.6 6681.7 6707.7 6750.0 6752.0 6757.1 4987.5 5103.1 5192.2 5217.2 5280.8 5209.5 5236.7 5258.5 5289.4 5362.1 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#4] prototype.html Cycles:180 Average:4735.44 Median:5296.10 stddev:1442.26 (27.2%) stddev-sans-first:1407.48 11:35:30 INFO - PROCESS | 6758 | Values: 307.1 309.1 311.5 312.1 313.4 5201.7 5263.2 5266.8 5291.8 5300.4 5191.8 5202.3 5213.7 5239.8 5250.4 3892.0 3970.9 4072.1 4092.1 4103.4 4054.6 4064.9 4071.5 4093.8 4095.2 3871.3 4026.6 4051.8 4053.9 4058.8 3748.4 3967.6 3976.4 4060.6 4080.6 3555.3 3581.8 3592.8 3599.0 3610.3 5054.3 5054.6 5074.3 5080.8 5101.4 4562.6 4577.7 4596.7 4617.1 4672.0 5358.3 5384.9 5397.6 5401.6 5454.4 3901.3 3918.8 3936.7 3947.6 3948.8 5432.6 5605.3 5641.9 5680.2 5684.6 3930.5 3933.4 3943.7 3964.4 3971.4 3994.7 3995.0 3997.5 4010.1 4027.3 4444.9 4562.4 4564.3 4580.7 4585.8 5410.8 5435.3 5452.9 5456.3 5497.5 1465.4 1475.9 1480.1 1480.4 1490.3 934.7 974.8 997.1 999.9 1012.3 5215.8 5261.6 5325.9 5343.2 5349.2 5642.3 5651.8 5714.6 5741.3 5747.8 5114.3 5208.2 5300.6 5373.7 5400.4 3195.4 3222.0 3233.1 3236.6 3249.9 6098.2 6206.3 6272.7 6274.5 6276.0 6189.8 6194.2 6212.5 6215.2 6216.9 6220.7 6233.7 6257.0 6275.9 6282.4 5911.9 6092.7 6115.8 6116.2 6118.6 5980.5 5982.0 5983.1 5994.0 6026.2 6014.5 6230.5 6245.5 6251.2 6254.8 4172.2 4200.9 4204.4 4211.3 4220.5 6037.1 6038.3 6040.1 6045.5 6059.2 5789.0 5877.9 5882.2 5937.9 5944.9 5844.3 5863.1 5880.7 5882.6 5890.1 5187.4 5792.3 5814.6 5821.9 5845.1 5087.9 5405.9 5440.3 5493.4 5496.9 5402.4 5432.9 5448.1 5458.5 5480.4 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - PROCESS | 6758 | [#5] yui.html Cycles:180 Average:14286.64 Median:4361.56 stddev:16181.12 (371.0%) stddev-sans-first:16194.56 11:35:30 INFO - PROCESS | 6758 | Values: 745.5 792.0 801.4 816.6 851.8 14320.2 14426.8 14583.1 14619.8 15109.5 12392.8 12447.3 12501.6 12511.0 12514.5 680.5 788.4 817.7 821.2 824.7 822.4 823.7 824.4 825.6 825.6 713.2 861.8 863.6 864.0 864.2 835.2 863.8 863.9 863.9 864.4 634.0 634.7 635.1 635.6 636.0 13594.2 13678.6 13724.0 13752.3 13772.4 13959.7 14085.4 14236.2 14273.4 14314.3 11325.9 11327.9 11367.1 11500.3 11632.5 3806.3 3848.2 3921.0 3952.3 4025.0 51910.2 52242.0 54097.0 54160.5 54400.5 10480.8 10759.8 10881.8 11048.3 11119.6 18252.0 18353.7 18398.4 18434.7 18444.0 7992.2 8002.4 8066.4 8540.2 8547.9 4390.1 4498.8 4577.3 4637.0 4732.2 3150.4 3208.6 3222.3 3222.6 3225.2 532.0 552.7 574.1 577.5 628.0 691.5 711.6 712.8 731.9 753.4 8252.7 8459.0 8527.5 8618.8 8624.1 684.2 732.6 736.5 746.1 755.9 4310.1 4342.1 4381.0 4391.1 4401.2 54215.5 54639.2 57310.4 64791.0 64879.7 48384.3 48942.8 51197.0 51629.5 51799.2 46737.5 46969.8 47483.0 47948.3 48123.3 9221.5 9675.6 9777.3 9913.5 9929.6 32783.0 32861.3 33515.7 33850.5 33884.3 39761.0 42719.8 42763.4 42883.4 43276.1 11492.9 11718.1 11763.6 11782.9 12346.3 12396.9 12443.0 12459.3 12579.2 13228.4 11728.4 11764.4 11957.2 11973.9 13110.5 12977.2 13041.9 13336.3 13504.5 13511.7 12802.1 13033.3 13035.3 13047.7 13048.0 9986.9 10163.4 10303.6 10305.2 10343.3 10235.7 10368.7 10383.1 10436.5 10476.8 11:35:30 INFO - PROCESS | 6758 | -------- Summary: end -------- 11:35:30 INFO - PROCESS | 6758 | 11:35:30 INFO - TEST-INFO | 6758: exit 0 11:35:30 INFO - TEST-OK | dromaeo_css | took 1112561ms 11:35:30 INFO - TEST-START | kraken 11:35:30 INFO - operating with platform_type : linux_ 11:35:30 INFO - Initialising browser for kraken test... 11:35:30 INFO - TEST-INFO | started process 6940 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbcXscO/profile http://localhost:34553/getInfo.html) 11:35:35 INFO - PROCESS | 6940 | __metrics Screen width/height:1600/1200 11:35:35 INFO - PROCESS | 6940 | colorDepth:24 11:35:35 INFO - PROCESS | 6940 | Browser inner width/height: 1024/697 11:35:35 INFO - PROCESS | 6940 | __metrics 11:35:36 ERROR - PROCESS | 6940 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:35:36 INFO - TEST-INFO | 6940: exit 0 11:35:36 INFO - Browser initialized. 11:35:36 INFO - Running cycle 1/1 for kraken test... 11:35:36 INFO - TEST-INFO | started process 7022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbcXscO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:35:48 INFO - PROCESS | 7022 | RSS: Main: 154505216 11:35:48 INFO - PROCESS | 7022 | 11:35:51 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34553/tests/kraken/driver.html?testName=audio-beat-detection) 11:35:51 INFO - PROCESS | 7022 | RSS: Main: 209416192 11:35:51 INFO - PROCESS | 7022 | 11:35:54 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34553/tests/kraken/driver.html?testName=audio-dft) 11:35:55 INFO - PROCESS | 7022 | RSS: Main: 195248128 11:35:55 INFO - PROCESS | 7022 | 11:35:58 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34553/tests/kraken/driver.html?testName=audio-fft) 11:35:59 INFO - PROCESS | 7022 | RSS: Main: 266756096 11:35:59 INFO - PROCESS | 7022 | 11:36:01 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34553/tests/kraken/driver.html?testName=audio-oscillator) 11:36:01 INFO - PROCESS | 7022 | RSS: Main: 174227456 11:36:01 INFO - PROCESS | 7022 | 11:36:03 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:36:04 INFO - PROCESS | 7022 | RSS: Main: 178708480 11:36:04 INFO - PROCESS | 7022 | 11:36:07 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34553/tests/kraken/driver.html?testName=imaging-darkroom) 11:36:08 INFO - PROCESS | 7022 | RSS: Main: 214642688 11:36:08 INFO - PROCESS | 7022 | 11:36:11 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34553/tests/kraken/driver.html?testName=imaging-desaturate) 11:36:12 INFO - PROCESS | 7022 | RSS: Main: 215281664 11:36:12 INFO - PROCESS | 7022 | 11:36:14 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34553/tests/kraken/driver.html?testName=json-parse-financial) 11:36:15 INFO - PROCESS | 7022 | RSS: Main: 215666688 11:36:15 INFO - PROCESS | 7022 | 11:36:17 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:36:17 INFO - PROCESS | 7022 | RSS: Main: 202567680 11:36:17 INFO - PROCESS | 7022 | 11:36:19 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:36:19 INFO - PROCESS | 7022 | RSS: Main: 203665408 11:36:19 INFO - PROCESS | 7022 | 11:36:22 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:36:22 INFO - PROCESS | 7022 | RSS: Main: 215543808 11:36:22 INFO - PROCESS | 7022 | 11:36:25 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:36:25 INFO - PROCESS | 7022 | RSS: Main: 223666176 11:36:25 INFO - PROCESS | 7022 | 11:36:29 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:36:29 INFO - PROCESS | 7022 | RSS: Main: 221319168 11:36:29 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | Cycle 1(1): loaded http://localhost:34553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34553/tests/kraken/driver.html?testName=ai-astar) 11:36:31 INFO - PROCESS | 7022 | RSS: Main: 285560832 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | __start_tp_report 11:36:31 INFO - PROCESS | 7022 | _x_x_mozilla_page_load 11:36:31 INFO - PROCESS | 7022 | _x_x_mozilla_page_load_details 11:36:31 INFO - PROCESS | 7022 | |i|pagename|runs| 11:36:31 INFO - PROCESS | 7022 | |0;ai-astar;111;110;107;110;110;111;101;109;109;112 11:36:31 INFO - PROCESS | 7022 | |1;audio-beat-detection;153;128;154;197;134;158;155;138;190;132 11:36:31 INFO - PROCESS | 7022 | |2;audio-dft;163;161;160;160;161;163;160;161;161;160 11:36:31 INFO - PROCESS | 7022 | |3;audio-fft;73;76;118;113;77;76;76;125;117;73 11:36:31 INFO - PROCESS | 7022 | |4;audio-oscillator;93;94;156;94;92;94;163;93;93;154 11:36:31 INFO - PROCESS | 7022 | |5;imaging-gaussian-blur;117;117;117;117;118;116;117;117;117;117 11:36:31 INFO - PROCESS | 7022 | |6;imaging-darkroom;145;146;145;146;144;146;147;145;145;146 11:36:31 INFO - PROCESS | 7022 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 11:36:31 INFO - PROCESS | 7022 | |8;json-parse-financial;60;57;58;58;60;57;59;81;55;54 11:36:31 INFO - PROCESS | 7022 | |9;json-stringify-tinderbox;62;61;61;62;63;61;61;62;61;61 11:36:31 INFO - PROCESS | 7022 | |10;stanford-crypto-aes;77;77;85;77;85;78;78;83;83;77 11:36:31 INFO - PROCESS | 7022 | |11;stanford-crypto-ccm;130;129;144;126;128;128;127;130;164;128 11:36:31 INFO - PROCESS | 7022 | |12;stanford-crypto-pbkdf2;199;207;203;205;217;205;200;206;201;211 11:36:31 INFO - PROCESS | 7022 | |13;stanford-crypto-sha256-iterative;69;69;71;71;71;70;70;70;70;66 11:36:31 INFO - PROCESS | 7022 | __end_tp_report 11:36:31 INFO - PROCESS | 7022 | __start_cc_report 11:36:31 INFO - PROCESS | 7022 | _x_x_mozilla_cycle_collect,1622 11:36:31 INFO - PROCESS | 7022 | __end_cc_report 11:36:31 INFO - PROCESS | 7022 | __startTimestamp1461782191667__endTimestamp 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | ------- Summary: start ------- 11:36:31 INFO - PROCESS | 7022 | Number of tests: 14 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:3.13 (2.8%) stddev-sans-first:3.23 11:36:31 INFO - PROCESS | 7022 | Values: 111.0 110.0 107.0 110.0 110.0 111.0 101.0 109.0 109.0 112.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#1] audio-beat-detection Cycles:10 Average:153.90 Median:154.50 stddev:23.52 (15.2%) stddev-sans-first:24.94 11:36:31 INFO - PROCESS | 7022 | Values: 153.0 128.0 154.0 197.0 134.0 158.0 155.0 138.0 190.0 132.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 11:36:31 INFO - PROCESS | 7022 | Values: 163.0 161.0 160.0 160.0 161.0 163.0 160.0 161.0 161.0 160.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#3] audio-fft Cycles:10 Average:92.40 Median:74.50 stddev:22.47 (30.2%) stddev-sans-first:22.71 11:36:31 INFO - PROCESS | 7022 | Values: 73.0 76.0 118.0 113.0 77.0 76.0 76.0 125.0 117.0 73.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#4] audio-oscillator Cycles:10 Average:112.60 Median:93.00 stddev:31.18 (33.5%) stddev-sans-first:32.26 11:36:31 INFO - PROCESS | 7022 | Values: 93.0 94.0 156.0 94.0 92.0 94.0 163.0 93.0 93.0 154.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 11:36:31 INFO - PROCESS | 7022 | Values: 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 11:36:31 INFO - PROCESS | 7022 | Values: 145.0 146.0 145.0 146.0 144.0 146.0 147.0 145.0 145.0 146.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 11:36:31 INFO - PROCESS | 7022 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:58.50 stddev:7.67 (13.1%) stddev-sans-first:8.13 11:36:31 INFO - PROCESS | 7022 | Values: 60.0 57.0 58.0 58.0 60.0 57.0 59.0 81.0 55.0 54.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 11:36:31 INFO - PROCESS | 7022 | Values: 62.0 61.0 61.0 62.0 63.0 61.0 61.0 62.0 61.0 61.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.50 stddev:3.53 (4.4%) stddev-sans-first:3.57 11:36:31 INFO - PROCESS | 7022 | Values: 77.0 77.0 85.0 77.0 85.0 78.0 78.0 83.0 83.0 77.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#11] stanford-crypto-ccm Cycles:10 Average:133.40 Median:129.50 stddev:11.90 (9.2%) stddev-sans-first:12.56 11:36:31 INFO - PROCESS | 7022 | Values: 130.0 129.0 144.0 126.0 128.0 128.0 127.0 130.0 164.0 128.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.40 Median:205.50 stddev:5.42 (2.6%) stddev-sans-first:5.23 11:36:31 INFO - PROCESS | 7022 | Values: 199.0 207.0 203.0 205.0 217.0 205.0 200.0 206.0 201.0 211.0 11:36:31 INFO - PROCESS | 7022 | 11:36:31 INFO - PROCESS | 7022 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.49 (2.1%) stddev-sans-first:1.56 11:36:31 INFO - PROCESS | 7022 | Values: 69.0 69.0 71.0 71.0 71.0 70.0 70.0 70.0 70.0 66.0 11:36:31 INFO - PROCESS | 7022 | -------- Summary: end -------- 11:36:31 INFO - PROCESS | 7022 | 11:36:32 INFO - TEST-INFO | 7022: exit 0 11:36:32 INFO - TEST-OK | kraken | took 61725ms 11:36:32 INFO - SUITE-END | took 1174s 11:36:32 INFO - Completed test suite (00:19:34) 11:36:32 INFO - javascript benchmark 11:36:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.527570864371, 3199.8240096794666, 3235.7411407087434, 3238.2713889374877, 3271.4929185976166, 5323.840284791462, 5409.702466364349, 5443.428440013801, 5449.100898351777, 5536.667799932002, 5452.454754524555, 5475.260839786628, 5478.726063696819, 5550.306211723535, 5585.022621041322, 4873.147199240137, 4905.4849240829735, 4928.162212423897, 4937.950620493803, 5080.237964305346, 4988.102141614508, 5001.8249361272365, 5055.949440505603, 5060.924086138711, 5065.848024559251, 4750.16872047392, 4823.5176482351835, 4831.05794370098, 4875.829345972874, 4904.558589726942, 4924.0, 4926.802927882881, 4937.432195297551, 4939.629527785394, 4980.05378977993, 4865.635077369211, 4899.363082799213, 4913.459519452857, 4914.311996320518, 4946.629002824802, 5201.713905735183, 5335.733213339317, 5378.973105134489, 5438.483344082306, 5444.564434845203, 5676.0, 5690.032694441955, 5700.344460387067, 5701.059325211335, 5703.3441154267375, 5583.190437386556, 5738.081906894919, 5739.053056245714, 5742.397048309909, 5751.884962300773, 5429.891402171974, 5455.536279416255, 5493.560515158778, 5507.421720719307, 5508.889822203613, 5885.382034886361, 5886.411358864165, 5888.646681199141, 5890.705464726747, 5891.322497912709, 5523.475269849358, 5674.1488776683, 5676.2337084493065, 5680.573956953245, 5685.317761868602, 5767.077267637158, 5771.59598828078, 5772.538196944234, 5792.536597072224, 5816.069428891357, 5733.168690539848, 5743.62666426681, 5747.8563035924435, 5748.885022299614, 5752.539796816244, 5547.55619550435, 5699.059655156935, 5741.799037033684, 5744.770209191669, 5785.681787501728, 4338.566143385699, 4349.826006959749, 4358.411614432011, 4384.232759267115, 4399.988002759319, 3895.454636350913, 3914.64768170866, 3918.0792513759243, 3947.111899822517, 3947.210557888434, 5294.788208471618, 5472.589555783332, 5476.288082549243, 5483.0, 5583.302087239095, 5758.913616295759, 5778.566607504454, 5783.65298082108, 5796.88406231873, 5814.912776308359, 5476.3702174250475, 5485.753141108659, 5488.039593071277, 5488.1493368527945, 5490.203920431515, 4695.248760198352, 4783.402074740658, 4837.9032419351415, 4858.8785280367565, 4884.3650325458175, 6001.459868611846, 6013.458788709037, 6041.818745437644, 6045.637261764308, 6046.183765191643, 5837.708114594338, 5870.970645146747, 5872.647641141545, 5872.823815285548, 5874.970625146847, 5883.55873309512, 5947.791827286024, 5972.58191926561, 6023.698815059141, 6035.818925432244, 5551.9722401387735, 5606.6636001840025, 5607.186957891165, 5611.298587676541, 5628.493435590653, 5537.086380747292, 5716.42835716425, 5729.026065568946, 5730.140478928194, 5738.598298119258, 5576.0799468088935, 5598.664080155204, 5609.27079479665, 5636.633618814764, 5664.745086471201, 5604.663720176803, 5613.747381367766, 5621.353544342453, 5632.070708333243, 5633.4366563344975, 5443.7278136092245, 5498.3127109111365, 5534.695591742493, 5549.556035517229, 5563.02647036775, 5635.323761148525, 5646.096624540054, 5649.37856835756, 5653.547716182778, 5701.800436984686, 5504.862378440571, 5508.697021663686, 5509.449055094618, 5512.751926163252, 5559.777608895599], "value": 5317.2998226448235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.3167788720856, 643.2955913274965, 643.2988043033093, 645.3772109913932, 664.8836453620618, 28433.0, 29035.27411814703, 29078.709212907863, 29082.41835163298, 36009.27981440369, 37571.0, 38172.85481435561, 38185.42721859174, 38240.80879595598, 38278.0, 8017.438779285452, 8320.625571849281, 8321.66713331466, 8322.958385208065, 8323.417360784748, 8123.796905077391, 8190.959045204795, 8267.793305167359, 8272.17278272174, 8273.669053237863, 8250.051244106944, 8532.402731808776, 8538.103499132594, 8541.017782954978, 8550.572471376438, 8428.957855210747, 8519.48883067018, 8522.87215691762, 8527.488350698946, 8528.232459078712, 8400.41197116202, 8512.404131710782, 8520.0, 8521.10528394522, 8523.06246312905, 37137.44293835595, 37601.435978460344, 37623.247535049144, 37640.0, 37655.0, 3467.809270490122, 3490.528778614905, 3591.3356029134693, 3787.16682329887, 3977.5823538528616, 28663.57004644932, 28973.42053158946, 29087.800341962367, 29110.41779164426, 29814.254643633867, 3684.4657524658774, 3684.9447258291148, 3709.629037096269, 3808.2383523295453, 3835.8657446989223, 234541.0, 236118.0, 236690.0, 237000.0, 237557.81221093956, 51156.23265651018, 52303.73848130735, 52365.73817130966, 52374.21438678423, 52401.0, 55016.44983550193, 55060.44939550553, 55090.72454637701, 55111.0, 55245.44754552403, 15914.204289785695, 15945.123018234108, 16006.359745610045, 16032.278547465392, 16080.839191607934, 12018.519259229533, 12034.157608967289, 12039.578614748441, 12040.518379264731, 12058.397080145958, 14523.636909077357, 15668.294926728096, 15733.797268382436, 15770.605734856721, 15806.841931580768, 5125.873217337421, 5995.250593675791, 6168.19813424252, 6258.499320054476, 6331.303556608769, 1100.4662738571785, 1100.5267734873921, 1122.1023181454802, 1137.4445346387665, 1185.0815617896028, 28155.0, 28496.145115646563, 28670.426591468055, 28882.85558572236, 29190.124296271148, 1115.9051480624075, 1165.7668466306773, 1216.7444836584239, 1222.2177806204036, 1243.3410292544966, 15340.309686064154, 16112.75830862538, 16124.27440765145, 16344.264508097163, 16378.935369200964, 322610.0, 325111.0, 325976.0, 326574.0, 326720.0, 50848.0, 51031.87617485925, 51121.0, 51185.0, 51215.23177152346, 1265.544404014554, 1277.5847849448967, 1384.3908680180689, 1410.4146779086768, 1417.957461276184, 27400.040998565753, 28058.43883122367, 30590.84704576463, 31329.216769580944, 31539.84230078835, 43425.0, 43575.9106022352, 43621.0, 43700.125997479234, 43755.0, 215518.0, 216255.0, 216520.0, 225557.0, 226543.0, 35355.29289414146, 37368.252634946606, 37589.0, 37757.62242377651, 37783.0, 42438.0, 42831.1433771329, 43234.56765432305, 43790.34314485349, 44626.3306050416, 36628.81685591555, 37056.814715926244, 37072.81463592665, 37165.0, 37173.814130929175, 36431.016520315316, 36725.81637091797, 36755.63244367629, 37141.442878356305, 37359.626403735616, 37169.44245836369, 37258.81370593238, 37627.24745505129, 38004.619953801215, 38434.42348364721, 32307.0, 32846.34307313793, 32856.83571582127, 33422.665773341956, 33432.49851252282, 25740.742592573835, 26160.215193544223, 26253.474930501667, 26579.867100664374, 26816.865915670296], "value": 18019.08236913324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4113829256116, 393.7952264822292, 394.3434182086822, 403.54601073791986, 415.86068666996556, 16853.0, 17338.82661173388, 17867.910660446694, 17894.821051789477, 17936.0, 16767.748483772753, 17127.57181070474, 17623.0, 18307.63384732308, 18337.0, 5497.367802702691, 5535.114381698929, 5641.492265696086, 5649.802256921009, 5650.830475085754, 4783.880402989918, 5391.973040134794, 5556.611037227396, 5620.971895140518, 5623.100303951369, 5431.674099554019, 5541.445855414447, 5548.833534993957, 5550.555955523569, 5570.8607284818, 5443.374011988632, 5548.611597188198, 5585.74864131111, 5606.55147588192, 5611.046122159243, 4896.46138924718, 4968.279599458058, 4992.900141997176, 5004.599632029429, 5022.648414610979, 21008.159673613038, 21222.363329099997, 21430.785692143036, 21651.783482165134, 21728.34814955538, 2342.0865862313713, 2463.507298540281, 2464.051340234023, 2486.5026994601153, 2583.7803786678155, 13481.730365392736, 13629.386677599532, 13720.65698357549, 13764.93117534416, 14199.148051116967, 1918.3765276285262, 1939.4763413878175, 2040.1737296394836, 2079.5736873940805, 2085.447356450542, 65081.0, 66582.66708666425, 66946.66526667336, 67037.65924681528, 67876.0, 9932.205423566098, 10008.849867251996, 10049.849252261221, 10186.694399168036, 10446.895531044593, 14291.785623215661, 14307.92846035763, 14308.427662893368, 14357.64105897361, 14392.280385980868, 7872.881906771403, 8036.879446808302, 8049.9597502013285, 8225.917740822515, 8252.587370631445, 8125.4718443301, 8138.877916831252, 8146.511209327459, 8290.751277461686, 8299.211574900375, 6379.393957574023, 6544.214694236627, 6560.606363618198, 6563.0, 6686.765963191362, 1786.6783978883925, 1856.916438760259, 1858.3402891973155, 1880.4586922582084, 1929.6623090959026, 882.580774132282, 964.3490643815537, 1088.7169335972703, 1097.720081379243, 1100.1583788401879, 17845.910770446066, 18291.81708182928, 18705.81294187041, 19098.618027639368, 19289.8071019288, 1019.0828254570946, 1042.9739256518649, 1053.6312290698347, 1069.1233188785277, 1069.1713921710736, 10388.636397726195, 10947.616833410792, 11009.174311926638, 11066.0, 11150.665480035612, 438409.0, 439549.0, 443277.0, 484819.0, 486964.0, 46625.5337446628, 58057.0, 67480.66259668769, 67969.3203067973, 71621.6418917902, 462.28576848768733, 473.4933621025539, 474.8456751555758, 475.9190937540696, 476.99761501192273, 8250.917490825255, 10302.690919272434, 10415.895841041493, 10572.154227661615, 10757.731056723644, 32979.186144762265, 34761.304773904885, 34850.82574587111, 35187.64812351844, 36682.266354672225, 45351.31973020342, 45806.770966144955, 46935.0, 47155.76422117867, 48133.0, 8021.197880211932, 8078.030636323679, 8235.258826705625, 8338.66645334204, 8423.536705481258, 9456.952715236379, 11219.71950701239, 12189.451474683838, 12607.0, 12690.175138615961, 6272.529560282906, 8636.0, 11290.362897379999, 11337.489812958273, 11373.14701397382, 10304.536295866554, 10983.780324393307, 10987.560497580007, 11124.555017799521, 11182.944085279521, 11008.834867476835, 11022.889771102185, 11211.103111750877, 11329.093672506344, 11420.0, 8144.592770361577, 8348.6243119061, 8382.664693412195, 8436.45163064399, 8800.38397312182, 11124.721881953015, 11184.440777961232, 11515.942420287845, 11702.70743231426, 11717.0], "value": 8548.808294929704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.7317323121995, 1487.2415068315158, 1491.7911492391063, 1648.769172315876, 1805.2327760701708, 8078.192180781919, 8420.410571260007, 8431.241188293052, 8431.62057707403, 8441.662333506652, 8183.631736571853, 8352.91647083529, 8377.664893404271, 8380.161983801618, 8385.790355241123, 1926.7687877454655, 1988.5128143604836, 1991.1239054815908, 2207.459172502739, 2256.7291924968945, 2223.110755697726, 2293.54129174165, 2295.357299956015, 2295.5753185660624, 2295.747467778543, 2218.2901471529117, 2226.065052677871, 2236.5079682469914, 2236.709227800384, 2275.6358982562792, 1940.5439721665398, 2154.676798480225, 2187.7593464718866, 2201.7798220178015, 2203.7796220377913, 2250.932472025842, 2261.129465155927, 2268.534950335177, 2268.7164104486938, 2272.9545409091725, 8309.667613295462, 8451.323894088458, 8478.406511544194, 8480.279176270025, 8480.872786908201, 4827.372441582607, 4969.105560999019, 4971.502849715036, 4979.153543897546, 4983.850484485435, 3011.111722041981, 3128.1710346758127, 3138.168385377877, 3143.5599016137758, 3147.6380216275193, 966.7196513011219, 976.5995941663955, 984.4929861121528, 1000.0949141027381, 1019.1948360795037, 11654.533818647244, 11716.23844450108, 11759.94120029403, 11765.176437649454, 11806.0, 4394.055278115156, 4410.095930334338, 4430.579094985971, 4515.367848501212, 4556.65825063115, 9822.852657210147, 9827.213822894151, 9828.21374290055, 9840.409575425354, 9846.704598862045, 5085.2372144177925, 5129.461406552333, 5130.769115389817, 5131.512506311894, 5134.48655134491, 4197.601227883346, 4557.156925968707, 4637.211674015425, 4653.581177694024, 4771.212749896228, 3619.7104231661406, 3672.540932383452, 3680.8895733128047, 3699.445083237482, 3742.419924911643, 1222.9938850305691, 1236.9814452783216, 1240.0451652227734, 1242.993785031087, 1249.419020155614, 937.1425145991478, 998.8751406074241, 1012.1346248957183, 1015.5125539740966, 1015.7031082902698, 5920.141579470953, 6050.45545900871, 6055.8486037848525, 6113.602615829956, 6265.373462653698, 414.17785695394616, 420.258244198989, 425.1200015966919, 428.2441490768796, 429.26381256145237, 3921.2353591049473, 4011.5186177658284, 4030.2946984277633, 4084.9183016340103, 4175.457190565267, 10012.148967337858, 10032.247581431422, 10431.895681043245, 10449.05958463742, 10589.629362972259, 10235.846462303072, 10482.68551943443, 10536.15710743154, 10546.472676366151, 10582.365058096539, 7724.7682569523, 7781.221877812173, 7821.139674635786, 7956.602169891482, 7985.600719964094, 5866.50134738552, 6038.3357830637715, 6129.233845769279, 6162.075688646739, 6173.69131543412, 9664.420134791653, 9664.661736839176, 9913.157381622643, 9990.400575965465, 10019.799604007732, 9830.966397655231, 9891.653792117242, 10217.489125543545, 10384.065434110966, 10396.064354208158, 6886.896696549656, 7343.045404097433, 7504.287092726182, 7584.203658615766, 7621.12357078943, 8250.504969701837, 8320.459170153921, 8368.330533557179, 8382.203690649378, 8404.87392689098, 7344.449166312441, 7362.116546014512, 7464.738734144279, 7509.774706758806, 7591.848163036819, 7157.284271572801, 7287.489875708649, 7326.047613810384, 7353.669084891086, 7480.625968701434, 6681.665916704243, 6707.698153583003, 6749.966250168718, 6752.0, 6757.054012438361, 4987.476314986874, 5103.137354865522, 5192.247124166899, 5217.243499692448, 5280.81517146898, 5209.453007434165, 5236.659617124875, 5258.526732594085, 5289.3652761668845, 5362.0616392132], "value": 4385.7972221067175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.12622588735053, 309.11746962422285, 311.5202588014458, 312.0638085742772, 313.3951473655843, 5201.713905735183, 5263.210518422234, 5266.7893284268675, 5291.814786482474, 5300.390455097665, 5191.766370513326, 5202.271681964528, 5213.661112027716, 5239.79040838366, 5250.422453530108, 3892.034522566107, 3970.8808735737975, 4072.063425412157, 4092.0792821615196, 4103.425520427142, 4054.6148115929086, 4064.9024763314037, 4071.5391916870717, 4093.815778289984, 4095.1604920991126, 3871.30290572504, 4026.637602615764, 4051.756894586334, 4053.9391909121387, 4058.776767277813, 3748.4189950557707, 3967.603239676024, 3976.4234186043004, 4060.573639767841, 4080.5919408059253, 3555.3422616815974, 3581.7850928944345, 3592.8023958682525, 3598.9845686415692, 3610.3031575159634, 5054.292399064134, 5054.646174767768, 5074.31496747938, 5080.79676812927, 5101.362329708786, 4562.634989200889, 4577.748223847687, 4596.666966579688, 4617.145828021827, 4671.976640116778, 5358.33020872391, 5384.865378365533, 5397.595180361489, 5401.621886467949, 5454.400015998237, 3901.3367727486398, 3918.8236529356245, 3936.6850651947775, 3947.644711975936, 3948.822302996372, 5432.565394768409, 5605.32736071674, 5641.943580564224, 5680.204771332017, 5684.602077854552, 3930.5479869815335, 3933.370660694275, 3943.664788492953, 3964.4053391991433, 3971.4439978403047, 3994.720369574102, 3994.960050399459, 3997.5402828675074, 4010.0776821331256, 4027.2549578328103, 4444.911101777946, 4562.406887104655, 4564.338170965191, 4580.725156490621, 4585.839495617637, 5410.810621628224, 5435.315052333789, 5452.863678408072, 5456.345238571317, 5497.505224529811, 1465.4431316099815, 1475.9409623614936, 1480.0897448069575, 1480.363443719211, 1490.2995592071925, 934.6915517879083, 974.8099120671401, 997.1275134257525, 999.9200063994863, 1012.2863381316155, 5215.843524694265, 5261.631685781958, 5325.920111198335, 5343.171808369631, 5349.1976203569775, 5642.294713160855, 5651.8304450866535, 5714.5999780015, 5741.282339707536, 5747.827565173052, 5114.28400023997, 5208.192730126836, 5300.628955973045, 5373.731313434313, 5400.351957765093, 3195.3769015041844, 3221.9689699296, 3233.1270556949494, 3236.64396916339, 3249.8537565809597, 6098.176746139215, 6206.286277078104, 6272.686365681698, 6274.466670333073, 6276.0, 6189.783357582463, 6194.163787888578, 6212.502999760009, 6215.192025036807, 6216.906746398808, 6220.657863817533, 6233.688315584202, 6257.0, 6275.937240627718, 6282.434580887742, 5911.881762364643, 6092.69536523181, 6115.755369785159, 6116.17431646722, 6118.632882027093, 5980.491658209101, 5981.970090149522, 5983.102534619842, 5993.9700301498215, 6026.216591843032, 6014.458698717136, 6230.532710046674, 6245.500359971101, 6251.187345645037, 6254.843628909131, 4172.183775951282, 4200.936985945152, 4204.411382406404, 4211.326187809936, 4220.472440944882, 6037.068138195736, 6038.3357830637715, 6040.063790112539, 6045.516358691382, 6059.182010428712, 5789.0, 5877.853053673693, 5882.23530940975, 5937.940620593911, 5944.940550594439, 5844.327902291121, 5863.120531920075, 5880.705964701662, 5882.558808089325, 5890.087036509348, 5187.351581052369, 5792.275965504312, 5814.622049566765, 5821.893840170354, 5845.064789633639, 5087.92368114486, 5405.864853378697, 5440.29276194108, 5493.395726470005, 5496.900619875961, 5402.378726446509, 5432.945670543244, 5448.1203984625245, 5458.4814442627885, 5480.424555421623], "value": 4276.271375478442, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.5154149802631, 791.9524828510289, 801.4069588504507, 816.5549775372418, 851.795569063425, 14320.21238831865, 14426.78359824601, 14583.052101613388, 14619.780703289445, 15109.471168509104, 12392.81410778839, 12447.253164810118, 12501.624951251477, 12511.0, 12514.499420023189, 680.4726337088746, 788.3811208201535, 817.7383237364046, 821.1501096365263, 824.6907409721355, 822.4448497264352, 823.6952327638752, 824.3775949158377, 825.6078362777693, 825.6119623776815, 713.2439614009166, 861.7759382560516, 863.6415887406719, 863.9740807775777, 864.2308345572445, 835.1773503099472, 863.8315528471951, 863.8574635185187, 863.8920134983096, 864.416518849777, 633.9904901426482, 634.7207228819304, 635.0823060677324, 635.6345101566618, 636.0427556527443, 13594.184348939096, 13678.589642310746, 13724.039317247796, 13752.31238438074, 13772.380242889094, 13959.65100872476, 14085.436582536686, 14236.217008064552, 14273.357698903574, 14314.284285785774, 11325.943370283178, 11327.943360283145, 11367.147463940237, 11500.25248358854, 11632.534698612046, 3806.3148633246005, 3848.1534062506203, 3921.0393453603765, 3952.3281042222893, 4024.9597504024878, 51910.22134667983, 52242.0, 54097.0, 54160.45839541594, 54400.45599544033, 10480.790384192274, 10759.83860242097, 10881.83677244842, 11048.281861678965, 11119.555217791198, 18252.0, 18353.7246941296, 18398.448046558624, 18434.723479147822, 18444.0, 7992.200779921961, 8002.3598112150885, 8066.395020373496, 8540.231379175904, 8547.871781923277, 4390.100029493946, 4498.752568608758, 4577.3362862384765, 4636.953630463652, 4732.1718699227495, 3150.385674793439, 3208.598925134358, 3222.2910959588853, 3222.6132864056462, 3225.193701574606, 532.0282973156344, 552.7457369609943, 574.052812858778, 577.4831525784399, 627.9874402512015, 691.4710246661308, 711.619283683227, 712.8467379513429, 731.9487635865545, 753.3822265742039, 8252.711155109542, 8459.0, 8527.530985795716, 8618.827623447622, 8624.051354351017, 684.1516519515864, 732.5897497401472, 736.5433431272645, 746.0823187479323, 755.8677231484468, 4310.116426132698, 4342.066455712037, 4381.047381546135, 4391.099824536026, 4401.207782599163, 54215.45784542183, 54639.18041229385, 57310.42689573134, 64791.0, 64879.67560162169, 48384.27423588649, 48942.75528622334, 51197.0, 51629.48370516247, 51799.22301165485, 46737.53262467332, 46969.76515117471, 47483.0, 47948.28077578909, 48123.27815082776, 9221.49281789508, 9675.564599592895, 9777.266704997011, 9913.5129730541, 9929.602815887283, 32783.0, 32861.34277314488, 33515.66484335125, 33850.49224261687, 33884.32231355408, 39761.0, 42719.786401067795, 42763.35854962116, 42883.35674964941, 43276.13447731091, 11492.94253528727, 11718.062554995751, 11763.647090587296, 11782.882171178522, 12346.259224446203, 12396.876031239934, 12443.0, 12459.252444853339, 12579.182353147016, 13228.40472178735, 11728.354940478355, 11764.411779410824, 11957.162998590014, 11973.880261197277, 13110.541131060367, 12977.221366718028, 13041.869581304445, 13336.333183340988, 13504.45982160742, 13511.662208444868, 12802.103852730592, 13033.283169425393, 13035.283059431822, 13047.739045219232, 13048.0, 9986.900130998887, 10163.390196588229, 10303.587856485656, 10305.227107966783, 10343.27597068198, 10235.692929212135, 10368.688939331832, 10383.117435017806, 10436.530356134144, 10476.842847357448], "value": 6615.266898689968, "unit": "score"}], "name": "dromaeo_css", "value": 6831.330892609176, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 107.0, 110.0, 110.0, 111.0, 101.0, 109.0, 109.0, 112.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 128.0, 154.0, 197.0, 134.0, 158.0, 155.0, 138.0, 190.0, 132.0], "value": 153.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 160.0, 161.0, 163.0, 160.0, 161.0, 161.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 118.0, 113.0, 77.0, 76.0, 76.0, 125.0, 117.0, 73.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 156.0, 94.0, 92.0, 94.0, 163.0, 93.0, 93.0, 154.0], "value": 112.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 144.0, 146.0, 147.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 58.0, 58.0, 60.0, 57.0, 59.0, 81.0, 55.0, 54.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 62.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 85.0, 77.0, 85.0, 78.0, 78.0, 83.0, 83.0, 77.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 144.0, 126.0, 128.0, 128.0, 127.0, 130.0, 164.0, 128.0], "value": 133.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 207.0, 203.0, 205.0, 217.0, 205.0, 200.0, 206.0, 201.0, 211.0], "value": 205.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 71.0, 71.0, 70.0, 70.0, 70.0, 70.0, 66.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1596.3000000000002, "alertThreshold": 2.0}]} 11:36:32 INFO - Return code: 0 11:36:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:36:32 ERROR - # TBPL SUCCESS # 11:36:32 INFO - Running post-action listener: _resource_record_post_action 11:36:32 INFO - Running post-run listener: _resource_record_post_run 11:36:34 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 545939456; Read time: 8; Write time: 730012 11:36:34 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 240140288; Read time: 0; Write time: 482340 11:36:34 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290721792; Read time: 8; Write time: 242928 11:36:34 INFO - Running post-run listener: _upload_blobber_files 11:36:34 INFO - Blob upload gear active. 11:36:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:36:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:36:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:36:34 INFO - (blobuploader) - INFO - Open directory for files ... 11:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:35 INFO - (blobuploader) - INFO - Done attempting. 11:36:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:36:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:35 INFO - (blobuploader) - INFO - Done attempting. 11:36:35 INFO - (blobuploader) - INFO - Iteration through files over. 11:36:35 INFO - Return code: 0 11:36:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:36:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:36:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927038c9995914f9a7ca91ed38e713ce81ef2692e87b5316cf798695ab0c74e7164447e940d181078f14e72cca9ece4305b66328962c01afc8a9c9be6f9ba11e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe67913c7cff744be014aeafb2760da54d7bdbea2707d26f5fd8fd7c21506590ecc810487e27a48ff9e19aa6d4a9a9e22c3a2ea2d1bbbc1269b0eaa940062feb"} 11:36:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:36:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:36:35 INFO - Contents: 11:36:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927038c9995914f9a7ca91ed38e713ce81ef2692e87b5316cf798695ab0c74e7164447e940d181078f14e72cca9ece4305b66328962c01afc8a9c9be6f9ba11e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe67913c7cff744be014aeafb2760da54d7bdbea2707d26f5fd8fd7c21506590ecc810487e27a48ff9e19aa6d4a9a9e22c3a2ea2d1bbbc1269b0eaa940062feb"} 11:36:35 INFO - Running post-run listener: copy_logs_to_upload_dir 11:36:35 INFO - Copying logs to upload dir... 11:36:35 INFO - mkdir: /builds/slave/test/build/upload/logs 11:36:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.393450 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-04-27 11:36:35.681650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 11:36:35.685501) ========= 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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 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/927038c9995914f9a7ca91ed38e713ce81ef2692e87b5316cf798695ab0c74e7164447e940d181078f14e72cca9ece4305b66328962c01afc8a9c9be6f9ba11e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe67913c7cff744be014aeafb2760da54d7bdbea2707d26f5fd8fd7c21506590ecc810487e27a48ff9e19aa6d4a9a9e22c3a2ea2d1bbbc1269b0eaa940062feb"} build_url:https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/XYg5LfMdRWW0JqXsboGV_Q/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/927038c9995914f9a7ca91ed38e713ce81ef2692e87b5316cf798695ab0c74e7164447e940d181078f14e72cca9ece4305b66328962c01afc8a9c9be6f9ba11e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe67913c7cff744be014aeafb2760da54d7bdbea2707d26f5fd8fd7c21506590ecc810487e27a48ff9e19aa6d4a9a9e22c3a2ea2d1bbbc1269b0eaa940062feb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 11:36:35.717317) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:36:35.717920) ========= 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=aa52b8aba932b19cc50b5275000001de-1461780963.399270-867971883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 11:36:35.799533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 11:36:35.799898) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 11:36:35.800210) ========= ========= Total master_lag: 0.19 =========