builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1461932493.61 results: success (0) buildid: 20160429023013 builduid: e9c2a1257bfc4eb9b5c53ebc6fff8ea9 revision: 70b3e6ac383941005652196f432d9134dea468a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.614758) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.615106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.615368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.642091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.642346) ========= 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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-29 05:21:34-- 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% 23.8M=0s 2016-04-29 05:21:34 (23.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079105 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.736821) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.737090) ========= 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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019252 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.786447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:33.786752) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 70b3e6ac383941005652196f432d9134dea468a7 --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 70b3e6ac383941005652196f432d9134dea468a7 --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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-29 05:21:34,191 truncating revision to first 12 chars 2016-04-29 05:21:34,191 Setting DEBUG logging. 2016-04-29 05:21:34,192 attempt 1/10 2016-04-29 05:21:34,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/70b3e6ac3839?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 05:21:34,443 unpacking tar archive at: fx-team-70b3e6ac3839/testing/mozharness/ program finished with exit code 0 elapsedTime=0.520437 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:34.319074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:34.319334) ========= script_repo_revision: 70b3e6ac383941005652196f432d9134dea468a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:34.319707) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:34.319967) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 05:21:34.328471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-29 05:21:34.328840) ========= /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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:21:34 INFO - MultiFileLogger online at 20160429 05:21:34 in /builds/slave/test 05:21:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:21:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:34 INFO - {'append_to_log': False, 05:21:34 INFO - 'base_work_dir': '/builds/slave/test', 05:21:34 INFO - 'blob_upload_branch': 'Fx-Team', 05:21:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:34 INFO - 'branch': 'Fx-Team', 05:21:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:34 INFO - 'config_files': ('talos/linux_config.py',), 05:21:34 INFO - 'default_actions': ('clobber', 05:21:34 INFO - 'read-buildbot-config', 05:21:34 INFO - 'download-and-extract', 05:21:34 INFO - 'populate-webroot', 05:21:34 INFO - 'create-virtualenv', 05:21:34 INFO - 'install', 05:21:34 INFO - 'run-tests'), 05:21:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:34 INFO - 'download_minidump_stackwalk': True, 05:21:34 INFO - 'download_symbols': 'ondemand', 05:21:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:34 INFO - 'installer_path': 'installer.exe', 05:21:34 INFO - 'log_level': 'info', 05:21:34 INFO - 'log_name': 'talos', 05:21:34 INFO - 'log_to_console': True, 05:21:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:21:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:21:34 INFO - 'opt_config_files': (), 05:21:34 INFO - 'pip_index': False, 05:21:34 INFO - 'sps_profile': False, 05:21:34 INFO - 'sps_profile_interval': 0, 05:21:34 INFO - 'suite': 'dromaeojs', 05:21:34 INFO - 'system_bits': '32', 05:21:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:34 INFO - 'title': 'talos-linux64-ix-065', 05:21:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:34 INFO - 'use_talos_json': True, 05:21:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:34 INFO - 'work_dir': 'build'} 05:21:34 INFO - ##### 05:21:34 INFO - ##### Running clobber step. 05:21:34 INFO - ##### 05:21:34 INFO - Running pre-action listener: _resource_record_pre_action 05:21:34 INFO - Running main action method: clobber 05:21:34 INFO - rmtree: /builds/slave/test/build 05:21:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:35 INFO - Running post-action listener: _resource_record_post_action 05:21:35 INFO - ##### 05:21:35 INFO - ##### Running read-buildbot-config step. 05:21:35 INFO - ##### 05:21:35 INFO - Running pre-action listener: _resource_record_pre_action 05:21:35 INFO - Running main action method: read_buildbot_config 05:21:35 INFO - Using buildbot properties: 05:21:35 INFO - { 05:21:35 INFO - "project": "", 05:21:35 INFO - "product": "firefox", 05:21:35 INFO - "script_repo_revision": "production", 05:21:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:21:35 INFO - "repository": "", 05:21:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:21:35 INFO - "buildid": "20160429023013", 05:21:35 INFO - "pgo_build": "True", 05:21:35 INFO - "basedir": "/builds/slave/test", 05:21:35 INFO - "buildnumber": 1121, 05:21:35 INFO - "slavename": "talos-linux64-ix-065", 05:21:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:21:35 INFO - "platform": "ubuntu64_hw", 05:21:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:21:35 INFO - "branch": "fx-team", 05:21:35 INFO - "repo_path": "integration/fx-team", 05:21:35 INFO - "revision": "70b3e6ac383941005652196f432d9134dea468a7", 05:21:35 INFO - "stage_platform": "linux64-pgo", 05:21:35 INFO - "builduid": "e9c2a1257bfc4eb9b5c53ebc6fff8ea9", 05:21:35 INFO - "slavebuilddir": "test" 05:21:35 INFO - } 05:21:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:21:35 INFO - Running post-action listener: _resource_record_post_action 05:21:35 INFO - ##### 05:21:35 INFO - ##### Running download-and-extract step. 05:21:35 INFO - ##### 05:21:35 INFO - Running pre-action listener: _resource_record_pre_action 05:21:35 INFO - Running main action method: download_and_extract 05:21:35 INFO - mkdir: /builds/slave/test/build/tests 05:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:35 INFO - https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:21:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:41 INFO - Downloaded 1361 bytes. 05:21:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:41 INFO - Using the following test package requirements: 05:21:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:21:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:21:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'jsshell-linux-x86_64.zip'], 05:21:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:21:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:21:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:21:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:21:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:21:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:21:41 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')]} 05:21:41 INFO - https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:43 INFO - Downloaded 23873634 bytes. 05:21:43 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'] 05:21:43 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 05:21:44 INFO - Return code: 0 05:21:44 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 05:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:44 INFO - https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:46 INFO - Downloaded 23873634 bytes. 05:21:46 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'] 05:21:46 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 05:21:47 INFO - Return code: 0 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:47 INFO - https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:21:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:49 INFO - Downloaded 10840145 bytes. 05:21:49 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'] 05:21:49 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 05:21:50 INFO - Return code: 0 05:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:50 INFO - https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:21:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrtbK_75R0uLmbrOf2FYiw/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 05:21:52 INFO - Downloaded 58407211 bytes. 05:21:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:52 INFO - mkdir: /builds/slave/test/properties 05:21:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:52 INFO - Contents: 05:21:52 INFO - build_url:https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:53 INFO - Running post-action listener: _resource_record_post_action 05:21:53 INFO - Running post-action listener: set_extra_try_arguments 05:21:53 INFO - ##### 05:21:53 INFO - ##### Running populate-webroot step. 05:21:53 INFO - ##### 05:21:53 INFO - Running pre-action listener: _resource_record_pre_action 05:21:53 INFO - Running main action method: populate_webroot 05:21:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:21:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:21:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:21:53 INFO - '--tppagecycles', 05:21:53 INFO - '7'], 05:21:53 INFO - 'tests': ['tsvgm']}}, 05:21:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:21:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:21:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:21:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:21:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['damp', 'tps']}, 05:21:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'tests': ['damp', 'tps']}, 05:21:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['dromaeo_dom']}, 05:21:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:21:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'other-e10s': {'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['a11yr', 05:21:53 INFO - 'ts_paint', 05:21:53 INFO - 'tpaint', 05:21:53 INFO - 'sessionrestore', 05:21:53 INFO - 'sessionrestore_no_auto_restore', 05:21:53 INFO - 'tabpaint']}, 05:21:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['tsvgx', 05:21:53 INFO - 'tsvgr_opacity', 05:21:53 INFO - 'tart', 05:21:53 INFO - 'tscrollx', 05:21:53 INFO - 'cart']}, 05:21:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:21:53 INFO - 'tsvgr_opacity', 05:21:53 INFO - 'tart', 05:21:53 INFO - 'tscrollx', 05:21:53 INFO - 'cart']}, 05:21:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'talos_options': ['--disable-e10s'], 05:21:53 INFO - 'tests': ['tp5o']}, 05:21:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'tests': ['tp5o']}, 05:21:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'talos_options': ['--disable-e10s', 05:21:53 INFO - '--xperf_path', 05:21:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:53 INFO - 'tests': ['tp5n']}, 05:21:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:53 INFO - 'talos_options': ['--xperf_path', 05:21:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:53 INFO - 'tests': ['tp5n']}}, 05:21:53 INFO - 'talos.zip': {'path': '', 05:21:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:21:53 INFO - Running post-action listener: _resource_record_post_action 05:21:53 INFO - ##### 05:21:53 INFO - ##### Running create-virtualenv step. 05:21:53 INFO - ##### 05:21:53 INFO - Running pre-action listener: _resource_record_pre_action 05:21:53 INFO - Running main action method: create_virtualenv 05:21:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:21:53 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 05:21:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:21:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:21:53 INFO - Using real prefix '/usr' 05:21:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:21:54 INFO - Installing distribute.............................................................................................................................................................................................done. 05:21:56 INFO - Installing pip.................done. 05:21:56 INFO - Return code: 0 05:21:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:21:56 INFO - Using env: {'DISPLAY': ':0', 05:21:56 INFO - 'HOME': '/home/cltbld', 05:21:56 INFO - 'LANG': 'en_US.UTF-8', 05:21:56 INFO - 'LANGUAGE': 'en_US:en', 05:21:56 INFO - 'LOGNAME': 'cltbld', 05:21:56 INFO - 'MAIL': '/var/mail/cltbld', 05:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:56 INFO - 'MOZ_NO_REMOTE': '1', 05:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:56 INFO - 'NO_EM_RESTART': '1', 05:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:56 INFO - 'PWD': '/builds/slave/test', 05:21:56 INFO - 'SHELL': '/bin/bash', 05:21:56 INFO - 'SHLVL': '1', 05:21:56 INFO - 'TERM': 'linux', 05:21:56 INFO - 'TMOUT': '86400', 05:21:56 INFO - 'USER': 'cltbld', 05:21:56 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:56 INFO - '_': '/tools/buildbot/bin/python'} 05:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:21:56 INFO - Cleaning up... 05:21:56 INFO - Return code: 0 05:21:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:21:56 INFO - Using env: {'DISPLAY': ':0', 05:21:56 INFO - 'HOME': '/home/cltbld', 05:21:56 INFO - 'LANG': 'en_US.UTF-8', 05:21:56 INFO - 'LANGUAGE': 'en_US:en', 05:21:56 INFO - 'LOGNAME': 'cltbld', 05:21:56 INFO - 'MAIL': '/var/mail/cltbld', 05:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:56 INFO - 'MOZ_NO_REMOTE': '1', 05:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:56 INFO - 'NO_EM_RESTART': '1', 05:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:56 INFO - 'PWD': '/builds/slave/test', 05:21:56 INFO - 'SHELL': '/bin/bash', 05:21:56 INFO - 'SHLVL': '1', 05:21:56 INFO - 'TERM': 'linux', 05:21:56 INFO - 'TMOUT': '86400', 05:21:56 INFO - 'USER': 'cltbld', 05:21:56 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:56 INFO - '_': '/tools/buildbot/bin/python'} 05:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:56 INFO - Downloading/unpacking psutil>=0.7.1 05:21:56 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 05:21:56 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 05:21:56 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 05:21:56 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 05:21:56 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 05:21:56 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 05:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:21:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:58 INFO - Installing collected packages: psutil 05:21:58 INFO - Running setup.py install for psutil 05:21:58 INFO - building 'psutil._psutil_linux' extension 05:21:58 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 05:21:58 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 05:21:58 INFO - building 'psutil._psutil_posix' extension 05:21:58 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 05:21:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:59 INFO - Successfully installed psutil 05:21:59 INFO - Cleaning up... 05:21:59 INFO - Return code: 0 05:21:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:21:59 INFO - Using env: {'DISPLAY': ':0', 05:21:59 INFO - 'HOME': '/home/cltbld', 05:21:59 INFO - 'LANG': 'en_US.UTF-8', 05:21:59 INFO - 'LANGUAGE': 'en_US:en', 05:21:59 INFO - 'LOGNAME': 'cltbld', 05:21:59 INFO - 'MAIL': '/var/mail/cltbld', 05:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:59 INFO - 'MOZ_NO_REMOTE': '1', 05:21:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:59 INFO - 'NO_EM_RESTART': '1', 05:21:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:59 INFO - 'PWD': '/builds/slave/test', 05:21:59 INFO - 'SHELL': '/bin/bash', 05:21:59 INFO - 'SHLVL': '1', 05:21:59 INFO - 'TERM': 'linux', 05:21:59 INFO - 'TMOUT': '86400', 05:21:59 INFO - 'USER': 'cltbld', 05:21:59 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:59 INFO - '_': '/tools/buildbot/bin/python'} 05:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:22:01 INFO - Installing collected packages: mozsystemmonitor 05:22:01 INFO - Running setup.py install for mozsystemmonitor 05:22:01 INFO - Successfully installed mozsystemmonitor 05:22:01 INFO - Cleaning up... 05:22:02 INFO - Return code: 0 05:22:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:02 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')]} 05:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:02 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')]} 05:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:02 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 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:02 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 05:22:02 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 05:22:02 INFO - Using env: {'DISPLAY': ':0', 05:22:02 INFO - 'HOME': '/home/cltbld', 05:22:02 INFO - 'LANG': 'en_US.UTF-8', 05:22:02 INFO - 'LANGUAGE': 'en_US:en', 05:22:02 INFO - 'LOGNAME': 'cltbld', 05:22:02 INFO - 'MAIL': '/var/mail/cltbld', 05:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:02 INFO - 'MOZ_NO_REMOTE': '1', 05:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:02 INFO - 'NO_EM_RESTART': '1', 05:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:02 INFO - 'PWD': '/builds/slave/test', 05:22:02 INFO - 'SHELL': '/bin/bash', 05:22:02 INFO - 'SHLVL': '1', 05:22:02 INFO - 'TERM': 'linux', 05:22:02 INFO - 'TMOUT': '86400', 05:22:02 INFO - 'USER': 'cltbld', 05:22:02 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:02 INFO - '_': '/tools/buildbot/bin/python'} 05:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:22:02 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 05:22:02 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 05:22:02 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 05:22:02 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 05:22:02 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 05:22:02 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 05:22:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:04 INFO - Downloading docopt-0.6.1.tar.gz 05:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:04 INFO - Running setup.py install for blobuploader 05:22:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:04 INFO - Running setup.py install for requests 05:22:05 INFO - Running setup.py install for docopt 05:22:05 INFO - Successfully installed blobuploader requests docopt 05:22:05 INFO - Cleaning up... 05:22:05 INFO - Return code: 0 05:22:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:05 INFO - Using env: {'DISPLAY': ':0', 05:22:05 INFO - 'HOME': '/home/cltbld', 05:22:05 INFO - 'LANG': 'en_US.UTF-8', 05:22:05 INFO - 'LANGUAGE': 'en_US:en', 05:22:05 INFO - 'LOGNAME': 'cltbld', 05:22:05 INFO - 'MAIL': '/var/mail/cltbld', 05:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:05 INFO - 'MOZ_NO_REMOTE': '1', 05:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:05 INFO - 'NO_EM_RESTART': '1', 05:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:05 INFO - 'PWD': '/builds/slave/test', 05:22:05 INFO - 'SHELL': '/bin/bash', 05:22:05 INFO - 'SHLVL': '1', 05:22:05 INFO - 'TERM': 'linux', 05:22:05 INFO - 'TMOUT': '86400', 05:22:05 INFO - 'USER': 'cltbld', 05:22:05 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:05 INFO - '_': '/tools/buildbot/bin/python'} 05:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:05 INFO - Running setup.py (path:/tmp/pip-qqaz17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:05 INFO - Running setup.py (path:/tmp/pip-2YIeX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:05 INFO - Running setup.py (path:/tmp/pip-I2Z6Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:05 INFO - Running setup.py (path:/tmp/pip-oUW1Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:05 INFO - Running setup.py (path:/tmp/pip-GJvRou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:06 INFO - Running setup.py (path:/tmp/pip-LJSybW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:06 INFO - Running setup.py (path:/tmp/pip-jMacbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:06 INFO - Running setup.py (path:/tmp/pip-fovOB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:06 INFO - Running setup.py (path:/tmp/pip-cKP_Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:06 INFO - Running setup.py (path:/tmp/pip-6kC_3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:06 INFO - Running setup.py (path:/tmp/pip-yYN27m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:06 INFO - Running setup.py (path:/tmp/pip-gtrwg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:06 INFO - Running setup.py (path:/tmp/pip-JFMVDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:06 INFO - Running setup.py (path:/tmp/pip-hKEMlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:06 INFO - Running setup.py (path:/tmp/pip-2mKk9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:06 INFO - Running setup.py (path:/tmp/pip-0DV9t7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Running setup.py (path:/tmp/pip-noWQTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:06 INFO - Running setup.py install for manifestparser 05:22:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozcrash 05:22:07 INFO - Running setup.py install for mozdebug 05:22:07 INFO - Running setup.py install for mozdevice 05:22:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozfile 05:22:07 INFO - Running setup.py install for mozhttpd 05:22:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozinfo 05:22:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozInstall 05:22:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for mozleak 05:22:07 INFO - Running setup.py install for mozlog 05:22:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:07 INFO - Running setup.py install for moznetwork 05:22:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozprocess 05:22:08 INFO - Running setup.py install for mozprofile 05:22:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozrunner 05:22:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Running setup.py install for mozscreenshot 05:22:08 INFO - Running setup.py install for moztest 05:22:08 INFO - Running setup.py install for mozversion 05:22:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:08 INFO - Cleaning up... 05:22:08 INFO - Return code: 0 05:22:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:08 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 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:08 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 05:22:08 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 05:22:08 INFO - Using env: {'DISPLAY': ':0', 05:22:08 INFO - 'HOME': '/home/cltbld', 05:22:08 INFO - 'LANG': 'en_US.UTF-8', 05:22:08 INFO - 'LANGUAGE': 'en_US:en', 05:22:08 INFO - 'LOGNAME': 'cltbld', 05:22:08 INFO - 'MAIL': '/var/mail/cltbld', 05:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:08 INFO - 'MOZ_NO_REMOTE': '1', 05:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:08 INFO - 'NO_EM_RESTART': '1', 05:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:08 INFO - 'PWD': '/builds/slave/test', 05:22:08 INFO - 'SHELL': '/bin/bash', 05:22:08 INFO - 'SHLVL': '1', 05:22:08 INFO - 'TERM': 'linux', 05:22:08 INFO - 'TMOUT': '86400', 05:22:08 INFO - 'USER': 'cltbld', 05:22:08 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:08 INFO - '_': '/tools/buildbot/bin/python'} 05:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:08 INFO - Running setup.py (path:/tmp/pip-NGQj8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:09 INFO - Running setup.py (path:/tmp/pip-bxeD3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:09 INFO - Running setup.py (path:/tmp/pip-rMOv8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:09 INFO - Running setup.py (path:/tmp/pip-MR31Nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:09 INFO - Running setup.py (path:/tmp/pip-26lPTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:09 INFO - Running setup.py (path:/tmp/pip-cp7yTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:09 INFO - Running setup.py (path:/tmp/pip-yGMCNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:09 INFO - Running setup.py (path:/tmp/pip-WpnjLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:09 INFO - Running setup.py (path:/tmp/pip-Day8I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:09 INFO - Running setup.py (path:/tmp/pip-ygM7GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:09 INFO - Running setup.py (path:/tmp/pip-p0xROp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:09 INFO - Running setup.py (path:/tmp/pip-HiwSPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:09 INFO - Running setup.py (path:/tmp/pip-Ut89ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:09 INFO - Running setup.py (path:/tmp/pip-fkSrbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:09 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)) 05:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:09 INFO - Running setup.py (path:/tmp/pip-eMSxY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:10 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)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:10 INFO - Running setup.py (path:/tmp/pip-RB2wSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:10 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)) 05:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:10 INFO - Running setup.py (path:/tmp/pip-GzuYSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:10 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)) 05:22:10 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)) 05:22:10 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)) 05:22:10 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)) 05:22:10 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)) 05:22:10 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)) 05:22:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:12 INFO - Downloading blessings-1.6.tar.gz 05:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:12 INFO - Installing collected packages: blessings 05:22:12 INFO - Running setup.py install for blessings 05:22:12 INFO - Successfully installed blessings 05:22:12 INFO - Cleaning up... 05:22:12 INFO - Return code: 0 05:22:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:12 INFO - Reading from file tmpfile_stdout 05:22:12 INFO - Current package versions: 05:22:12 INFO - argparse == 1.2.1 05:22:12 INFO - blessings == 1.6 05:22:12 INFO - blobuploader == 1.2.4 05:22:12 INFO - docopt == 0.6.1 05:22:12 INFO - manifestparser == 1.1 05:22:12 INFO - mozInstall == 1.12 05:22:12 INFO - mozcrash == 0.17 05:22:12 INFO - mozdebug == 0.1 05:22:12 INFO - mozdevice == 0.48 05:22:12 INFO - mozfile == 1.2 05:22:12 INFO - mozhttpd == 0.7 05:22:12 INFO - mozinfo == 0.9 05:22:12 INFO - mozleak == 0.1 05:22:12 INFO - mozlog == 3.1 05:22:12 INFO - moznetwork == 0.27 05:22:12 INFO - mozprocess == 0.22 05:22:12 INFO - mozprofile == 0.28 05:22:12 INFO - mozrunner == 6.11 05:22:12 INFO - mozscreenshot == 0.1 05:22:12 INFO - mozsystemmonitor == 0.0 05:22:12 INFO - moztest == 0.7 05:22:12 INFO - mozversion == 1.4 05:22:12 INFO - psutil == 3.1.1 05:22:12 INFO - requests == 1.2.3 05:22:12 INFO - wsgiref == 0.1.2 05:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:12 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 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:12 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 05:22:12 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 05:22:12 INFO - Using env: {'DISPLAY': ':0', 05:22:12 INFO - 'HOME': '/home/cltbld', 05:22:12 INFO - 'LANG': 'en_US.UTF-8', 05:22:12 INFO - 'LANGUAGE': 'en_US:en', 05:22:12 INFO - 'LOGNAME': 'cltbld', 05:22:12 INFO - 'MAIL': '/var/mail/cltbld', 05:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:12 INFO - 'MOZ_NO_REMOTE': '1', 05:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:12 INFO - 'NO_EM_RESTART': '1', 05:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:12 INFO - 'PWD': '/builds/slave/test', 05:22:12 INFO - 'SHELL': '/bin/bash', 05:22:12 INFO - 'SHLVL': '1', 05:22:12 INFO - 'TERM': 'linux', 05:22:12 INFO - 'TMOUT': '86400', 05:22:12 INFO - 'USER': 'cltbld', 05:22:12 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:12 INFO - '_': '/tools/buildbot/bin/python'} 05:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 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)) 05:22:12 INFO - Cleaning up... 05:22:12 INFO - Return code: 0 05:22:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:12 INFO - Using env: {'DISPLAY': ':0', 05:22:12 INFO - 'HOME': '/home/cltbld', 05:22:12 INFO - 'LANG': 'en_US.UTF-8', 05:22:12 INFO - 'LANGUAGE': 'en_US:en', 05:22:12 INFO - 'LOGNAME': 'cltbld', 05:22:12 INFO - 'MAIL': '/var/mail/cltbld', 05:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:12 INFO - 'MOZ_NO_REMOTE': '1', 05:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:12 INFO - 'NO_EM_RESTART': '1', 05:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:12 INFO - 'PWD': '/builds/slave/test', 05:22:12 INFO - 'SHELL': '/bin/bash', 05:22:12 INFO - 'SHLVL': '1', 05:22:12 INFO - 'TERM': 'linux', 05:22:12 INFO - 'TMOUT': '86400', 05:22:12 INFO - 'USER': 'cltbld', 05:22:12 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:12 INFO - '_': '/tools/buildbot/bin/python'} 05:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:12 INFO - Downloading/unpacking jsonschema 05:22:12 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 05:22:12 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 05:22:12 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 05:22:12 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 05:22:12 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 05:22:12 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 05:22:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:14 INFO - Downloading/unpacking functools32 (from jsonschema) 05:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:15 INFO - warning: no files found matching '*.txt' 05:22:15 INFO - no previously-included directories found matching 'build' 05:22:15 INFO - no previously-included directories found matching 'dist' 05:22:15 INFO - no previously-included directories found matching '.git*' 05:22:15 INFO - Installing collected packages: jsonschema, functools32 05:22:15 INFO - Running setup.py install for functools32 05:22:15 INFO - warning: no files found matching '*.txt' 05:22:15 INFO - no previously-included directories found matching 'build' 05:22:15 INFO - no previously-included directories found matching 'dist' 05:22:15 INFO - no previously-included directories found matching '.git*' 05:22:15 INFO - Successfully installed jsonschema functools32 05:22:15 INFO - Cleaning up... 05:22:15 INFO - Return code: 0 05:22:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:22:15 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')]} 05:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:15 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')]} 05:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:15 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 0x7f3cb65d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20309a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, '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': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', '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 05:22:15 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 05:22:15 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 05:22:15 INFO - Using env: {'DISPLAY': ':0', 05:22:15 INFO - 'HOME': '/home/cltbld', 05:22:15 INFO - 'LANG': 'en_US.UTF-8', 05:22:15 INFO - 'LANGUAGE': 'en_US:en', 05:22:15 INFO - 'LOGNAME': 'cltbld', 05:22:15 INFO - 'MAIL': '/var/mail/cltbld', 05:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:15 INFO - 'MOZ_NO_REMOTE': '1', 05:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:15 INFO - 'NO_EM_RESTART': '1', 05:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:15 INFO - 'PWD': '/builds/slave/test', 05:22:15 INFO - 'SHELL': '/bin/bash', 05:22:15 INFO - 'SHLVL': '1', 05:22:15 INFO - 'TERM': 'linux', 05:22:15 INFO - 'TMOUT': '86400', 05:22:15 INFO - 'USER': 'cltbld', 05:22:15 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:15 INFO - '_': '/tools/buildbot/bin/python'} 05:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:15 INFO - Downloading/unpacking flake8 05:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:22:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:22:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 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 05:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:22:18 INFO - no previously-included directories found matching 'docs/_build' 05:22:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:22:18 INFO - Running setup.py install for pep8 05:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:22:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:22:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:22:18 INFO - no previously-included directories found matching 'docs/_build' 05:22:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:22:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:22:18 INFO - Cleaning up... 05:22:18 INFO - Return code: 0 05:22:18 INFO - Running post-action listener: _resource_record_post_action 05:22:18 INFO - Running post-action listener: _start_resource_monitoring 05:22:18 INFO - Starting resource monitoring. 05:22:18 INFO - ##### 05:22:18 INFO - ##### Running install step. 05:22:18 INFO - ##### 05:22:18 INFO - Running pre-action listener: _resource_record_pre_action 05:22:18 INFO - Running main action method: install 05:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:18 INFO - Reading from file tmpfile_stdout 05:22:18 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:22:18 INFO - Reading from file tmpfile_stdout 05:22:18 INFO - Output received: 05:22:18 INFO - Usage: mozinstall [options] installer 05:22:18 INFO - Options: 05:22:18 INFO - -h, --help show this help message and exit 05:22:18 INFO - -d DEST, --destination=DEST 05:22:18 INFO - Directory to install application into. [default: 05:22:18 INFO - "/builds/slave/test"] 05:22:18 INFO - --app=APP Application being installed. [default: firefox] 05:22:18 INFO - mkdir: /builds/slave/test/build/application 05:22:18 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'] 05:22:18 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 05:22:35 INFO - Reading from file tmpfile_stdout 05:22:35 INFO - Output received: 05:22:35 INFO - /builds/slave/test/build/application/firefox/firefox 05:22:35 INFO - Running post-action listener: _resource_record_post_action 05:22:35 INFO - ##### 05:22:35 INFO - ##### Running run-tests step. 05:22:35 INFO - ##### 05:22:35 INFO - Running pre-action listener: _resource_record_pre_action 05:22:35 INFO - Running main action method: run_tests 05:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:35 INFO - Python 2.7.3 05:22:35 INFO - Return code: 0 05:22:35 INFO - grabbing minidump binary from tooltool 05:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:35 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 0x20bf810>, '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 0x20c0710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c0ba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:35 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 05:22:35 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 05:22:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:35 INFO - Return code: 0 05:22:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:22:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:22:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:22:36 INFO - Return code: 0 05:22:36 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/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 05:22:36 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/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 05:22:36 INFO - Using env: {'DISPLAY': ':0', 05:22:36 INFO - 'HOME': '/home/cltbld', 05:22:36 INFO - 'LANG': 'en_US.UTF-8', 05:22:36 INFO - 'LANGUAGE': 'en_US:en', 05:22:36 INFO - 'LOGNAME': 'cltbld', 05:22:36 INFO - 'MAIL': '/var/mail/cltbld', 05:22:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:36 INFO - 'MOZ_NO_REMOTE': '1', 05:22:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:36 INFO - 'NO_EM_RESTART': '1', 05:22:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:36 INFO - 'PWD': '/builds/slave/test', 05:22:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:36 INFO - 'SHELL': '/bin/bash', 05:22:36 INFO - 'SHLVL': '1', 05:22:36 INFO - 'TERM': 'linux', 05:22:36 INFO - 'TMOUT': '86400', 05:22:36 INFO - 'USER': 'cltbld', 05:22:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165', 05:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:36 INFO - '_': '/tools/buildbot/bin/python'} 05:22:36 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/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 05:22:37 INFO - mozversion application_buildid: 20160429023013 05:22:37 INFO - mozversion application_changeset: 70b3e6ac383941005652196f432d9134dea468a7 05:22:37 INFO - mozversion application_display_name: Nightly 05:22:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:37 INFO - mozversion application_name: Firefox 05:22:37 INFO - mozversion application_remotingname: firefox 05:22:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:22:37 INFO - mozversion application_vendor: Mozilla 05:22:37 INFO - mozversion application_version: 49.0a1 05:22:37 INFO - mozversion platform_buildid: 20160429023013 05:22:37 INFO - mozversion platform_changeset: 70b3e6ac383941005652196f432d9134dea468a7 05:22:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:22:37 INFO - mozversion platform_version: 49.0a1 05:22:37 INFO - using testdate: 1461932557 05:22:37 INFO - actual date: 1461932557 05:22:37 INFO - starting webserver on 'localhost:49736' 05:22:37 INFO - SUITE-START | Running 2 tests 05:22:37 INFO - TEST-START | dromaeo_css 05:22:37 INFO - operating with platform_type : linux_ 05:22:37 INFO - Initialising browser for dromaeo_css test... 05:22:37 INFO - TEST-INFO | started process 10960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9dBhv/profile http://localhost:49736/getInfo.html) 05:22:41 INFO - PROCESS | 10960 | __metrics Screen width/height:1600/1200 05:22:41 INFO - PROCESS | 10960 | colorDepth:24 05:22:41 INFO - PROCESS | 10960 | Browser inner width/height: 1024/697 05:22:41 INFO - PROCESS | 10960 | __metrics 05:22:42 ERROR - PROCESS | 10960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:22:42 INFO - TEST-INFO | 10960: exit 0 05:22:42 INFO - Browser initialized. 05:22:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:42 INFO - TEST-INFO | started process 11041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9dBhv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:54 INFO - PROCESS | 11041 | RSS: Main: 154214400 05:22:54 INFO - PROCESS | 11041 | 05:23:45 INFO - PROCESS | 11041 | 1461932625580 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] 05:25:53 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49736/tests/dromaeo/cssquery-ext.html) 05:25:54 INFO - PROCESS | 11041 | RSS: Main: 252928000 05:25:54 INFO - PROCESS | 11041 | 05:28:56 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-ext.html (next: http://localhost:49736/tests/dromaeo/cssquery-jquery.html) 05:28:56 INFO - PROCESS | 11041 | RSS: Main: 230682624 05:28:56 INFO - PROCESS | 11041 | 05:31:58 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49736/tests/dromaeo/cssquery-mootools.html) 05:31:59 INFO - PROCESS | 11041 | RSS: Main: 239456256 05:31:59 INFO - PROCESS | 11041 | 05:35:01 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49736/tests/dromaeo/cssquery-prototype.html) 05:35:02 INFO - PROCESS | 11041 | RSS: Main: 237355008 05:35:02 INFO - PROCESS | 11041 | 05:38:04 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49736/tests/dromaeo/cssquery-yui.html) 05:38:05 INFO - PROCESS | 11041 | RSS: Main: 225574912 05:38:05 INFO - PROCESS | 11041 | 05:41:07 INFO - PROCESS | 11041 | Cycle 1(1): loaded http://localhost:49736/tests/dromaeo/cssquery-yui.html (next: http://localhost:49736/tests/dromaeo/cssquery-dojo.html) 05:41:08 INFO - PROCESS | 11041 | RSS: Main: 281583616 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | __start_tp_report 05:41:08 INFO - PROCESS | 11041 | _x_x_mozilla_page_load 05:41:08 INFO - PROCESS | 11041 | _x_x_mozilla_page_load_details 05:41:08 INFO - PROCESS | 11041 | |i|pagename|runs| 05:41:08 INFO - PROCESS | 11041 | |0;dojo.html;3100.1474594486517;3130.0453361724667;3165.050484854543;3169.2393825481877;3201.823899685518;5181.559567436772;5229.973850130754;5243.4756524347595;5267.315249017623;5287.841364759059;5242.161254199329;5252.894942101156;5305.602079844008;5376.327959005124;5392.892142157155;4720.268358404453;4834.854954351366;4901.387326584177;4916.803327866881;4943.134951383511;4860.222364421693;4878.317035615017;4897.314375987365;4929.383827021622;4990.825321113762;4700.083483720676;4840.903181936359;4867.415910090776;4899.412070551521;4925.950740492585;4809.350737650408;4818.819389249621;4821.710697358135;4833.661643684944;4864.173090574611;4626.444826620794;4795.2807078938085;4811.8075276988875;4817.205161148407;4853.077915196137;5119.129747942859;5154.53609175176;5165.405978312505;5185.818496352609;5219.973900130513;5586.050371436825;5603.523700485463;5611.607187496877;5627.296587926509;5635.042042852725;5550.972245138789;5655.886882262332;5669.092945128801;5673.404292549264;5708.971455142698;5255.86860328491;5260.9736951315;5266.973665131688;5299.046171689094;5300.0459917214885;5676.290463692038;5685.658860468385;5700.743466544015;5709.3434255060365;5710.571707121982;5364.034473794754;5464.508194262536;5496;5550.278463799761;5612.242347283146;5638.492535671728;5640.492355687926;5661.915071273934;5662.320521537359;5681.488666019995;5603.215549823028;5619.8595035123635;5621.15682647598;5633.070543360382;5636.267285252891;5479.698616576047;5611.663300201919;5673.461021202979;5734.283214598176;5758.481736643722;4187.392828039917;4190.455240818735;4190.476190476191;4193.958060419418;4243.851465198765;3824.6557809796695;3832.0994566276904;3841.3853783394525;3841.750286231386;3854.3447613905705;5139.434662187156;5194.818181363634;5237.240600112962;5298.311219541435;5302.734863256822;5685.204071430003;5687.544996400361;5687.77248910039;5693.373728889819;5706.258186435737;5357.8392648220615;5376.596755243372;5377.973110134424;5386.784528618889;5427.158790387496;4632.884177895579;4768.427788665313;4810.374651295379;4836.806527738851;4857.271409288564;5706.9429305707245;5727.226824378656;5729.799457018984;5733.397993210653;5736.196932429463;5530.834074977757;5535.308086489189;5537.584681148849;5549.8335049947755;5585.469380408855;5830.2420685310635;5873.882522349614;5884.440978107073;5931.169636251015;5939.227900372924;5558.333000040021;5564.109742441352;5571.470710282517;5573.191887176417;5574.832755017356;5565.749541270571;5587.916181257203;5648.322201335702;5657.12314591239;5661.547076233974;5485.61600687948;5486.588505862156;5486.945130548644;5490.286262785812;5530.225768392348;5461.399246083007;5461.918071228855;5464.3442786865835;5468.070428027166;5470.617056805985;5325.201219817059;5388.919166212589;5395.86510337229;5431.103867861917;5460.535854452416;5437.048516509516;5445.101558242845;5450.945490545044;5457.317835270591;5548.0845660465575;5303.098473259478;5329.20061990689;5333.066713325075;5333.626646134889;5347.411784703757 05:41:08 INFO - PROCESS | 11041 | |1;ext.html;600.2197143713172;630.9652170440476;638.6295948349957;656.3207080671506;659.8185498987781;31936.20159496011;37673.24653506928;38392;38677.806610966974;38686.613133868654;40353.798231008805;41038.17923641526;41184;41244.793776031154;41262.793686031604;7689.961550192241;8492.065872753992;8739.563021848913;8746.518941458216;9931.553080111376;10067.446290454021;10237.436940968244;10242;10253.794924101514;10259.07668309852;10177.08406243438;10302.278840481169;10311.226658000642;10315.690529284133;10322.071013608775;7668.156502784717;7868.213178682143;9866.703998880044;10090.9495452523;10143.391396516232;9841.409515429097;9978.501074946224;9979.401235925867;9990.100890919852;9994.050565196294;42240;42572.78713606443;42917.141657166685;42929.785351073355;42975.570244297785;3449.5688038995127;3476.4959080933377;3483.4078206704667;3526.0303416560396;3565.3938830398633;32533.51199732006;32791.344173116646;32895.6710432895;33051.83474082638;33197.83401083003;4250.14997000598;4362.781860906942;4396.856133956036;4400.251957167289;4408.426904502394;338270.30864845763;339171;340113;341158;341397;59871.40128598746;60403.395966040654;60429.697851510464;60488.697556512816;60490.39509604847;58131.41868581345;59008;59028;59423.70288148532;59489.10766338508;17651.55871103233;17824.287028518713;17942.37201697934;17961.7305740414;17992.730109048374;13621.387037583332;13683.179009259477;13693.931530342285;13701.931490342484;13705.862941370659;20358.18567257323;20369.287074952306;20630.277940272317;20668;20678.518577770465;5919.230500034968;7184.659133502946;7229.783106506813;7395.223501532261;7545.358644515278;1043.7860238651058;1052.0967211644884;1066.317556763672;1126.0147371050332;1161.7502237019082;32102.35795284128;32111.197220069684;32405.351892962008;32460.513092303634;34465;1120.3389999900098;1130.3726431830248;1167.9065674746;1174.7944109780753;1221.9083568732203;18025.369112081014;18057.187426565837;18059.813270757455;18109.36617218391;18321.17554710041;536121;543463;546351;547832;548489;59530.404695952486;61234.38765612287;61296.080558791655;61353.69323153356;61622.69188654028;1381.412899517701;1381.6338670252392;1429.2639290765264;1433.598592394152;1501.3319073012624;31944.52083218707;33634.327313453105;33756.83121584376;33781.83109084439;34257.657423425444;53639.463605363446;53726.194107089206;53749.46250537601;54038.189427157835;54147;426264;426775;427899;429271;432512;24135.75864241335;26536.601950971137;27135.914563417242;29369.7063029367;29703.702962970958;43938.34092488679;44591.33113003372;45141.774291129645;45241.773791130836;45309.320360195285;37448.438273426465;38617.42073868951;40068;40533.39199912063;42183.7890810544;41064.58935410727;41201.587984120975;41342;42590.148197036506;42620.57379426166;41496.58503414927;42375;42497;42602;42670.78664606781;34741.305173896886;36232.63767362293;36617;36659.98690502211;37284;28358.291042724097;28608.57087143736;28635.570466442605;28652;28658.426831462835 05:41:08 INFO - PROCESS | 11041 | |2;jquery.html;379.2017802525683;381.8396273565102;382.55050315878844;385.7974563354237;391.21366054230987;14585.999999999973;15016.474423395184;17625.54936420914;18357.816421835778;18957.14692838822;15810.4466343678;17856.464306070808;18524.351647692332;18626.627467450642;19216.807831921677;5517.779288828452;5659.915101273484;5687.829365119053;5713.314402271733;5714.71426428679;4069.776162311072;4262.7655478948645;4279.40088387626;4282.764447955376;5671.291088613923;6005.729742161591;6290.622562646233;6299;6300.968495157518;6301.432871041606;5881.823545293648;6233.688315584248;6241;6244.656543890062;6248.250209974832;5171.63798534099;5432.483914028161;5448;5451.454854514556;5459.727013649302;12780.424880880382;13123;13341.466341346335;13375.26436046017;13380.665483362995;2112.0812446585783;2250.6061439248065;2268.977310226893;2275.328778010491;2387.0929046962215;9263.25893928484;9820.165285950703;9974.850377244347;9981.950090249575;10095.495225238783;1922.5866438715743;1953.687410014391;1974.6544354738007;2020.5150763816725;2034.8270397016272;78037;81974.9999999994;82937.58531207366;83663.5816820912;84014.57992710058;10887.564497420015;11001.449927503752;11003.504842281956;11156.219064665558;11203.60787372438;15487;15598.220088995506;15614.3754249831;15627.531174064796;15641;7991.200879911962;8145.18990948122;8328.167183281623;8366.916330836735;8450.957745211357;8041.7989550260545;8076.83846323082;8168.632411541495;8207.589620518951;8256.628451719567;4664.207084795593;4715.622750179978;4756.7145971241835;4761.880952976203;4859;1910.9235630574622;1965.5970526042126;1985.9503512412305;2001.769796473395;2041.8162365387186;898.7977705016319;985.4727720669407;1000.6097621927454;1048.4128887822844;1051.148569658579;17924.13213885772;18667.346642867433;18976.240950362106;19106.426807195807;19244.326448574233;972.1202311907734;987.7036888933292;993.6969224386631;1008.3042700536606;1024.2522958240527;10125.240606954509;10201.693949181536;10676.412797296069;10737.194710396752;10992.340459572451;463736;474203;479940.45699258056;482748;484457;92550;94159;94782.52608736911;96081.51959240298;96451.51774241224;450.75659144061837;471.6132771127711;1122.6407549584055;1148.3339662995443;1151.2689442204094;10432.947835260775;10530.420826854597;10866.293690910066;10958.342499450058;11545.538178472767;35708.10729731674;37181.442278365306;38793.418098727976;40428.393574097;40458.39312410257;54542.45457545374;56335.43664563466;57595;58079;58094.12858807206;8251.33989280868;8526.48841069513;8660.09069047741;8768.95615521944;8866;11739.647810565355;12912.741745164856;12935.935320323715;13075.346232688138;13088.411021504246;11419.086473081967;11479.540818367172;11540.307581545134;11840.348780817138;11899.107566932342;6048.546359023179;9510.191633710936;11608.535658573563;11770.705732356759;11897.702557436134;11997.40012999364;12033.75932481328;12162.209456385306;12261.141720079866;12384.380780960735;8828.470291782514;8881.555922203994;8890.511021893857;8918.554072296489;9089.954550227207;9233.353665243369;12069.456874440495;12167.574134905235;12182.086343524094;12302.75394492123 05:41:08 INFO - PROCESS | 11041 | |3;mootools.html;1343.5902049874785;1478.7707905274679;1484.6288427893023;1644.391575117206;1798.694221982263;7973.401994850387;8311.168883111686;8321;8324.417290789646;8330.333573314136;8217.794555136124;8455.027671817743;8460;8483.109273526283;8490.278326342268;2019.636465436221;2064.876107433559;2071.3682326890294;2274.40865375002;2317.9536409271805;2194.2868567715477;2269.024319542596;2269.069681430614;2273.27255278311;2274.3404412720292;2229.687843701883;2241.5628952354296;2247.584196923566;2250.077468238023;2287.931362059141;1970.6945423459244;2229.8550594211324;2253.8196944244505;2262.9660555091687;2265.026038803322;2270;2286.2341115726254;2291.415688999311;2292.3925159832656;2297.781710737477;8110.513369197867;8130.512169269863;8164.795880102985;8176.75469735903;8188.7133950312045;4809.903801923977;4998.425181104183;5015.247712843066;5022.648414610979;5027.572656324217;3136.294333774905;3255.739540836751;3416.8120753358558;3434.3303055904107;3442.5008373785913;1134.534840715311;1143.4854315558032;1156.4333476596491;1160.0777381981345;1163.5520324674976;11601.535938562447;11647.941760291229;11652.242604230698;11658.708532286675;11682.299062056303;4489.528599497071;4611.9077618447445;4654.6741728078705;4683;4692.882677933011;9794;9823.508824558743;9848.556814943344;9857.40855548655;9863.358881672668;5218.739063046833;5225.555827754683;5241.895162096813;5248.05535003703;5268.657537260143;4306.956930430718;4607.9308810367875;4673.345731597579;4739.170645137087;4819.084373968934;3885.378339465672;3916.2950668879307;3982.6017398260524;3988.80055997199;4003.659688926474;1226.6810629236463;1247.5695884919687;1250.5435516036582;1254.6424269083268;1264.5700461842835;833.8499070167428;897.1118592593285;983.311681822727;988;1005.3666190300211;5752.482276595127;5785.1611516329895;5856.502197313276;5956.940430595725;6032.155498230251;418.4204876246421;424.74090804609165;431.2237971651016;440.1636889909198;443.72045611264696;4083.632473077437;4107.856225032109;4131.586841315844;4134.91730165401;4155.605217504332;9937.65218217359;10406.271560990657;10419.843702344471;10567.788644227072;10582.100521455608;10245.692629221136;10390.220733445021;10433.21750868688;10445.321054131457;10453.21600879937;7902.4863383879865;7975.601219939096;7975.960120199362;8011.519308841488;8056.879146812803;5802.912956305743;5843.094320380348;5902.291724993029;6017.037274036134;6047.425494578008;9417.199538039069;9688.370255933341;9813.362131461432;9841.409515429097;9916.256280779115;9688.12806847387;9767.511624418892;9817.116459518678;9836;9884.901150988399;6760.08738820253;7200.531965422231;7308.415326773739;7386.335229829341;7393.8151546211775;8070.878936815835;8077.151899050515;8125.3905957052275;8138;8156.510609363457;7087.114110736158;7264.382527485223;7316.743913962986;7355.374793142642;7423.777286681408;6956.304369563003;7059.647017649199;7100.822479437848;7127.714891404493;7341.155767086853;6672.633005184762;6770.424513916372;6778.593284402951;6780.86438271222;6784.185897692111;4897.608191344612;4917.754112294442;4964.3298154748645;4968.105740966516;4996.625927869864;4960.206366981266;5172.172452407597;5227.0591293566;5293.550048245942;5347.0375332440535 05:41:08 INFO - PROCESS | 11041 | |4;prototype.html;316.8289123873109;318.442725230846;320.42483741683674;321.0448914479424;322.806316210274;5308.761105750241;5318.175682769172;5350.5719542436655;5368.731563421823;5398.433164517728;5277.70972596507;5278.181881808317;5280.762365693543;5291.518374855037;5299.046171689094;3972.423998520213;4029.2948733971584;4123.855665051724;4155.979220103895;4180.456540649711;4150.750954942713;4158.189153115129;4179.644730197937;4197.958020419803;4199.6220340169375;3907.7069219808486;4151.750894946298;4156.334986402177;4156.97921510392;4165.854195103172;3995.9400608990886;4140.23405669952;4155.148194620096;4155.335146376596;4162.791860406998;3647.8358473868475;3648.2703459308245;3656.579493358271;3658.506101676266;3664.59689434162;5156.8968620627375;5168.121419358718;5171.974140129313;5193.818216362447;5197.1424714922;4497.325401189848;4712.198926182557;4714.7642617869315;4726.810927562893;4729.503402142795;5556.611037227396;5580.693061881595;5587.664740115606;5594.384617692051;5598.3282006159525;3920.5298013245033;3937.606239376068;3952.0910190656023;3959.3862951242604;3967.444557761916;5536.833894983157;5777.786664800439;5808.767649294024;5823.67969761663;5832;3993.0017495626093;4001.6198461146146;4007.519097708235;4020.2763502569233;4025.295573274665;4062.2687916175373;4073.592640735962;4086.1010577672864;4111.259973204792;4111.321631930698;4573.451185857718;4634.675572709878;4654.301854721819;4699.412573428322;4716.316134160596;5493;5560.888782224333;5601.663900165921;5601.831945041656;5645.971770141123;1460.1531111955042;1461.291273732239;1462.7878957551095;1463.6826855829838;1472.8527147285185;881.5371929736812;928.9163975242261;948.9051094890593;957.9042095790504;970.1850445625709;5655.717214139277;5706.686132262766;5731.254936858265;5741.741621627037;5742.253507044057;6151.5386346024225;6152.477039451696;6162.630242185483;6163.3528479509905;6168.228971378578;5652.85867853307;5710.571707121982;5757.85605359861;5764.769409223584;5776.624519406225;3230.5800245967876;3253.398121347511;3260.266440051017;3265.7387409007224;3268.6731326867125;6182.350853160443;6273.059041143773;6312.810615681537;6318.609905820712;6337.6197428154455;6218.968905155536;6225.533085018642;6229.470495007975;6243.25080990275;6259;6036.189143257093;6270.968645156745;6271.404216599416;6294.559380843297;6305.905411418832;6022.126791615104;6042.667653279112;6061.363556826646;6111.882939700873;6115.785947491817;5922.92697555852;5971.611845230046;5975.16347711312;5979.189780803015;6032.819015429544;6060.090986352083;6238.563300568917;6313.179286692701;6344.555881088279;6348;4180.540140584594;4196.160767846504;4220.303649897733;4233.449651545279;4240.89397765046;5923.229980102559;6123.142760013513;6135.662538560244;6148.907766383597;6149.907751383822;5861.824145275648;5868.23712917318;5940.287165540163;5953.315368732651;5968.552358573212;5884.264466941632;5915.40845915405;5923.318818335774;5923.407659234214;5934;5377.870647164139;5530.253415788978;5566.715637773733;5813.505852002627;5835.299764028344;5639.859003524781;5652.85191627179;5683.7442315095095;5686.459786320293;5695.572832037697;5438;5509.677677357486;5570.721463926707;5573.832785016456;5599.776008959759 05:41:08 INFO - PROCESS | 11041 | |5;yui.html;855.183299948549;880.7577916073075;884.4030279561299;895.3285036222833;914.3599480363747;14381;14805.185714785683;14821;14834.332455039523;14837.62905927351;13532.255725935069;13787.310634468262;13927.094738841968;14009.789853152211;14015.089019213743;710.140729717043;841.381584535366;852.26705033671;857.7341024282462;857.8069934264803;715.8675645005665;716.090564982472;716.4018044932487;818.6970820796314;846.3144852669327;864.6671031652827;881.0220655072864;881.3478026260585;882.0165515450259;884.38093334666;859.7420773767876;874.567089290802;876.8684697295392;877.4384393987852;877.4384393987852;537.8225185688752;630.1241274628225;630.2814791138106;634.6097150252562;640.3308542573022;13336.799948000787;13339;13345.532906348328;13375.665608359772;13396.196228226338;13804.17174969505;14847.703045939128;14882.10707357562;14887.10677359362;15017.098974061591;11734.53061877524;11793.702692703795;11936.462859171272;11937.045036397067;12035.819462708067;3846.9230615387814;3925.646691797723;4000.6799456043736;4019.437278780973;4057.452243947059;53429.46570534284;53727.731361343336;54002;54067;54809.72595137039;10282.228832837567;10512.894871051345;10556.63051793199;10619.787604247871;10736.570537178426;18345.90827045883;18542.536436589195;18543.81456185448;18974.241030358906;18988;8083.272505474535;8124.146964568635;8151.021877374753;8170.223828736261;8375.874361884577;4580.7938642761155;4633.351330813689;4720.551547602972;4764.666473346832;4799.496052914394;3206.2625596113025;3282.917927051843;3638.599754027055;3773.3585290500682;3781.1681430085337;674.3795707948699;683.9521233513607;690.2062627977765;691.2327316678483;715.8353578676933;666.6400143922227;758.5145506875604;758.6623952341155;765.2041876448432;769.6036541181296;8567.448455013777;8723.563821809012;8756.781080472912;8779.868301975475;8998.14517620825;740.5223630758238;747.5066456139031;756.5763172623349;764.6291548598925;771.7028943856656;4427.911441771146;4471.35165401015;4560.635149188123;4568.771561421916;4603;64762.676186618766;67554;68187.65906170537;68289;71522.64238678878;53622.195667065025;54918.725406372716;55968.44031559714;56817.71591142018;59476.40523594709;52889;53766.19350709742;53806.92386152333;54092;54474.18288725672;9244.214241789523;9306.302027347841;9334.673286434941;9449;9461;34998.12504687403;35017.124571885906;35588.466173006906;35640;35935.28129437449;43783;47334.76332618315;47385.52614473949;47884.760576196895;48624;11215.214934954802;11330.830037549607;11352.886471135513;11462.369569673749;11627.360495172847;12960.481580737041;13217.14088584239;13613.319334033456;13722.588322349946;14117.505887294306;12075.637730868088;12416.503339866304;12543.372831358578;12627.431765570387;12827.615171544869;12702.491900324254;12744.299063551594;13051;13583.388747506584;13602.455901763818;11793.29240245588;11964.940175299416;12358.691032723895;12760.74478510406;12831;10657.413842238751;10700.197485188797;10877.51051202682;11065.668029959113;11418.657440276806;10300.742481438025;10356.955773404115;10445;10504.579816807242;10530.157587393143 05:41:08 INFO - PROCESS | 11041 | __end_tp_report 05:41:08 INFO - PROCESS | 11041 | __start_cc_report 05:41:08 INFO - PROCESS | 11041 | _x_x_mozilla_cycle_collect,807 05:41:08 INFO - PROCESS | 11041 | __end_cc_report 05:41:08 INFO - PROCESS | 11041 | __startTimestamp1461933668158__endTimestamp 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | ------- Summary: start ------- 05:41:08 INFO - PROCESS | 11041 | Number of tests: 6 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#0] dojo.html Cycles:165 Average:5225.04 Median:5395.87 stddev:578.50 (10.7%) stddev-sans-first:555.74 05:41:08 INFO - PROCESS | 11041 | Values: 3100.1 3130.0 3165.1 3169.2 3201.8 5181.6 5230.0 5243.5 5267.3 5287.8 5242.2 5252.9 5305.6 5376.3 5392.9 4720.3 4834.9 4901.4 4916.8 4943.1 4860.2 4878.3 4897.3 4929.4 4990.8 4700.1 4840.9 4867.4 4899.4 4926.0 4809.4 4818.8 4821.7 4833.7 4864.2 4626.4 4795.3 4811.8 4817.2 4853.1 5119.1 5154.5 5165.4 5185.8 5220.0 5586.1 5603.5 5611.6 5627.3 5635.0 5551.0 5655.9 5669.1 5673.4 5709.0 5255.9 5261.0 5267.0 5299.0 5300.0 5676.3 5685.7 5700.7 5709.3 5710.6 5364.0 5464.5 5496.0 5550.3 5612.2 5638.5 5640.5 5661.9 5662.3 5681.5 5603.2 5619.9 5621.2 5633.1 5636.3 5479.7 5611.7 5673.5 5734.3 5758.5 4187.4 4190.5 4190.5 4194.0 4243.9 3824.7 3832.1 3841.4 3841.8 3854.3 5139.4 5194.8 5237.2 5298.3 5302.7 5685.2 5687.5 5687.8 5693.4 5706.3 5357.8 5376.6 5378.0 5386.8 5427.2 4632.9 4768.4 4810.4 4836.8 4857.3 5706.9 5727.2 5729.8 5733.4 5736.2 5530.8 5535.3 5537.6 5549.8 5585.5 5830.2 5873.9 5884.4 5931.2 5939.2 5558.3 5564.1 5571.5 5573.2 5574.8 5565.7 5587.9 5648.3 5657.1 5661.5 5485.6 5486.6 5486.9 5490.3 5530.2 5461.4 5461.9 5464.3 5468.1 5470.6 5325.2 5388.9 5395.9 5431.1 5460.5 5437.0 5445.1 5450.9 5457.3 5548.1 5303.1 5329.2 5333.1 5333.6 5347.4 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#1] ext.html Cycles:180 Average:60162.57 Median:38504.71 stddev:118080.69 (306.7%) stddev-sans-first:118327.25 05:41:08 INFO - PROCESS | 11041 | Values: 600.2 631.0 638.6 656.3 659.8 31936.2 37673.2 38392.0 38677.8 38686.6 40353.8 41038.2 41184.0 41244.8 41262.8 7690.0 8492.1 8739.6 8746.5 9931.6 10067.4 10237.4 10242.0 10253.8 10259.1 10177.1 10302.3 10311.2 10315.7 10322.1 7668.2 7868.2 9866.7 10090.9 10143.4 9841.4 9978.5 9979.4 9990.1 9994.1 42240.0 42572.8 42917.1 42929.8 42975.6 3449.6 3476.5 3483.4 3526.0 3565.4 32533.5 32791.3 32895.7 33051.8 33197.8 4250.1 4362.8 4396.9 4400.3 4408.4 338270.3 339171.0 340113.0 341158.0 341397.0 59871.4 60403.4 60429.7 60488.7 60490.4 58131.4 59008.0 59028.0 59423.7 59489.1 17651.6 17824.3 17942.4 17961.7 17992.7 13621.4 13683.2 13693.9 13701.9 13705.9 20358.2 20369.3 20630.3 20668.0 20678.5 5919.2 7184.7 7229.8 7395.2 7545.4 1043.8 1052.1 1066.3 1126.0 1161.8 32102.4 32111.2 32405.4 32460.5 34465.0 1120.3 1130.4 1167.9 1174.8 1221.9 18025.4 18057.2 18059.8 18109.4 18321.2 536121.0 543463.0 546351.0 547832.0 548489.0 59530.4 61234.4 61296.1 61353.7 61622.7 1381.4 1381.6 1429.3 1433.6 1501.3 31944.5 33634.3 33756.8 33781.8 34257.7 53639.5 53726.2 53749.5 54038.2 54147.0 426264.0 426775.0 427899.0 429271.0 432512.0 24135.8 26536.6 27135.9 29369.7 29703.7 43938.3 44591.3 45141.8 45241.8 45309.3 37448.4 38617.4 40068.0 40533.4 42183.8 41064.6 41201.6 41342.0 42590.1 42620.6 41496.6 42375.0 42497.0 42602.0 42670.8 34741.3 36232.6 36617.0 36660.0 37284.0 28358.3 28608.6 28635.6 28652.0 28658.4 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#2] jquery.html Cycles:180 Average:28017.32 Median:384.17 stddev:78892.66 (20535.7%) stddev-sans-first:79086.68 05:41:08 INFO - PROCESS | 11041 | Values: 379.2 381.8 382.6 385.8 391.2 14586.0 15016.5 17625.5 18357.8 18957.1 15810.4 17856.5 18524.4 18626.6 19216.8 5517.8 5659.9 5687.8 5713.3 5714.7 4069.8 4262.8 4279.4 4282.8 5671.3 6005.7 6290.6 6299.0 6301.0 6301.4 5881.8 6233.7 6241.0 6244.7 6248.3 5171.6 5432.5 5448.0 5451.5 5459.7 12780.4 13123.0 13341.5 13375.3 13380.7 2112.1 2250.6 2269.0 2275.3 2387.1 9263.3 9820.2 9974.9 9982.0 10095.5 1922.6 1953.7 1974.7 2020.5 2034.8 78037.0 81975.0 82937.6 83663.6 84014.6 10887.6 11001.4 11003.5 11156.2 11203.6 15487.0 15598.2 15614.4 15627.5 15641.0 7991.2 8145.2 8328.2 8366.9 8451.0 8041.8 8076.8 8168.6 8207.6 8256.6 4664.2 4715.6 4756.7 4761.9 4859.0 1910.9 1965.6 1986.0 2001.8 2041.8 898.8 985.5 1000.6 1048.4 1051.1 17924.1 18667.3 18976.2 19106.4 19244.3 972.1 987.7 993.7 1008.3 1024.3 10125.2 10201.7 10676.4 10737.2 10992.3 463736.0 474203.0 479940.5 482748.0 484457.0 92550.0 94159.0 94782.5 96081.5 96451.5 450.8 471.6 1122.6 1148.3 1151.3 10432.9 10530.4 10866.3 10958.3 11545.5 35708.1 37181.4 38793.4 40428.4 40458.4 54542.5 56335.4 57595.0 58079.0 58094.1 8251.3 8526.5 8660.1 8769.0 8866.0 11739.6 12912.7 12935.9 13075.3 13088.4 11419.1 11479.5 11540.3 11840.3 11899.1 6048.5 9510.2 11608.5 11770.7 11897.7 11997.4 12033.8 12162.2 12261.1 12384.4 8828.5 8881.6 8890.5 8918.6 9090.0 9233.4 12069.5 12167.6 12182.1 12302.8 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#3] mootools.html Cycles:180 Average:5566.52 Median:4982.37 stddev:3134.08 (62.9%) stddev-sans-first:3126.80 05:41:08 INFO - PROCESS | 11041 | Values: 1343.6 1478.8 1484.6 1644.4 1798.7 7973.4 8311.2 8321.0 8324.4 8330.3 8217.8 8455.0 8460.0 8483.1 8490.3 2019.6 2064.9 2071.4 2274.4 2318.0 2194.3 2269.0 2269.1 2273.3 2274.3 2229.7 2241.6 2247.6 2250.1 2287.9 1970.7 2229.9 2253.8 2263.0 2265.0 2270.0 2286.2 2291.4 2292.4 2297.8 8110.5 8130.5 8164.8 8176.8 8188.7 4809.9 4998.4 5015.2 5022.6 5027.6 3136.3 3255.7 3416.8 3434.3 3442.5 1134.5 1143.5 1156.4 1160.1 1163.6 11601.5 11647.9 11652.2 11658.7 11682.3 4489.5 4611.9 4654.7 4683.0 4692.9 9794.0 9823.5 9848.6 9857.4 9863.4 5218.7 5225.6 5241.9 5248.1 5268.7 4307.0 4607.9 4673.3 4739.2 4819.1 3885.4 3916.3 3982.6 3988.8 4003.7 1226.7 1247.6 1250.5 1254.6 1264.6 833.8 897.1 983.3 988.0 1005.4 5752.5 5785.2 5856.5 5956.9 6032.2 418.4 424.7 431.2 440.2 443.7 4083.6 4107.9 4131.6 4134.9 4155.6 9937.7 10406.3 10419.8 10567.8 10582.1 10245.7 10390.2 10433.2 10445.3 10453.2 7902.5 7975.6 7976.0 8011.5 8056.9 5802.9 5843.1 5902.3 6017.0 6047.4 9417.2 9688.4 9813.4 9841.4 9916.3 9688.1 9767.5 9817.1 9836.0 9884.9 6760.1 7200.5 7308.4 7386.3 7393.8 8070.9 8077.2 8125.4 8138.0 8156.5 7087.1 7264.4 7316.7 7355.4 7423.8 6956.3 7059.6 7100.8 7127.7 7341.2 6672.6 6770.4 6778.6 6780.9 6784.2 4897.6 4917.8 4964.3 4968.1 4996.6 4960.2 5172.2 5227.1 5293.6 5347.0 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#4] prototype.html Cycles:180 Average:4830.35 Median:5546.72 stddev:1475.73 (26.6%) stddev-sans-first:1440.46 05:41:08 INFO - PROCESS | 11041 | Values: 316.8 318.4 320.4 321.0 322.8 5308.8 5318.2 5350.6 5368.7 5398.4 5277.7 5278.2 5280.8 5291.5 5299.0 3972.4 4029.3 4123.9 4156.0 4180.5 4150.8 4158.2 4179.6 4198.0 4199.6 3907.7 4151.8 4156.3 4157.0 4165.9 3995.9 4140.2 4155.1 4155.3 4162.8 3647.8 3648.3 3656.6 3658.5 3664.6 5156.9 5168.1 5172.0 5193.8 5197.1 4497.3 4712.2 4714.8 4726.8 4729.5 5556.6 5580.7 5587.7 5594.4 5598.3 3920.5 3937.6 3952.1 3959.4 3967.4 5536.8 5777.8 5808.8 5823.7 5832.0 3993.0 4001.6 4007.5 4020.3 4025.3 4062.3 4073.6 4086.1 4111.3 4111.3 4573.5 4634.7 4654.3 4699.4 4716.3 5493.0 5560.9 5601.7 5601.8 5646.0 1460.2 1461.3 1462.8 1463.7 1472.9 881.5 928.9 948.9 957.9 970.2 5655.7 5706.7 5731.3 5741.7 5742.3 6151.5 6152.5 6162.6 6163.4 6168.2 5652.9 5710.6 5757.9 5764.8 5776.6 3230.6 3253.4 3260.3 3265.7 3268.7 6182.4 6273.1 6312.8 6318.6 6337.6 6219.0 6225.5 6229.5 6243.3 6259.0 6036.2 6271.0 6271.4 6294.6 6305.9 6022.1 6042.7 6061.4 6111.9 6115.8 5922.9 5971.6 5975.2 5979.2 6032.8 6060.1 6238.6 6313.2 6344.6 6348.0 4180.5 4196.2 4220.3 4233.4 4240.9 5923.2 6123.1 6135.7 6148.9 6149.9 5861.8 5868.2 5940.3 5953.3 5968.6 5884.3 5915.4 5923.3 5923.4 5934.0 5377.9 5530.3 5566.7 5813.5 5835.3 5639.9 5652.9 5683.7 5686.5 5695.6 5438.0 5509.7 5570.7 5573.8 5599.8 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - PROCESS | 11041 | [#5] yui.html Cycles:180 Average:15182.20 Median:4591.90 stddev:17933.24 (390.5%) stddev-sans-first:17951.27 05:41:08 INFO - PROCESS | 11041 | Values: 855.2 880.8 884.4 895.3 914.4 14381.0 14805.2 14821.0 14834.3 14837.6 13532.3 13787.3 13927.1 14009.8 14015.1 710.1 841.4 852.3 857.7 857.8 715.9 716.1 716.4 818.7 846.3 864.7 881.0 881.3 882.0 884.4 859.7 874.6 876.9 877.4 877.4 537.8 630.1 630.3 634.6 640.3 13336.8 13339.0 13345.5 13375.7 13396.2 13804.2 14847.7 14882.1 14887.1 15017.1 11734.5 11793.7 11936.5 11937.0 12035.8 3846.9 3925.6 4000.7 4019.4 4057.5 53429.5 53727.7 54002.0 54067.0 54809.7 10282.2 10512.9 10556.6 10619.8 10736.6 18345.9 18542.5 18543.8 18974.2 18988.0 8083.3 8124.1 8151.0 8170.2 8375.9 4580.8 4633.4 4720.6 4764.7 4799.5 3206.3 3282.9 3638.6 3773.4 3781.2 674.4 684.0 690.2 691.2 715.8 666.6 758.5 758.7 765.2 769.6 8567.4 8723.6 8756.8 8779.9 8998.1 740.5 747.5 756.6 764.6 771.7 4427.9 4471.4 4560.6 4568.8 4603.0 64762.7 67554.0 68187.7 68289.0 71522.6 53622.2 54918.7 55968.4 56817.7 59476.4 52889.0 53766.2 53806.9 54092.0 54474.2 9244.2 9306.3 9334.7 9449.0 9461.0 34998.1 35017.1 35588.5 35640.0 35935.3 43783.0 47334.8 47385.5 47884.8 48624.0 11215.2 11330.8 11352.9 11462.4 11627.4 12960.5 13217.1 13613.3 13722.6 14117.5 12075.6 12416.5 12543.4 12627.4 12827.6 12702.5 12744.3 13051.0 13583.4 13602.5 11793.3 11964.9 12358.7 12760.7 12831.0 10657.4 10700.2 10877.5 11065.7 11418.7 10300.7 10357.0 10445.0 10504.6 10530.2 05:41:08 INFO - PROCESS | 11041 | -------- Summary: end -------- 05:41:08 INFO - PROCESS | 11041 | 05:41:08 INFO - TEST-INFO | 11041: exit 0 05:41:08 INFO - TEST-OK | dromaeo_css | took 1111824ms 05:41:08 INFO - TEST-START | kraken 05:41:08 INFO - operating with platform_type : linux_ 05:41:08 INFO - Initialising browser for kraken test... 05:41:08 INFO - TEST-INFO | started process 11229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDF_8L/profile http://localhost:49736/getInfo.html) 05:41:12 INFO - PROCESS | 11229 | __metrics Screen width/height:1600/1200 05:41:12 INFO - PROCESS | 11229 | colorDepth:24 05:41:12 INFO - PROCESS | 11229 | Browser inner width/height: 1024/697 05:41:12 INFO - PROCESS | 11229 | __metrics 05:41:13 ERROR - PROCESS | 11229 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:41:14 INFO - TEST-INFO | 11229: exit 0 05:41:14 INFO - Browser initialized. 05:41:14 INFO - Running cycle 1/1 for kraken test... 05:41:14 INFO - TEST-INFO | started process 11308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDF_8L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:26 INFO - PROCESS | 11308 | RSS: Main: 154148864 05:41:26 INFO - PROCESS | 11308 | 05:41:28 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49736/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:29 INFO - PROCESS | 11308 | RSS: Main: 208998400 05:41:29 INFO - PROCESS | 11308 | 05:41:32 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49736/tests/kraken/driver.html?testName=audio-dft) 05:41:32 INFO - PROCESS | 11308 | RSS: Main: 194998272 05:41:32 INFO - PROCESS | 11308 | 05:41:36 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49736/tests/kraken/driver.html?testName=audio-fft) 05:41:36 INFO - PROCESS | 11308 | RSS: Main: 265744384 05:41:36 INFO - PROCESS | 11308 | 05:41:38 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49736/tests/kraken/driver.html?testName=audio-oscillator) 05:41:39 INFO - PROCESS | 11308 | RSS: Main: 173060096 05:41:39 INFO - PROCESS | 11308 | 05:41:41 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49736/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:42 INFO - PROCESS | 11308 | RSS: Main: 178380800 05:41:42 INFO - PROCESS | 11308 | 05:41:45 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49736/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:45 INFO - PROCESS | 11308 | RSS: Main: 214405120 05:41:45 INFO - PROCESS | 11308 | 05:41:49 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49736/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:49 INFO - PROCESS | 11308 | RSS: Main: 214831104 05:41:49 INFO - PROCESS | 11308 | 05:41:52 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49736/tests/kraken/driver.html?testName=json-parse-financial) 05:41:53 INFO - PROCESS | 11308 | RSS: Main: 214654976 05:41:53 INFO - PROCESS | 11308 | 05:41:54 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49736/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:55 INFO - PROCESS | 11308 | RSS: Main: 204193792 05:41:55 INFO - PROCESS | 11308 | 05:41:56 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:57 INFO - PROCESS | 11308 | RSS: Main: 203747328 05:41:57 INFO - PROCESS | 11308 | 05:41:59 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:00 INFO - PROCESS | 11308 | RSS: Main: 215560192 05:42:00 INFO - PROCESS | 11308 | 05:42:02 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:03 INFO - PROCESS | 11308 | RSS: Main: 224837632 05:42:03 INFO - PROCESS | 11308 | 05:42:06 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:06 INFO - PROCESS | 11308 | RSS: Main: 217657344 05:42:06 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | Cycle 1(1): loaded http://localhost:49736/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49736/tests/kraken/driver.html?testName=ai-astar) 05:42:08 INFO - PROCESS | 11308 | RSS: Main: 281268224 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | __start_tp_report 05:42:08 INFO - PROCESS | 11308 | _x_x_mozilla_page_load 05:42:08 INFO - PROCESS | 11308 | _x_x_mozilla_page_load_details 05:42:08 INFO - PROCESS | 11308 | |i|pagename|runs| 05:42:08 INFO - PROCESS | 11308 | |0;ai-astar;110;110;105;110;112;112;100;109;109;110 05:42:08 INFO - PROCESS | 11308 | |1;audio-beat-detection;150;125;152;180;133;157;160;129;185;133 05:42:08 INFO - PROCESS | 11308 | |2;audio-dft;160;160;161;161;160;160;159;161;160;160 05:42:08 INFO - PROCESS | 11308 | |3;audio-fft;72;75;122;109;76;72;76;127;105;77 05:42:08 INFO - PROCESS | 11308 | |4;audio-oscillator;94;94;151;94;92;93;149;93;93;162 05:42:08 INFO - PROCESS | 11308 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;116;116;116 05:42:08 INFO - PROCESS | 11308 | |6;imaging-darkroom;146;145;145;144;146;146;145;146;145;147 05:42:08 INFO - PROCESS | 11308 | |7;imaging-desaturate;94;95;96;94;94;94;95;96;95;95 05:42:08 INFO - PROCESS | 11308 | |8;json-parse-financial;58;56;58;56;56;58;58;72;55;55 05:42:08 INFO - PROCESS | 11308 | |9;json-stringify-tinderbox;62;62;61;62;62;62;63;62;62;64 05:42:08 INFO - PROCESS | 11308 | |10;stanford-crypto-aes;76;82;75;76;76;76;78;77;77;77 05:42:08 INFO - PROCESS | 11308 | |11;stanford-crypto-ccm;131;129;124;128;134;119;133;121;158;134 05:42:08 INFO - PROCESS | 11308 | |12;stanford-crypto-pbkdf2;166;163;166;166;164;164;165;164;165;169 05:42:08 INFO - PROCESS | 11308 | |13;stanford-crypto-sha256-iterative;69;68;67;67;69;67;67;68;67;66 05:42:08 INFO - PROCESS | 11308 | __end_tp_report 05:42:08 INFO - PROCESS | 11308 | __start_cc_report 05:42:08 INFO - PROCESS | 11308 | _x_x_mozilla_cycle_collect,1631 05:42:08 INFO - PROCESS | 11308 | __end_cc_report 05:42:08 INFO - PROCESS | 11308 | __startTimestamp1461933728648__endTimestamp 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | ------- Summary: start ------- 05:42:08 INFO - PROCESS | 11308 | Number of tests: 14 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:3.62 (3.3%) stddev-sans-first:3.81 05:42:08 INFO - PROCESS | 11308 | Values: 110.0 110.0 105.0 110.0 112.0 112.0 100.0 109.0 109.0 110.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:154.50 stddev:20.87 (13.5%) stddev-sans-first:22.14 05:42:08 INFO - PROCESS | 11308 | Values: 150.0 125.0 152.0 180.0 133.0 157.0 160.0 129.0 185.0 133.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:42:08 INFO - PROCESS | 11308 | Values: 160.0 160.0 161.0 161.0 160.0 160.0 159.0 161.0 160.0 160.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#3] audio-fft Cycles:10 Average:91.10 Median:73.50 stddev:22.11 (30.1%) stddev-sans-first:22.35 05:42:08 INFO - PROCESS | 11308 | Values: 72.0 75.0 122.0 109.0 76.0 72.0 76.0 127.0 105.0 77.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#4] audio-oscillator Cycles:10 Average:111.50 Median:93.00 stddev:29.52 (31.7%) stddev-sans-first:30.62 05:42:08 INFO - PROCESS | 11308 | Values: 94.0 94.0 151.0 94.0 92.0 93.0 149.0 93.0 93.0 162.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:42:08 INFO - PROCESS | 11308 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 05:42:08 INFO - PROCESS | 11308 | Values: 146.0 145.0 145.0 144.0 146.0 146.0 145.0 146.0 145.0 147.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 05:42:08 INFO - PROCESS | 11308 | Values: 94.0 95.0 96.0 94.0 94.0 94.0 95.0 96.0 95.0 95.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.00 stddev:5.01 (8.6%) stddev-sans-first:5.31 05:42:08 INFO - PROCESS | 11308 | Values: 58.0 56.0 58.0 56.0 56.0 58.0 58.0 72.0 55.0 55.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 05:42:08 INFO - PROCESS | 11308 | Values: 62.0 62.0 61.0 62.0 62.0 62.0 63.0 62.0 62.0 64.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.00 stddev:1.94 (2.5%) stddev-sans-first:2.03 05:42:08 INFO - PROCESS | 11308 | Values: 76.0 82.0 75.0 76.0 76.0 76.0 78.0 77.0 77.0 77.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:132.00 stddev:10.84 (8.2%) stddev-sans-first:11.49 05:42:08 INFO - PROCESS | 11308 | Values: 131.0 129.0 124.0 128.0 134.0 119.0 133.0 121.0 158.0 134.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.50 stddev:1.69 (1.0%) stddev-sans-first:1.76 05:42:08 INFO - PROCESS | 11308 | Values: 166.0 163.0 166.0 166.0 164.0 164.0 165.0 164.0 165.0 169.0 05:42:08 INFO - PROCESS | 11308 | 05:42:08 INFO - PROCESS | 11308 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:0.97 (1.4%) stddev-sans-first:0.87 05:42:08 INFO - PROCESS | 11308 | Values: 69.0 68.0 67.0 67.0 69.0 67.0 67.0 68.0 67.0 66.0 05:42:08 INFO - PROCESS | 11308 | -------- Summary: end -------- 05:42:08 INFO - PROCESS | 11308 | 05:42:09 INFO - TEST-INFO | 11308: exit 0 05:42:09 INFO - TEST-OK | kraken | took 60761ms 05:42:09 INFO - SUITE-END | took 1172s 05:42:09 INFO - Completed test suite (00:19:32) 05:42:09 INFO - javascript benchmark 05:42:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.1474594486517, 3130.0453361724667, 3165.050484854543, 3169.2393825481877, 3201.823899685518, 5181.559567436772, 5229.973850130754, 5243.4756524347595, 5267.315249017623, 5287.841364759059, 5242.161254199329, 5252.894942101156, 5305.602079844008, 5376.327959005124, 5392.892142157155, 4720.268358404453, 4834.854954351366, 4901.387326584177, 4916.803327866881, 4943.134951383511, 4860.222364421693, 4878.317035615017, 4897.314375987365, 4929.383827021622, 4990.825321113762, 4700.083483720676, 4840.903181936359, 4867.415910090776, 4899.412070551521, 4925.950740492585, 4809.350737650408, 4818.819389249621, 4821.710697358135, 4833.661643684944, 4864.173090574611, 4626.444826620794, 4795.2807078938085, 4811.8075276988875, 4817.205161148407, 4853.077915196137, 5119.129747942859, 5154.53609175176, 5165.405978312505, 5185.818496352609, 5219.973900130513, 5586.050371436825, 5603.523700485463, 5611.607187496877, 5627.296587926509, 5635.042042852725, 5550.972245138789, 5655.886882262332, 5669.092945128801, 5673.404292549264, 5708.971455142698, 5255.86860328491, 5260.9736951315, 5266.973665131688, 5299.046171689094, 5300.0459917214885, 5676.290463692038, 5685.658860468385, 5700.743466544015, 5709.3434255060365, 5710.571707121982, 5364.034473794754, 5464.508194262536, 5496.0, 5550.278463799761, 5612.242347283146, 5638.492535671728, 5640.492355687926, 5661.915071273934, 5662.320521537359, 5681.488666019995, 5603.215549823028, 5619.8595035123635, 5621.15682647598, 5633.070543360382, 5636.267285252891, 5479.698616576047, 5611.663300201919, 5673.461021202979, 5734.283214598176, 5758.481736643722, 4187.392828039917, 4190.455240818735, 4190.476190476191, 4193.958060419418, 4243.851465198765, 3824.6557809796695, 3832.0994566276904, 3841.3853783394525, 3841.750286231386, 3854.3447613905705, 5139.434662187156, 5194.818181363634, 5237.240600112962, 5298.311219541435, 5302.734863256822, 5685.204071430003, 5687.544996400361, 5687.77248910039, 5693.373728889819, 5706.258186435737, 5357.8392648220615, 5376.596755243372, 5377.973110134424, 5386.784528618889, 5427.158790387496, 4632.884177895579, 4768.427788665313, 4810.374651295379, 4836.806527738851, 4857.271409288564, 5706.9429305707245, 5727.226824378656, 5729.799457018984, 5733.397993210653, 5736.196932429463, 5530.834074977757, 5535.308086489189, 5537.584681148849, 5549.8335049947755, 5585.469380408855, 5830.2420685310635, 5873.882522349614, 5884.440978107073, 5931.169636251015, 5939.227900372924, 5558.333000040021, 5564.109742441352, 5571.470710282517, 5573.191887176417, 5574.832755017356, 5565.749541270571, 5587.916181257203, 5648.322201335702, 5657.12314591239, 5661.547076233974, 5485.61600687948, 5486.588505862156, 5486.945130548644, 5490.286262785812, 5530.225768392348, 5461.399246083007, 5461.918071228855, 5464.3442786865835, 5468.070428027166, 5470.617056805985, 5325.201219817059, 5388.919166212589, 5395.86510337229, 5431.103867861917, 5460.535854452416, 5437.048516509516, 5445.101558242845, 5450.945490545044, 5457.317835270591, 5548.0845660465575, 5303.098473259478, 5329.20061990689, 5333.066713325075, 5333.626646134889, 5347.411784703757], "value": 5187.388098232626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.2197143713172, 630.9652170440476, 638.6295948349957, 656.3207080671506, 659.8185498987781, 31936.20159496011, 37673.24653506928, 38392.0, 38677.806610966974, 38686.613133868654, 40353.798231008805, 41038.17923641526, 41184.0, 41244.793776031154, 41262.793686031604, 7689.961550192241, 8492.065872753992, 8739.563021848913, 8746.518941458216, 9931.553080111376, 10067.446290454021, 10237.436940968244, 10242.0, 10253.794924101514, 10259.07668309852, 10177.08406243438, 10302.278840481169, 10311.226658000642, 10315.690529284133, 10322.071013608775, 7668.156502784717, 7868.213178682143, 9866.703998880044, 10090.9495452523, 10143.391396516232, 9841.409515429097, 9978.501074946224, 9979.401235925867, 9990.100890919852, 9994.050565196294, 42240.0, 42572.78713606443, 42917.141657166685, 42929.785351073355, 42975.570244297785, 3449.5688038995127, 3476.4959080933377, 3483.4078206704667, 3526.0303416560396, 3565.3938830398633, 32533.51199732006, 32791.344173116646, 32895.6710432895, 33051.83474082638, 33197.83401083003, 4250.14997000598, 4362.781860906942, 4396.856133956036, 4400.251957167289, 4408.426904502394, 338270.30864845763, 339171.0, 340113.0, 341158.0, 341397.0, 59871.40128598746, 60403.395966040654, 60429.697851510464, 60488.697556512816, 60490.39509604847, 58131.41868581345, 59008.0, 59028.0, 59423.70288148532, 59489.10766338508, 17651.55871103233, 17824.287028518713, 17942.37201697934, 17961.7305740414, 17992.730109048374, 13621.387037583332, 13683.179009259477, 13693.931530342285, 13701.931490342484, 13705.862941370659, 20358.18567257323, 20369.287074952306, 20630.277940272317, 20668.0, 20678.518577770465, 5919.230500034968, 7184.659133502946, 7229.783106506813, 7395.223501532261, 7545.358644515278, 1043.7860238651058, 1052.0967211644884, 1066.317556763672, 1126.0147371050332, 1161.7502237019082, 32102.35795284128, 32111.197220069684, 32405.351892962008, 32460.513092303634, 34465.0, 1120.3389999900098, 1130.3726431830248, 1167.9065674746, 1174.7944109780753, 1221.9083568732203, 18025.369112081014, 18057.187426565837, 18059.813270757455, 18109.36617218391, 18321.17554710041, 536121.0, 543463.0, 546351.0, 547832.0, 548489.0, 59530.404695952486, 61234.38765612287, 61296.080558791655, 61353.69323153356, 61622.69188654028, 1381.412899517701, 1381.6338670252392, 1429.2639290765264, 1433.598592394152, 1501.3319073012624, 31944.52083218707, 33634.327313453105, 33756.83121584376, 33781.83109084439, 34257.657423425444, 53639.463605363446, 53726.194107089206, 53749.46250537601, 54038.189427157835, 54147.0, 426264.0, 426775.0, 427899.0, 429271.0, 432512.0, 24135.75864241335, 26536.601950971137, 27135.914563417242, 29369.7063029367, 29703.702962970958, 43938.34092488679, 44591.33113003372, 45141.774291129645, 45241.773791130836, 45309.320360195285, 37448.438273426465, 38617.42073868951, 40068.0, 40533.39199912063, 42183.7890810544, 41064.58935410727, 41201.587984120975, 41342.0, 42590.148197036506, 42620.57379426166, 41496.58503414927, 42375.0, 42497.0, 42602.0, 42670.78664606781, 34741.305173896886, 36232.63767362293, 36617.0, 36659.98690502211, 37284.0, 28358.291042724097, 28608.57087143736, 28635.570466442605, 28652.0, 28658.426831462835], "value": 20553.46435316101, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.2017802525683, 381.8396273565102, 382.55050315878844, 385.7974563354237, 391.21366054230987, 14585.999999999973, 15016.474423395184, 17625.54936420914, 18357.816421835778, 18957.14692838822, 15810.4466343678, 17856.464306070808, 18524.351647692332, 18626.627467450642, 19216.807831921677, 5517.779288828452, 5659.915101273484, 5687.829365119053, 5713.314402271733, 5714.71426428679, 4069.776162311072, 4262.7655478948645, 4279.40088387626, 4282.764447955376, 5671.291088613923, 6005.729742161591, 6290.622562646233, 6299.0, 6300.968495157518, 6301.432871041606, 5881.823545293648, 6233.688315584248, 6241.0, 6244.656543890062, 6248.250209974832, 5171.63798534099, 5432.483914028161, 5448.0, 5451.454854514556, 5459.727013649302, 12780.424880880382, 13123.0, 13341.466341346335, 13375.26436046017, 13380.665483362995, 2112.0812446585783, 2250.6061439248065, 2268.977310226893, 2275.328778010491, 2387.0929046962215, 9263.25893928484, 9820.165285950703, 9974.850377244347, 9981.950090249575, 10095.495225238783, 1922.5866438715743, 1953.687410014391, 1974.6544354738007, 2020.5150763816725, 2034.8270397016272, 78037.0, 81974.9999999994, 82937.58531207366, 83663.5816820912, 84014.57992710058, 10887.564497420015, 11001.449927503752, 11003.504842281956, 11156.219064665558, 11203.60787372438, 15487.0, 15598.220088995506, 15614.3754249831, 15627.531174064796, 15641.0, 7991.200879911962, 8145.18990948122, 8328.167183281623, 8366.916330836735, 8450.957745211357, 8041.7989550260545, 8076.83846323082, 8168.632411541495, 8207.589620518951, 8256.628451719567, 4664.207084795593, 4715.622750179978, 4756.7145971241835, 4761.880952976203, 4859.0, 1910.9235630574622, 1965.5970526042126, 1985.9503512412305, 2001.769796473395, 2041.8162365387186, 898.7977705016319, 985.4727720669407, 1000.6097621927454, 1048.4128887822844, 1051.148569658579, 17924.13213885772, 18667.346642867433, 18976.240950362106, 19106.426807195807, 19244.326448574233, 972.1202311907734, 987.7036888933292, 993.6969224386631, 1008.3042700536606, 1024.2522958240527, 10125.240606954509, 10201.693949181536, 10676.412797296069, 10737.194710396752, 10992.340459572451, 463736.0, 474203.0, 479940.45699258056, 482748.0, 484457.0, 92550.0, 94159.0, 94782.52608736911, 96081.51959240298, 96451.51774241224, 450.75659144061837, 471.6132771127711, 1122.6407549584055, 1148.3339662995443, 1151.2689442204094, 10432.947835260775, 10530.420826854597, 10866.293690910066, 10958.342499450058, 11545.538178472767, 35708.10729731674, 37181.442278365306, 38793.418098727976, 40428.393574097, 40458.39312410257, 54542.45457545374, 56335.43664563466, 57595.0, 58079.0, 58094.12858807206, 8251.33989280868, 8526.48841069513, 8660.09069047741, 8768.95615521944, 8866.0, 11739.647810565355, 12912.741745164856, 12935.935320323715, 13075.346232688138, 13088.411021504246, 11419.086473081967, 11479.540818367172, 11540.307581545134, 11840.348780817138, 11899.107566932342, 6048.546359023179, 9510.191633710936, 11608.535658573563, 11770.705732356759, 11897.702557436134, 11997.40012999364, 12033.75932481328, 12162.209456385306, 12261.141720079866, 12384.380780960735, 8828.470291782514, 8881.555922203994, 8890.511021893857, 8918.554072296489, 9089.954550227207, 9233.353665243369, 12069.456874440495, 12167.574134905235, 12182.086343524094, 12302.75394492123], "value": 8793.954142514738, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.5902049874785, 1478.7707905274679, 1484.6288427893023, 1644.391575117206, 1798.694221982263, 7973.401994850387, 8311.168883111686, 8321.0, 8324.417290789646, 8330.333573314136, 8217.794555136124, 8455.027671817743, 8460.0, 8483.109273526283, 8490.278326342268, 2019.636465436221, 2064.876107433559, 2071.3682326890294, 2274.40865375002, 2317.9536409271805, 2194.2868567715477, 2269.024319542596, 2269.069681430614, 2273.27255278311, 2274.3404412720292, 2229.687843701883, 2241.5628952354296, 2247.584196923566, 2250.077468238023, 2287.931362059141, 1970.6945423459244, 2229.8550594211324, 2253.8196944244505, 2262.9660555091687, 2265.026038803322, 2270.0, 2286.2341115726254, 2291.415688999311, 2292.3925159832656, 2297.781710737477, 8110.513369197867, 8130.512169269863, 8164.795880102985, 8176.75469735903, 8188.7133950312045, 4809.903801923977, 4998.425181104183, 5015.247712843066, 5022.648414610979, 5027.572656324217, 3136.294333774905, 3255.739540836751, 3416.8120753358558, 3434.3303055904107, 3442.5008373785913, 1134.534840715311, 1143.4854315558032, 1156.4333476596491, 1160.0777381981345, 1163.5520324674976, 11601.535938562447, 11647.941760291229, 11652.242604230698, 11658.708532286675, 11682.299062056303, 4489.528599497071, 4611.9077618447445, 4654.6741728078705, 4683.0, 4692.882677933011, 9794.0, 9823.508824558743, 9848.556814943344, 9857.40855548655, 9863.358881672668, 5218.739063046833, 5225.555827754683, 5241.895162096813, 5248.05535003703, 5268.657537260143, 4306.956930430718, 4607.9308810367875, 4673.345731597579, 4739.170645137087, 4819.084373968934, 3885.378339465672, 3916.2950668879307, 3982.6017398260524, 3988.80055997199, 4003.659688926474, 1226.6810629236463, 1247.5695884919687, 1250.5435516036582, 1254.6424269083268, 1264.5700461842835, 833.8499070167428, 897.1118592593285, 983.311681822727, 988.0, 1005.3666190300211, 5752.482276595127, 5785.1611516329895, 5856.502197313276, 5956.940430595725, 6032.155498230251, 418.4204876246421, 424.74090804609165, 431.2237971651016, 440.1636889909198, 443.72045611264696, 4083.632473077437, 4107.856225032109, 4131.586841315844, 4134.91730165401, 4155.605217504332, 9937.65218217359, 10406.271560990657, 10419.843702344471, 10567.788644227072, 10582.100521455608, 10245.692629221136, 10390.220733445021, 10433.21750868688, 10445.321054131457, 10453.21600879937, 7902.4863383879865, 7975.601219939096, 7975.960120199362, 8011.519308841488, 8056.879146812803, 5802.912956305743, 5843.094320380348, 5902.291724993029, 6017.037274036134, 6047.425494578008, 9417.199538039069, 9688.370255933341, 9813.362131461432, 9841.409515429097, 9916.256280779115, 9688.12806847387, 9767.511624418892, 9817.116459518678, 9836.0, 9884.901150988399, 6760.08738820253, 7200.531965422231, 7308.415326773739, 7386.335229829341, 7393.8151546211775, 8070.878936815835, 8077.151899050515, 8125.3905957052275, 8138.0, 8156.510609363457, 7087.114110736158, 7264.382527485223, 7316.743913962986, 7355.374793142642, 7423.777286681408, 6956.304369563003, 7059.647017649199, 7100.822479437848, 7127.714891404493, 7341.155767086853, 6672.633005184762, 6770.424513916372, 6778.593284402951, 6780.86438271222, 6784.185897692111, 4897.608191344612, 4917.754112294442, 4964.3298154748645, 4968.105740966516, 4996.625927869864, 4960.206366981266, 5172.172452407597, 5227.0591293566, 5293.550048245942, 5347.0375332440535], "value": 4402.188616077471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.8289123873109, 318.442725230846, 320.42483741683674, 321.0448914479424, 322.806316210274, 5308.761105750241, 5318.175682769172, 5350.5719542436655, 5368.731563421823, 5398.433164517728, 5277.70972596507, 5278.181881808317, 5280.762365693543, 5291.518374855037, 5299.046171689094, 3972.423998520213, 4029.2948733971584, 4123.855665051724, 4155.979220103895, 4180.456540649711, 4150.750954942713, 4158.189153115129, 4179.644730197937, 4197.958020419803, 4199.6220340169375, 3907.7069219808486, 4151.750894946298, 4156.334986402177, 4156.97921510392, 4165.854195103172, 3995.9400608990886, 4140.23405669952, 4155.148194620096, 4155.335146376596, 4162.791860406998, 3647.8358473868475, 3648.2703459308245, 3656.579493358271, 3658.506101676266, 3664.59689434162, 5156.8968620627375, 5168.121419358718, 5171.974140129313, 5193.818216362447, 5197.1424714922, 4497.325401189848, 4712.198926182557, 4714.7642617869315, 4726.810927562893, 4729.503402142795, 5556.611037227396, 5580.693061881595, 5587.664740115606, 5594.384617692051, 5598.3282006159525, 3920.5298013245033, 3937.606239376068, 3952.0910190656023, 3959.3862951242604, 3967.444557761916, 5536.833894983157, 5777.786664800439, 5808.767649294024, 5823.67969761663, 5832.0, 3993.0017495626093, 4001.6198461146146, 4007.519097708235, 4020.2763502569233, 4025.295573274665, 4062.2687916175373, 4073.592640735962, 4086.1010577672864, 4111.259973204792, 4111.321631930698, 4573.451185857718, 4634.675572709878, 4654.301854721819, 4699.412573428322, 4716.316134160596, 5493.0, 5560.888782224333, 5601.663900165921, 5601.831945041656, 5645.971770141123, 1460.1531111955042, 1461.291273732239, 1462.7878957551095, 1463.6826855829838, 1472.8527147285185, 881.5371929736812, 928.9163975242261, 948.9051094890593, 957.9042095790504, 970.1850445625709, 5655.717214139277, 5706.686132262766, 5731.254936858265, 5741.741621627037, 5742.253507044057, 6151.5386346024225, 6152.477039451696, 6162.630242185483, 6163.3528479509905, 6168.228971378578, 5652.85867853307, 5710.571707121982, 5757.85605359861, 5764.769409223584, 5776.624519406225, 3230.5800245967876, 3253.398121347511, 3260.266440051017, 3265.7387409007224, 3268.6731326867125, 6182.350853160443, 6273.059041143773, 6312.810615681537, 6318.609905820712, 6337.6197428154455, 6218.968905155536, 6225.533085018642, 6229.470495007975, 6243.25080990275, 6259.0, 6036.189143257093, 6270.968645156745, 6271.404216599416, 6294.559380843297, 6305.905411418832, 6022.126791615104, 6042.667653279112, 6061.363556826646, 6111.882939700873, 6115.785947491817, 5922.92697555852, 5971.611845230046, 5975.16347711312, 5979.189780803015, 6032.819015429544, 6060.090986352083, 6238.563300568917, 6313.179286692701, 6344.555881088279, 6348.0, 4180.540140584594, 4196.160767846504, 4220.303649897733, 4233.449651545279, 4240.89397765046, 5923.229980102559, 6123.142760013513, 6135.662538560244, 6148.907766383597, 6149.907751383822, 5861.824145275648, 5868.23712917318, 5940.287165540163, 5953.315368732651, 5968.552358573212, 5884.264466941632, 5915.40845915405, 5923.318818335774, 5923.407659234214, 5934.0, 5377.870647164139, 5530.253415788978, 5566.715637773733, 5813.505852002627, 5835.299764028344, 5639.859003524781, 5652.85191627179, 5683.7442315095095, 5686.459786320293, 5695.572832037697, 5438.0, 5509.677677357486, 5570.721463926707, 5573.832785016456, 5599.776008959759], "value": 4353.915162847189, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.183299948549, 880.7577916073075, 884.4030279561299, 895.3285036222833, 914.3599480363747, 14381.0, 14805.185714785683, 14821.0, 14834.332455039523, 14837.62905927351, 13532.255725935069, 13787.310634468262, 13927.094738841968, 14009.789853152211, 14015.089019213743, 710.140729717043, 841.381584535366, 852.26705033671, 857.7341024282462, 857.8069934264803, 715.8675645005665, 716.090564982472, 716.4018044932487, 818.6970820796314, 846.3144852669327, 864.6671031652827, 881.0220655072864, 881.3478026260585, 882.0165515450259, 884.38093334666, 859.7420773767876, 874.567089290802, 876.8684697295392, 877.4384393987852, 877.4384393987852, 537.8225185688752, 630.1241274628225, 630.2814791138106, 634.6097150252562, 640.3308542573022, 13336.799948000787, 13339.0, 13345.532906348328, 13375.665608359772, 13396.196228226338, 13804.17174969505, 14847.703045939128, 14882.10707357562, 14887.10677359362, 15017.098974061591, 11734.53061877524, 11793.702692703795, 11936.462859171272, 11937.045036397067, 12035.819462708067, 3846.9230615387814, 3925.646691797723, 4000.6799456043736, 4019.437278780973, 4057.452243947059, 53429.46570534284, 53727.731361343336, 54002.0, 54067.0, 54809.72595137039, 10282.228832837567, 10512.894871051345, 10556.63051793199, 10619.787604247871, 10736.570537178426, 18345.90827045883, 18542.536436589195, 18543.81456185448, 18974.241030358906, 18988.0, 8083.272505474535, 8124.146964568635, 8151.021877374753, 8170.223828736261, 8375.874361884577, 4580.7938642761155, 4633.351330813689, 4720.551547602972, 4764.666473346832, 4799.496052914394, 3206.2625596113025, 3282.917927051843, 3638.599754027055, 3773.3585290500682, 3781.1681430085337, 674.3795707948699, 683.9521233513607, 690.2062627977765, 691.2327316678483, 715.8353578676933, 666.6400143922227, 758.5145506875604, 758.6623952341155, 765.2041876448432, 769.6036541181296, 8567.448455013777, 8723.563821809012, 8756.781080472912, 8779.868301975475, 8998.14517620825, 740.5223630758238, 747.5066456139031, 756.5763172623349, 764.6291548598925, 771.7028943856656, 4427.911441771146, 4471.35165401015, 4560.635149188123, 4568.771561421916, 4603.0, 64762.676186618766, 67554.0, 68187.65906170537, 68289.0, 71522.64238678878, 53622.195667065025, 54918.725406372716, 55968.44031559714, 56817.71591142018, 59476.40523594709, 52889.0, 53766.19350709742, 53806.92386152333, 54092.0, 54474.18288725672, 9244.214241789523, 9306.302027347841, 9334.673286434941, 9449.0, 9461.0, 34998.12504687403, 35017.124571885906, 35588.466173006906, 35640.0, 35935.28129437449, 43783.0, 47334.76332618315, 47385.52614473949, 47884.760576196895, 48624.0, 11215.214934954802, 11330.830037549607, 11352.886471135513, 11462.369569673749, 11627.360495172847, 12960.481580737041, 13217.14088584239, 13613.319334033456, 13722.588322349946, 14117.505887294306, 12075.637730868088, 12416.503339866304, 12543.372831358578, 12627.431765570387, 12827.615171544869, 12702.491900324254, 12744.299063551594, 13051.0, 13583.388747506584, 13602.455901763818, 11793.29240245588, 11964.940175299416, 12358.691032723895, 12760.74478510406, 12831.0, 10657.413842238751, 10700.197485188797, 10877.51051202682, 11065.668029959113, 11418.657440276806, 10300.742481438025, 10356.955773404115, 10445.0, 10504.579816807242, 10530.157587393143], "value": 6859.237049633191, "unit": "score"}], "name": "dromaeo_css", "value": 7054.74783884145, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 105.0, 110.0, 112.0, 112.0, 100.0, 109.0, 109.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 152.0, 180.0, 133.0, 157.0, 160.0, 129.0, 185.0, 133.0], "value": 150.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 159.0, 161.0, 160.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 122.0, 109.0, 76.0, 72.0, 76.0, 127.0, 105.0, 77.0], "value": 91.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 151.0, 94.0, 92.0, 93.0, 149.0, 93.0, 93.0, 162.0], "value": 111.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 144.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 94.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 56.0, 56.0, 58.0, 58.0, 72.0, 55.0, 55.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 64.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 75.0, 76.0, 76.0, 76.0, 78.0, 77.0, 77.0, 77.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 124.0, 128.0, 134.0, 119.0, 133.0, 121.0, 158.0, 134.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 166.0, 166.0, 164.0, 164.0, 165.0, 164.0, 165.0, 169.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 67.0, 69.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1539.8999999999999, "alertThreshold": 2.0}]} 05:42:09 INFO - Return code: 0 05:42:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:42:09 ERROR - # TBPL SUCCESS # 05:42:09 INFO - Running post-action listener: _resource_record_post_action 05:42:09 INFO - Running post-run listener: _resource_record_post_run 05:42:11 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 298049536; Read time: 12; Write time: 194748 05:42:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 8044544; Read time: 0; Write time: 1040 05:42:11 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284696576; Read time: 12; Write time: 186276 05:42:11 INFO - Running post-run listener: _upload_blobber_files 05:42:11 INFO - Blob upload gear active. 05:42:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:42:11 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 05:42:11 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'] 05:42:11 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 05:42:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:42:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:42:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:42:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:12 INFO - (blobuploader) - INFO - Done attempting. 05:42:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:42:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:42:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:12 INFO - (blobuploader) - INFO - Done attempting. 05:42:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:42:12 INFO - Return code: 0 05:42:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:42:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:42:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10cbc222c10ff07847b26e13c9e5e8386ab17893b1af5d1c73fd4937cea7b3201cab28d617e44cc414104191470f3602fe767718785398939a412b6f5e111e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee71ee58c78ec25663452e8b2757be73be6682aed6b2d09dcac158ad09bb3f6db12e7db9e36cda85b1e178ef069dfa0fa15f561135e82d616cc9c6f7ff2f16c"} 05:42:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:42:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:42:12 INFO - Contents: 05:42:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10cbc222c10ff07847b26e13c9e5e8386ab17893b1af5d1c73fd4937cea7b3201cab28d617e44cc414104191470f3602fe767718785398939a412b6f5e111e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee71ee58c78ec25663452e8b2757be73be6682aed6b2d09dcac158ad09bb3f6db12e7db9e36cda85b1e178ef069dfa0fa15f561135e82d616cc9c6f7ff2f16c"} 05:42:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:42:12 INFO - Copying logs to upload dir... 05:42:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:42:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.323635 ========= master_lag: 1.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-29 05:42:13.789276) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 05:42:13.797205) ========= 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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 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/c10cbc222c10ff07847b26e13c9e5e8386ab17893b1af5d1c73fd4937cea7b3201cab28d617e44cc414104191470f3602fe767718785398939a412b6f5e111e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee71ee58c78ec25663452e8b2757be73be6682aed6b2d09dcac158ad09bb3f6db12e7db9e36cda85b1e178ef069dfa0fa15f561135e82d616cc9c6f7ff2f16c"} build_url:https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/UrtbK_75R0uLmbrOf2FYiw/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/c10cbc222c10ff07847b26e13c9e5e8386ab17893b1af5d1c73fd4937cea7b3201cab28d617e44cc414104191470f3602fe767718785398939a412b6f5e111e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ee71ee58c78ec25663452e8b2757be73be6682aed6b2d09dcac158ad09bb3f6db12e7db9e36cda85b1e178ef069dfa0fa15f561135e82d616cc9c6f7ff2f16c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 05:42:13.829766) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:42:13.830123) ========= 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=523ca41ca1e0623de333ad52000001bf-1461930249.889765-279631165 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003116 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:42:13.881477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 05:42:13.883828) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 05:42:13.884365) ========= ========= Total master_lag: 1.26 =========