builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0064 starttime: 1462791844.93 results: success (0) buildid: 20160509021645 builduid: 4bdfeeee93334929abd2f75de36800c0 revision: fd8bdef8ef752875c509141b3345ab9e722d6cec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:04.932791) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:04.933260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:04.933570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:05.162446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:05.162772) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-09 04:04:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to 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% 161M=0s 2016-05-09 04:04:05 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.597630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:05.776796) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:05.777120) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:05.825009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:04:05.825327) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fd8bdef8ef752875c509141b3345ab9e722d6cec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fd8bdef8ef752875c509141b3345ab9e722d6cec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-09 04:04:05,902 truncating revision to first 12 chars 2016-05-09 04:04:05,902 Setting DEBUG logging. 2016-05-09 04:04:05,902 attempt 1/10 2016-05-09 04:04:05,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fd8bdef8ef75?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 04:04:07,070 unpacking tar archive at: mozilla-central-fd8bdef8ef75/testing/mozharness/ program finished with exit code 0 elapsedTime=1.410082 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:04:07.258146) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:07.258483) ========= script_repo_revision: fd8bdef8ef752875c509141b3345ab9e722d6cec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:07.258937) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:07.259222) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:04:07.273292) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-09 04:04:07.273590) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:04:07 INFO - MultiFileLogger online at 20160509 04:04:07 in /builds/slave/test 04:04:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:04:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:04:07 INFO - {'append_to_log': False, 04:04:07 INFO - 'base_work_dir': '/builds/slave/test', 04:04:07 INFO - 'blob_upload_branch': 'Firefox', 04:04:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:04:07 INFO - 'branch': 'Firefox', 04:04:07 INFO - 'buildbot_json_path': 'buildprops.json', 04:04:07 INFO - 'config_files': ('talos/mac_config.py',), 04:04:07 INFO - 'default_actions': ('clobber', 04:04:07 INFO - 'read-buildbot-config', 04:04:07 INFO - 'download-and-extract', 04:04:07 INFO - 'populate-webroot', 04:04:07 INFO - 'create-virtualenv', 04:04:07 INFO - 'install', 04:04:07 INFO - 'run-tests'), 04:04:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:04:07 INFO - 'download_minidump_stackwalk': True, 04:04:07 INFO - 'download_symbols': 'ondemand', 04:04:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:04:07 INFO - 'tooltool.py': '/tools/tooltool.py', 04:04:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:04:07 INFO - '/tools/misc-python/virtualenv.py')}, 04:04:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:04:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:04:07 INFO - 'installer_path': 'installer.exe', 04:04:07 INFO - 'log_level': 'info', 04:04:07 INFO - 'log_name': 'talos', 04:04:07 INFO - 'log_to_console': True, 04:04:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:04:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:04:07 INFO - 'opt_config_files': (), 04:04:07 INFO - 'pip_index': False, 04:04:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:07 INFO - 'cmd': ('bash', 04:04:07 INFO - '-c', 04:04:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:07 INFO - 'enabled': True, 04:04:07 INFO - 'halt_on_failure': False, 04:04:07 INFO - 'name': 'check_screen_resolution'},), 04:04:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:07 INFO - 'cmd': ('bash', 04:04:07 INFO - '-c', 04:04:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:07 INFO - 'enabled': True, 04:04:07 INFO - 'halt_on_failure': False, 04:04:07 INFO - 'name': 'check_screen_resolution'},), 04:04:07 INFO - 'run_cmd_checks_enabled': True, 04:04:07 INFO - 'sps_profile': False, 04:04:07 INFO - 'sps_profile_interval': 0, 04:04:07 INFO - 'suite': 'dromaeojs', 04:04:07 INFO - 'system_bits': '32', 04:04:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:04:07 INFO - 'title': 't-yosemite-r7-0064', 04:04:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:04:07 INFO - 'use_talos_json': True, 04:04:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:04:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:04:07 INFO - 'work_dir': 'build'} 04:04:07 INFO - ##### 04:04:07 INFO - ##### Running clobber step. 04:04:07 INFO - ##### 04:04:07 INFO - Running pre-action listener: _resource_record_pre_action 04:04:07 INFO - Running main action method: clobber 04:04:07 INFO - rmtree: /builds/slave/test/build 04:04:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:04:07 INFO - Running post-action listener: _resource_record_post_action 04:04:07 INFO - ##### 04:04:07 INFO - ##### Finished clobber step (success) 04:04:07 INFO - ##### 04:04:07 INFO - ##### 04:04:07 INFO - ##### Running read-buildbot-config step. 04:04:07 INFO - ##### 04:04:07 INFO - Running pre-action listener: _resource_record_pre_action 04:04:07 INFO - Running main action method: read_buildbot_config 04:04:07 INFO - Using buildbot properties: 04:04:07 INFO - { 04:04:07 INFO - "project": "", 04:04:07 INFO - "product": "firefox", 04:04:07 INFO - "script_repo_revision": "production", 04:04:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:04:07 INFO - "repository": "", 04:04:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:04:07 INFO - "buildid": "20160509021645", 04:04:07 INFO - "pgo_build": "False", 04:04:07 INFO - "basedir": "/builds/slave/test", 04:04:07 INFO - "buildnumber": 242, 04:04:07 INFO - "slavename": "t-yosemite-r7-0064", 04:04:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:04:07 INFO - "platform": "yosemite_r7", 04:04:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:04:07 INFO - "branch": "mozilla-central", 04:04:07 INFO - "repo_path": "mozilla-central", 04:04:07 INFO - "revision": "fd8bdef8ef752875c509141b3345ab9e722d6cec", 04:04:07 INFO - "stage_platform": "macosx64", 04:04:07 INFO - "builduid": "4bdfeeee93334929abd2f75de36800c0", 04:04:07 INFO - "slavebuilddir": "test" 04:04:07 INFO - } 04:04:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:04:07 INFO - Running post-action listener: _resource_record_post_action 04:04:07 INFO - ##### 04:04:07 INFO - ##### Finished read-buildbot-config step (success) 04:04:07 INFO - ##### 04:04:07 INFO - ##### 04:04:07 INFO - ##### Running download-and-extract step. 04:04:07 INFO - ##### 04:04:07 INFO - Running pre-action listener: _resource_record_pre_action 04:04:07 INFO - Running main action method: download_and_extract 04:04:07 INFO - mkdir: /builds/slave/test/build/tests 04:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:07 INFO - https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:04:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:04:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:04:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:04:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:04:09 INFO - Downloaded 1199 bytes. 04:04:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:04:09 INFO - Using the following test package requirements: 04:04:09 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:04:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:04:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:04:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'jsshell-mac.zip'], 04:04:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:04:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:04:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:04:09 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:04:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:04:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:04:09 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:04:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:09 INFO - https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:04:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:04:10 INFO - Downloaded 18144981 bytes. 04:04:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:04:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:04:13 INFO - Return code: 0 04:04:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:13 INFO - https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:04:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:04:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:04:14 INFO - Downloaded 18144981 bytes. 04:04:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:04:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:04:18 INFO - Return code: 0 04:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:18 INFO - https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:04:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:04:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:04:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:04:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:04:19 INFO - Downloaded 10840152 bytes. 04:04:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:04:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:04:19 INFO - Return code: 0 04:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:19 INFO - https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:04:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:04:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:04:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:04:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:04:22 INFO - Downloaded 110317300 bytes. 04:04:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:04:22 INFO - mkdir: /builds/slave/test/properties 04:04:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:04:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:04:22 INFO - Contents: 04:04:22 INFO - build_url:https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:04:23 INFO - Running post-action listener: _resource_record_post_action 04:04:23 INFO - Running post-action listener: set_extra_try_arguments 04:04:23 INFO - ##### 04:04:23 INFO - ##### Finished download-and-extract step (success) 04:04:23 INFO - ##### 04:04:23 INFO - ##### 04:04:23 INFO - ##### Running populate-webroot step. 04:04:23 INFO - ##### 04:04:23 INFO - Running pre-action listener: _resource_record_pre_action 04:04:23 INFO - Running main action method: populate_webroot 04:04:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:04:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:04:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:04:23 INFO - '--tppagecycles', 04:04:23 INFO - '7'], 04:04:23 INFO - 'tests': ['tsvgm']}}, 04:04:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:04:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:04:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:04:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:04:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:04:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:04:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['damp', 'tps']}, 04:04:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'tests': ['damp', 'tps']}, 04:04:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['dromaeo_dom']}, 04:04:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:04:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['a11yr', 04:04:23 INFO - 'ts_paint', 04:04:23 INFO - 'tpaint', 04:04:23 INFO - 'sessionrestore', 04:04:23 INFO - 'sessionrestore_no_auto_restore', 04:04:23 INFO - 'tabpaint']}, 04:04:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['tsvgx', 04:04:23 INFO - 'tsvgr_opacity', 04:04:23 INFO - 'tart', 04:04:23 INFO - 'tscrollx', 04:04:23 INFO - 'cart']}, 04:04:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:04:23 INFO - 'tsvgr_opacity', 04:04:23 INFO - 'tart', 04:04:23 INFO - 'tscrollx', 04:04:23 INFO - 'cart']}, 04:04:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'talos_options': ['--disable-e10s'], 04:04:23 INFO - 'tests': ['tp5o']}, 04:04:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'tests': ['tp5o']}, 04:04:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'talos_options': ['--disable-e10s', 04:04:23 INFO - '--xperf_path', 04:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:04:23 INFO - 'tests': ['tp5n']}, 04:04:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:04:23 INFO - 'talos_options': ['--xperf_path', 04:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:04:23 INFO - 'tests': ['tp5n']}}, 04:04:23 INFO - 'talos.zip': {'path': '', 04:04:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:04:23 INFO - Running post-action listener: _resource_record_post_action 04:04:23 INFO - ##### 04:04:23 INFO - ##### Finished populate-webroot step (success) 04:04:23 INFO - ##### 04:04:23 INFO - ##### 04:04:23 INFO - ##### Running create-virtualenv step. 04:04:23 INFO - ##### 04:04:23 INFO - Running pre-action listener: _resource_record_pre_action 04:04:23 INFO - Running main action method: create_virtualenv 04:04:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:04:23 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 04:04:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:04:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:04:23 INFO - Using real prefix '/tools/python27' 04:04:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:04:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:04:27 INFO - Installing pip.................done. 04:04:27 INFO - Return code: 0 04:04:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:27 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:27 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 04:04:27 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 04:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:27 INFO - 'HOME': '/Users/cltbld', 04:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:27 INFO - 'LOGNAME': 'cltbld', 04:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:27 INFO - 'MOZ_NO_REMOTE': '1', 04:04:27 INFO - 'NO_EM_RESTART': '1', 04:04:27 INFO - 'PAGER': '/bin/cat', 04:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:27 INFO - 'PWD': '/builds/slave/test', 04:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:27 INFO - 'SHELL': '/bin/bash', 04:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:27 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:27 INFO - 'USER': 'cltbld', 04:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:27 INFO - 'XPC_FLAGS': '0x0', 04:04:27 INFO - 'XPC_SERVICE_NAME': '0', 04:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:27 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 04:04:27 INFO - Cleaning up... 04:04:27 INFO - Return code: 0 04:04:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:27 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:27 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 04:04:27 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 04:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:27 INFO - 'HOME': '/Users/cltbld', 04:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:27 INFO - 'LOGNAME': 'cltbld', 04:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:27 INFO - 'MOZ_NO_REMOTE': '1', 04:04:27 INFO - 'NO_EM_RESTART': '1', 04:04:27 INFO - 'PAGER': '/bin/cat', 04:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:27 INFO - 'PWD': '/builds/slave/test', 04:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:27 INFO - 'SHELL': '/bin/bash', 04:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:27 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:27 INFO - 'USER': 'cltbld', 04:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:27 INFO - 'XPC_FLAGS': '0x0', 04:04:27 INFO - 'XPC_SERVICE_NAME': '0', 04:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:28 INFO - Downloading/unpacking psutil>=0.7.1 04:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:04:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:04:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:04:31 INFO - Installing collected packages: psutil 04:04:31 INFO - Running setup.py install for psutil 04:04:31 INFO - building 'psutil._psutil_osx' extension 04:04:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:04:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:04:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:04:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:04:31 INFO - building 'psutil._psutil_posix' extension 04:04:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:04:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:04:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:04:32 INFO - ^ 04:04:32 INFO - 1 warning generated. 04:04:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:04:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:04:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:04:32 INFO - Successfully installed psutil 04:04:32 INFO - Cleaning up... 04:04:32 INFO - Return code: 0 04:04:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:32 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:32 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 04:04:32 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 04:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:32 INFO - 'HOME': '/Users/cltbld', 04:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:32 INFO - 'LOGNAME': 'cltbld', 04:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:32 INFO - 'MOZ_NO_REMOTE': '1', 04:04:32 INFO - 'NO_EM_RESTART': '1', 04:04:32 INFO - 'PAGER': '/bin/cat', 04:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:32 INFO - 'PWD': '/builds/slave/test', 04:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:32 INFO - 'SHELL': '/bin/bash', 04:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:32 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:32 INFO - 'USER': 'cltbld', 04:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:32 INFO - 'XPC_FLAGS': '0x0', 04:04:32 INFO - 'XPC_SERVICE_NAME': '0', 04:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:04:32 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 04:04:32 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 04:04:32 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 04:04:32 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 04:04:32 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 04:04:32 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 04:04:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:04:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:04:35 INFO - Installing collected packages: mozsystemmonitor 04:04:35 INFO - Running setup.py install for mozsystemmonitor 04:04:35 INFO - Successfully installed mozsystemmonitor 04:04:35 INFO - Cleaning up... 04:04:35 INFO - Return code: 0 04:04:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:04: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')]} 04:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04: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')]} 04:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:35 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:35 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 04:04:35 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 04:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:35 INFO - 'HOME': '/Users/cltbld', 04:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:35 INFO - 'LOGNAME': 'cltbld', 04:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:35 INFO - 'MOZ_NO_REMOTE': '1', 04:04:35 INFO - 'NO_EM_RESTART': '1', 04:04:35 INFO - 'PAGER': '/bin/cat', 04:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:35 INFO - 'PWD': '/builds/slave/test', 04:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:35 INFO - 'SHELL': '/bin/bash', 04:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:35 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:35 INFO - 'USER': 'cltbld', 04:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:35 INFO - 'XPC_FLAGS': '0x0', 04:04:35 INFO - 'XPC_SERVICE_NAME': '0', 04:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:35 INFO - Downloading/unpacking blobuploader==1.2.4 04:04:35 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 04:04:35 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 04:04:35 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 04:04:35 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 04:04:35 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 04:04:35 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 04:04:37 INFO - Downloading blobuploader-1.2.4.tar.gz 04:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:04:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:04:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:04:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:04:38 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 04:04:38 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 04:04:38 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 04:04:38 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 04:04:38 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 04:04:38 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 04:04:39 INFO - Downloading docopt-0.6.1.tar.gz 04:04:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:04:39 INFO - Installing collected packages: blobuploader, requests, docopt 04:04:39 INFO - Running setup.py install for blobuploader 04:04:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:04:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:04:39 INFO - Running setup.py install for requests 04:04:39 INFO - Running setup.py install for docopt 04:04:39 INFO - Successfully installed blobuploader requests docopt 04:04:39 INFO - Cleaning up... 04:04:40 INFO - Return code: 0 04:04:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:40 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')]} 04:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:40 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')]} 04:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:40 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:40 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 04:04:40 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 04:04:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:40 INFO - 'HOME': '/Users/cltbld', 04:04:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:40 INFO - 'LOGNAME': 'cltbld', 04:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:40 INFO - 'MOZ_NO_REMOTE': '1', 04:04:40 INFO - 'NO_EM_RESTART': '1', 04:04:40 INFO - 'PAGER': '/bin/cat', 04:04:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:40 INFO - 'PWD': '/builds/slave/test', 04:04:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:40 INFO - 'SHELL': '/bin/bash', 04:04:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:40 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:40 INFO - 'USER': 'cltbld', 04:04:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:40 INFO - 'XPC_FLAGS': '0x0', 04:04:40 INFO - 'XPC_SERVICE_NAME': '0', 04:04:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QvcdA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ccVcIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-RAnWev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ySAEPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-7DCGI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-jGmsao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:04:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-YeE7c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-aMbbNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-vpTtAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-HzuAwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-tudUYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-OuUUrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-azKCV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-DfAz9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Qr40GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-TWiRQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:04:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-W6lBlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:04:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:04:42 INFO - Running setup.py install for manifestparser 04:04:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:04:42 INFO - Running setup.py install for mozcrash 04:04:42 INFO - Running setup.py install for mozdebug 04:04:42 INFO - Running setup.py install for mozdevice 04:04:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:04:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:04:42 INFO - Running setup.py install for mozfile 04:04:42 INFO - Running setup.py install for mozhttpd 04:04:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:04:42 INFO - Running setup.py install for mozinfo 04:04:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Running setup.py install for mozInstall 04:04:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Running setup.py install for mozleak 04:04:43 INFO - Running setup.py install for mozlog 04:04:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Running setup.py install for moznetwork 04:04:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Running setup.py install for mozprocess 04:04:43 INFO - Running setup.py install for mozprofile 04:04:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:04:43 INFO - Running setup.py install for mozrunner 04:04:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:04:44 INFO - Running setup.py install for mozscreenshot 04:04:44 INFO - Running setup.py install for moztest 04:04:44 INFO - Running setup.py install for mozversion 04:04:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:04:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:04:44 INFO - Cleaning up... 04:04:44 INFO - Return code: 0 04:04:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04: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')]} 04:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04: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')]} 04:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:44 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:44 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 04:04:44 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 04:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:44 INFO - 'HOME': '/Users/cltbld', 04:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:44 INFO - 'LOGNAME': 'cltbld', 04:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:44 INFO - 'MOZ_NO_REMOTE': '1', 04:04:44 INFO - 'NO_EM_RESTART': '1', 04:04:44 INFO - 'PAGER': '/bin/cat', 04:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:44 INFO - 'PWD': '/builds/slave/test', 04:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:44 INFO - 'SHELL': '/bin/bash', 04:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:44 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:44 INFO - 'USER': 'cltbld', 04:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:44 INFO - 'XPC_FLAGS': '0x0', 04:04:44 INFO - 'XPC_SERVICE_NAME': '0', 04:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:04:44 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-s3HqTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:04:44 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)) 04:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:04:44 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-9pEXEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-CiXKS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-oLaKKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-kEqJjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-DsrOcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-GncI4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-gG8dyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Fj9An9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-UtdZk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:04:45 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)) 04:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:04:45 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-WHF95K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-6kqgqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-lzLU61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-hyoRMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-XLmvB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QaXCNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:04:46 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)) 04:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:04:46 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-E1Q_1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:04:46 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)) 04:04:46 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)) 04:04:46 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)) 04:04:46 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)) 04:04:46 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)) 04:04:46 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)) 04:04:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:04:46 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 04:04:46 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 04:04:46 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 04:04:46 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 04:04:46 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 04:04:46 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 04:04:49 INFO - Downloading blessings-1.6.tar.gz 04:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:04:49 INFO - Installing collected packages: blessings 04:04:49 INFO - Running setup.py install for blessings 04:04:49 INFO - Successfully installed blessings 04:04:49 INFO - Cleaning up... 04:04:49 INFO - Return code: 0 04:04:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:04:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:49 INFO - Reading from file tmpfile_stdout 04:04:49 INFO - Current package versions: 04:04:49 INFO - blessings == 1.6 04:04:49 INFO - blobuploader == 1.2.4 04:04:49 INFO - docopt == 0.6.1 04:04:49 INFO - manifestparser == 1.1 04:04:49 INFO - mozInstall == 1.12 04:04:49 INFO - mozcrash == 0.17 04:04:49 INFO - mozdebug == 0.1 04:04:49 INFO - mozdevice == 0.48 04:04:49 INFO - mozfile == 1.2 04:04:49 INFO - mozhttpd == 0.7 04:04:49 INFO - mozinfo == 0.9 04:04:49 INFO - mozleak == 0.1 04:04:49 INFO - mozlog == 3.1 04:04:49 INFO - moznetwork == 0.27 04:04:49 INFO - mozprocess == 0.22 04:04:49 INFO - mozprofile == 0.28 04:04:49 INFO - mozrunner == 6.11 04:04:49 INFO - mozscreenshot == 0.1 04:04:49 INFO - mozsystemmonitor == 0.0 04:04:49 INFO - moztest == 0.7 04:04:49 INFO - mozversion == 1.4 04:04:49 INFO - psutil == 3.1.1 04:04:49 INFO - requests == 1.2.3 04:04:49 INFO - wsgiref == 0.1.2 04:04:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:49 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:49 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 04:04:49 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 04:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:49 INFO - 'HOME': '/Users/cltbld', 04:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:49 INFO - 'LOGNAME': 'cltbld', 04:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:49 INFO - 'MOZ_NO_REMOTE': '1', 04:04:49 INFO - 'NO_EM_RESTART': '1', 04:04:49 INFO - 'PAGER': '/bin/cat', 04:04:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:49 INFO - 'PWD': '/builds/slave/test', 04:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:49 INFO - 'SHELL': '/bin/bash', 04:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:49 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:49 INFO - 'USER': 'cltbld', 04:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:49 INFO - 'XPC_FLAGS': '0x0', 04:04:49 INFO - 'XPC_SERVICE_NAME': '0', 04:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 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)) 04:04:49 INFO - Cleaning up... 04:04:49 INFO - Return code: 0 04:04:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:49 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:49 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 04:04:49 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 04:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:49 INFO - 'HOME': '/Users/cltbld', 04:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:49 INFO - 'LOGNAME': 'cltbld', 04:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:49 INFO - 'MOZ_NO_REMOTE': '1', 04:04:49 INFO - 'NO_EM_RESTART': '1', 04:04:49 INFO - 'PAGER': '/bin/cat', 04:04:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:49 INFO - 'PWD': '/builds/slave/test', 04:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:49 INFO - 'SHELL': '/bin/bash', 04:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:49 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:49 INFO - 'USER': 'cltbld', 04:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:49 INFO - 'XPC_FLAGS': '0x0', 04:04:49 INFO - 'XPC_SERVICE_NAME': '0', 04:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:50 INFO - Downloading/unpacking jsonschema 04:04:50 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 04:04:50 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 04:04:50 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 04:04:50 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 04:04:50 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 04:04:50 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 04:04:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:04:52 INFO - Downloading/unpacking functools32 (from jsonschema) 04:04:52 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 04:04:52 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 04:04:52 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 04:04:52 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 04:04:52 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 04:04:52 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 04:04:52 INFO - Downloading functools32-3.2.3-2.tar.gz 04:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:04:53 INFO - warning: no files found matching '*.txt' 04:04:53 INFO - no previously-included directories found matching 'build' 04:04:53 INFO - no previously-included directories found matching 'dist' 04:04:53 INFO - no previously-included directories found matching '.git*' 04:04:53 INFO - Installing collected packages: jsonschema, functools32 04:04:53 INFO - Running setup.py install for functools32 04:04:53 INFO - warning: no files found matching '*.txt' 04:04:53 INFO - no previously-included directories found matching 'build' 04:04:53 INFO - no previously-included directories found matching 'dist' 04:04:53 INFO - no previously-included directories found matching '.git*' 04:04:53 INFO - Successfully installed jsonschema functools32 04:04:53 INFO - Cleaning up... 04:04:53 INFO - Return code: 0 04:04:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:04:53 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 0x10ce2da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc734505e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:04:53 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 04:04:53 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 04:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:53 INFO - 'HOME': '/Users/cltbld', 04:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:53 INFO - 'LOGNAME': 'cltbld', 04:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:53 INFO - 'MOZ_NO_REMOTE': '1', 04:04:53 INFO - 'NO_EM_RESTART': '1', 04:04:53 INFO - 'PAGER': '/bin/cat', 04:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:53 INFO - 'PWD': '/builds/slave/test', 04:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:53 INFO - 'SHELL': '/bin/bash', 04:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:04:53 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:53 INFO - 'USER': 'cltbld', 04:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:53 INFO - 'XPC_FLAGS': '0x0', 04:04:53 INFO - 'XPC_SERVICE_NAME': '0', 04:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:53 INFO - Downloading/unpacking flake8 04:04:53 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 04:04:53 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 04:04:53 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 04:04:53 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 04:04:53 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 04:04:53 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 04:04:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:04:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:04:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04: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 04:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:04:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:04:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:04:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:04:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:04:57 INFO - no previously-included directories found matching 'docs/_build' 04:04:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:04:57 INFO - Running setup.py install for pep8 04:04:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:04:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:04:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:04:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:04:57 INFO - no previously-included directories found matching 'docs/_build' 04:04:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:04:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:04:57 INFO - Cleaning up... 04:04:57 INFO - Return code: 0 04:04:57 INFO - Running post-action listener: _resource_record_post_action 04:04:57 INFO - Running post-action listener: _start_resource_monitoring 04:04:57 INFO - Starting resource monitoring. 04:04:57 INFO - ##### 04:04:57 INFO - ##### Finished create-virtualenv step (success) 04:04:57 INFO - ##### 04:04:57 INFO - ##### 04:04:57 INFO - ##### Running install step. 04:04:57 INFO - ##### 04:04:57 INFO - Running pre-action listener: _resource_record_pre_action 04:04:57 INFO - Running main action method: install 04:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:57 INFO - Reading from file tmpfile_stdout 04:04:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:04:58 INFO - Reading from file tmpfile_stdout 04:04:58 INFO - Output received: 04:04:58 INFO - Usage: mozinstall [options] installer 04:04:58 INFO - Options: 04:04:58 INFO - -h, --help show this help message and exit 04:04:58 INFO - -d DEST, --destination=DEST 04:04:58 INFO - Directory to install application into. [default: 04:04:58 INFO - "/builds/slave/test"] 04:04:58 INFO - --app=APP Application being installed. [default: firefox] 04:04:58 INFO - mkdir: /builds/slave/test/build/application 04:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:05:31 INFO - Reading from file tmpfile_stdout 04:05:31 INFO - Output received: 04:05:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:05:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - ##### 04:05:31 INFO - ##### Finished install step (success) 04:05:31 INFO - ##### 04:05:31 INFO - ##### 04:05:31 INFO - ##### Running run-tests step. 04:05:31 INFO - ##### 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: run_tests 04:05:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:05:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:05:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:05:31 INFO - 2016-05-09 04:05:31.535 screenresolution[2359:23698] starting screenresolution argv=screenresolution get 04:05:31 INFO - 2016-05-09 04:05:31.538 screenresolution[2359:23698] Display 0: 1600x1200x32@60 04:05:31 INFO - 2016-05-09 04:05:31.544 screenresolution[2360:23700] starting screenresolution argv=screenresolution list 04:05:31 INFO - Available Modes on Display 0 04:05:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:05:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:05:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:05:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:05:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:05:31 INFO - Intel Iris: 04:05:31 INFO - Chipset Model: Intel Iris 04:05:31 INFO - Type: GPU 04:05:31 INFO - Bus: Built-In 04:05:31 INFO - VRAM (Dynamic, Max): 1536 MB 04:05:31 INFO - Vendor: Intel (0x8086) 04:05:31 INFO - Device ID: 0x0a2e 04:05:31 INFO - Revision ID: 0x0009 04:05:31 INFO - Displays: 04:05:31 INFO - Display: 04:05:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:05:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:05:31 INFO - Display Serial Number: 1600x1200 60 04:05:31 INFO - Main Display: Yes 04:05:31 INFO - Mirror: Off 04:05:31 INFO - Online: Yes 04:05:31 INFO - Rotation: Supported 04:05:31 INFO - Return code: 0 04:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:05:31 INFO - Python 2.7.3 04:05:31 INFO - Return code: 0 04:05:31 INFO - grabbing minidump binary from tooltool 04:05:31 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')]} 04:05:31 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd1d30>, '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 0x10caf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:05:31 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:05:31 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:05:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:05:31 INFO - Return code: 0 04:05:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:05:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:05:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:05:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:05:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:05:32 INFO - Return code: 0 04:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 04:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0064 --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 04:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.paj6LXBQ5R/Render', 04:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:32 INFO - 'HOME': '/Users/cltbld', 04:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:32 INFO - 'LOGNAME': 'cltbld', 04:05:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:32 INFO - 'MOZ_NO_REMOTE': '1', 04:05:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:32 INFO - 'NO_EM_RESTART': '1', 04:05:32 INFO - 'PAGER': '/bin/cat', 04:05:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:32 INFO - 'PWD': '/builds/slave/test', 04:05:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:32 INFO - 'SHELL': '/bin/bash', 04:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners', 04:05:32 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 04:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:32 INFO - 'USER': 'cltbld', 04:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:32 INFO - 'XPC_FLAGS': '0x0', 04:05:32 INFO - 'XPC_SERVICE_NAME': '0', 04:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 04:05:33 INFO - mozversion application_buildid: 20160509021645 04:05:33 INFO - mozversion application_changeset: fd8bdef8ef752875c509141b3345ab9e722d6cec 04:05:33 INFO - mozversion application_display_name: Nightly 04:05:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:05:33 INFO - mozversion application_name: Firefox 04:05:33 INFO - mozversion application_remotingname: firefox 04:05:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:05:33 INFO - mozversion application_vendor: Mozilla 04:05:33 INFO - mozversion application_version: 49.0a1 04:05:33 INFO - mozversion platform_buildid: 20160509021645 04:05:33 INFO - mozversion platform_changeset: fd8bdef8ef752875c509141b3345ab9e722d6cec 04:05:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:05:33 INFO - mozversion platform_version: 49.0a1 04:05:33 INFO - using testdate: 1462791933 04:05:33 INFO - actual date: 1462791933 04:05:33 INFO - starting webserver on 'localhost:57404' 04:05:33 INFO - SUITE-START | Running 2 tests 04:05:33 INFO - TEST-START | dromaeo_css 04:05:33 INFO - operating with platform_type : mac_ 04:05:33 INFO - Initialising browser for dromaeo_css test... 04:05:33 INFO - TEST-INFO | started process 2378 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpfvMj0P/profile http://localhost:57404/getInfo.html) 04:05:35 INFO - PROCESS | 2378 | __metrics Screen width/height:1600/1200 04:05:35 INFO - PROCESS | 2378 | colorDepth:24 04:05:35 INFO - PROCESS | 2378 | Browser inner width/height: 1024/689 04:05:35 INFO - PROCESS | 2378 | __metrics 04:05:35 INFO - PROCESS | 2378 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:05:35 ERROR - PROCESS | 2378 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:05:36 INFO - TEST-INFO | 2378: exit 0 04:05:36 INFO - Browser initialized. 04:05:36 INFO - Running cycle 1/1 for dromaeo_css test... 04:05:36 INFO - TEST-INFO | started process 2380 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpfvMj0P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:48 INFO - PROCESS | 2380 | RSS: Main: 239247360 04:05:48 INFO - PROCESS | 2380 | 04:06:39 INFO - PROCESS | 2380 | 1462791999359 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] 04:08:49 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57404/tests/dromaeo/cssquery-ext.html) 04:08:51 INFO - PROCESS | 2380 | RSS: Main: 349429760 04:08:51 INFO - PROCESS | 2380 | 04:11:53 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-ext.html (next: http://localhost:57404/tests/dromaeo/cssquery-jquery.html) 04:11:54 INFO - PROCESS | 2380 | RSS: Main: 371150848 04:11:54 INFO - PROCESS | 2380 | 04:14:56 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57404/tests/dromaeo/cssquery-mootools.html) 04:14:57 INFO - PROCESS | 2380 | RSS: Main: 367972352 04:14:57 INFO - PROCESS | 2380 | 04:17:59 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57404/tests/dromaeo/cssquery-prototype.html) 04:17:59 INFO - PROCESS | 2380 | RSS: Main: 366440448 04:17:59 INFO - PROCESS | 2380 | 04:21:03 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57404/tests/dromaeo/cssquery-yui.html) 04:21:03 INFO - PROCESS | 2380 | RSS: Main: 337002496 04:21:03 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:57404/tests/dromaeo/cssquery-yui.html (next: http://localhost:57404/tests/dromaeo/cssquery-dojo.html) 04:24:07 INFO - PROCESS | 2380 | RSS: Main: 382717952 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | __start_tp_report 04:24:07 INFO - PROCESS | 2380 | _x_x_mozilla_page_load 04:24:07 INFO - PROCESS | 2380 | _x_x_mozilla_page_load_details 04:24:07 INFO - PROCESS | 2380 | |i|pagename|runs| 04:24:07 INFO - PROCESS | 2380 | |0;dojo.html;3403.7277017838574;3405.6424075472078;3422.931541369171;3425.400554902891;3476.808775517347;5616.494515493605;5669.886602267952;5692.943070569293;5738.311402631685;5742;5143.922841157386;5712.971435142818;5764.798232061889;5821.592488525795;5866.442687944649;5147.2021836615195;5222.294990176316;5281.603879709018;5299.814506492274;5386.138217885139;4624.3294722265255;5351.7056561889085;5364.758585863646;5401.108817045202;5401.918971215435;5164.992826398816;5186.7147306898105;5228.503292187236;5249.160134378481;5303.416624171338;5176.171812510018;5211.869703257411;5217.869553261161;5234.581233501311;5250.65870718402;5238.8690282742855;5262.684238945637;5267.288915996368;5279.788808447657;5301;5538.778448862041;5620.775168993236;5702.315722113373;5706.429357064302;5750.6262092963825;6238.189035425411;6299.8740025199695;6331.588446750947;6337.239531256233;6346.397092276226;6285.0886621440095;6318.905216421711;6322.936770632326;6353.936460635381;6365.140706004677;5700.743466544015;5744.971275143597;5750.88498230033;5849.941500584982;5906.26171728534;6296.244450665949;6300.558960872695;6327.905081423783;6341.0805433212845;6348.269948955837;6209.5032397408095;6227.564070515021;6228.408301211378;6232.158658581124;6232.657203853832;6153.7230824613;6227.06594010893;6257.2491301044165;6289.182406287154;6348.079528468346;6243.406876346739;6277.968610157011;6288.465480434214;6298.118263443122;6315.463185629181;6315.715792789336;6333;6334.208223972004;6334.334894836068;6346.555741098079;4840.564349208588;4874.902501949941;4877.512248775094;4887.437944636412;4928.112939670822;4400.713953593006;4422.226110430726;4446.777661116931;4457.465104187453;4491.775411229425;5815.098659707752;5828.009238429478;5845.357010728816;5877.0596704527925;5910.113482977587;6173.567850250529;6218.9378106218355;6289.056641503719;6291.307956124822;6344.365563443619;5770.653760774367;5824.359320474744;5838.503727183237;5854.326752423441;5923.4076592340425;4614.169449499055;5432.6468779529205;5438.91841622376;5447.12845944647;5450.972745136249;6223.937760622336;6357.110004599453;6376.681165941684;6377.681115944277;6401.263854656681;6225.595336303126;6255.311915689362;6274.498040156868;6327.955887278547;6333.2716737575765;6312.905306420315;6372.776952806629;6377.617342959437;6382.744690212339;6395.264544577433;6115.899138154997;6255.624662520263;6261.092141639528;6261.68691565429;6265.906011409924;6144.634315788364;6224.097505861534;6281.120643109877;6290.119383286252;6363.745450182126;5971.313298970674;6165.753369865334;6173.382661733791;6200.937990620217;6201.751929922752;5963.552733544915;6158.876822463436;6167.414095660905;6189.226346706661;6190.969045154746;5785.566082543742;5908.143319218775;5959.880802384015;5970.910436343372;6061.545384096298;6139.109829074849;6187.907181392373;6188.690565471618;6194.783182588588;6204.410580994799;5843.795467158629;6039.275286965592;6050.878982420415;6072.452566755555;6119.2350956130485 04:24:07 INFO - PROCESS | 2380 | |1;ext.html;490.95581397674204;493.87406211416084;525.5401523666792;538.9947747451005;570.577772448388;31172.908948186818;31749.206269843264;42475.15049699008;43574.56425435745;43714;43748.56251437485;43925;44040;44052.55947440524;44166.558334416644;8306.875396869036;9824.01759824019;9833.65582204623;9840.70477885661;9844.704658860246;8206.671733130668;9675.806483870318;9678.709638710814;9684.70945871625;9693.612255509806;9675.274354423447;9698.806023879519;9708.077732615391;9708.368956017835;9722.368046076983;9628.229741620653;9683.031696830261;9687.32188746788;9694.36986595869;9705.514724263758;9683.322167448281;9744.853827192597;9749.512524373824;9753.170980466668;9772.21822254218;40060.19879602391;40378.79810600928;40463.1907361854;40497;40505.1898962022;3243.0595127413258;3259.2177877309514;3385.153711572107;3416.436288012475;3596.0829988353066;28606;28667.56998645022;28871.278218044507;29487.852560737272;29616;2724.209979106057;2757.1728481455684;2771.085541771231;2804.705505921869;2816.5915942188267;436043.81978089904;437792.8110359428;438062;440220;440912;60296;60760;60879.08681369783;60882.69558652178;60951.39048609457;36300.63699363025;37124.628753712655;37241;38465;60214.69892650596;17598.560035998948;17602.735958960628;17735.645287094187;17744;17760.467185984442;13319.866801332057;13426.731465370833;13437.462501499831;13437.86562134366;13451.192928424325;16466.09436480981;16495.17524123813;18082.915025098537;19806;19962.90018549927;6733.730650774012;6787.694553745093;6935.930640693629;7991.72028978983;8063.556504392197;951.7240000399945;968.3995922527937;1038.3199004651815;1043.4678314059731;1111.0556027376826;27285.45429091407;29758;29769.106926792232;30151.09546713602;30220.395592088476;1090.7818436312768;1102.2009043443438;1104.8508451359126;1174.9118816088828;1225.6445630767157;15254.08475491474;15339.923300383427;15348.002379845275;15506.807489485656;15796.21018949071;642248;647477;648798;649733;650177;50755.74622126866;60100;60154;60260.69869650711;60275.69862150661;1304.5694920676153;1343.4424713743883;1349.6558377613646;1479.3786609623983;1495.9902066105335;25043.373915652253;31903.361932760752;31904.840475798403;32129.35741285208;32537.837310813295;54626.453735462135;55687.16469252884;55694;55770;55827;530170;550605;551995;552734;554031;23357.2992810216;23907.402314942265;23962;26712;27120.72879271182;36561;42093.57906421019;46040.07919841517;49471.25793113178;49976.75011624918;37226.627733722315;37923;38366.424503633025;38381;38827.98166284231;28983.420331592828;37423.87728368153;37941.81029094947;38122.809385952896;38201.235975279786;31513.28702062072;32339.19152021218;37162.25675486421;37544.43683344698;37810.81094594509;27835.58246626262;31998.52002220015;32406;33137;33532.664673352956;24226;24743.38141546476;24829.379265518506;25444.618330725425;25458.108966186093 04:24:07 INFO - PROCESS | 2380 | |2;jquery.html;335.2507146527511;395.6735693053231;402.62153575638916;404.4250013563034;421.72798544938513;20493.897530512346;21561.46096347592;21873.67189492159;21887.890560547195;22061.889690551565;20580.073896674647;20894.85078320692;21510.139594416243;22129.114835406606;22135.557288854212;5958.7616495340135;6182.938170618304;6190.195274614294;6228.781992630259;6251.937480625181;5999.220101386835;6118.541109416789;6128.57100003;6165.106059621352;6204.6587437690905;6119.877602447948;6159.969200153993;6161.568690191689;6168.920438923193;6172.506199504051;6052;6108.572399932006;6147.354527774564;6159.568830181866;6164.414380633855;5303.496167864047;5325.094733895247;5344.198370244456;5379.67721936685;5401.540869026098;25389.619155712495;25695;26042.73957260422;26579.734202658114;27045.78293976756;2280.133549251274;2494.987525062363;2528.3931856354525;2683.892644294226;2955.7487613552657;14092.929535352258;15050.698986020217;15270.618234544114;15517.689646207013;15673.294701738449;1648.4230519318262;1751.1156865782825;1799.5693423728167;1805.4403135028183;1812.7371531128044;122763;124430;124649.37675311656;125042;125785.99999999908;10830.350178989285;11103.944480277547;11428.542858285575;11523.76952460946;11716.88283117175;14393.92803035999;14482.855171448362;14485.49300774489;14490.058146220463;14505.782413263809;8653.39426240157;8845.867311990325;8871.024187339388;8963.148500892405;8971.237444817263;8073.6770529178175;8830.735077947671;8866.689665861664;8983.416077954913;8996.280297576179;6475.996220585816;6651.301613330627;6780.491463140284;6787.8121328767265;6798.082258894986;1688.50189194188;1905.961251118144;1912.2463652340584;1913.6939987315252;1938.2634598852346;842.7640260727006;936.157458287533;942.7649778789807;955.8375076237056;1046.576136664652;20659.69010464844;21847.781522184894;22000.88999554992;22015.889920550293;22037.559248814934;939.6147579492375;951.5004622573205;968.4576637083256;977.2816979520047;1043.9873322876797;11017.11863050954;11267.38029408374;11595.594154204562;11649.47577359021;11746.119041071954;610629;618430;622662;640136;641028;88283;122310;122482;124135.31090815518;129635.70364296425;1011.5852500474917;1031.8813336466253;1043.1029314789275;1045.6601604478424;1047;19566.21735130579;19842.404727858186;19893;19914;19925.10337034808;50540;51015.23477147771;52771.6252768634;53236.73381633067;54593.90812183813;52883.4711652894;76046.23953760392;76833;76926;79169.60415197887;11835.289882606727;12592.181508201938;12790;12858.035647326675;13191.417029956083;12238.449269782703;13210.27343496117;13391.397387117799;13891.861081389057;14082.788758168841;7784.455088143775;8257.339412847077;8356.958215208884;8441.577921104043;8511.8723219153;8115.79710507242;8206.343492520704;8406.117357677356;8414.579271036546;8435.283000944988;8334.458260213309;8444.282236010007;8551.444156129832;8635.525046122524;8667.95984481841;12020.63938081859;12414.379281035732;12546.87453125457;12691.619251422471;12786.36068196605;10624.309419887682;11145.662520497592;11610.722820489571;11997.340146292037;12076.275423474268 04:24:07 INFO - PROCESS | 2380 | |3;mootools.html;1418.4326269492196;1599.7760313556103;1607.7990251218598;1881.5108071901302;2009.7186393904847;7672.309492145706;8527.744167674971;8787.428817126898;8799.472031678095;8921.55392230389;8528.360372972036;8755.168259015383;8757.43076700014;8762.649494020232;8780.956095219515;2318.7797159269926;2437.293184976359;2479.5536803375385;2482.2925466242123;2485.801135909132;2409.447177313273;2466.1491785334033;2467.198160597813;2469.1111199967995;2469.2221950085664;2434.6348047792885;2451.4974430241846;2476.616124500705;2481.0695989004125;2483.428811373376;2427.4780922101654;2450.240425468102;2457.594496908008;2460.6678098456837;2462.8891699873548;2323.7095363079616;2388.832781705264;2399.4241382068353;2402.147237730598;2403.495265994143;7800.765977020698;8031.919680803234;8708.869366959501;8878.822423551557;8885.511296878669;5209.505097015777;5407.864803379907;5415.025295446818;5417.864553386157;5419.918701219485;3546.219831637036;3572.8928132156075;3650.945235821465;3729.7948612826285;3735.477033215352;1052.095198129608;1065.6217042949718;1066.226985435553;1077.4289626497969;1086.5056399338282;11658.883411165949;11780.175387722862;11784.764304713857;11790.88209117915;11805.291682498992;4770.165221086366;4828.420589529279;4928.704277743347;4932.605391568666;4967.950320496821;10079.344842585355;10133.797324053625;10139.340942838691;10140.594376225015;10151;5402.702851343135;5404.054290499225;5438.238646589481;5438.320209973754;5443.591730620219;4756.7145971241835;4894.755262236874;4931.087748766489;4946.629002824802;4963.10664080469;3808.847646094125;3844.7693138411223;3915.923121141652;3933.7443066201176;3980.721349505565;1294.2946094378585;1300.473308310136;1303.947842086325;1313.1202094596642;1321.4185758266333;807.9919200807917;847.9152084791594;879.7360791762445;932.3706498113746;984.5520288268821;5873.911891321633;6019.939800601938;6029.788957386558;6155.35368786271;6181.072839074175;804.4007214625142;894.4677916639589;937.329809186435;957.8228027814877;999.1756800639502;3888.902777430587;4001.2797696414923;4001.559828418872;4035.535913369941;4068.3694027425795;10401.63594274212;10808.567657293777;10835.133189344833;10926.672199834018;10941.945290273497;10710.571577136983;10764.515596798163;10796.730081747863;10900.836487452694;10967.670969870756;6572.638504882278;8202.17978202175;8292.834143317099;8312.6674933002;8350.4154709171;5670.829875103754;5776.588363564145;5879.970600146972;5897.823065308047;5944.019236825875;9577.23382129442;9778;10149.34029288094;10152.949235253776;10330.535125919201;9650.565724542554;10235.1300139486;10254.948725256327;10378.896211038094;10394.324368915995;7186.568805871664;7494.325510703845;7547.962260188664;7549.320561149523;7677.117131529945;8418.663253470038;8428.915710842813;8496.022957359983;8503.659853605786;8528.616212270586;7436.144843343085;7474.2525747424825;7534.623268836646;7574.772756817304;7673.34776544;6719.26088130315;7258.49190556656;7309;7438.256174382518;7558.962205188939;7050.612216328152;7079.681414336264;7127.180374256819;7127.82180445493;7134.108236470442;4833.758312084311;4890.9021819562695;4993.575546078624;5014.398272207359;5180.559652429586;4941.308216849716;5033.597312215087;5063.544281014726;5129.640925135204;5307.177387504943 04:24:07 INFO - PROCESS | 2380 | |4;prototype.html;309.52333406553896;313.60172580822353;327.05481159449187;331.953526506289;332.9966700332997;4232.470941132362;5275.446078161791;5290.539898946999;5307.416184219737;5347.732613369335;4282.507511636163;4654.2785868190485;4956.5291297326785;5213.741411617739;5289.947100528993;3312.850921708517;4026.557078721353;4047.7451989883084;4062.2484840304564;4072.87781366558;3659.1949771050326;4034.2939985502567;4066.2883995300854;4089.26393249215;4139.896502587444;3168.603924509436;3539.743391096162;3606.6753992140702;3643.1256498440316;4022.2558826617023;3800.1069748609057;3970.980145099285;4010.5108555556853;4030.355143177092;4047.311956967308;3273.4762438009802;3301.851416686255;3522.2603253316834;3534.9748572913822;3543.964560354415;4101.835926562934;4964.851054468336;5003.54968052877;5036.899262014776;5042.445331013586;3928.958825911136;4366;4408.250597398418;4555.271156614959;4556.681032327739;4892.902141957176;4940.987097644974;5075.949240507622;5449.7275136243425;5457.444628280921;3852.57621661617;3859.9035024124623;3870.4131306164336;3878.3627133549458;3888.5113527342523;4474.753888536129;5645.745941432645;5676.858078548069;5681.261436013292;5715.571332150063;3616.914925522333;3886.3393223152134;3918.8432462701744;3927.509061367329;3942;3442.3631628148873;3910.3156947534067;3998.5801490843623;4012.9398059029136;4033.072393349546;4205.369194620831;4550.522195169526;4604.493505714368;4630.467496237909;4633.930491042637;5517.834464966058;5539.861503462445;5568.820996473319;5570.384588469298;5593.244911936918;1058.8147074261974;1176.253079294654;1233.9876601234052;1241.7951038078797;1268.7502809732407;810.508664058124;819.336337566573;840.8780726794703;915.4415806358116;944.2776276148752;4231.703780735381;4259.446271984684;4303.009408461089;4829.227323628203;5016.072026675077;4695.859124226278;4786.736729479842;5315.548178404802;5405.837824865182;5474.534664553557;4057.8376864925663;4275.508316543575;4419.801108950105;4517.412736344254;4673.906521869543;2396.688430504058;2461.6799816023804;2559.7312282210473;2769.819961702483;2852.1728698677566;4850.345203397566;5596.888062238732;5605.1872478491205;5608.6073974821375;5619.353774315924;4456.376107344974;5023;5158.252053452228;5239.843184258557;5530.281063461724;4965.925511117336;5012.348394708664;5025.572826309732;5603.5797315202335;5640.405413509831;4741.644376671832;4955.4301255356095;5029;5137.8201762938115;5166.571711295685;5237.685738855681;5318.867028324323;5719.542436604978;5727.513161381329;5757.769689212385;5406.891587224531;5469.288992430958;5755.3381361144;5948.345681974893;6018.8169222390015;3378.3243351329343;3472.166679996833;3863.459115723857;3911.357229963365;3938.6455219030427;5205.219217117463;5257.631965762513;5291;5318;5737.340205876378;4446.599806017474;5182.844514664566;5244.4493328201515;5284;5578.553715702815;4800.399950006249;5016.266134956384;5356.303680521507;5527.336719593674;5578.856334451509;4810.086083644096;5090.796368145233;5127.846164615067;5425.321834770653;5443.455654434525;4620;4767.404074490689;5080.847574572768;5209.140491818959;5255.973720131375;4420.734755914656;5265.657732247391;5333.4133245343755;5371.355437347544;5404.486573775485 04:24:07 INFO - PROCESS | 2380 | |5;yui.html;656.1502853804326;664.1598378051765;722.3895808042206;760.1676164599764;762.79785856748;14774;15415.614609634764;15428.84283678723;15477.767833482507;15501.767473487907;13148.802767958488;14086.225257610828;14203.715925681507;14336.569902902904;14356.712865742678;927.323054170456;938.0853667674031;942.9198518125968;944.4003058058148;946.0870260198907;924.9871390827034;932.2262522106669;933.0995589256366;937.1799675284126;938.3384713776805;988.9357191782547;995.0696099147284;995.1640621877622;997.4314640654829;1002.0180223381091;986.6645340584236;987.3483500889414;988.9851652225223;989.5645915797062;990.336474562038;744.4602663069286;749.4678778067579;749.9512531685423;749.966251518683;751.4777229825273;12859.549915752903;13140.474381024747;13200.537981170613;13202.273874936875;13212.471501139942;11072.114230861513;11091.611793587186;11164.553417863275;11345.262557933707;11755.412229388496;9052.502112383816;9317.673881414186;9683.107360954786;10098.040686134806;10170.237232207613;4018.4976877890263;4179.686523510749;4198.328267477189;4322.848700295506;4341.4790225172865;63229.735405291634;65518;69276.6536167316;71314.57370852606;71583;8734.475931444134;9451.763705907408;9579.089986451276;9640.951795240979;9793.167580755715;20975.475613109487;20994.055267512693;21016.894915525325;21017;21039.579208415747;7325.194228634846;7404.555726656418;7491.288327608869;7555.320021198118;7727.806804829925;4130.648894843912;4172.979135104305;4177.3316269396755;4194.140201258735;4201.411802347674;3480.286541259036;3608.693261072838;3611.0791748104066;3613.56637203532;3623.710103191791;656.6651007985866;660.6894759463045;667.4993754683987;674;683.135833171039;569.291232415637;572.2743946812118;609.9237595300588;611.4252602553586;622.483338828771;7807.297343239135;8155.83688326242;8273.958630206811;8487.660493580188;8546.102659220816;517.7178437751435;554.8751530905514;556.3379578301808;558.9659130608424;608.3308360803081;3813.504244448204;3836.7506112103183;3875.224955008953;3995.9200815983518;3996.087329999948;82903;83681.58159209165;88311;91707.54146229225;92090;53013;75923;77222.61388693021;77989.22010779934;82150.17849821545;56534;63711.362886370545;65078;66060;67427.66286168537;7680.9615951922115;8181.263686268265;8232.711855085043;8312.25189732927;8325.209105135002;37304.88085357444;41486;42079.36880946849;42261;42645.786771067185;54045;59512.70243648754;59788;59933;60517.487062823784;10626.73433164177;11122.61070862516;11305.830412543653;11390.430478476208;11426.542938282708;11319.320840749582;11569.19015668895;11813.763724725286;11840.23038502529;11996.940015299868;11566.306021638393;11629.476673549541;11680.76638467209;11810.645680629596;11990.100742444525;11431.485583148611;11554.42227888619;11853.822192666945;12053.819192712292;12104.757904841677;11424.48589813477;11522.423878806194;11619.302841829518;11873.168878178445;12248.693782655504;8647;8740.734899858087;9487.715368538955;9517.666881659108;9530;7084.326988936043;8499.150084991452;9727.756806079904;9765.535169724599;9814.214862810815 04:24:07 INFO - PROCESS | 2380 | __end_tp_report 04:24:07 INFO - PROCESS | 2380 | __start_cc_report 04:24:07 INFO - PROCESS | 2380 | _x_x_mozilla_cycle_collect,1203 04:24:07 INFO - PROCESS | 2380 | __end_cc_report 04:24:07 INFO - PROCESS | 2380 | __startTimestamp1462793047334__endTimestamp 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | ------- Summary: start ------- 04:24:07 INFO - PROCESS | 2380 | Number of tests: 6 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#0] dojo.html Cycles:165 Average:5787.39 Median:6039.28 stddev:657.78 (10.9%) stddev-sans-first:632.66 04:24:07 INFO - PROCESS | 2380 | Values: 3403.7 3405.6 3422.9 3425.4 3476.8 5616.5 5669.9 5692.9 5738.3 5742.0 5143.9 5713.0 5764.8 5821.6 5866.4 5147.2 5222.3 5281.6 5299.8 5386.1 4624.3 5351.7 5364.8 5401.1 5401.9 5165.0 5186.7 5228.5 5249.2 5303.4 5176.2 5211.9 5217.9 5234.6 5250.7 5238.9 5262.7 5267.3 5279.8 5301.0 5538.8 5620.8 5702.3 5706.4 5750.6 6238.2 6299.9 6331.6 6337.2 6346.4 6285.1 6318.9 6322.9 6353.9 6365.1 5700.7 5745.0 5750.9 5849.9 5906.3 6296.2 6300.6 6327.9 6341.1 6348.3 6209.5 6227.6 6228.4 6232.2 6232.7 6153.7 6227.1 6257.2 6289.2 6348.1 6243.4 6278.0 6288.5 6298.1 6315.5 6315.7 6333.0 6334.2 6334.3 6346.6 4840.6 4874.9 4877.5 4887.4 4928.1 4400.7 4422.2 4446.8 4457.5 4491.8 5815.1 5828.0 5845.4 5877.1 5910.1 6173.6 6218.9 6289.1 6291.3 6344.4 5770.7 5824.4 5838.5 5854.3 5923.4 4614.2 5432.6 5438.9 5447.1 5451.0 6223.9 6357.1 6376.7 6377.7 6401.3 6225.6 6255.3 6274.5 6328.0 6333.3 6312.9 6372.8 6377.6 6382.7 6395.3 6115.9 6255.6 6261.1 6261.7 6265.9 6144.6 6224.1 6281.1 6290.1 6363.7 5971.3 6165.8 6173.4 6200.9 6201.8 5963.6 6158.9 6167.4 6189.2 6191.0 5785.6 5908.1 5959.9 5970.9 6061.5 6139.1 6187.9 6188.7 6194.8 6204.4 5843.8 6039.3 6050.9 6072.5 6119.2 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#1] ext.html Cycles:180 Average:67476.60 Median:38283.83 stddev:147413.10 (385.1%) stddev-sans-first:147740.84 04:24:07 INFO - PROCESS | 2380 | Values: 491.0 493.9 525.5 539.0 570.6 31172.9 31749.2 42475.2 43574.6 43714.0 43748.6 43925.0 44040.0 44052.6 44166.6 8306.9 9824.0 9833.7 9840.7 9844.7 8206.7 9675.8 9678.7 9684.7 9693.6 9675.3 9698.8 9708.1 9708.4 9722.4 9628.2 9683.0 9687.3 9694.4 9705.5 9683.3 9744.9 9749.5 9753.2 9772.2 40060.2 40378.8 40463.2 40497.0 40505.2 3243.1 3259.2 3385.2 3416.4 3596.1 28606.0 28667.6 28871.3 29487.9 29616.0 2724.2 2757.2 2771.1 2804.7 2816.6 436043.8 437792.8 438062.0 440220.0 440912.0 60296.0 60760.0 60879.1 60882.7 60951.4 36300.6 37124.6 37241.0 38465.0 60214.7 17598.6 17602.7 17735.6 17744.0 17760.5 13319.9 13426.7 13437.5 13437.9 13451.2 16466.1 16495.2 18082.9 19806.0 19962.9 6733.7 6787.7 6935.9 7991.7 8063.6 951.7 968.4 1038.3 1043.5 1111.1 27285.5 29758.0 29769.1 30151.1 30220.4 1090.8 1102.2 1104.9 1174.9 1225.6 15254.1 15339.9 15348.0 15506.8 15796.2 642248.0 647477.0 648798.0 649733.0 650177.0 50755.7 60100.0 60154.0 60260.7 60275.7 1304.6 1343.4 1349.7 1479.4 1496.0 25043.4 31903.4 31904.8 32129.4 32537.8 54626.5 55687.2 55694.0 55770.0 55827.0 530170.0 550605.0 551995.0 552734.0 554031.0 23357.3 23907.4 23962.0 26712.0 27120.7 36561.0 42093.6 46040.1 49471.3 49976.8 37226.6 37923.0 38366.4 38381.0 38828.0 28983.4 37423.9 37941.8 38122.8 38201.2 31513.3 32339.2 37162.3 37544.4 37810.8 27835.6 31998.5 32406.0 33137.0 33532.7 24226.0 24743.4 24829.4 25444.6 25458.1 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#2] jquery.html Cycles:180 Average:35980.07 Median:25868.87 stddev:104123.97 (402.5%) stddev-sans-first:104381.67 04:24:07 INFO - PROCESS | 2380 | Values: 335.3 395.7 402.6 404.4 421.7 20493.9 21561.5 21873.7 21887.9 22061.9 20580.1 20894.9 21510.1 22129.1 22135.6 5958.8 6182.9 6190.2 6228.8 6251.9 5999.2 6118.5 6128.6 6165.1 6204.7 6119.9 6160.0 6161.6 6168.9 6172.5 6052.0 6108.6 6147.4 6159.6 6164.4 5303.5 5325.1 5344.2 5379.7 5401.5 25389.6 25695.0 26042.7 26579.7 27045.8 2280.1 2495.0 2528.4 2683.9 2955.7 14092.9 15050.7 15270.6 15517.7 15673.3 1648.4 1751.1 1799.6 1805.4 1812.7 122763.0 124430.0 124649.4 125042.0 125786.0 10830.4 11103.9 11428.5 11523.8 11716.9 14393.9 14482.9 14485.5 14490.1 14505.8 8653.4 8845.9 8871.0 8963.1 8971.2 8073.7 8830.7 8866.7 8983.4 8996.3 6476.0 6651.3 6780.5 6787.8 6798.1 1688.5 1906.0 1912.2 1913.7 1938.3 842.8 936.2 942.8 955.8 1046.6 20659.7 21847.8 22000.9 22015.9 22037.6 939.6 951.5 968.5 977.3 1044.0 11017.1 11267.4 11595.6 11649.5 11746.1 610629.0 618430.0 622662.0 640136.0 641028.0 88283.0 122310.0 122482.0 124135.3 129635.7 1011.6 1031.9 1043.1 1045.7 1047.0 19566.2 19842.4 19893.0 19914.0 19925.1 50540.0 51015.2 52771.6 53236.7 54593.9 52883.5 76046.2 76833.0 76926.0 79169.6 11835.3 12592.2 12790.0 12858.0 13191.4 12238.4 13210.3 13391.4 13891.9 14082.8 7784.5 8257.3 8357.0 8441.6 8511.9 8115.8 8206.3 8406.1 8414.6 8435.3 8334.5 8444.3 8551.4 8635.5 8668.0 12020.6 12414.4 12546.9 12691.6 12786.4 10624.3 11145.7 11610.7 11997.3 12076.3 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#3] mootools.html Cycles:180 Average:5760.89 Median:4936.96 stddev:3195.55 (64.7%) stddev-sans-first:3187.85 04:24:07 INFO - PROCESS | 2380 | Values: 1418.4 1599.8 1607.8 1881.5 2009.7 7672.3 8527.7 8787.4 8799.5 8921.6 8528.4 8755.2 8757.4 8762.6 8781.0 2318.8 2437.3 2479.6 2482.3 2485.8 2409.4 2466.1 2467.2 2469.1 2469.2 2434.6 2451.5 2476.6 2481.1 2483.4 2427.5 2450.2 2457.6 2460.7 2462.9 2323.7 2388.8 2399.4 2402.1 2403.5 7800.8 8031.9 8708.9 8878.8 8885.5 5209.5 5407.9 5415.0 5417.9 5419.9 3546.2 3572.9 3650.9 3729.8 3735.5 1052.1 1065.6 1066.2 1077.4 1086.5 11658.9 11780.2 11784.8 11790.9 11805.3 4770.2 4828.4 4928.7 4932.6 4968.0 10079.3 10133.8 10139.3 10140.6 10151.0 5402.7 5404.1 5438.2 5438.3 5443.6 4756.7 4894.8 4931.1 4946.6 4963.1 3808.8 3844.8 3915.9 3933.7 3980.7 1294.3 1300.5 1303.9 1313.1 1321.4 808.0 847.9 879.7 932.4 984.6 5873.9 6019.9 6029.8 6155.4 6181.1 804.4 894.5 937.3 957.8 999.2 3888.9 4001.3 4001.6 4035.5 4068.4 10401.6 10808.6 10835.1 10926.7 10941.9 10710.6 10764.5 10796.7 10900.8 10967.7 6572.6 8202.2 8292.8 8312.7 8350.4 5670.8 5776.6 5880.0 5897.8 5944.0 9577.2 9778.0 10149.3 10152.9 10330.5 9650.6 10235.1 10254.9 10378.9 10394.3 7186.6 7494.3 7548.0 7549.3 7677.1 8418.7 8428.9 8496.0 8503.7 8528.6 7436.1 7474.3 7534.6 7574.8 7673.3 6719.3 7258.5 7309.0 7438.3 7559.0 7050.6 7079.7 7127.2 7127.8 7134.1 4833.8 4890.9 4993.6 5014.4 5180.6 4941.3 5033.6 5063.5 5129.6 5307.2 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#4] prototype.html Cycles:180 Average:4340.98 Median:4793.57 stddev:1319.52 (27.5%) stddev-sans-first:1288.06 04:24:07 INFO - PROCESS | 2380 | Values: 309.5 313.6 327.1 332.0 333.0 4232.5 5275.4 5290.5 5307.4 5347.7 4282.5 4654.3 4956.5 5213.7 5289.9 3312.9 4026.6 4047.7 4062.2 4072.9 3659.2 4034.3 4066.3 4089.3 4139.9 3168.6 3539.7 3606.7 3643.1 4022.3 3800.1 3971.0 4010.5 4030.4 4047.3 3273.5 3301.9 3522.3 3535.0 3544.0 4101.8 4964.9 5003.5 5036.9 5042.4 3929.0 4366.0 4408.3 4555.3 4556.7 4892.9 4941.0 5075.9 5449.7 5457.4 3852.6 3859.9 3870.4 3878.4 3888.5 4474.8 5645.7 5676.9 5681.3 5715.6 3616.9 3886.3 3918.8 3927.5 3942.0 3442.4 3910.3 3998.6 4012.9 4033.1 4205.4 4550.5 4604.5 4630.5 4633.9 5517.8 5539.9 5568.8 5570.4 5593.2 1058.8 1176.3 1234.0 1241.8 1268.8 810.5 819.3 840.9 915.4 944.3 4231.7 4259.4 4303.0 4829.2 5016.1 4695.9 4786.7 5315.5 5405.8 5474.5 4057.8 4275.5 4419.8 4517.4 4673.9 2396.7 2461.7 2559.7 2769.8 2852.2 4850.3 5596.9 5605.2 5608.6 5619.4 4456.4 5023.0 5158.3 5239.8 5530.3 4965.9 5012.3 5025.6 5603.6 5640.4 4741.6 4955.4 5029.0 5137.8 5166.6 5237.7 5318.9 5719.5 5727.5 5757.8 5406.9 5469.3 5755.3 5948.3 6018.8 3378.3 3472.2 3863.5 3911.4 3938.6 5205.2 5257.6 5291.0 5318.0 5737.3 4446.6 5182.8 5244.4 5284.0 5578.6 4800.4 5016.3 5356.3 5527.3 5578.9 4810.1 5090.8 5127.8 5425.3 5443.5 4620.0 4767.4 5080.8 5209.1 5256.0 4420.7 5265.7 5333.4 5371.4 5404.5 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - PROCESS | 2380 | [#5] yui.html Cycles:180 Average:16943.59 Median:30562.91 stddev:23162.50 (75.8%) stddev-sans-first:23195.19 04:24:07 INFO - PROCESS | 2380 | Values: 656.2 664.2 722.4 760.2 762.8 14774.0 15415.6 15428.8 15477.8 15501.8 13148.8 14086.2 14203.7 14336.6 14356.7 927.3 938.1 942.9 944.4 946.1 925.0 932.2 933.1 937.2 938.3 988.9 995.1 995.2 997.4 1002.0 986.7 987.3 989.0 989.6 990.3 744.5 749.5 750.0 750.0 751.5 12859.5 13140.5 13200.5 13202.3 13212.5 11072.1 11091.6 11164.6 11345.3 11755.4 9052.5 9317.7 9683.1 10098.0 10170.2 4018.5 4179.7 4198.3 4322.8 4341.5 63229.7 65518.0 69276.7 71314.6 71583.0 8734.5 9451.8 9579.1 9641.0 9793.2 20975.5 20994.1 21016.9 21017.0 21039.6 7325.2 7404.6 7491.3 7555.3 7727.8 4130.6 4173.0 4177.3 4194.1 4201.4 3480.3 3608.7 3611.1 3613.6 3623.7 656.7 660.7 667.5 674.0 683.1 569.3 572.3 609.9 611.4 622.5 7807.3 8155.8 8274.0 8487.7 8546.1 517.7 554.9 556.3 559.0 608.3 3813.5 3836.8 3875.2 3995.9 3996.1 82903.0 83681.6 88311.0 91707.5 92090.0 53013.0 75923.0 77222.6 77989.2 82150.2 56534.0 63711.4 65078.0 66060.0 67427.7 7681.0 8181.3 8232.7 8312.3 8325.2 37304.9 41486.0 42079.4 42261.0 42645.8 54045.0 59512.7 59788.0 59933.0 60517.5 10626.7 11122.6 11305.8 11390.4 11426.5 11319.3 11569.2 11813.8 11840.2 11996.9 11566.3 11629.5 11680.8 11810.6 11990.1 11431.5 11554.4 11853.8 12053.8 12104.8 11424.5 11522.4 11619.3 11873.2 12248.7 8647.0 8740.7 9487.7 9517.7 9530.0 7084.3 8499.2 9727.8 9765.5 9814.2 04:24:07 INFO - PROCESS | 2380 | -------- Summary: end -------- 04:24:07 INFO - PROCESS | 2380 | 04:24:07 INFO - TEST-INFO | 2380: exit 0 04:24:07 INFO - TEST-OK | dromaeo_css | took 1114761ms 04:24:07 INFO - TEST-START | kraken 04:24:07 INFO - operating with platform_type : mac_ 04:24:07 INFO - Initialising browser for kraken test... 04:24:07 INFO - TEST-INFO | started process 2382 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpMDV2VR/profile http://localhost:57404/getInfo.html) 04:24:10 INFO - PROCESS | 2382 | __metrics Screen width/height:1600/1200 04:24:10 INFO - PROCESS | 2382 | colorDepth:24 04:24:10 INFO - PROCESS | 2382 | Browser inner width/height: 1024/689 04:24:10 INFO - PROCESS | 2382 | __metrics 04:24:10 INFO - PROCESS | 2382 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:24:10 ERROR - PROCESS | 2382 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:24:10 INFO - TEST-INFO | 2382: exit 0 04:24:10 INFO - Browser initialized. 04:24:10 INFO - Running cycle 1/1 for kraken test... 04:24:10 INFO - TEST-INFO | started process 2384 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpMDV2VR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:22 INFO - PROCESS | 2384 | RSS: Main: 239190016 04:24:22 INFO - PROCESS | 2384 | 04:24:25 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57404/tests/kraken/driver.html?testName=audio-beat-detection) 04:24:26 INFO - PROCESS | 2384 | RSS: Main: 287309824 04:24:26 INFO - PROCESS | 2384 | 04:24:28 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57404/tests/kraken/driver.html?testName=audio-dft) 04:24:29 INFO - PROCESS | 2384 | RSS: Main: 295538688 04:24:29 INFO - PROCESS | 2384 | 04:24:33 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57404/tests/kraken/driver.html?testName=audio-fft) 04:24:33 INFO - PROCESS | 2384 | RSS: Main: 382885888 04:24:33 INFO - PROCESS | 2384 | 04:24:35 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57404/tests/kraken/driver.html?testName=audio-oscillator) 04:24:36 INFO - PROCESS | 2384 | RSS: Main: 284086272 04:24:36 INFO - PROCESS | 2384 | 04:24:38 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57404/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:24:39 INFO - PROCESS | 2384 | RSS: Main: 287903744 04:24:39 INFO - PROCESS | 2384 | 04:24:41 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57404/tests/kraken/driver.html?testName=imaging-darkroom) 04:24:42 INFO - PROCESS | 2384 | RSS: Main: 324878336 04:24:42 INFO - PROCESS | 2384 | 04:24:45 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57404/tests/kraken/driver.html?testName=imaging-desaturate) 04:24:45 INFO - PROCESS | 2384 | RSS: Main: 326172672 04:24:45 INFO - PROCESS | 2384 | 04:24:48 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57404/tests/kraken/driver.html?testName=json-parse-financial) 04:24:49 INFO - PROCESS | 2384 | RSS: Main: 326049792 04:24:49 INFO - PROCESS | 2384 | 04:24:50 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57404/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:24:51 INFO - PROCESS | 2384 | RSS: Main: 312922112 04:24:51 INFO - PROCESS | 2384 | 04:24:53 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:24:53 INFO - PROCESS | 2384 | RSS: Main: 320053248 04:24:53 INFO - PROCESS | 2384 | 04:24:55 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:24:56 INFO - PROCESS | 2384 | RSS: Main: 321454080 04:24:56 INFO - PROCESS | 2384 | 04:24:59 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:00 INFO - PROCESS | 2384 | RSS: Main: 326266880 04:25:00 INFO - PROCESS | 2384 | 04:25:03 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:03 INFO - PROCESS | 2384 | RSS: Main: 323874816 04:25:03 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:57404/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57404/tests/kraken/driver.html?testName=ai-astar) 04:25:05 INFO - PROCESS | 2384 | RSS: Main: 370802688 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | __start_tp_report 04:25:05 INFO - PROCESS | 2384 | _x_x_mozilla_page_load 04:25:05 INFO - PROCESS | 2384 | _x_x_mozilla_page_load_details 04:25:05 INFO - PROCESS | 2384 | |i|pagename|runs| 04:25:05 INFO - PROCESS | 2384 | |0;ai-astar;112;105;112;112;113;113;113;113;112;111 04:25:05 INFO - PROCESS | 2384 | |1;audio-beat-detection;157;119;142;165;117;139;135;118;168;117 04:25:05 INFO - PROCESS | 2384 | |2;audio-dft;184;183;182;182;185;183;182;183;186;185 04:25:05 INFO - PROCESS | 2384 | |3;audio-fft;76;78;56;109;57;60;56;57;116;56 04:25:05 INFO - PROCESS | 2384 | |4;audio-oscillator;91;97;102;79;77;77;97;105;111;137 04:25:05 INFO - PROCESS | 2384 | |5;imaging-gaussian-blur;86;84;86;84;86;83;88;84;86;84 04:25:05 INFO - PROCESS | 2384 | |6;imaging-darkroom;92;92;92;91;92;91;92;93;93;92 04:25:05 INFO - PROCESS | 2384 | |7;imaging-desaturate;83;83;83;83;84;85;90;83;85;84 04:25:05 INFO - PROCESS | 2384 | |8;json-parse-financial;60;60;61;61;60;59;60;77;59;56 04:25:05 INFO - PROCESS | 2384 | |9;json-stringify-tinderbox;64;65;71;78;72;73;69;69;68;69 04:25:05 INFO - PROCESS | 2384 | |10;stanford-crypto-aes;85;85;85;83;84;84;82;86;85;86 04:25:05 INFO - PROCESS | 2384 | |11;stanford-crypto-ccm;149;154;140;142;118;141;144;144;192;141 04:25:05 INFO - PROCESS | 2384 | |12;stanford-crypto-pbkdf2;212;196;167;171;169;168;168;167;164;166 04:25:05 INFO - PROCESS | 2384 | |13;stanford-crypto-sha256-iterative;68;67;69;69;65;65;66;65;65;68 04:25:05 INFO - PROCESS | 2384 | __end_tp_report 04:25:05 INFO - PROCESS | 2384 | __start_cc_report 04:25:05 INFO - PROCESS | 2384 | _x_x_mozilla_cycle_collect,1929 04:25:05 INFO - PROCESS | 2384 | __end_cc_report 04:25:05 INFO - PROCESS | 2384 | __startTimestamp1462793105649__endTimestamp 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | ------- Summary: start ------- 04:25:05 INFO - PROCESS | 2384 | Number of tests: 14 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.41 (2.1%) stddev-sans-first:2.55 04:25:05 INFO - PROCESS | 2384 | Values: 112.0 105.0 112.0 112.0 113.0 113.0 113.0 113.0 112.0 111.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:140.50 stddev:20.16 (14.3%) stddev-sans-first:20.14 04:25:05 INFO - PROCESS | 2384 | Values: 157.0 119.0 142.0 165.0 117.0 139.0 135.0 118.0 168.0 117.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.43 (0.8%) stddev-sans-first:1.51 04:25:05 INFO - PROCESS | 2384 | Values: 184.0 183.0 182.0 182.0 185.0 183.0 182.0 183.0 186.0 185.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#3] audio-fft Cycles:10 Average:72.10 Median:57.00 stddev:22.90 (40.2%) stddev-sans-first:24.24 04:25:05 INFO - PROCESS | 2384 | Values: 76.0 78.0 56.0 109.0 57.0 60.0 56.0 57.0 116.0 56.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:78.00 stddev:18.39 (23.6%) stddev-sans-first:19.36 04:25:05 INFO - PROCESS | 2384 | Values: 91.0 97.0 102.0 79.0 77.0 77.0 97.0 105.0 111.0 137.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 04:25:05 INFO - PROCESS | 2384 | Values: 86.0 84.0 86.0 84.0 86.0 83.0 88.0 84.0 86.0 84.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#6] imaging-darkroom Cycles:10 Average:92.00 Median:92.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 04:25:05 INFO - PROCESS | 2384 | Values: 92.0 92.0 92.0 91.0 92.0 91.0 92.0 93.0 93.0 92.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:2.16 (2.6%) stddev-sans-first:2.24 04:25:05 INFO - PROCESS | 2384 | Values: 83.0 83.0 83.0 83.0 84.0 85.0 90.0 83.0 85.0 84.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:60.00 stddev:5.70 (9.5%) stddev-sans-first:6.02 04:25:05 INFO - PROCESS | 2384 | Values: 60.0 60.0 61.0 61.0 60.0 59.0 60.0 77.0 59.0 56.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:4.02 (5.7%) stddev-sans-first:3.68 04:25:05 INFO - PROCESS | 2384 | Values: 64.0 65.0 71.0 78.0 72.0 73.0 69.0 69.0 68.0 69.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 04:25:05 INFO - PROCESS | 2384 | Values: 85.0 85.0 85.0 83.0 84.0 84.0 82.0 86.0 85.0 86.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:144.00 stddev:18.50 (12.8%) stddev-sans-first:19.60 04:25:05 INFO - PROCESS | 2384 | Values: 149.0 154.0 140.0 142.0 118.0 141.0 144.0 144.0 192.0 141.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:168.50 stddev:15.95 (9.5%) stddev-sans-first:9.70 04:25:05 INFO - PROCESS | 2384 | Values: 212.0 196.0 167.0 171.0 169.0 168.0 168.0 167.0 164.0 166.0 04:25:05 INFO - PROCESS | 2384 | 04:25:05 INFO - PROCESS | 2384 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.50 stddev:1.70 (2.5%) stddev-sans-first:1.74 04:25:05 INFO - PROCESS | 2384 | Values: 68.0 67.0 69.0 69.0 65.0 65.0 66.0 65.0 65.0 68.0 04:25:05 INFO - PROCESS | 2384 | -------- Summary: end -------- 04:25:05 INFO - PROCESS | 2384 | 04:25:06 INFO - TEST-INFO | 2384: exit 0 04:25:06 INFO - TEST-OK | kraken | took 58520ms 04:25:06 INFO - SUITE-END | took 1173s 04:25:06 INFO - Completed test suite (00:19:33) 04:25:06 INFO - javascript benchmark 04:25:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.7277017838574, 3405.6424075472078, 3422.931541369171, 3425.400554902891, 3476.808775517347, 5616.494515493605, 5669.886602267952, 5692.943070569293, 5738.311402631685, 5742.0, 5143.922841157386, 5712.971435142818, 5764.798232061889, 5821.592488525795, 5866.442687944649, 5147.2021836615195, 5222.294990176316, 5281.603879709018, 5299.814506492274, 5386.138217885139, 4624.3294722265255, 5351.7056561889085, 5364.758585863646, 5401.108817045202, 5401.918971215435, 5164.992826398816, 5186.7147306898105, 5228.503292187236, 5249.160134378481, 5303.416624171338, 5176.171812510018, 5211.869703257411, 5217.869553261161, 5234.581233501311, 5250.65870718402, 5238.8690282742855, 5262.684238945637, 5267.288915996368, 5279.788808447657, 5301.0, 5538.778448862041, 5620.775168993236, 5702.315722113373, 5706.429357064302, 5750.6262092963825, 6238.189035425411, 6299.8740025199695, 6331.588446750947, 6337.239531256233, 6346.397092276226, 6285.0886621440095, 6318.905216421711, 6322.936770632326, 6353.936460635381, 6365.140706004677, 5700.743466544015, 5744.971275143597, 5750.88498230033, 5849.941500584982, 5906.26171728534, 6296.244450665949, 6300.558960872695, 6327.905081423783, 6341.0805433212845, 6348.269948955837, 6209.5032397408095, 6227.564070515021, 6228.408301211378, 6232.158658581124, 6232.657203853832, 6153.7230824613, 6227.06594010893, 6257.2491301044165, 6289.182406287154, 6348.079528468346, 6243.406876346739, 6277.968610157011, 6288.465480434214, 6298.118263443122, 6315.463185629181, 6315.715792789336, 6333.0, 6334.208223972004, 6334.334894836068, 6346.555741098079, 4840.564349208588, 4874.902501949941, 4877.512248775094, 4887.437944636412, 4928.112939670822, 4400.713953593006, 4422.226110430726, 4446.777661116931, 4457.465104187453, 4491.775411229425, 5815.098659707752, 5828.009238429478, 5845.357010728816, 5877.0596704527925, 5910.113482977587, 6173.567850250529, 6218.9378106218355, 6289.056641503719, 6291.307956124822, 6344.365563443619, 5770.653760774367, 5824.359320474744, 5838.503727183237, 5854.326752423441, 5923.4076592340425, 4614.169449499055, 5432.6468779529205, 5438.91841622376, 5447.12845944647, 5450.972745136249, 6223.937760622336, 6357.110004599453, 6376.681165941684, 6377.681115944277, 6401.263854656681, 6225.595336303126, 6255.311915689362, 6274.498040156868, 6327.955887278547, 6333.2716737575765, 6312.905306420315, 6372.776952806629, 6377.617342959437, 6382.744690212339, 6395.264544577433, 6115.899138154997, 6255.624662520263, 6261.092141639528, 6261.68691565429, 6265.906011409924, 6144.634315788364, 6224.097505861534, 6281.120643109877, 6290.119383286252, 6363.745450182126, 5971.313298970674, 6165.753369865334, 6173.382661733791, 6200.937990620217, 6201.751929922752, 5963.552733544915, 6158.876822463436, 6167.414095660905, 6189.226346706661, 6190.969045154746, 5785.566082543742, 5908.143319218775, 5959.880802384015, 5970.910436343372, 6061.545384096298, 6139.109829074849, 6187.907181392373, 6188.690565471618, 6194.783182588588, 6204.410580994799, 5843.795467158629, 6039.275286965592, 6050.878982420415, 6072.452566755555, 6119.2350956130485], "value": 5744.649962346353, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.95581397674204, 493.87406211416084, 525.5401523666792, 538.9947747451005, 570.577772448388, 31172.908948186818, 31749.206269843264, 42475.15049699008, 43574.56425435745, 43714.0, 43748.56251437485, 43925.0, 44040.0, 44052.55947440524, 44166.558334416644, 8306.875396869036, 9824.01759824019, 9833.65582204623, 9840.70477885661, 9844.704658860246, 8206.671733130668, 9675.806483870318, 9678.709638710814, 9684.70945871625, 9693.612255509806, 9675.274354423447, 9698.806023879519, 9708.077732615391, 9708.368956017835, 9722.368046076983, 9628.229741620653, 9683.031696830261, 9687.32188746788, 9694.36986595869, 9705.514724263758, 9683.322167448281, 9744.853827192597, 9749.512524373824, 9753.170980466668, 9772.21822254218, 40060.19879602391, 40378.79810600928, 40463.1907361854, 40497.0, 40505.1898962022, 3243.0595127413258, 3259.2177877309514, 3385.153711572107, 3416.436288012475, 3596.0829988353066, 28606.0, 28667.56998645022, 28871.278218044507, 29487.852560737272, 29616.0, 2724.209979106057, 2757.1728481455684, 2771.085541771231, 2804.705505921869, 2816.5915942188267, 436043.81978089904, 437792.8110359428, 438062.0, 440220.0, 440912.0, 60296.0, 60760.0, 60879.08681369783, 60882.69558652178, 60951.39048609457, 36300.63699363025, 37124.628753712655, 37241.0, 38465.0, 60214.69892650596, 17598.560035998948, 17602.735958960628, 17735.645287094187, 17744.0, 17760.467185984442, 13319.866801332057, 13426.731465370833, 13437.462501499831, 13437.86562134366, 13451.192928424325, 16466.09436480981, 16495.17524123813, 18082.915025098537, 19806.0, 19962.90018549927, 6733.730650774012, 6787.694553745093, 6935.930640693629, 7991.72028978983, 8063.556504392197, 951.7240000399945, 968.3995922527937, 1038.3199004651815, 1043.4678314059731, 1111.0556027376826, 27285.45429091407, 29758.0, 29769.106926792232, 30151.09546713602, 30220.395592088476, 1090.7818436312768, 1102.2009043443438, 1104.8508451359126, 1174.9118816088828, 1225.6445630767157, 15254.08475491474, 15339.923300383427, 15348.002379845275, 15506.807489485656, 15796.21018949071, 642248.0, 647477.0, 648798.0, 649733.0, 650177.0, 50755.74622126866, 60100.0, 60154.0, 60260.69869650711, 60275.69862150661, 1304.5694920676153, 1343.4424713743883, 1349.6558377613646, 1479.3786609623983, 1495.9902066105335, 25043.373915652253, 31903.361932760752, 31904.840475798403, 32129.35741285208, 32537.837310813295, 54626.453735462135, 55687.16469252884, 55694.0, 55770.0, 55827.0, 530170.0, 550605.0, 551995.0, 552734.0, 554031.0, 23357.2992810216, 23907.402314942265, 23962.0, 26712.0, 27120.72879271182, 36561.0, 42093.57906421019, 46040.07919841517, 49471.25793113178, 49976.75011624918, 37226.627733722315, 37923.0, 38366.424503633025, 38381.0, 38827.98166284231, 28983.420331592828, 37423.87728368153, 37941.81029094947, 38122.809385952896, 38201.235975279786, 31513.28702062072, 32339.19152021218, 37162.25675486421, 37544.43683344698, 37810.81094594509, 27835.58246626262, 31998.52002220015, 32406.0, 33137.0, 33532.664673352956, 24226.0, 24743.38141546476, 24829.379265518506, 25444.618330725425, 25458.108966186093], "value": 19576.233749532064, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.2507146527511, 395.6735693053231, 402.62153575638916, 404.4250013563034, 421.72798544938513, 20493.897530512346, 21561.46096347592, 21873.67189492159, 21887.890560547195, 22061.889690551565, 20580.073896674647, 20894.85078320692, 21510.139594416243, 22129.114835406606, 22135.557288854212, 5958.7616495340135, 6182.938170618304, 6190.195274614294, 6228.781992630259, 6251.937480625181, 5999.220101386835, 6118.541109416789, 6128.57100003, 6165.106059621352, 6204.6587437690905, 6119.877602447948, 6159.969200153993, 6161.568690191689, 6168.920438923193, 6172.506199504051, 6052.0, 6108.572399932006, 6147.354527774564, 6159.568830181866, 6164.414380633855, 5303.496167864047, 5325.094733895247, 5344.198370244456, 5379.67721936685, 5401.540869026098, 25389.619155712495, 25695.0, 26042.73957260422, 26579.734202658114, 27045.78293976756, 2280.133549251274, 2494.987525062363, 2528.3931856354525, 2683.892644294226, 2955.7487613552657, 14092.929535352258, 15050.698986020217, 15270.618234544114, 15517.689646207013, 15673.294701738449, 1648.4230519318262, 1751.1156865782825, 1799.5693423728167, 1805.4403135028183, 1812.7371531128044, 122763.0, 124430.0, 124649.37675311656, 125042.0, 125785.99999999908, 10830.350178989285, 11103.944480277547, 11428.542858285575, 11523.76952460946, 11716.88283117175, 14393.92803035999, 14482.855171448362, 14485.49300774489, 14490.058146220463, 14505.782413263809, 8653.39426240157, 8845.867311990325, 8871.024187339388, 8963.148500892405, 8971.237444817263, 8073.6770529178175, 8830.735077947671, 8866.689665861664, 8983.416077954913, 8996.280297576179, 6475.996220585816, 6651.301613330627, 6780.491463140284, 6787.8121328767265, 6798.082258894986, 1688.50189194188, 1905.961251118144, 1912.2463652340584, 1913.6939987315252, 1938.2634598852346, 842.7640260727006, 936.157458287533, 942.7649778789807, 955.8375076237056, 1046.576136664652, 20659.69010464844, 21847.781522184894, 22000.88999554992, 22015.889920550293, 22037.559248814934, 939.6147579492375, 951.5004622573205, 968.4576637083256, 977.2816979520047, 1043.9873322876797, 11017.11863050954, 11267.38029408374, 11595.594154204562, 11649.47577359021, 11746.119041071954, 610629.0, 618430.0, 622662.0, 640136.0, 641028.0, 88283.0, 122310.0, 122482.0, 124135.31090815518, 129635.70364296425, 1011.5852500474917, 1031.8813336466253, 1043.1029314789275, 1045.6601604478424, 1047.0, 19566.21735130579, 19842.404727858186, 19893.0, 19914.0, 19925.10337034808, 50540.0, 51015.23477147771, 52771.6252768634, 53236.73381633067, 54593.90812183813, 52883.4711652894, 76046.23953760392, 76833.0, 76926.0, 79169.60415197887, 11835.289882606727, 12592.181508201938, 12790.0, 12858.035647326675, 13191.417029956083, 12238.449269782703, 13210.27343496117, 13391.397387117799, 13891.861081389057, 14082.788758168841, 7784.455088143775, 8257.339412847077, 8356.958215208884, 8441.577921104043, 8511.8723219153, 8115.79710507242, 8206.343492520704, 8406.117357677356, 8414.579271036546, 8435.283000944988, 8334.458260213309, 8444.282236010007, 8551.444156129832, 8635.525046122524, 8667.95984481841, 12020.63938081859, 12414.379281035732, 12546.87453125457, 12691.619251422471, 12786.36068196605, 10624.309419887682, 11145.662520497592, 11610.722820489571, 11997.340146292037, 12076.275423474268], "value": 9980.163511846164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.4326269492196, 1599.7760313556103, 1607.7990251218598, 1881.5108071901302, 2009.7186393904847, 7672.309492145706, 8527.744167674971, 8787.428817126898, 8799.472031678095, 8921.55392230389, 8528.360372972036, 8755.168259015383, 8757.43076700014, 8762.649494020232, 8780.956095219515, 2318.7797159269926, 2437.293184976359, 2479.5536803375385, 2482.2925466242123, 2485.801135909132, 2409.447177313273, 2466.1491785334033, 2467.198160597813, 2469.1111199967995, 2469.2221950085664, 2434.6348047792885, 2451.4974430241846, 2476.616124500705, 2481.0695989004125, 2483.428811373376, 2427.4780922101654, 2450.240425468102, 2457.594496908008, 2460.6678098456837, 2462.8891699873548, 2323.7095363079616, 2388.832781705264, 2399.4241382068353, 2402.147237730598, 2403.495265994143, 7800.765977020698, 8031.919680803234, 8708.869366959501, 8878.822423551557, 8885.511296878669, 5209.505097015777, 5407.864803379907, 5415.025295446818, 5417.864553386157, 5419.918701219485, 3546.219831637036, 3572.8928132156075, 3650.945235821465, 3729.7948612826285, 3735.477033215352, 1052.095198129608, 1065.6217042949718, 1066.226985435553, 1077.4289626497969, 1086.5056399338282, 11658.883411165949, 11780.175387722862, 11784.764304713857, 11790.88209117915, 11805.291682498992, 4770.165221086366, 4828.420589529279, 4928.704277743347, 4932.605391568666, 4967.950320496821, 10079.344842585355, 10133.797324053625, 10139.340942838691, 10140.594376225015, 10151.0, 5402.702851343135, 5404.054290499225, 5438.238646589481, 5438.320209973754, 5443.591730620219, 4756.7145971241835, 4894.755262236874, 4931.087748766489, 4946.629002824802, 4963.10664080469, 3808.847646094125, 3844.7693138411223, 3915.923121141652, 3933.7443066201176, 3980.721349505565, 1294.2946094378585, 1300.473308310136, 1303.947842086325, 1313.1202094596642, 1321.4185758266333, 807.9919200807917, 847.9152084791594, 879.7360791762445, 932.3706498113746, 984.5520288268821, 5873.911891321633, 6019.939800601938, 6029.788957386558, 6155.35368786271, 6181.072839074175, 804.4007214625142, 894.4677916639589, 937.329809186435, 957.8228027814877, 999.1756800639502, 3888.902777430587, 4001.2797696414923, 4001.559828418872, 4035.535913369941, 4068.3694027425795, 10401.63594274212, 10808.567657293777, 10835.133189344833, 10926.672199834018, 10941.945290273497, 10710.571577136983, 10764.515596798163, 10796.730081747863, 10900.836487452694, 10967.670969870756, 6572.638504882278, 8202.17978202175, 8292.834143317099, 8312.6674933002, 8350.4154709171, 5670.829875103754, 5776.588363564145, 5879.970600146972, 5897.823065308047, 5944.019236825875, 9577.23382129442, 9778.0, 10149.34029288094, 10152.949235253776, 10330.535125919201, 9650.565724542554, 10235.1300139486, 10254.948725256327, 10378.896211038094, 10394.324368915995, 7186.568805871664, 7494.325510703845, 7547.962260188664, 7549.320561149523, 7677.117131529945, 8418.663253470038, 8428.915710842813, 8496.022957359983, 8503.659853605786, 8528.616212270586, 7436.144843343085, 7474.2525747424825, 7534.623268836646, 7574.772756817304, 7673.34776544, 6719.26088130315, 7258.49190556656, 7309.0, 7438.256174382518, 7558.962205188939, 7050.612216328152, 7079.681414336264, 7127.180374256819, 7127.82180445493, 7134.108236470442, 4833.758312084311, 4890.9021819562695, 4993.575546078624, 5014.398272207359, 5180.559652429586, 4941.308216849716, 5033.597312215087, 5063.544281014726, 5129.640925135204, 5307.177387504943], "value": 4647.753336242757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.52333406553896, 313.60172580822353, 327.05481159449187, 331.953526506289, 332.9966700332997, 4232.470941132362, 5275.446078161791, 5290.539898946999, 5307.416184219737, 5347.732613369335, 4282.507511636163, 4654.2785868190485, 4956.5291297326785, 5213.741411617739, 5289.947100528993, 3312.850921708517, 4026.557078721353, 4047.7451989883084, 4062.2484840304564, 4072.87781366558, 3659.1949771050326, 4034.2939985502567, 4066.2883995300854, 4089.26393249215, 4139.896502587444, 3168.603924509436, 3539.743391096162, 3606.6753992140702, 3643.1256498440316, 4022.2558826617023, 3800.1069748609057, 3970.980145099285, 4010.5108555556853, 4030.355143177092, 4047.311956967308, 3273.4762438009802, 3301.851416686255, 3522.2603253316834, 3534.9748572913822, 3543.964560354415, 4101.835926562934, 4964.851054468336, 5003.54968052877, 5036.899262014776, 5042.445331013586, 3928.958825911136, 4366.0, 4408.250597398418, 4555.271156614959, 4556.681032327739, 4892.902141957176, 4940.987097644974, 5075.949240507622, 5449.7275136243425, 5457.444628280921, 3852.57621661617, 3859.9035024124623, 3870.4131306164336, 3878.3627133549458, 3888.5113527342523, 4474.753888536129, 5645.745941432645, 5676.858078548069, 5681.261436013292, 5715.571332150063, 3616.914925522333, 3886.3393223152134, 3918.8432462701744, 3927.509061367329, 3942.0, 3442.3631628148873, 3910.3156947534067, 3998.5801490843623, 4012.9398059029136, 4033.072393349546, 4205.369194620831, 4550.522195169526, 4604.493505714368, 4630.467496237909, 4633.930491042637, 5517.834464966058, 5539.861503462445, 5568.820996473319, 5570.384588469298, 5593.244911936918, 1058.8147074261974, 1176.253079294654, 1233.9876601234052, 1241.7951038078797, 1268.7502809732407, 810.508664058124, 819.336337566573, 840.8780726794703, 915.4415806358116, 944.2776276148752, 4231.703780735381, 4259.446271984684, 4303.009408461089, 4829.227323628203, 5016.072026675077, 4695.859124226278, 4786.736729479842, 5315.548178404802, 5405.837824865182, 5474.534664553557, 4057.8376864925663, 4275.508316543575, 4419.801108950105, 4517.412736344254, 4673.906521869543, 2396.688430504058, 2461.6799816023804, 2559.7312282210473, 2769.819961702483, 2852.1728698677566, 4850.345203397566, 5596.888062238732, 5605.1872478491205, 5608.6073974821375, 5619.353774315924, 4456.376107344974, 5023.0, 5158.252053452228, 5239.843184258557, 5530.281063461724, 4965.925511117336, 5012.348394708664, 5025.572826309732, 5603.5797315202335, 5640.405413509831, 4741.644376671832, 4955.4301255356095, 5029.0, 5137.8201762938115, 5166.571711295685, 5237.685738855681, 5318.867028324323, 5719.542436604978, 5727.513161381329, 5757.769689212385, 5406.891587224531, 5469.288992430958, 5755.3381361144, 5948.345681974893, 6018.8169222390015, 3378.3243351329343, 3472.166679996833, 3863.459115723857, 3911.357229963365, 3938.6455219030427, 5205.219217117463, 5257.631965762513, 5291.0, 5318.0, 5737.340205876378, 4446.599806017474, 5182.844514664566, 5244.4493328201515, 5284.0, 5578.553715702815, 4800.399950006249, 5016.266134956384, 5356.303680521507, 5527.336719593674, 5578.856334451509, 4810.086083644096, 5090.796368145233, 5127.846164615067, 5425.321834770653, 5443.455654434525, 4620.0, 4767.404074490689, 5080.847574572768, 5209.140491818959, 5255.973720131375, 4420.734755914656, 5265.657732247391, 5333.4133245343755, 5371.355437347544, 5404.486573775485], "value": 3927.0040154744024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.1502853804326, 664.1598378051765, 722.3895808042206, 760.1676164599764, 762.79785856748, 14774.0, 15415.614609634764, 15428.84283678723, 15477.767833482507, 15501.767473487907, 13148.802767958488, 14086.225257610828, 14203.715925681507, 14336.569902902904, 14356.712865742678, 927.323054170456, 938.0853667674031, 942.9198518125968, 944.4003058058148, 946.0870260198907, 924.9871390827034, 932.2262522106669, 933.0995589256366, 937.1799675284126, 938.3384713776805, 988.9357191782547, 995.0696099147284, 995.1640621877622, 997.4314640654829, 1002.0180223381091, 986.6645340584236, 987.3483500889414, 988.9851652225223, 989.5645915797062, 990.336474562038, 744.4602663069286, 749.4678778067579, 749.9512531685423, 749.966251518683, 751.4777229825273, 12859.549915752903, 13140.474381024747, 13200.537981170613, 13202.273874936875, 13212.471501139942, 11072.114230861513, 11091.611793587186, 11164.553417863275, 11345.262557933707, 11755.412229388496, 9052.502112383816, 9317.673881414186, 9683.107360954786, 10098.040686134806, 10170.237232207613, 4018.4976877890263, 4179.686523510749, 4198.328267477189, 4322.848700295506, 4341.4790225172865, 63229.735405291634, 65518.0, 69276.6536167316, 71314.57370852606, 71583.0, 8734.475931444134, 9451.763705907408, 9579.089986451276, 9640.951795240979, 9793.167580755715, 20975.475613109487, 20994.055267512693, 21016.894915525325, 21017.0, 21039.579208415747, 7325.194228634846, 7404.555726656418, 7491.288327608869, 7555.320021198118, 7727.806804829925, 4130.648894843912, 4172.979135104305, 4177.3316269396755, 4194.140201258735, 4201.411802347674, 3480.286541259036, 3608.693261072838, 3611.0791748104066, 3613.56637203532, 3623.710103191791, 656.6651007985866, 660.6894759463045, 667.4993754683987, 674.0, 683.135833171039, 569.291232415637, 572.2743946812118, 609.9237595300588, 611.4252602553586, 622.483338828771, 7807.297343239135, 8155.83688326242, 8273.958630206811, 8487.660493580188, 8546.102659220816, 517.7178437751435, 554.8751530905514, 556.3379578301808, 558.9659130608424, 608.3308360803081, 3813.504244448204, 3836.7506112103183, 3875.224955008953, 3995.9200815983518, 3996.087329999948, 82903.0, 83681.58159209165, 88311.0, 91707.54146229225, 92090.0, 53013.0, 75923.0, 77222.61388693021, 77989.22010779934, 82150.17849821545, 56534.0, 63711.362886370545, 65078.0, 66060.0, 67427.66286168537, 7680.9615951922115, 8181.263686268265, 8232.711855085043, 8312.25189732927, 8325.209105135002, 37304.88085357444, 41486.0, 42079.36880946849, 42261.0, 42645.786771067185, 54045.0, 59512.70243648754, 59788.0, 59933.0, 60517.487062823784, 10626.73433164177, 11122.61070862516, 11305.830412543653, 11390.430478476208, 11426.542938282708, 11319.320840749582, 11569.19015668895, 11813.763724725286, 11840.23038502529, 11996.940015299868, 11566.306021638393, 11629.476673549541, 11680.76638467209, 11810.645680629596, 11990.100742444525, 11431.485583148611, 11554.42227888619, 11853.822192666945, 12053.819192712292, 12104.757904841677, 11424.48589813477, 11522.423878806194, 11619.302841829518, 11873.168878178445, 12248.693782655504, 8647.0, 8740.734899858087, 9487.715368538955, 9517.666881659108, 9530.0, 7084.326988936043, 8499.150084991452, 9727.756806079904, 9765.535169724599, 9814.214862810815], "value": 6799.728113061206, "unit": "score"}], "name": "dromaeo_css", "value": 7199.940955407454, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 142.0, 165.0, 117.0, 139.0, 135.0, 118.0, 168.0, 117.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 182.0, 185.0, 183.0, 182.0, 183.0, 186.0, 185.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 56.0, 109.0, 57.0, 60.0, 56.0, 57.0, 116.0, 56.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 102.0, 79.0, 77.0, 77.0, 97.0, 105.0, 111.0, 137.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 83.0, 88.0, 84.0, 86.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 91.0, 92.0, 91.0, 92.0, 93.0, 93.0, 92.0], "value": 92.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 84.0, 85.0, 90.0, 83.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 61.0, 60.0, 59.0, 60.0, 77.0, 59.0, 56.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 71.0, 78.0, 72.0, 73.0, 69.0, 69.0, 68.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 83.0, 84.0, 84.0, 82.0, 86.0, 85.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 154.0, 140.0, 142.0, 118.0, 141.0, 144.0, 144.0, 192.0, 141.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 196.0, 167.0, 171.0, 169.0, 168.0, 168.0, 167.0, 164.0, 166.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 69.0, 65.0, 65.0, 66.0, 65.0, 65.0, 68.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1467.1999999999998, "alertThreshold": 2.0}]} 04:25:06 INFO - Return code: 0 04:25:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:25:06 ERROR - # TBPL SUCCESS # 04:25:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:06 INFO - 2016-05-09 04:25:06.947 screenresolution[2388:25528] starting screenresolution argv=screenresolution get 04:25:06 INFO - 2016-05-09 04:25:06.950 screenresolution[2388:25528] Display 0: 1600x1200x32@60 04:25:06 INFO - 2016-05-09 04:25:06.956 screenresolution[2389:25532] starting screenresolution argv=screenresolution list 04:25:06 INFO - Available Modes on Display 0 04:25:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:07 INFO - Intel Iris: 04:25:07 INFO - Chipset Model: Intel Iris 04:25:07 INFO - Type: GPU 04:25:07 INFO - Bus: Built-In 04:25:07 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:07 INFO - Vendor: Intel (0x8086) 04:25:07 INFO - Device ID: 0x0a2e 04:25:07 INFO - Revision ID: 0x0009 04:25:07 INFO - Displays: 04:25:07 INFO - Display: 04:25:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:07 INFO - Display Serial Number: 1600x1200 60 04:25:07 INFO - Main Display: Yes 04:25:07 INFO - Mirror: Off 04:25:07 INFO - Online: Yes 04:25:07 INFO - Rotation: Supported 04:25:07 INFO - Return code: 0 04:25:07 INFO - Running post-action listener: _resource_record_post_action 04:25:07 INFO - ##### 04:25:07 INFO - ##### Finished run-tests step (success) 04:25:07 INFO - ##### 04:25:07 INFO - Running post-run listener: _resource_record_post_run 04:25:08 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 135168; Write bytes: 697604608; Read time: 5; Write time: 1971 04:25:08 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 377850368; Write bytes: 374081024; Read time: 28046; Write time: 700 04:25:08 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 40960; Write bytes: 296612864; Read time: 1; Write time: 1217 04:25:08 INFO - Running post-run listener: _upload_blobber_files 04:25:08 INFO - Blob upload gear active. 04:25:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:25:08 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 04:25:08 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:25:08 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:25:08 INFO - (blobuploader) - INFO - Open directory for files ... 04:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:09 INFO - (blobuploader) - INFO - Done attempting. 04:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:25:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:10 INFO - (blobuploader) - INFO - Done attempting. 04:25:10 INFO - (blobuploader) - INFO - Iteration through files over. 04:25:10 INFO - Return code: 0 04:25:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:25:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:25:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a44f05e916c756aad3a1fcad9690bf0baaf6578e601ed0cb5d3d325818f6715ec20e8dbda4deed6a00aa5776728f40b960c1ca5b0f894e5fe317d1f4bcfafafc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e90dfe79535b17650a9bdc958591bdc4a8e348bafe30781fa263f6ecf43778a6ef229af9389e05f618e8eeb082971288976c97b20deeceb80d5b3a90bf7383"} 04:25:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:25:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:25:10 INFO - Contents: 04:25:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a44f05e916c756aad3a1fcad9690bf0baaf6578e601ed0cb5d3d325818f6715ec20e8dbda4deed6a00aa5776728f40b960c1ca5b0f894e5fe317d1f4bcfafafc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e90dfe79535b17650a9bdc958591bdc4a8e348bafe30781fa263f6ecf43778a6ef229af9389e05f618e8eeb082971288976c97b20deeceb80d5b3a90bf7383"} 04:25:10 INFO - Running post-run listener: copy_logs_to_upload_dir 04:25:10 INFO - Copying logs to upload dir... 04:25:10 INFO - mkdir: /builds/slave/test/build/upload/logs 04:25:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.058234 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-09 04:25:10.383184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:25:10.387999) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a44f05e916c756aad3a1fcad9690bf0baaf6578e601ed0cb5d3d325818f6715ec20e8dbda4deed6a00aa5776728f40b960c1ca5b0f894e5fe317d1f4bcfafafc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e90dfe79535b17650a9bdc958591bdc4a8e348bafe30781fa263f6ecf43778a6ef229af9389e05f618e8eeb082971288976c97b20deeceb80d5b3a90bf7383"} build_url:https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010565 build_url: 'https://queue.taskcluster.net/v1/task/DIat2v2hQfSGejMISrR1og/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a44f05e916c756aad3a1fcad9690bf0baaf6578e601ed0cb5d3d325818f6715ec20e8dbda4deed6a00aa5776728f40b960c1ca5b0f894e5fe317d1f4bcfafafc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e90dfe79535b17650a9bdc958591bdc4a8e348bafe30781fa263f6ecf43778a6ef229af9389e05f618e8eeb082971288976c97b20deeceb80d5b3a90bf7383"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:25:10.417540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:25:10.417845) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.paj6LXBQ5R/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Poh7USlpiQ/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005400 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:25:10.464953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:25:10.465229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:25:10.465498) ========= ========= Total master_lag: 0.18 =========