builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0133 starttime: 1463055155.84 results: success (0) buildid: 20160512030253 builduid: b5913a5383694d02beae43ab6792dc0d revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.842725) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.843160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.843429) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.920109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.920387) ========= 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.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-12 05:12:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.3M=0.001s 2016-05-12 05:12:36 (14.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.605639 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:36.548433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:36.548742) ========= 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.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.029704 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:36.599495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 05:12:36.599826) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --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 c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-12 05:12:36,677 truncating revision to first 12 chars 2016-05-12 05:12:36,677 Setting DEBUG logging. 2016-05-12 05:12:36,677 attempt 1/10 2016-05-12 05:12:36,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3f5e6079284?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 05:12:37,837 unpacking tar archive at: mozilla-central-c3f5e6079284/testing/mozharness/ program finished with exit code 0 elapsedTime=1.409584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 05:12:38.031370) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:38.031702) ========= script_repo_revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:38.032124) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:38.032389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:38.060757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-05-12 05:12:38.061044) ========= /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.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:12:38 INFO - MultiFileLogger online at 20160512 05:12:38 in /builds/slave/test 05:12:38 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 05:12:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:38 INFO - {'append_to_log': False, 05:12:38 INFO - 'base_work_dir': '/builds/slave/test', 05:12:38 INFO - 'blob_upload_branch': 'Firefox', 05:12:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:38 INFO - 'branch': 'Firefox', 05:12:38 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:38 INFO - 'config_files': ('talos/mac_config.py',), 05:12:38 INFO - 'default_actions': ('clobber', 05:12:38 INFO - 'read-buildbot-config', 05:12:38 INFO - 'download-and-extract', 05:12:38 INFO - 'populate-webroot', 05:12:38 INFO - 'create-virtualenv', 05:12:38 INFO - 'install', 05:12:38 INFO - 'run-tests'), 05:12:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:38 INFO - 'download_minidump_stackwalk': True, 05:12:38 INFO - 'download_symbols': 'ondemand', 05:12:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:38 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:38 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:38 INFO - 'installer_path': 'installer.exe', 05:12:38 INFO - 'log_level': 'info', 05:12:38 INFO - 'log_name': 'talos', 05:12:38 INFO - 'log_to_console': True, 05:12:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:12:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:12:38 INFO - 'opt_config_files': (), 05:12:38 INFO - 'pip_index': False, 05:12:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:38 INFO - 'cmd': ('bash', 05:12:38 INFO - '-c', 05:12:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:38 INFO - 'enabled': True, 05:12:38 INFO - 'halt_on_failure': False, 05:12:38 INFO - 'name': 'check_screen_resolution'},), 05:12:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:38 INFO - 'cmd': ('bash', 05:12:38 INFO - '-c', 05:12:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:38 INFO - 'enabled': True, 05:12:38 INFO - 'halt_on_failure': False, 05:12:38 INFO - 'name': 'check_screen_resolution'},), 05:12:38 INFO - 'run_cmd_checks_enabled': True, 05:12:38 INFO - 'sps_profile': False, 05:12:38 INFO - 'sps_profile_interval': 0, 05:12:38 INFO - 'suite': 'dromaeojs', 05:12:38 INFO - 'system_bits': '32', 05:12:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:38 INFO - 'title': 't-yosemite-r7-0133', 05:12:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:38 INFO - 'use_talos_json': True, 05:12:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:38 INFO - 'work_dir': 'build'} 05:12:38 INFO - ##### 05:12:38 INFO - ##### Running clobber step. 05:12:38 INFO - ##### 05:12:38 INFO - Running pre-action listener: _resource_record_pre_action 05:12:38 INFO - Running main action method: clobber 05:12:38 INFO - rmtree: /builds/slave/test/build 05:12:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - ##### 05:12:40 INFO - ##### Finished clobber step (success) 05:12:40 INFO - ##### 05:12:40 INFO - ##### 05:12:40 INFO - ##### Running read-buildbot-config step. 05:12:40 INFO - ##### 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: read_buildbot_config 05:12:40 INFO - Using buildbot properties: 05:12:40 INFO - { 05:12:40 INFO - "project": "", 05:12:40 INFO - "product": "firefox", 05:12:40 INFO - "script_repo_revision": "production", 05:12:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:12:40 INFO - "repository": "", 05:12:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:12:40 INFO - "buildid": "20160512030253", 05:12:40 INFO - "stage_platform": "macosx64", 05:12:40 INFO - "basedir": "/builds/slave/test", 05:12:40 INFO - "nightly_build": "True", 05:12:40 INFO - "buildnumber": 249, 05:12:40 INFO - "slavename": "t-yosemite-r7-0133", 05:12:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:12:40 INFO - "platform": "yosemite_r7", 05:12:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:12:40 INFO - "branch": "mozilla-central", 05:12:40 INFO - "repo_path": "mozilla-central", 05:12:40 INFO - "revision": "c3f5e6079284a7b7053c41f05d0fe06ff031db03", 05:12:40 INFO - "pgo_build": "False", 05:12:40 INFO - "builduid": "b5913a5383694d02beae43ab6792dc0d", 05:12:40 INFO - "slavebuilddir": "test" 05:12:40 INFO - } 05:12:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - ##### 05:12:40 INFO - ##### Finished read-buildbot-config step (success) 05:12:40 INFO - ##### 05:12:40 INFO - ##### 05:12:40 INFO - ##### Running download-and-extract step. 05:12:40 INFO - ##### 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: download_and_extract 05:12:40 INFO - mkdir: /builds/slave/test/build/tests 05:12: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')]} 05:12:40 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:12:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:04 INFO - Downloaded 1199 bytes. 05:13:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:13:04 INFO - Using the following test package requirements: 05:13:04 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:04 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:13:04 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:13:04 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'jsshell-mac.zip'], 05:13:04 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:13:04 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:04 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:13:04 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:13:04 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:13:04 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:04 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:13:04 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:04 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:16 INFO - Downloaded 18151282 bytes. 05:13:16 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'] 05:13:16 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 05:13:19 INFO - Return code: 0 05:13:19 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 05:13: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')]} 05:13:19 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:27 INFO - Downloaded 18151282 bytes. 05:13:27 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'] 05:13:27 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 05:13:30 INFO - Return code: 0 05:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:30 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:31 INFO - Downloaded 10840239 bytes. 05:13:31 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'] 05:13:31 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 05:13:31 INFO - Return code: 0 05:13: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')]} 05:13:31 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:13:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 05:13:40 INFO - Downloaded 109957691 bytes. 05:13:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:40 INFO - mkdir: /builds/slave/test/properties 05:13:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:40 INFO - Contents: 05:13:40 INFO - build_url:https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:41 INFO - Running post-action listener: _resource_record_post_action 05:13:41 INFO - Running post-action listener: set_extra_try_arguments 05:13:41 INFO - ##### 05:13:41 INFO - ##### Finished download-and-extract step (success) 05:13:41 INFO - ##### 05:13:41 INFO - ##### 05:13:41 INFO - ##### Running populate-webroot step. 05:13:41 INFO - ##### 05:13:41 INFO - Running pre-action listener: _resource_record_pre_action 05:13:41 INFO - Running main action method: populate_webroot 05:13:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:41 INFO - '--tppagecycles', 05:13:41 INFO - '7'], 05:13:41 INFO - 'tests': ['tsvgm']}}, 05:13:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['damp', 'tps']}, 05:13:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'tests': ['damp', 'tps']}, 05:13:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['dromaeo_dom']}, 05:13:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['a11yr', 05:13:41 INFO - 'ts_paint', 05:13:41 INFO - 'tpaint', 05:13:41 INFO - 'sessionrestore', 05:13:41 INFO - 'sessionrestore_no_auto_restore', 05:13:41 INFO - 'tabpaint']}, 05:13:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['tsvgx', 05:13:41 INFO - 'tsvgr_opacity', 05:13:41 INFO - 'tart', 05:13:41 INFO - 'tscrollx', 05:13:41 INFO - 'cart']}, 05:13:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:41 INFO - 'tsvgr_opacity', 05:13:41 INFO - 'tart', 05:13:41 INFO - 'tscrollx', 05:13:41 INFO - 'cart']}, 05:13:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'talos_options': ['--disable-e10s'], 05:13:41 INFO - 'tests': ['tp5o']}, 05:13:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'tests': ['tp5o']}, 05:13:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'talos_options': ['--disable-e10s', 05:13:41 INFO - '--xperf_path', 05:13:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:41 INFO - 'tests': ['tp5n']}, 05:13:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:41 INFO - 'talos_options': ['--xperf_path', 05:13:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:41 INFO - 'tests': ['tp5n']}}, 05:13:41 INFO - 'talos.zip': {'path': '', 05:13:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:41 INFO - Running post-action listener: _resource_record_post_action 05:13:41 INFO - ##### 05:13:41 INFO - ##### Finished populate-webroot step (success) 05:13:41 INFO - ##### 05:13:41 INFO - ##### 05:13:41 INFO - ##### Running create-virtualenv step. 05:13:41 INFO - ##### 05:13:41 INFO - Running pre-action listener: _resource_record_pre_action 05:13:41 INFO - Running main action method: create_virtualenv 05:13:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:13:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:41 INFO - Using real prefix '/tools/python27' 05:13:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:45 INFO - Installing pip.................done. 05:13:45 INFO - Return code: 0 05:13:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:45 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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:45 INFO - 'HOME': '/Users/cltbld', 05:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:45 INFO - 'LOGNAME': 'cltbld', 05:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:45 INFO - 'MOZ_NO_REMOTE': '1', 05:13:45 INFO - 'NO_EM_RESTART': '1', 05:13:45 INFO - 'PAGER': '/bin/cat', 05:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:45 INFO - 'PWD': '/builds/slave/test', 05:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:45 INFO - 'SHELL': '/bin/bash', 05:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:13:45 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:45 INFO - 'USER': 'cltbld', 05:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:45 INFO - 'XPC_FLAGS': '0x0', 05:13:45 INFO - 'XPC_SERVICE_NAME': '0', 05:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:13:45 INFO - Cleaning up... 05:13:45 INFO - Return code: 0 05:13:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:45 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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:45 INFO - 'HOME': '/Users/cltbld', 05:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:45 INFO - 'LOGNAME': 'cltbld', 05:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:45 INFO - 'MOZ_NO_REMOTE': '1', 05:13:45 INFO - 'NO_EM_RESTART': '1', 05:13:45 INFO - 'PAGER': '/bin/cat', 05:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:45 INFO - 'PWD': '/builds/slave/test', 05:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:45 INFO - 'SHELL': '/bin/bash', 05:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:13:45 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:45 INFO - 'USER': 'cltbld', 05:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:45 INFO - 'XPC_FLAGS': '0x0', 05:13:45 INFO - 'XPC_SERVICE_NAME': '0', 05:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:46 INFO - Downloading/unpacking psutil>=0.7.1 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:49 INFO - Installing collected packages: psutil 05:13:49 INFO - Running setup.py install for psutil 05:13:49 INFO - building 'psutil._psutil_osx' extension 05:13:49 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 05:13:50 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 05:13:50 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 05:13:50 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 05:13:50 INFO - building 'psutil._psutil_posix' extension 05:13:50 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 05:13:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:13:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:13:50 INFO - ^ 05:13:50 INFO - 1 warning generated. 05:13:50 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 05:13:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:50 INFO - Successfully installed psutil 05:13:50 INFO - Cleaning up... 05:13:50 INFO - Return code: 0 05:13:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:50 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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:13:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:13:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:13:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:50 INFO - 'HOME': '/Users/cltbld', 05:13:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:50 INFO - 'LOGNAME': 'cltbld', 05:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:50 INFO - 'MOZ_NO_REMOTE': '1', 05:13:50 INFO - 'NO_EM_RESTART': '1', 05:13:50 INFO - 'PAGER': '/bin/cat', 05:13:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:50 INFO - 'PWD': '/builds/slave/test', 05:13:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:50 INFO - 'SHELL': '/bin/bash', 05:13:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:13:50 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:13:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:50 INFO - 'USER': 'cltbld', 05:13:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:50 INFO - 'XPC_FLAGS': '0x0', 05:13:50 INFO - 'XPC_SERVICE_NAME': '0', 05:13:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:53 INFO - Installing collected packages: mozsystemmonitor 05:13:53 INFO - Running setup.py install for mozsystemmonitor 05:13:53 INFO - Successfully installed mozsystemmonitor 05:13:53 INFO - Cleaning up... 05:13:53 INFO - Return code: 0 05:13:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', '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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:13: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:13: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 blobuploader==1.2.4 05:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:53 INFO - 'HOME': '/Users/cltbld', 05:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:53 INFO - 'LOGNAME': 'cltbld', 05:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:53 INFO - 'MOZ_NO_REMOTE': '1', 05:13:53 INFO - 'NO_EM_RESTART': '1', 05:13:53 INFO - 'PAGER': '/bin/cat', 05:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:53 INFO - 'PWD': '/builds/slave/test', 05:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:53 INFO - 'SHELL': '/bin/bash', 05:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:13:53 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:53 INFO - 'USER': 'cltbld', 05:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:53 INFO - 'XPC_FLAGS': '0x0', 05:13:53 INFO - 'XPC_SERVICE_NAME': '0', 05:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:56 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:57 INFO - Downloading docopt-0.6.1.tar.gz 05:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:57 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:57 INFO - Running setup.py install for blobuploader 05:13:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:57 INFO - Running setup.py install for requests 05:13:58 INFO - Running setup.py install for docopt 05:13:58 INFO - Successfully installed blobuploader requests docopt 05:13:58 INFO - Cleaning up... 05:13:58 INFO - Return code: 0 05:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:13:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:58 INFO - 'HOME': '/Users/cltbld', 05:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:58 INFO - 'LOGNAME': 'cltbld', 05:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:58 INFO - 'MOZ_NO_REMOTE': '1', 05:13:58 INFO - 'NO_EM_RESTART': '1', 05:13:58 INFO - 'PAGER': '/bin/cat', 05:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:58 INFO - 'PWD': '/builds/slave/test', 05:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:58 INFO - 'SHELL': '/bin/bash', 05:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:13:58 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:58 INFO - 'USER': 'cltbld', 05:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:58 INFO - 'XPC_FLAGS': '0x0', 05:13:58 INFO - 'XPC_SERVICE_NAME': '0', 05:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:58 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-85W_q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-dBolI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-l2PA_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ZEgmJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-syF050-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-C6X3dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7eLEsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-bvDB7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SxyxoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Gm2fgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:59 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-VhhSvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-zpvrUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ukTjah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-iCssqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-AM9Z7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-D4uyhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:00 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-YybgNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:00 INFO - Running setup.py install for manifestparser 05:14:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:00 INFO - Running setup.py install for mozcrash 05:14:00 INFO - Running setup.py install for mozdebug 05:14:01 INFO - Running setup.py install for mozdevice 05:14:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Running setup.py install for mozfile 05:14:01 INFO - Running setup.py install for mozhttpd 05:14:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Running setup.py install for mozinfo 05:14:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Running setup.py install for mozInstall 05:14:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:01 INFO - Running setup.py install for mozleak 05:14:01 INFO - Running setup.py install for mozlog 05:14:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Running setup.py install for moznetwork 05:14:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Running setup.py install for mozprocess 05:14:02 INFO - Running setup.py install for mozprofile 05:14:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Running setup.py install for mozrunner 05:14:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:02 INFO - Running setup.py install for mozscreenshot 05:14:02 INFO - Running setup.py install for moztest 05:14:03 INFO - Running setup.py install for mozversion 05:14:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:03 INFO - Cleaning up... 05:14:03 INFO - Return code: 0 05:14:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:03 INFO - 'HOME': '/Users/cltbld', 05:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:03 INFO - 'LOGNAME': 'cltbld', 05:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:03 INFO - 'MOZ_NO_REMOTE': '1', 05:14:03 INFO - 'NO_EM_RESTART': '1', 05:14:03 INFO - 'PAGER': '/bin/cat', 05:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:03 INFO - 'PWD': '/builds/slave/test', 05:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:03 INFO - 'SHELL': '/bin/bash', 05:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:14:03 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:03 INFO - 'USER': 'cltbld', 05:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:03 INFO - 'XPC_FLAGS': '0x0', 05:14:03 INFO - 'XPC_SERVICE_NAME': '0', 05:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rgy6Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sh8a0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-fI57fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SS2qMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ZCWlez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-hvKuoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-IGvUNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7mrOzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-KZdKd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-M75dLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-WIXAuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-vkgUuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ooisj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-wdGXRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:04 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-PIpyte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-B30z4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Ymwu3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:07 INFO - Downloading blessings-1.6.tar.gz 05:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:07 INFO - Installing collected packages: blessings 05:14:07 INFO - Running setup.py install for blessings 05:14:07 INFO - Successfully installed blessings 05:14:07 INFO - Cleaning up... 05:14:08 INFO - Return code: 0 05:14:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:08 INFO - Reading from file tmpfile_stdout 05:14:08 INFO - Current package versions: 05:14:08 INFO - blessings == 1.6 05:14:08 INFO - blobuploader == 1.2.4 05:14:08 INFO - docopt == 0.6.1 05:14:08 INFO - manifestparser == 1.1 05:14:08 INFO - mozInstall == 1.12 05:14:08 INFO - mozcrash == 0.17 05:14:08 INFO - mozdebug == 0.1 05:14:08 INFO - mozdevice == 0.48 05:14:08 INFO - mozfile == 1.2 05:14:08 INFO - mozhttpd == 0.7 05:14:08 INFO - mozinfo == 0.9 05:14:08 INFO - mozleak == 0.1 05:14:08 INFO - mozlog == 3.2 05:14:08 INFO - moznetwork == 0.27 05:14:08 INFO - mozprocess == 0.22 05:14:08 INFO - mozprofile == 0.28 05:14:08 INFO - mozrunner == 6.11 05:14:08 INFO - mozscreenshot == 0.1 05:14:08 INFO - mozsystemmonitor == 0.0 05:14:08 INFO - moztest == 0.7 05:14:08 INFO - mozversion == 1.4 05:14:08 INFO - psutil == 3.1.1 05:14:08 INFO - requests == 1.2.3 05:14:08 INFO - wsgiref == 0.1.2 05:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 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 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:08 INFO - 'HOME': '/Users/cltbld', 05:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:08 INFO - 'LOGNAME': 'cltbld', 05:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:08 INFO - 'MOZ_NO_REMOTE': '1', 05:14:08 INFO - 'NO_EM_RESTART': '1', 05:14:08 INFO - 'PAGER': '/bin/cat', 05:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:08 INFO - 'PWD': '/builds/slave/test', 05:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:08 INFO - 'SHELL': '/bin/bash', 05:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:14:08 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:08 INFO - 'USER': 'cltbld', 05:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:08 INFO - 'XPC_FLAGS': '0x0', 05:14:08 INFO - 'XPC_SERVICE_NAME': '0', 05:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:08 INFO - Cleaning up... 05:14:08 INFO - Return code: 0 05:14:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:08 INFO - 'HOME': '/Users/cltbld', 05:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:08 INFO - 'LOGNAME': 'cltbld', 05:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:08 INFO - 'MOZ_NO_REMOTE': '1', 05:14:08 INFO - 'NO_EM_RESTART': '1', 05:14:08 INFO - 'PAGER': '/bin/cat', 05:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:08 INFO - 'PWD': '/builds/slave/test', 05:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:08 INFO - 'SHELL': '/bin/bash', 05:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:14:08 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:08 INFO - 'USER': 'cltbld', 05:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:08 INFO - 'XPC_FLAGS': '0x0', 05:14:08 INFO - 'XPC_SERVICE_NAME': '0', 05:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:08 INFO - Downloading/unpacking jsonschema 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:11 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:11 INFO - warning: no files found matching '*.txt' 05:14:11 INFO - no previously-included directories found matching 'build' 05:14:11 INFO - no previously-included directories found matching 'dist' 05:14:11 INFO - no previously-included directories found matching '.git*' 05:14:11 INFO - Installing collected packages: jsonschema, functools32 05:14:11 INFO - Running setup.py install for functools32 05:14:11 INFO - warning: no files found matching '*.txt' 05:14:11 INFO - no previously-included directories found matching 'build' 05:14:11 INFO - no previously-included directories found matching 'dist' 05:14:11 INFO - no previously-included directories found matching '.git*' 05:14:12 INFO - Successfully installed jsonschema functools32 05:14:12 INFO - Cleaning up... 05:14:12 INFO - Return code: 0 05:14:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eed9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eedf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb5848da40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, '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.SAdDw3reCD/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.d3V4DbagwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 05:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:12 INFO - 'HOME': '/Users/cltbld', 05:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:12 INFO - 'LOGNAME': 'cltbld', 05:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:12 INFO - 'MOZ_NO_REMOTE': '1', 05:14:12 INFO - 'NO_EM_RESTART': '1', 05:14:12 INFO - 'PAGER': '/bin/cat', 05:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:12 INFO - 'PWD': '/builds/slave/test', 05:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:12 INFO - 'SHELL': '/bin/bash', 05:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:14:12 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:12 INFO - 'USER': 'cltbld', 05:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:12 INFO - 'XPC_FLAGS': '0x0', 05:14:12 INFO - 'XPC_SERVICE_NAME': '0', 05:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:12 INFO - Downloading/unpacking flake8 05:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:14:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:14:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:14:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:16 INFO - no previously-included directories found matching 'docs/_build' 05:14:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:14:16 INFO - Running setup.py install for pep8 05:14:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:16 INFO - no previously-included directories found matching 'docs/_build' 05:14:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:14:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:14:16 INFO - Cleaning up... 05:14:16 INFO - Return code: 0 05:14:16 INFO - Running post-action listener: _resource_record_post_action 05:14:16 INFO - Running post-action listener: _start_resource_monitoring 05:14:16 INFO - Starting resource monitoring. 05:14:16 INFO - ##### 05:14:16 INFO - ##### Finished create-virtualenv step (success) 05:14:16 INFO - ##### 05:14:16 INFO - ##### 05:14:16 INFO - ##### Running install step. 05:14:16 INFO - ##### 05:14:16 INFO - Running pre-action listener: _resource_record_pre_action 05:14:16 INFO - Running main action method: install 05:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:16 INFO - Reading from file tmpfile_stdout 05:14:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:17 INFO - Reading from file tmpfile_stdout 05:14:17 INFO - Output received: 05:14:17 INFO - Usage: mozinstall [options] installer 05:14:17 INFO - Options: 05:14:17 INFO - -h, --help show this help message and exit 05:14:17 INFO - -d DEST, --destination=DEST 05:14:17 INFO - Directory to install application into. [default: 05:14:17 INFO - "/builds/slave/test"] 05:14:17 INFO - --app=APP Application being installed. [default: firefox] 05:14:17 INFO - mkdir: /builds/slave/test/build/application 05:14:17 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'] 05:14:17 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 05:14:50 INFO - Reading from file tmpfile_stdout 05:14:50 INFO - Output received: 05:14:50 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:14:50 INFO - Running post-action listener: _resource_record_post_action 05:14:50 INFO - ##### 05:14:50 INFO - ##### Finished install step (success) 05:14:50 INFO - ##### 05:14:50 INFO - ##### 05:14:50 INFO - ##### Running run-tests step. 05:14:50 INFO - ##### 05:14:50 INFO - Running pre-action listener: _resource_record_pre_action 05:14:50 INFO - Running main action method: run_tests 05:14:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:50 INFO - 2016-05-12 05:14:50.557 screenresolution[1643:8081] starting screenresolution argv=screenresolution get 05:14:50 INFO - 2016-05-12 05:14:50.560 screenresolution[1643:8081] Display 0: 1600x1200x32@60 05:14:50 INFO - 2016-05-12 05:14:50.566 screenresolution[1644:8083] starting screenresolution argv=screenresolution list 05:14:50 INFO - Available Modes on Display 0 05:14:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:50 INFO - Intel Iris: 05:14:50 INFO - Chipset Model: Intel Iris 05:14:50 INFO - Type: GPU 05:14:50 INFO - Bus: Built-In 05:14:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:50 INFO - Vendor: Intel (0x8086) 05:14:50 INFO - Device ID: 0x0a2e 05:14:50 INFO - Revision ID: 0x0009 05:14:50 INFO - Displays: 05:14:50 INFO - Display: 05:14:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:50 INFO - Display Serial Number: 1600x1200 60 05:14:50 INFO - Main Display: Yes 05:14:50 INFO - Mirror: Off 05:14:50 INFO - Online: Yes 05:14:50 INFO - Rotation: Supported 05:14:50 INFO - Return code: 0 05:14:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:14:50 INFO - Python 2.7.3 05:14:50 INFO - Return code: 0 05:14:50 INFO - grabbing minidump binary from tooltool 05:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:50 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7dd30>, '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 0x10eba1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ed74b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:14:50 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 05:14:50 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 05:14:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:14:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:14:50 INFO - Return code: 0 05:14:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:14:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:14:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:14:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:14:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:14:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:14:52 INFO - Return code: 0 05:14:52 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:14:52 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SAdDw3reCD/Render', 05:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:52 INFO - 'HOME': '/Users/cltbld', 05:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:52 INFO - 'LOGNAME': 'cltbld', 05:14:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:14:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:52 INFO - 'MOZ_NO_REMOTE': '1', 05:14:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:14:52 INFO - 'NO_EM_RESTART': '1', 05:14:52 INFO - 'PAGER': '/bin/cat', 05:14:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:52 INFO - 'PWD': '/builds/slave/test', 05:14:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:52 INFO - 'SHELL': '/bin/bash', 05:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d3V4DbagwA/Listeners', 05:14:52 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:52 INFO - 'USER': 'cltbld', 05:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:52 INFO - 'XPC_FLAGS': '0x0', 05:14:52 INFO - 'XPC_SERVICE_NAME': '0', 05:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:52 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:14:52 INFO - mozversion application_buildid: 20160512030253 05:14:52 INFO - mozversion application_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 05:14:52 INFO - mozversion application_display_name: Nightly 05:14:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:14:52 INFO - mozversion application_name: Firefox 05:14:52 INFO - mozversion application_remotingname: firefox 05:14:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:14:52 INFO - mozversion application_vendor: Mozilla 05:14:52 INFO - mozversion application_version: 49.0a1 05:14:52 INFO - mozversion platform_buildid: 20160512030253 05:14:52 INFO - mozversion platform_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 05:14:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:14:52 INFO - mozversion platform_version: 49.0a1 05:14:52 INFO - using testdate: 1463055292 05:14:52 INFO - actual date: 1463055292 05:14:52 INFO - starting webserver on 'localhost:49270' 05:14:52 INFO - SUITE-START | Running 2 tests 05:14:52 INFO - TEST-START | dromaeo_css 05:14:52 INFO - operating with platform_type : mac_ 05:14:52 INFO - Initialising browser for dromaeo_css test... 05:14:52 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpvtO9w8/profile http://localhost:49270/getInfo.html) 05:14:54 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:14:54 INFO - PROCESS | 1662 | colorDepth:24 05:14:54 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:14:54 INFO - PROCESS | 1662 | __metrics 05:14:54 INFO - PROCESS | 1662 | 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" 05:14:54 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:14:55 INFO - TEST-INFO | 1662: exit 0 05:14:55 INFO - Browser initialized. 05:14:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:14:55 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpvtO9w8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:07 INFO - PROCESS | 1666 | RSS: Main: 238600192 05:15:07 INFO - PROCESS | 1666 | 05:15:58 INFO - PROCESS | 1666 | 1463055358969 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:18:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:18:10 INFO - PROCESS | 1666 | RSS: Main: 379863040 05:18:10 INFO - PROCESS | 1666 | 05:21:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:21:14 INFO - PROCESS | 1666 | RSS: Main: 372903936 05:21:14 INFO - PROCESS | 1666 | 05:24:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:24:17 INFO - PROCESS | 1666 | RSS: Main: 368660480 05:24:17 INFO - PROCESS | 1666 | 05:27:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:27:20 INFO - PROCESS | 1666 | RSS: Main: 368279552 05:27:20 INFO - PROCESS | 1666 | 05:30:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:30:24 INFO - PROCESS | 1666 | RSS: Main: 340754432 05:30:24 INFO - PROCESS | 1666 | 05:33:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:33:28 INFO - PROCESS | 1666 | RSS: Main: 372670464 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | __start_tp_report 05:33:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:33:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:33:28 INFO - PROCESS | 1666 | |i|pagename|runs| 05:33:28 INFO - PROCESS | 1666 | |0;dojo.html;2954.8374839383837;3386.7629265951387;3443.9323809619004;3480.321337339217;3512.947305790414;4740.525947405258;5692.459216374448;5739.713014349287;5782.566307526937;5825.737841797124;5725.713714314288;5781.277340332458;5835.503982161521;5849.795257166;5870.442307980746;5101.38783345999;5171.250168725552;5268.710220937866;5285.656432331905;5369.328833895763;5336.59975501837;5380.24676545284;5388;5400.243965844766;5426.91859622104;5176.430592634807;5177.171652535595;5214.661047031931;5241;5288.101022826128;5203.037438073969;5211.7133557654315;5215.322008138955;5229.973850130763;5236.266922630834;5203.245529398216;5228.137357336035;5272.525472707436;5290;5332.546733527654;5606.962711898312;5671.461211184931;5717.428257174291;5733.942660573382;5747.5114615257335;6271.655058971754;6284.968575157095;6335.683215839235;6342.619442833445;6369.8407539811415;6272.121902933547;6341.429271365553;6346.904796428057;6363.236411630588;6377.744890204386;5747.885042299173;5789.797357092481;5823.970880145572;5890.145928840337;5939.762409503614;6309.621422714652;6337.873242535124;6359.2368915729485;6363.522735794834;6366.140571022853;6232.439080482778;6249.937500624936;6254.6872656366995;6262.874742505124;6264.154339164245;6159.4456498915315;6171.907421388682;6222.28443728977;6264.9373506265265;6338.397852204033;6146.416090471398;6179.28938172107;6194.721237544321;6207.751689932443;6207.782727604512;6136.447719705158;6239.625622462667;6241.531885108635;6244.1570387997945;6257;4829.420469543677;4865.95134048662;4872.853814385574;4880.145974454456;4897.632677549198;4429.269170586817;4462.085272519126;4464.732116073046;4469.307257375112;4487.41663583732;5721.199032135504;5722;5727.341355744059;5731.742071606787;5734.541236701054;6228.532860035515;6232.719527621268;6270.9372906271265;6321.494280457644;6324.272708638474;5748.511376532958;5761.049426844607;5767.336756272998;5774.369814932967;5813.186153938452;4644;5401;5419.05166595852;5419.403865574784;5438.428964958702;6243.96878015607;6305.905411418832;6318.368163183737;6371.936280637135;6443.484521238196;6314.526410519137;6322.146510221245;6331.715072821642;6342.936570634234;6360.809175724736;6252.218472690914;6408.871822563616;6433.228012638513;6435.613863168225;6470.38531339522;6145.600535965148;6151.138840562236;6222.81331560054;6274.309825919237;6292.2134733158355;6335.3981371769605;6336.493080553452;6337.746490140343;6340.587861788969;6340.904886426615;5797.507211887037;6103.328633850184;6151.169592105009;6157.261128664589;6183.196184495987;6104.913929256043;6110.51115910735;6136.274005700836;6202.41077097675;6215.782447614311;6049.909251361321;6060.454559089703;6070.817875463743;6075.162895950155;6083.361247068994;6011.789587364421;6110.052941794029;6157.168782214523;6208.906866396917;6218.440340369208;5965.433283838029;6018.398160183947;6035.879282414415;6048.7278072487725;6099.908501372572 05:33:28 INFO - PROCESS | 1666 | |1;ext.html;502.30430853268234;512.3134999101206;513.8150265904275;519.4545726986663;522.7725939216439;30611.540826887616;31158.68841311589;42696.78651606741;43466;43591.34612980808;41864.95337616553;43955.560444395545;44143.55856441434;44196.55803441965;44212.336814947805;9582.329236953416;9765.267604929622;9768.316217864753;9777.364471309342;9778.413295202276;9538.809223815519;9670.564824582876;9674.951625241863;9678.370905891095;9680.951595242013;9636.132748052674;9683.467409292487;9686.70939871805;9688.854667179998;9689.32174747768;8442.57787110645;9659.710208693716;9678.032196780336;9680.612775488971;9686.515674216262;9559.330846840723;9637.951810240904;9641.951790241073;9647.662331818352;9654.372465789771;40852;41072.58927410717;41200.38199427011;41204.79397602993;41285.587144128476;3156.352947645727;3161;3427.605825330069;3748.1004558905665;4014.7189696720948;27608;29459.05450273535;30303.393932121453;30404.391912161853;30415.847920760476;2637.4856902903944;2660.4812061647986;2723.8229515081457;2787.1777825541512;2972.390659914712;497602;500819.99999999633;500973;501457;502869.48565257306;63236.367636323965;63625;63694;63760;63764.68117659382;65427.67286163539;65630.34369656337;65640.34359656343;65678.73088249248;65707.67146164335;15203.783697304189;16579.585510362336;16856.74714879278;16857.747133793004;16876.07181604999;11803.055755539537;11971.580994665144;11983.520659173535;11990.220635658654;11996.400179990966;14146.77468266679;18249.726254106197;19066.2373505061;19567.21731130767;19574.72764270318;6840.486963477759;8026;8124.5937703114605;8184.427090103754;8259.132791056853;874.2306770042322;899.8515244984635;1088.1621151713136;1101.3116801998751;1124.095103441721;29007.41985160285;29575.065742290273;29683;29787;29982.400351992837;1093.1418836213534;1138.1065863297438;1168.8831116888414;1169.5204965963915;1175.905927525796;15048;15110.168940708147;15202.175738911401;15560.844391556166;15591.688166236612;197280;200164;201763;201916.8562357829;202105;42431.57568424338;42749;42790.358144627855;42791.93020174521;42798;1338.946442142323;1354.8306461692289;1368.2406264523286;1402.3268830961197;1457.9781303280672;25576.488470230863;28982;30168.69831301659;30172.594090171304;30653.846730766203;49920.00159996707;49975.500244997085;50339;50403.49596503988;50492.24261636005;513266;524605;525797;525809.3709531428;526477;22601;22936.4265893354;23589.0564377423;25655;26304;38792.80603596964;43840.780796095816;44183.11633767371;44685;45764.08471830611;31719.682803171672;32167.678323217406;32545.837270813496;32733.18167045843;33197;36400.63599363973;36555.634443655224;36759.44860827036;37260.25479490449;37575.43636845395;36435.81782091073;36914.07714807151;37275.81362093172;37336.626633733315;37690.43464347982;31203.37593248077;32673.673263268014;32686.83656581702;32876.342473149925;33156.66843331536;22607.321780346614;23646.88176559106;23984.160554380513;24018.51962960766;24918.626220607068 05:33:28 INFO - PROCESS | 1666 | |2;jquery.html;391.7238346965389;396.61528317532003;401.4239566222469;405.3291802067577;423.7881059470265;14021.719565608682;19900;21257.787422125773;21292.89353553232;21664.783352166472;15224.086554806692;15532.534023979299;19716.225539701423;20198.596028079428;20874;5975.103734439847;6166.599171053889;6171.290301615304;6221.129041934133;6247.312795592504;5083.74581270935;6134.754609815602;6157.415045570686;6158.784442544512;6209.975354066573;3893.649571538575;4137.834486620532;4895.657303988704;5944.435278648543;6199.938000619981;6023.90964135534;6132.816015519542;6159.1993040904845;6186.103015062813;6210.441060304549;5984.162217289583;5993.820185394445;6001.639901605874;6010.879782404328;6017.097435384728;11138.774734779168;11598.362090085042;12289.877101229053;14167.433302667881;14216.289185540785;2195.1658369819365;2275.8862056897087;2318.8637567591927;2436.5857804173334;2575.0086216806662;9453.196478299284;9998.55006524701;10238.078572928473;10337.9483102584;10372.222083343779;1639.3442622950915;1753.2461041752042;1804.476701756489;1811.1397086383995;1840.7054871220541;111553;115537.42231288874;115894;116984.41507792492;118526;10589;11550.191486596026;11741.354225517678;11743.41282935867;11892.762144757056;15070.397184112731;15222.771658425132;15253.694926101416;15259.313330899915;15290.694186116216;7800.375969922393;8328.500289982621;8509.489430634181;8725.258353039935;8821.073787252375;7107.75122870697;7252.746153884589;8434.493930364199;8542;8585.399022068395;4573.451185857718;4667.463241727177;4671.5094915034115;4736.715797052188;4777.474477807438;1612.9758053629205;1977.0838013929626;1979.3369221311023;1980.5939782344585;2012.47675604341;743.4015617427914;869.9695510657223;882.6734108379941;998.7303428074524;1008.5612758450097;15878.285477153324;16602.25289861959;20178;21041.684374734392;21063;861.7457849934284;950.7718147644515;965.5317171171976;983.9360441571419;1010.3382284603597;9978.052085051908;10478.423686697304;10661.680149595524;10677.626283080055;10684.091852192496;525432;525686;526383;620544;651547;80984;81040;119562;141818;158323;980.1717548671361;1008.8133695266399;1011.3476807459148;1060.8620879285643;1086.0876863434594;13515.324233788468;18341.54146146357;18410.815891841445;19019.04904754729;19236.71144932799;34392.82803585966;54291.18563221476;54954.72522637521;55152;56250;69574.65212674107;69976.65011675113;75988;80248;81191.83413459278;8051.275385215359;8163.265306122477;8234.629441675259;8412;8418.915810841812;11334.376609286568;12407.937960310503;12602.117851750656;12649.304288264233;12758;10843.945780271364;10883.401412922365;10894.291871028357;11078.224524283223;11223.15826313046;10621.575136994434;10641.574337026432;10761.730956726144;10899.455027248447;11056.226064175431;10821.242513024014;11091.33451992883;11208;11385.487653055467;11430.428478575872;8119.30985866208;8227;8326.375521835766;8332.33341332704;8554.957225213833;9449.574769135543;9690.418574885529;10789.298695584763;10792;10893.183011274345 05:33:28 INFO - PROCESS | 1666 | |3;mootools.html;1383.3083458270864;1600.2238914126651;1615.927283272253;1863.5900101977568;1995.2617531513351;8007.239312265333;8773.429727067736;9032.819343613124;9073.410228335153;9160.312976526771;8691.130886911322;8993.685221017266;8996.100389961;8996.730098097067;9007.459552426842;2156.148321413041;2220.911163553456;2221.577900198965;2427.1141033522767;2471.122751423246;2212.9889350553226;2246.6854640350366;2382.4996750682376;2474.34429876083;2477.8884950177194;2420.216864954087;2436.658867758515;2446.987765061172;2450.0077468625145;2456.791172750309;2420.056178090546;2433.3065076453304;2436.9147079852296;2438.634204869275;2443.1204766284122;2140.636091864387;2367.715874095102;2442.68245128134;2446.9755302446924;2460.2373264288126;8833.293336533063;8912.019677835433;8927.598258078402;8936.329775266817;8974.147455991735;5301.469853014706;5419.918701219485;5450.127979523297;5467.988422141916;5474.589405794582;3494.3884820156627;3522.1018640246575;3552.5736911570775;3580.4629305604108;3587.461880717887;981.3277904635347;1002.9297949143563;1004.372267332917;1020.2297265564525;1034.0900007992993;12376.81434778479;12385.133040687066;12395.132340736152;12402.689932751664;12412.131150819447;4852.199387101159;4882.8779280517565;4938.234573641092;4974.552290293891;5009;10286.845697314546;10299.53652085658;10309.639162629272;10315.226358023177;10347.793044139074;5396.974574830848;5497.8075767347955;5510;5512.972435137879;5520.254765606592;4451.154280686724;4954.644792383863;4974;4978.427480839678;5075.187969924868;3313.5858017747782;3755.0987762937243;3823.445600387928;3899.395593682984;3909.807508709814;1282.3908643394313;1345.905786594929;1356.403182599653;1364.9522266720617;1364.9694480667033;847.2671139464319;873.122511875561;883.7746374674417;927.5547737086101;943.9433633981846;5957.851053723692;6123.989541725655;6124.663143527148;6139.263288405331;6150.7847225346895;969.9709008729608;971.2327261463506;987.8024395121005;990.8018396320765;992.3202606214766;4013.578574249662;4026.174634199982;4046.7369620975132;4097.549269580403;4108.239975604522;10684.893151068545;11185.66443006711;11201.383923884101;11336.206465547333;11362.772744545227;10680.786384272427;11268.38023908693;11273.54905803775;11295.378754168436;11295.604653837076;8378.49729016261;8413.663453461793;8414.158584141536;8538.957305213435;8564.272036876811;5812.18629391894;5922.496587790086;5958.940410596012;5986.521078313812;6008.639481631116;9908.653197138066;10242.385456872611;10457.84313235287;10469.895301046892;10483.68548943533;10257.794844103226;10413.687589372332;10512.26414151002;10534.315269507457;10548.789024219626;7181.1741649708865;7213.314735100157;7301.744438944612;7309.086364204474;7312.707491700273;7551.244875512405;7914;8052.5571093590415;8291.212334828182;8342.332613391036;6097.664628445478;7414.184439711519;7547.471676982558;7603.847923041398;7700.691972321044;7372.189059203376;7469.514481558681;7535.982642343213;7566.470347075872;7644.044494438195;7101.573905565476;7131.393831524379;7194.208637050025;7199.928000719926;7212.42300615939;4968.850934471971;4968.900621987467;5049.469805670352;5053.483954813615;5075.746212689277;5005.749712514433;5032.320636714143;5082.085224659449;5135.897282054413;5221.895562088813 05:33:28 INFO - PROCESS | 1666 | |4;prototype.html;314.69946201377684;318.21877291249984;325.3947657357316;328.4711614300975;331.953526506289;5375.086235339992;5413.891722165564;5418.59360547959;5418.864528386787;5447.536959358459;5326.094563924133;5350.5184533391985;5371.301730774994;5379.515843574078;5426.375966763823;3644.5262115924893;3810.2950954073444;3865.36221523449;3937.4093885917055;4147.564505726886;4062.8171732272117;4128.50457945047;4157.8544750933725;4160.77257209124;4184.476940382452;3475.5481787367607;3940.9014774630577;4120.711550191488;4128.699459670194;4171.812268447927;3359.5128706337687;3492.353914525821;3832.712546559005;3864.6908247340143;4107.54816970133;3153.495440729472;3267.6929228308627;3367.3265346930466;3541.6541714148716;3626.3291291111227;5160.793568257265;5168.483151684839;5216.895662086775;5248.399238232338;5260.842614624777;4180.561041090703;4363.301871700544;4490.550944905483;4648.325992731069;4654.417498050679;5584.776608935678;5694.402087780806;5706.9714651426475;5716.79991200306;5725.341585717612;3887.844486220548;3891.7859517726515;3898.5126859142606;3904.4143378493454;3909.3940439231965;5084.983003399335;5938.703064846827;5982.670953097578;5984.581079324449;6025.789097381615;3602.747807653439;3636.127329440968;3882.475865758143;3886.6113388660906;3945.6054394560315;3618;3957.762534247954;3959;3974.2250261198787;3986.820593073319;4386.4736231652405;4386.824527018884;4574.4968053514085;4635.884102897455;4689.974966146884;4807.567318941312;5706.574705750039;5728.255326807488;5747.971260143672;5761.625494342854;1208.2025862930423;1335.9933200333935;1345.7106722054805;1371.4788380415378;1379.744747221747;724.9528780629242;726.9531874101277;787.8975733154767;835.4569529805553;855.8074433252468;4619.19164146273;5039.77321020555;5120.359955005624;5247.291615631841;5257.5005374489365;4586.908261834745;5149.304843846107;5348.866278343073;5771.903338365697;5832.941670583325;4997.325361076281;5055.620828437882;5094.337736094284;5212;5254.3169387979315;3021.7789924405542;3021.879124835026;3056.954145687772;3060.173753086654;3068.4170007698467;5279.683219006872;5400.675959442446;5585.88282343533;6038.516918646497;6038.879222415527;5253.55344795689;5290.947090529123;5424.837254882281;5674.858128546819;6096.786612468542;5171.689698618095;5330.840074797762;6011.6693581853415;6022.277326720821;6024.57827952048;4799.832005879917;5404.837959838654;5750.597458177887;5781;5825.3592104867585;5691.943080569307;5700;5725.770969161187;5767.711614419179;5824.504917082096;5985.264273360676;6015.368386319548;6032.939670603238;6046.697665116639;6049.969750151221;3771.962280377271;3866.2742766349093;3903.609639036187;3910.7066969976795;3934.6852251819214;5199.792008319625;5734.541236701138;5756.481916627524;5822.737976791139;5829.621074630136;5616.971915140398;5621.8594535136945;5637.379888212211;5679.488846003879;5680.857978550569;5202.687838729688;5231.730168644597;5297.616072767193;5560.19377190297;5588.608797384144;4505.211587972158;4713.552212539803;4899.2362749410895;5163.6127290454115;5375.16684913839;4657.5575320344515;4956.008798240438;5095.85343297755;5375.4087050425205;5725.427457254408;4476.060027394154;5536.2249285100925;5576.693281869536;5585.413531579125;5598.188262701804 05:33:28 INFO - PROCESS | 1666 | |5;yui.html;647.7894684227625;678.1184460201737;713.243961400915;742.7994441500791;753.9019927409436;14656.853431465683;14950.476733314335;14998.325075371607;15151.545453636381;15381.69236615267;14201.431942722278;14246.430142794276;14267.643308917282;14280.071795333322;14283.928580357084;730.4412124724563;738.837455759732;890.3811850763722;896.7578753736487;918.1048477734222;910.6311943662827;915.5193523400202;917.2295271971543;921.1801496667971;923.9676611318605;985.3152059318776;987.8370068938616;987.8962708915568;989.5151375825866;990.7721224118457;763.6754379388738;924.6994726713845;938.6245501799267;940.0693313619536;983.7934033852899;701.7298340139032;704.3414407528926;705.6401235369998;710.8969199466101;713.3259070178683;14628.634284142876;14691.485797997018;14700.926495367561;14725.410983560645;14733;12126.757464850742;12179.451924663412;12274.38628068593;12336.136470447072;12425.130240883142;10606.52270647815;10665.093467055309;10779.892201077968;10981.835272470918;11005.944970275177;3222.017284728157;3943.9014024649327;3946.6645335146545;4035.1324465240114;4229.175310814392;73390.63304683442;80373.19626803775;80437.59781201057;80597;80896.59551702262;9289;9354;9604.375715578464;9626.614935402506;9842.852357214648;17291.308347665952;20826.583468330853;20920.58158836845;20975.685364719542;20993.47516312074;7235.2764723527225;7260.818479537948;7350.375218106521;7425.962870185723;7583.634945709825;3868.825902834379;3890.3775395936514;3905.5313362396128;3915.2169566086895;3988.12261302513;3313.9668603313658;3377.67912048355;3416.7949923004203;3418.1454636340914;3420.572428446444;553.941836107211;556.3296228045195;579.5884921705552;581.8399940016529;592.848823549992;529.2669652531272;550.5430492691057;565.7030059218861;583.9270091238595;584.5644994479055;7577.5453472791805;7635.23647635232;7684.231576842271;7693.384529237647;8048.678052877819;506.07135905612876;544.8937457195888;557.981683427746;577.2351634084771;580.1703563903658;3469.3581687387914;3627.72792040598;3667.2482141161;3760.1539653577724;3848.9615103849164;90121;94588.52705736428;95741.56387654191;96004.83958863575;96380;77683.83474247891;78437.60781196058;79205.60397197977;79778.60110699409;79800;53197;65278.673606631666;68185.65907170532;68699;69093.3090669107;6799.490038247052;7927.3261772747655;7981.680732770857;8196.303314218358;8214.383921205814;32027;42099.57900420957;42623.360649590904;44853.10293794171;45896.770516147204;63031.36968630255;64435;64437.35562644314;64516;64632.70734585376;10840.891591083988;10880.401577912975;11051.281666691639;11181.105511558893;11354.034907032665;11861.76276474483;11866.821997670215;12337.93831030875;12423.44094515767;12836.935815320863;8705.173008564176;9205.493697846683;9454.480003599592;10279.126274266471;10303.53634086453;10005.949375315487;10249.180065594586;10417.427041512487;10583.206259530412;10607.310524815863;9403.247740180906;9758.658446954323;9783.119519243302;10185.541650625592;10212.795744085226;8377.204165604258;8521.744347669579;8650.465537275559;8815;8919;8278.337732981227;8472.068072511895;8485.151484851713;8637.827243455222;8690.217880390543 05:33:28 INFO - PROCESS | 1666 | __end_tp_report 05:33:28 INFO - PROCESS | 1666 | __start_cc_report 05:33:28 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1423 05:33:28 INFO - PROCESS | 1666 | __end_cc_report 05:33:28 INFO - PROCESS | 1666 | __startTimestamp1463056408097__endTimestamp 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:33:28 INFO - PROCESS | 1666 | Number of tests: 6 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5786.84 Median:6060.45 stddev:664.32 (11.0%) stddev-sans-first:628.11 05:33:28 INFO - PROCESS | 1666 | Values: 2954.8 3386.8 3443.9 3480.3 3512.9 4740.5 5692.5 5739.7 5782.6 5825.7 5725.7 5781.3 5835.5 5849.8 5870.4 5101.4 5171.3 5268.7 5285.7 5369.3 5336.6 5380.2 5388.0 5400.2 5426.9 5176.4 5177.2 5214.7 5241.0 5288.1 5203.0 5211.7 5215.3 5230.0 5236.3 5203.2 5228.1 5272.5 5290.0 5332.5 5607.0 5671.5 5717.4 5733.9 5747.5 6271.7 6285.0 6335.7 6342.6 6369.8 6272.1 6341.4 6346.9 6363.2 6377.7 5747.9 5789.8 5824.0 5890.1 5939.8 6309.6 6337.9 6359.2 6363.5 6366.1 6232.4 6249.9 6254.7 6262.9 6264.2 6159.4 6171.9 6222.3 6264.9 6338.4 6146.4 6179.3 6194.7 6207.8 6207.8 6136.4 6239.6 6241.5 6244.2 6257.0 4829.4 4866.0 4872.9 4880.1 4897.6 4429.3 4462.1 4464.7 4469.3 4487.4 5721.2 5722.0 5727.3 5731.7 5734.5 6228.5 6232.7 6270.9 6321.5 6324.3 5748.5 5761.0 5767.3 5774.4 5813.2 4644.0 5401.0 5419.1 5419.4 5438.4 6244.0 6305.9 6318.4 6371.9 6443.5 6314.5 6322.1 6331.7 6342.9 6360.8 6252.2 6408.9 6433.2 6435.6 6470.4 6145.6 6151.1 6222.8 6274.3 6292.2 6335.4 6336.5 6337.7 6340.6 6340.9 5797.5 6103.3 6151.2 6157.3 6183.2 6104.9 6110.5 6136.3 6202.4 6215.8 6049.9 6060.5 6070.8 6075.2 6083.4 6011.8 6110.1 6157.2 6208.9 6218.4 5965.4 6018.4 6035.9 6048.7 6099.9 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:56025.66 Median:20542.36 stddev:115986.38 (564.6%) stddev-sans-first:116236.84 05:33:28 INFO - PROCESS | 1666 | Values: 502.3 512.3 513.8 519.5 522.8 30611.5 31158.7 42696.8 43466.0 43591.3 41865.0 43955.6 44143.6 44196.6 44212.3 9582.3 9765.3 9768.3 9777.4 9778.4 9538.8 9670.6 9675.0 9678.4 9681.0 9636.1 9683.5 9686.7 9688.9 9689.3 8442.6 9659.7 9678.0 9680.6 9686.5 9559.3 9638.0 9642.0 9647.7 9654.4 40852.0 41072.6 41200.4 41204.8 41285.6 3156.4 3161.0 3427.6 3748.1 4014.7 27608.0 29459.1 30303.4 30404.4 30415.8 2637.5 2660.5 2723.8 2787.2 2972.4 497602.0 500820.0 500973.0 501457.0 502869.5 63236.4 63625.0 63694.0 63760.0 63764.7 65427.7 65630.3 65640.3 65678.7 65707.7 15203.8 16579.6 16856.7 16857.7 16876.1 11803.1 11971.6 11983.5 11990.2 11996.4 14146.8 18249.7 19066.2 19567.2 19574.7 6840.5 8026.0 8124.6 8184.4 8259.1 874.2 899.9 1088.2 1101.3 1124.1 29007.4 29575.1 29683.0 29787.0 29982.4 1093.1 1138.1 1168.9 1169.5 1175.9 15048.0 15110.2 15202.2 15560.8 15591.7 197280.0 200164.0 201763.0 201916.9 202105.0 42431.6 42749.0 42790.4 42791.9 42798.0 1338.9 1354.8 1368.2 1402.3 1458.0 25576.5 28982.0 30168.7 30172.6 30653.8 49920.0 49975.5 50339.0 50403.5 50492.2 513266.0 524605.0 525797.0 525809.4 526477.0 22601.0 22936.4 23589.1 25655.0 26304.0 38792.8 43840.8 44183.1 44685.0 45764.1 31719.7 32167.7 32545.8 32733.2 33197.0 36400.6 36555.6 36759.4 37260.3 37575.4 36435.8 36914.1 37275.8 37336.6 37690.4 31203.4 32673.7 32686.8 32876.3 33156.7 22607.3 23646.9 23984.2 24018.5 24918.6 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33190.33 Median:2297.37 stddev:95676.55 (4164.6%) stddev-sans-first:95913.25 05:33:28 INFO - PROCESS | 1666 | Values: 391.7 396.6 401.4 405.3 423.8 14021.7 19900.0 21257.8 21292.9 21664.8 15224.1 15532.5 19716.2 20198.6 20874.0 5975.1 6166.6 6171.3 6221.1 6247.3 5083.7 6134.8 6157.4 6158.8 6210.0 3893.6 4137.8 4895.7 5944.4 6199.9 6023.9 6132.8 6159.2 6186.1 6210.4 5984.2 5993.8 6001.6 6010.9 6017.1 11138.8 11598.4 12289.9 14167.4 14216.3 2195.2 2275.9 2318.9 2436.6 2575.0 9453.2 9998.6 10238.1 10337.9 10372.2 1639.3 1753.2 1804.5 1811.1 1840.7 111553.0 115537.4 115894.0 116984.4 118526.0 10589.0 11550.2 11741.4 11743.4 11892.8 15070.4 15222.8 15253.7 15259.3 15290.7 7800.4 8328.5 8509.5 8725.3 8821.1 7107.8 7252.7 8434.5 8542.0 8585.4 4573.5 4667.5 4671.5 4736.7 4777.5 1613.0 1977.1 1979.3 1980.6 2012.5 743.4 870.0 882.7 998.7 1008.6 15878.3 16602.3 20178.0 21041.7 21063.0 861.7 950.8 965.5 983.9 1010.3 9978.1 10478.4 10661.7 10677.6 10684.1 525432.0 525686.0 526383.0 620544.0 651547.0 80984.0 81040.0 119562.0 141818.0 158323.0 980.2 1008.8 1011.3 1060.9 1086.1 13515.3 18341.5 18410.8 19019.0 19236.7 34392.8 54291.2 54954.7 55152.0 56250.0 69574.7 69976.7 75988.0 80248.0 81191.8 8051.3 8163.3 8234.6 8412.0 8418.9 11334.4 12407.9 12602.1 12649.3 12758.0 10843.9 10883.4 10894.3 11078.2 11223.2 10621.6 10641.6 10761.7 10899.5 11056.2 10821.2 11091.3 11208.0 11385.5 11430.4 8119.3 8227.0 8326.4 8332.3 8555.0 9449.6 9690.4 10789.3 10792.0 10893.2 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5839.55 Median:4974.28 stddev:3321.20 (66.8%) stddev-sans-first:3313.63 05:33:28 INFO - PROCESS | 1666 | Values: 1383.3 1600.2 1615.9 1863.6 1995.3 8007.2 8773.4 9032.8 9073.4 9160.3 8691.1 8993.7 8996.1 8996.7 9007.5 2156.1 2220.9 2221.6 2427.1 2471.1 2213.0 2246.7 2382.5 2474.3 2477.9 2420.2 2436.7 2447.0 2450.0 2456.8 2420.1 2433.3 2436.9 2438.6 2443.1 2140.6 2367.7 2442.7 2447.0 2460.2 8833.3 8912.0 8927.6 8936.3 8974.1 5301.5 5419.9 5450.1 5468.0 5474.6 3494.4 3522.1 3552.6 3580.5 3587.5 981.3 1002.9 1004.4 1020.2 1034.1 12376.8 12385.1 12395.1 12402.7 12412.1 4852.2 4882.9 4938.2 4974.6 5009.0 10286.8 10299.5 10309.6 10315.2 10347.8 5397.0 5497.8 5510.0 5513.0 5520.3 4451.2 4954.6 4974.0 4978.4 5075.2 3313.6 3755.1 3823.4 3899.4 3909.8 1282.4 1345.9 1356.4 1365.0 1365.0 847.3 873.1 883.8 927.6 943.9 5957.9 6124.0 6124.7 6139.3 6150.8 970.0 971.2 987.8 990.8 992.3 4013.6 4026.2 4046.7 4097.5 4108.2 10684.9 11185.7 11201.4 11336.2 11362.8 10680.8 11268.4 11273.5 11295.4 11295.6 8378.5 8413.7 8414.2 8539.0 8564.3 5812.2 5922.5 5958.9 5986.5 6008.6 9908.7 10242.4 10457.8 10469.9 10483.7 10257.8 10413.7 10512.3 10534.3 10548.8 7181.2 7213.3 7301.7 7309.1 7312.7 7551.2 7914.0 8052.6 8291.2 8342.3 6097.7 7414.2 7547.5 7603.8 7700.7 7372.2 7469.5 7536.0 7566.5 7644.0 7101.6 7131.4 7194.2 7199.9 7212.4 4968.9 4968.9 5049.5 5053.5 5075.7 5005.7 5032.3 5082.1 5135.9 5221.9 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4554.59 Median:5170.09 stddev:1405.49 (27.2%) stddev-sans-first:1372.93 05:33:28 INFO - PROCESS | 1666 | Values: 314.7 318.2 325.4 328.5 332.0 5375.1 5413.9 5418.6 5418.9 5447.5 5326.1 5350.5 5371.3 5379.5 5426.4 3644.5 3810.3 3865.4 3937.4 4147.6 4062.8 4128.5 4157.9 4160.8 4184.5 3475.5 3940.9 4120.7 4128.7 4171.8 3359.5 3492.4 3832.7 3864.7 4107.5 3153.5 3267.7 3367.3 3541.7 3626.3 5160.8 5168.5 5216.9 5248.4 5260.8 4180.6 4363.3 4490.6 4648.3 4654.4 5584.8 5694.4 5707.0 5716.8 5725.3 3887.8 3891.8 3898.5 3904.4 3909.4 5085.0 5938.7 5982.7 5984.6 6025.8 3602.7 3636.1 3882.5 3886.6 3945.6 3618.0 3957.8 3959.0 3974.2 3986.8 4386.5 4386.8 4574.5 4635.9 4690.0 4807.6 5706.6 5728.3 5748.0 5761.6 1208.2 1336.0 1345.7 1371.5 1379.7 725.0 727.0 787.9 835.5 855.8 4619.2 5039.8 5120.4 5247.3 5257.5 4586.9 5149.3 5348.9 5771.9 5832.9 4997.3 5055.6 5094.3 5212.0 5254.3 3021.8 3021.9 3057.0 3060.2 3068.4 5279.7 5400.7 5585.9 6038.5 6038.9 5253.6 5290.9 5424.8 5674.9 6096.8 5171.7 5330.8 6011.7 6022.3 6024.6 4799.8 5404.8 5750.6 5781.0 5825.4 5691.9 5700.0 5725.8 5767.7 5824.5 5985.3 6015.4 6032.9 6046.7 6050.0 3772.0 3866.3 3903.6 3910.7 3934.7 5199.8 5734.5 5756.5 5822.7 5829.6 5617.0 5621.9 5637.4 5679.5 5680.9 5202.7 5231.7 5297.6 5560.2 5588.6 4505.2 4713.6 4899.2 5163.6 5375.2 4657.6 4956.0 5095.9 5375.4 5725.4 4476.1 5536.2 5576.7 5585.4 5598.2 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17637.41 Median:63733.18 stddev:25205.42 (39.5%) stddev-sans-first:25243.85 05:33:28 INFO - PROCESS | 1666 | Values: 647.8 678.1 713.2 742.8 753.9 14656.9 14950.5 14998.3 15151.5 15381.7 14201.4 14246.4 14267.6 14280.1 14283.9 730.4 738.8 890.4 896.8 918.1 910.6 915.5 917.2 921.2 924.0 985.3 987.8 987.9 989.5 990.8 763.7 924.7 938.6 940.1 983.8 701.7 704.3 705.6 710.9 713.3 14628.6 14691.5 14700.9 14725.4 14733.0 12126.8 12179.5 12274.4 12336.1 12425.1 10606.5 10665.1 10779.9 10981.8 11005.9 3222.0 3943.9 3946.7 4035.1 4229.2 73390.6 80373.2 80437.6 80597.0 80896.6 9289.0 9354.0 9604.4 9626.6 9842.9 17291.3 20826.6 20920.6 20975.7 20993.5 7235.3 7260.8 7350.4 7426.0 7583.6 3868.8 3890.4 3905.5 3915.2 3988.1 3314.0 3377.7 3416.8 3418.1 3420.6 553.9 556.3 579.6 581.8 592.8 529.3 550.5 565.7 583.9 584.6 7577.5 7635.2 7684.2 7693.4 8048.7 506.1 544.9 558.0 577.2 580.2 3469.4 3627.7 3667.2 3760.2 3849.0 90121.0 94588.5 95741.6 96004.8 96380.0 77683.8 78437.6 79205.6 79778.6 79800.0 53197.0 65278.7 68185.7 68699.0 69093.3 6799.5 7927.3 7981.7 8196.3 8214.4 32027.0 42099.6 42623.4 44853.1 45896.8 63031.4 64435.0 64437.4 64516.0 64632.7 10840.9 10880.4 11051.3 11181.1 11354.0 11861.8 11866.8 12337.9 12423.4 12836.9 8705.2 9205.5 9454.5 10279.1 10303.5 10005.9 10249.2 10417.4 10583.2 10607.3 9403.2 9758.7 9783.1 10185.5 10212.8 8377.2 8521.7 8650.5 8815.0 8919.0 8278.3 8472.1 8485.2 8637.8 8690.2 05:33:28 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:33:28 INFO - PROCESS | 1666 | 05:33:28 INFO - TEST-INFO | 1666: exit 0 05:33:28 INFO - TEST-OK | dromaeo_css | took 1116521ms 05:33:28 INFO - TEST-START | kraken 05:33:28 INFO - operating with platform_type : mac_ 05:33:28 INFO - Initialising browser for kraken test... 05:33:28 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpbZuBui/profile http://localhost:49270/getInfo.html) 05:33:30 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:33:30 INFO - PROCESS | 1672 | colorDepth:24 05:33:30 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:33:30 INFO - PROCESS | 1672 | __metrics 05:33:31 INFO - PROCESS | 1672 | 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" 05:33:31 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:33:31 INFO - TEST-INFO | 1672: exit 0 05:33:31 INFO - Browser initialized. 05:33:31 INFO - Running cycle 1/1 for kraken test... 05:33:31 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpbZuBui/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:43 INFO - PROCESS | 1675 | RSS: Main: 238784512 05:33:43 INFO - PROCESS | 1675 | 05:33:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:47 INFO - PROCESS | 1675 | RSS: Main: 286797824 05:33:47 INFO - PROCESS | 1675 | 05:33:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:33:50 INFO - PROCESS | 1675 | RSS: Main: 295792640 05:33:50 INFO - PROCESS | 1675 | 05:33:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:33:54 INFO - PROCESS | 1675 | RSS: Main: 383819776 05:33:54 INFO - PROCESS | 1675 | 05:33:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:33:57 INFO - PROCESS | 1675 | RSS: Main: 284102656 05:33:57 INFO - PROCESS | 1675 | 05:33:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:00 INFO - PROCESS | 1675 | RSS: Main: 287629312 05:34:00 INFO - PROCESS | 1675 | 05:34:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:04 INFO - PROCESS | 1675 | RSS: Main: 326881280 05:34:04 INFO - PROCESS | 1675 | 05:34:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:08 INFO - PROCESS | 1675 | RSS: Main: 330358784 05:34:08 INFO - PROCESS | 1675 | 05:34:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:34:11 INFO - PROCESS | 1675 | RSS: Main: 326610944 05:34:11 INFO - PROCESS | 1675 | 05:34:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:14 INFO - PROCESS | 1675 | RSS: Main: 315875328 05:34:14 INFO - PROCESS | 1675 | 05:34:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:16 INFO - PROCESS | 1675 | RSS: Main: 322863104 05:34:16 INFO - PROCESS | 1675 | 05:34:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:19 INFO - PROCESS | 1675 | RSS: Main: 324829184 05:34:19 INFO - PROCESS | 1675 | 05:34:22 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:23 INFO - PROCESS | 1675 | RSS: Main: 329179136 05:34:23 INFO - PROCESS | 1675 | 05:34:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:26 INFO - PROCESS | 1675 | RSS: Main: 326918144 05:34:26 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:34:28 INFO - PROCESS | 1675 | RSS: Main: 373526528 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | __start_tp_report 05:34:28 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 05:34:28 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 05:34:28 INFO - PROCESS | 1675 | |i|pagename|runs| 05:34:28 INFO - PROCESS | 1675 | |0;ai-astar;113;107;114;113;113;114;113;114;113;115 05:34:28 INFO - PROCESS | 1675 | |1;audio-beat-detection;171;140;169;196;117;143;136;118;166;119 05:34:28 INFO - PROCESS | 1675 | |2;audio-dft;186;185;185;186;188;185;196;183;179;182 05:34:28 INFO - PROCESS | 1675 | |3;audio-fft;80;77;57;123;58;58;56;59;109;56 05:34:28 INFO - PROCESS | 1675 | |4;audio-oscillator;93;96;135;79;79;78;103;80;79;102 05:34:28 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;86;88;86;89;71;116;129;87;129 05:34:28 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;93;93;95;113;148;146;95;94;93 05:34:28 INFO - PROCESS | 1675 | |7;imaging-desaturate;85;84;84;86;83;84;86;86;85;85 05:34:28 INFO - PROCESS | 1675 | |8;json-parse-financial;58;58;60;57;58;57;59;122;57;57 05:34:28 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;74;76;74;69;66;66;66;73;67 05:34:28 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;84;85;83;85;83;84;82;85;85;86 05:34:28 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;140;136;141;138;146;144;132;130;244;151 05:34:28 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;166;173;168;169;170;169;168;173;169;167 05:34:28 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;59;59;64;70;82;84;84;85;66;66 05:34:28 INFO - PROCESS | 1675 | __end_tp_report 05:34:28 INFO - PROCESS | 1675 | __start_cc_report 05:34:28 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2285 05:34:28 INFO - PROCESS | 1675 | __end_cc_report 05:34:28 INFO - PROCESS | 1675 | __startTimestamp1463056468979__endTimestamp 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | ------- Summary: start ------- 05:34:28 INFO - PROCESS | 1675 | Number of tests: 14 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.50 stddev:2.18 (1.9%) stddev-sans-first:2.32 05:34:28 INFO - PROCESS | 1675 | Values: 113.0 107.0 114.0 113.0 113.0 114.0 113.0 114.0 113.0 115.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:154.50 stddev:26.94 (17.4%) stddev-sans-first:27.20 05:34:28 INFO - PROCESS | 1675 | Values: 171.0 140.0 169.0 196.0 117.0 143.0 136.0 118.0 166.0 119.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.50 Median:185.50 stddev:4.45 (2.4%) stddev-sans-first:4.72 05:34:28 INFO - PROCESS | 1675 | Values: 186.0 185.0 185.0 186.0 188.0 185.0 196.0 183.0 179.0 182.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:73.30 Median:58.00 stddev:24.36 (42.0%) stddev-sans-first:25.72 05:34:28 INFO - PROCESS | 1675 | Values: 80.0 77.0 57.0 123.0 58.0 58.0 56.0 59.0 109.0 56.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:79.00 stddev:18.05 (22.8%) stddev-sans-first:19.14 05:34:28 INFO - PROCESS | 1675 | Values: 93.0 96.0 135.0 79.0 79.0 78.0 103.0 80.0 79.0 102.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:86.50 stddev:20.15 (23.3%) stddev-sans-first:21.11 05:34:28 INFO - PROCESS | 1675 | Values: 88.0 86.0 88.0 86.0 89.0 71.0 116.0 129.0 87.0 129.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:93.50 stddev:22.23 (23.8%) stddev-sans-first:23.12 05:34:28 INFO - PROCESS | 1675 | Values: 94.0 93.0 93.0 95.0 113.0 148.0 146.0 95.0 94.0 93.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.09 05:34:28 INFO - PROCESS | 1675 | Values: 85.0 84.0 84.0 86.0 83.0 84.0 86.0 86.0 85.0 85.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:58.00 stddev:20.30 (35.0%) stddev-sans-first:21.40 05:34:28 INFO - PROCESS | 1675 | Values: 58.0 58.0 60.0 57.0 58.0 57.0 59.0 122.0 57.0 57.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:71.00 stddev:4.48 (6.3%) stddev-sans-first:4.11 05:34:28 INFO - PROCESS | 1675 | Values: 63.0 74.0 76.0 74.0 69.0 66.0 66.0 66.0 73.0 67.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 05:34:28 INFO - PROCESS | 1675 | Values: 84.0 85.0 83.0 85.0 83.0 84.0 82.0 85.0 85.0 86.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:142.50 stddev:33.56 (23.5%) stddev-sans-first:35.39 05:34:28 INFO - PROCESS | 1675 | Values: 140.0 136.0 141.0 138.0 146.0 144.0 132.0 130.0 244.0 151.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:169.00 stddev:2.30 (1.4%) stddev-sans-first:2.13 05:34:28 INFO - PROCESS | 1675 | Values: 166.0 173.0 168.0 169.0 170.0 169.0 168.0 173.0 169.0 167.0 05:34:28 INFO - PROCESS | 1675 | 05:34:28 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:76.00 stddev:10.72 (14.1%) stddev-sans-first:10.31 05:34:28 INFO - PROCESS | 1675 | Values: 59.0 59.0 64.0 70.0 82.0 84.0 84.0 85.0 66.0 66.0 05:34:28 INFO - PROCESS | 1675 | -------- Summary: end -------- 05:34:28 INFO - PROCESS | 1675 | 05:34:29 INFO - TEST-INFO | 1675: exit 0 05:34:29 INFO - TEST-OK | kraken | took 61052ms 05:34:29 INFO - SUITE-END | took 1177s 05:34:30 INFO - Completed test suite (00:19:37) 05:34:30 INFO - javascript benchmark 05:34:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2954.8374839383837, 3386.7629265951387, 3443.9323809619004, 3480.321337339217, 3512.947305790414, 4740.525947405258, 5692.459216374448, 5739.713014349287, 5782.566307526937, 5825.737841797124, 5725.713714314288, 5781.277340332458, 5835.503982161521, 5849.795257166, 5870.442307980746, 5101.38783345999, 5171.250168725552, 5268.710220937866, 5285.656432331905, 5369.328833895763, 5336.59975501837, 5380.24676545284, 5388.0, 5400.243965844766, 5426.91859622104, 5176.430592634807, 5177.171652535595, 5214.661047031931, 5241.0, 5288.101022826128, 5203.037438073969, 5211.7133557654315, 5215.322008138955, 5229.973850130763, 5236.266922630834, 5203.245529398216, 5228.137357336035, 5272.525472707436, 5290.0, 5332.546733527654, 5606.962711898312, 5671.461211184931, 5717.428257174291, 5733.942660573382, 5747.5114615257335, 6271.655058971754, 6284.968575157095, 6335.683215839235, 6342.619442833445, 6369.8407539811415, 6272.121902933547, 6341.429271365553, 6346.904796428057, 6363.236411630588, 6377.744890204386, 5747.885042299173, 5789.797357092481, 5823.970880145572, 5890.145928840337, 5939.762409503614, 6309.621422714652, 6337.873242535124, 6359.2368915729485, 6363.522735794834, 6366.140571022853, 6232.439080482778, 6249.937500624936, 6254.6872656366995, 6262.874742505124, 6264.154339164245, 6159.4456498915315, 6171.907421388682, 6222.28443728977, 6264.9373506265265, 6338.397852204033, 6146.416090471398, 6179.28938172107, 6194.721237544321, 6207.751689932443, 6207.782727604512, 6136.447719705158, 6239.625622462667, 6241.531885108635, 6244.1570387997945, 6257.0, 4829.420469543677, 4865.95134048662, 4872.853814385574, 4880.145974454456, 4897.632677549198, 4429.269170586817, 4462.085272519126, 4464.732116073046, 4469.307257375112, 4487.41663583732, 5721.199032135504, 5722.0, 5727.341355744059, 5731.742071606787, 5734.541236701054, 6228.532860035515, 6232.719527621268, 6270.9372906271265, 6321.494280457644, 6324.272708638474, 5748.511376532958, 5761.049426844607, 5767.336756272998, 5774.369814932967, 5813.186153938452, 4644.0, 5401.0, 5419.05166595852, 5419.403865574784, 5438.428964958702, 6243.96878015607, 6305.905411418832, 6318.368163183737, 6371.936280637135, 6443.484521238196, 6314.526410519137, 6322.146510221245, 6331.715072821642, 6342.936570634234, 6360.809175724736, 6252.218472690914, 6408.871822563616, 6433.228012638513, 6435.613863168225, 6470.38531339522, 6145.600535965148, 6151.138840562236, 6222.81331560054, 6274.309825919237, 6292.2134733158355, 6335.3981371769605, 6336.493080553452, 6337.746490140343, 6340.587861788969, 6340.904886426615, 5797.507211887037, 6103.328633850184, 6151.169592105009, 6157.261128664589, 6183.196184495987, 6104.913929256043, 6110.51115910735, 6136.274005700836, 6202.41077097675, 6215.782447614311, 6049.909251361321, 6060.454559089703, 6070.817875463743, 6075.162895950155, 6083.361247068994, 6011.789587364421, 6110.052941794029, 6157.168782214523, 6208.906866396917, 6218.440340369208, 5965.433283838029, 6018.398160183947, 6035.879282414415, 6048.7278072487725, 6099.908501372572], "value": 5742.860936322956, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.30430853268234, 512.3134999101206, 513.8150265904275, 519.4545726986663, 522.7725939216439, 30611.540826887616, 31158.68841311589, 42696.78651606741, 43466.0, 43591.34612980808, 41864.95337616553, 43955.560444395545, 44143.55856441434, 44196.55803441965, 44212.336814947805, 9582.329236953416, 9765.267604929622, 9768.316217864753, 9777.364471309342, 9778.413295202276, 9538.809223815519, 9670.564824582876, 9674.951625241863, 9678.370905891095, 9680.951595242013, 9636.132748052674, 9683.467409292487, 9686.70939871805, 9688.854667179998, 9689.32174747768, 8442.57787110645, 9659.710208693716, 9678.032196780336, 9680.612775488971, 9686.515674216262, 9559.330846840723, 9637.951810240904, 9641.951790241073, 9647.662331818352, 9654.372465789771, 40852.0, 41072.58927410717, 41200.38199427011, 41204.79397602993, 41285.587144128476, 3156.352947645727, 3161.0, 3427.605825330069, 3748.1004558905665, 4014.7189696720948, 27608.0, 29459.05450273535, 30303.393932121453, 30404.391912161853, 30415.847920760476, 2637.4856902903944, 2660.4812061647986, 2723.8229515081457, 2787.1777825541512, 2972.390659914712, 497602.0, 500819.99999999633, 500973.0, 501457.0, 502869.48565257306, 63236.367636323965, 63625.0, 63694.0, 63760.0, 63764.68117659382, 65427.67286163539, 65630.34369656337, 65640.34359656343, 65678.73088249248, 65707.67146164335, 15203.783697304189, 16579.585510362336, 16856.74714879278, 16857.747133793004, 16876.07181604999, 11803.055755539537, 11971.580994665144, 11983.520659173535, 11990.220635658654, 11996.400179990966, 14146.77468266679, 18249.726254106197, 19066.2373505061, 19567.21731130767, 19574.72764270318, 6840.486963477759, 8026.0, 8124.5937703114605, 8184.427090103754, 8259.132791056853, 874.2306770042322, 899.8515244984635, 1088.1621151713136, 1101.3116801998751, 1124.095103441721, 29007.41985160285, 29575.065742290273, 29683.0, 29787.0, 29982.400351992837, 1093.1418836213534, 1138.1065863297438, 1168.8831116888414, 1169.5204965963915, 1175.905927525796, 15048.0, 15110.168940708147, 15202.175738911401, 15560.844391556166, 15591.688166236612, 197280.0, 200164.0, 201763.0, 201916.8562357829, 202105.0, 42431.57568424338, 42749.0, 42790.358144627855, 42791.93020174521, 42798.0, 1338.946442142323, 1354.8306461692289, 1368.2406264523286, 1402.3268830961197, 1457.9781303280672, 25576.488470230863, 28982.0, 30168.69831301659, 30172.594090171304, 30653.846730766203, 49920.00159996707, 49975.500244997085, 50339.0, 50403.49596503988, 50492.24261636005, 513266.0, 524605.0, 525797.0, 525809.3709531428, 526477.0, 22601.0, 22936.4265893354, 23589.0564377423, 25655.0, 26304.0, 38792.80603596964, 43840.780796095816, 44183.11633767371, 44685.0, 45764.08471830611, 31719.682803171672, 32167.678323217406, 32545.837270813496, 32733.18167045843, 33197.0, 36400.63599363973, 36555.634443655224, 36759.44860827036, 37260.25479490449, 37575.43636845395, 36435.81782091073, 36914.07714807151, 37275.81362093172, 37336.626633733315, 37690.43464347982, 31203.37593248077, 32673.673263268014, 32686.83656581702, 32876.342473149925, 33156.66843331536, 22607.321780346614, 23646.88176559106, 23984.160554380513, 24018.51962960766, 24918.626220607068], "value": 18878.65182641714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.7238346965389, 396.61528317532003, 401.4239566222469, 405.3291802067577, 423.7881059470265, 14021.719565608682, 19900.0, 21257.787422125773, 21292.89353553232, 21664.783352166472, 15224.086554806692, 15532.534023979299, 19716.225539701423, 20198.596028079428, 20874.0, 5975.103734439847, 6166.599171053889, 6171.290301615304, 6221.129041934133, 6247.312795592504, 5083.74581270935, 6134.754609815602, 6157.415045570686, 6158.784442544512, 6209.975354066573, 3893.649571538575, 4137.834486620532, 4895.657303988704, 5944.435278648543, 6199.938000619981, 6023.90964135534, 6132.816015519542, 6159.1993040904845, 6186.103015062813, 6210.441060304549, 5984.162217289583, 5993.820185394445, 6001.639901605874, 6010.879782404328, 6017.097435384728, 11138.774734779168, 11598.362090085042, 12289.877101229053, 14167.433302667881, 14216.289185540785, 2195.1658369819365, 2275.8862056897087, 2318.8637567591927, 2436.5857804173334, 2575.0086216806662, 9453.196478299284, 9998.55006524701, 10238.078572928473, 10337.9483102584, 10372.222083343779, 1639.3442622950915, 1753.2461041752042, 1804.476701756489, 1811.1397086383995, 1840.7054871220541, 111553.0, 115537.42231288874, 115894.0, 116984.41507792492, 118526.0, 10589.0, 11550.191486596026, 11741.354225517678, 11743.41282935867, 11892.762144757056, 15070.397184112731, 15222.771658425132, 15253.694926101416, 15259.313330899915, 15290.694186116216, 7800.375969922393, 8328.500289982621, 8509.489430634181, 8725.258353039935, 8821.073787252375, 7107.75122870697, 7252.746153884589, 8434.493930364199, 8542.0, 8585.399022068395, 4573.451185857718, 4667.463241727177, 4671.5094915034115, 4736.715797052188, 4777.474477807438, 1612.9758053629205, 1977.0838013929626, 1979.3369221311023, 1980.5939782344585, 2012.47675604341, 743.4015617427914, 869.9695510657223, 882.6734108379941, 998.7303428074524, 1008.5612758450097, 15878.285477153324, 16602.25289861959, 20178.0, 21041.684374734392, 21063.0, 861.7457849934284, 950.7718147644515, 965.5317171171976, 983.9360441571419, 1010.3382284603597, 9978.052085051908, 10478.423686697304, 10661.680149595524, 10677.626283080055, 10684.091852192496, 525432.0, 525686.0, 526383.0, 620544.0, 651547.0, 80984.0, 81040.0, 119562.0, 141818.0, 158323.0, 980.1717548671361, 1008.8133695266399, 1011.3476807459148, 1060.8620879285643, 1086.0876863434594, 13515.324233788468, 18341.54146146357, 18410.815891841445, 19019.04904754729, 19236.71144932799, 34392.82803585966, 54291.18563221476, 54954.72522637521, 55152.0, 56250.0, 69574.65212674107, 69976.65011675113, 75988.0, 80248.0, 81191.83413459278, 8051.275385215359, 8163.265306122477, 8234.629441675259, 8412.0, 8418.915810841812, 11334.376609286568, 12407.937960310503, 12602.117851750656, 12649.304288264233, 12758.0, 10843.945780271364, 10883.401412922365, 10894.291871028357, 11078.224524283223, 11223.15826313046, 10621.575136994434, 10641.574337026432, 10761.730956726144, 10899.455027248447, 11056.226064175431, 10821.242513024014, 11091.33451992883, 11208.0, 11385.487653055467, 11430.428478575872, 8119.30985866208, 8227.0, 8326.375521835766, 8332.33341332704, 8554.957225213833, 9449.574769135543, 9690.418574885529, 10789.298695584763, 10792.0, 10893.183011274345], "value": 9246.197751955277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.3083458270864, 1600.2238914126651, 1615.927283272253, 1863.5900101977568, 1995.2617531513351, 8007.239312265333, 8773.429727067736, 9032.819343613124, 9073.410228335153, 9160.312976526771, 8691.130886911322, 8993.685221017266, 8996.100389961, 8996.730098097067, 9007.459552426842, 2156.148321413041, 2220.911163553456, 2221.577900198965, 2427.1141033522767, 2471.122751423246, 2212.9889350553226, 2246.6854640350366, 2382.4996750682376, 2474.34429876083, 2477.8884950177194, 2420.216864954087, 2436.658867758515, 2446.987765061172, 2450.0077468625145, 2456.791172750309, 2420.056178090546, 2433.3065076453304, 2436.9147079852296, 2438.634204869275, 2443.1204766284122, 2140.636091864387, 2367.715874095102, 2442.68245128134, 2446.9755302446924, 2460.2373264288126, 8833.293336533063, 8912.019677835433, 8927.598258078402, 8936.329775266817, 8974.147455991735, 5301.469853014706, 5419.918701219485, 5450.127979523297, 5467.988422141916, 5474.589405794582, 3494.3884820156627, 3522.1018640246575, 3552.5736911570775, 3580.4629305604108, 3587.461880717887, 981.3277904635347, 1002.9297949143563, 1004.372267332917, 1020.2297265564525, 1034.0900007992993, 12376.81434778479, 12385.133040687066, 12395.132340736152, 12402.689932751664, 12412.131150819447, 4852.199387101159, 4882.8779280517565, 4938.234573641092, 4974.552290293891, 5009.0, 10286.845697314546, 10299.53652085658, 10309.639162629272, 10315.226358023177, 10347.793044139074, 5396.974574830848, 5497.8075767347955, 5510.0, 5512.972435137879, 5520.254765606592, 4451.154280686724, 4954.644792383863, 4974.0, 4978.427480839678, 5075.187969924868, 3313.5858017747782, 3755.0987762937243, 3823.445600387928, 3899.395593682984, 3909.807508709814, 1282.3908643394313, 1345.905786594929, 1356.403182599653, 1364.9522266720617, 1364.9694480667033, 847.2671139464319, 873.122511875561, 883.7746374674417, 927.5547737086101, 943.9433633981846, 5957.851053723692, 6123.989541725655, 6124.663143527148, 6139.263288405331, 6150.7847225346895, 969.9709008729608, 971.2327261463506, 987.8024395121005, 990.8018396320765, 992.3202606214766, 4013.578574249662, 4026.174634199982, 4046.7369620975132, 4097.549269580403, 4108.239975604522, 10684.893151068545, 11185.66443006711, 11201.383923884101, 11336.206465547333, 11362.772744545227, 10680.786384272427, 11268.38023908693, 11273.54905803775, 11295.378754168436, 11295.604653837076, 8378.49729016261, 8413.663453461793, 8414.158584141536, 8538.957305213435, 8564.272036876811, 5812.18629391894, 5922.496587790086, 5958.940410596012, 5986.521078313812, 6008.639481631116, 9908.653197138066, 10242.385456872611, 10457.84313235287, 10469.895301046892, 10483.68548943533, 10257.794844103226, 10413.687589372332, 10512.26414151002, 10534.315269507457, 10548.789024219626, 7181.1741649708865, 7213.314735100157, 7301.744438944612, 7309.086364204474, 7312.707491700273, 7551.244875512405, 7914.0, 8052.5571093590415, 8291.212334828182, 8342.332613391036, 6097.664628445478, 7414.184439711519, 7547.471676982558, 7603.847923041398, 7700.691972321044, 7372.189059203376, 7469.514481558681, 7535.982642343213, 7566.470347075872, 7644.044494438195, 7101.573905565476, 7131.393831524379, 7194.208637050025, 7199.928000719926, 7212.42300615939, 4968.850934471971, 4968.900621987467, 5049.469805670352, 5053.483954813615, 5075.746212689277, 5005.749712514433, 5032.320636714143, 5082.085224659449, 5135.897282054413, 5221.895562088813], "value": 4674.400262349636, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.69946201377684, 318.21877291249984, 325.3947657357316, 328.4711614300975, 331.953526506289, 5375.086235339992, 5413.891722165564, 5418.59360547959, 5418.864528386787, 5447.536959358459, 5326.094563924133, 5350.5184533391985, 5371.301730774994, 5379.515843574078, 5426.375966763823, 3644.5262115924893, 3810.2950954073444, 3865.36221523449, 3937.4093885917055, 4147.564505726886, 4062.8171732272117, 4128.50457945047, 4157.8544750933725, 4160.77257209124, 4184.476940382452, 3475.5481787367607, 3940.9014774630577, 4120.711550191488, 4128.699459670194, 4171.812268447927, 3359.5128706337687, 3492.353914525821, 3832.712546559005, 3864.6908247340143, 4107.54816970133, 3153.495440729472, 3267.6929228308627, 3367.3265346930466, 3541.6541714148716, 3626.3291291111227, 5160.793568257265, 5168.483151684839, 5216.895662086775, 5248.399238232338, 5260.842614624777, 4180.561041090703, 4363.301871700544, 4490.550944905483, 4648.325992731069, 4654.417498050679, 5584.776608935678, 5694.402087780806, 5706.9714651426475, 5716.79991200306, 5725.341585717612, 3887.844486220548, 3891.7859517726515, 3898.5126859142606, 3904.4143378493454, 3909.3940439231965, 5084.983003399335, 5938.703064846827, 5982.670953097578, 5984.581079324449, 6025.789097381615, 3602.747807653439, 3636.127329440968, 3882.475865758143, 3886.6113388660906, 3945.6054394560315, 3618.0, 3957.762534247954, 3959.0, 3974.2250261198787, 3986.820593073319, 4386.4736231652405, 4386.824527018884, 4574.4968053514085, 4635.884102897455, 4689.974966146884, 4807.567318941312, 5706.574705750039, 5728.255326807488, 5747.971260143672, 5761.625494342854, 1208.2025862930423, 1335.9933200333935, 1345.7106722054805, 1371.4788380415378, 1379.744747221747, 724.9528780629242, 726.9531874101277, 787.8975733154767, 835.4569529805553, 855.8074433252468, 4619.19164146273, 5039.77321020555, 5120.359955005624, 5247.291615631841, 5257.5005374489365, 4586.908261834745, 5149.304843846107, 5348.866278343073, 5771.903338365697, 5832.941670583325, 4997.325361076281, 5055.620828437882, 5094.337736094284, 5212.0, 5254.3169387979315, 3021.7789924405542, 3021.879124835026, 3056.954145687772, 3060.173753086654, 3068.4170007698467, 5279.683219006872, 5400.675959442446, 5585.88282343533, 6038.516918646497, 6038.879222415527, 5253.55344795689, 5290.947090529123, 5424.837254882281, 5674.858128546819, 6096.786612468542, 5171.689698618095, 5330.840074797762, 6011.6693581853415, 6022.277326720821, 6024.57827952048, 4799.832005879917, 5404.837959838654, 5750.597458177887, 5781.0, 5825.3592104867585, 5691.943080569307, 5700.0, 5725.770969161187, 5767.711614419179, 5824.504917082096, 5985.264273360676, 6015.368386319548, 6032.939670603238, 6046.697665116639, 6049.969750151221, 3771.962280377271, 3866.2742766349093, 3903.609639036187, 3910.7066969976795, 3934.6852251819214, 5199.792008319625, 5734.541236701138, 5756.481916627524, 5822.737976791139, 5829.621074630136, 5616.971915140398, 5621.8594535136945, 5637.379888212211, 5679.488846003879, 5680.857978550569, 5202.687838729688, 5231.730168644597, 5297.616072767193, 5560.19377190297, 5588.608797384144, 4505.211587972158, 4713.552212539803, 4899.2362749410895, 5163.6127290454115, 5375.16684913839, 4657.5575320344515, 4956.008798240438, 5095.85343297755, 5375.4087050425205, 5725.427457254408, 4476.060027394154, 5536.2249285100925, 5576.693281869536, 5585.413531579125, 5598.188262701804], "value": 4101.446846257097, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.7894684227625, 678.1184460201737, 713.243961400915, 742.7994441500791, 753.9019927409436, 14656.853431465683, 14950.476733314335, 14998.325075371607, 15151.545453636381, 15381.69236615267, 14201.431942722278, 14246.430142794276, 14267.643308917282, 14280.071795333322, 14283.928580357084, 730.4412124724563, 738.837455759732, 890.3811850763722, 896.7578753736487, 918.1048477734222, 910.6311943662827, 915.5193523400202, 917.2295271971543, 921.1801496667971, 923.9676611318605, 985.3152059318776, 987.8370068938616, 987.8962708915568, 989.5151375825866, 990.7721224118457, 763.6754379388738, 924.6994726713845, 938.6245501799267, 940.0693313619536, 983.7934033852899, 701.7298340139032, 704.3414407528926, 705.6401235369998, 710.8969199466101, 713.3259070178683, 14628.634284142876, 14691.485797997018, 14700.926495367561, 14725.410983560645, 14733.0, 12126.757464850742, 12179.451924663412, 12274.38628068593, 12336.136470447072, 12425.130240883142, 10606.52270647815, 10665.093467055309, 10779.892201077968, 10981.835272470918, 11005.944970275177, 3222.017284728157, 3943.9014024649327, 3946.6645335146545, 4035.1324465240114, 4229.175310814392, 73390.63304683442, 80373.19626803775, 80437.59781201057, 80597.0, 80896.59551702262, 9289.0, 9354.0, 9604.375715578464, 9626.614935402506, 9842.852357214648, 17291.308347665952, 20826.583468330853, 20920.58158836845, 20975.685364719542, 20993.47516312074, 7235.2764723527225, 7260.818479537948, 7350.375218106521, 7425.962870185723, 7583.634945709825, 3868.825902834379, 3890.3775395936514, 3905.5313362396128, 3915.2169566086895, 3988.12261302513, 3313.9668603313658, 3377.67912048355, 3416.7949923004203, 3418.1454636340914, 3420.572428446444, 553.941836107211, 556.3296228045195, 579.5884921705552, 581.8399940016529, 592.848823549992, 529.2669652531272, 550.5430492691057, 565.7030059218861, 583.9270091238595, 584.5644994479055, 7577.5453472791805, 7635.23647635232, 7684.231576842271, 7693.384529237647, 8048.678052877819, 506.07135905612876, 544.8937457195888, 557.981683427746, 577.2351634084771, 580.1703563903658, 3469.3581687387914, 3627.72792040598, 3667.2482141161, 3760.1539653577724, 3848.9615103849164, 90121.0, 94588.52705736428, 95741.56387654191, 96004.83958863575, 96380.0, 77683.83474247891, 78437.60781196058, 79205.60397197977, 79778.60110699409, 79800.0, 53197.0, 65278.673606631666, 68185.65907170532, 68699.0, 69093.3090669107, 6799.490038247052, 7927.3261772747655, 7981.680732770857, 8196.303314218358, 8214.383921205814, 32027.0, 42099.57900420957, 42623.360649590904, 44853.10293794171, 45896.770516147204, 63031.36968630255, 64435.0, 64437.35562644314, 64516.0, 64632.70734585376, 10840.891591083988, 10880.401577912975, 11051.281666691639, 11181.105511558893, 11354.034907032665, 11861.76276474483, 11866.821997670215, 12337.93831030875, 12423.44094515767, 12836.935815320863, 8705.173008564176, 9205.493697846683, 9454.480003599592, 10279.126274266471, 10303.53634086453, 10005.949375315487, 10249.180065594586, 10417.427041512487, 10583.206259530412, 10607.310524815863, 9403.247740180906, 9758.658446954323, 9783.119519243302, 10185.541650625592, 10212.795744085226, 8377.204165604258, 8521.744347669579, 8650.465537275559, 8815.0, 8919.0, 8278.337732981227, 8472.068072511895, 8485.151484851713, 8637.827243455222, 8690.217880390543], "value": 6651.624414135379, "unit": "score"}], "name": "dromaeo_css", "value": 7097.672407189398, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0, 115.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 140.0, 169.0, 196.0, 117.0, 143.0, 136.0, 118.0, 166.0, 119.0], "value": 147.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 185.0, 186.0, 188.0, 185.0, 196.0, 183.0, 179.0, 182.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 57.0, 123.0, 58.0, 58.0, 56.0, 59.0, 109.0, 56.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 135.0, 79.0, 79.0, 78.0, 103.0, 80.0, 79.0, 102.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 89.0, 71.0, 116.0, 129.0, 87.0, 129.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 113.0, 148.0, 146.0, 95.0, 94.0, 93.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 83.0, 84.0, 86.0, 86.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 57.0, 58.0, 57.0, 59.0, 122.0, 57.0, 57.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 76.0, 74.0, 69.0, 66.0, 66.0, 66.0, 73.0, 67.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 85.0, 83.0, 84.0, 82.0, 85.0, 85.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 141.0, 138.0, 146.0, 144.0, 132.0, 130.0, 244.0, 151.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 173.0, 168.0, 169.0, 170.0, 169.0, 168.0, 173.0, 169.0, 167.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 64.0, 70.0, 82.0, 84.0, 84.0, 85.0, 66.0, 66.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1508.9, "alertThreshold": 2.0}]} 05:34:30 INFO - Return code: 0 05:34:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:34:30 ERROR - # TBPL SUCCESS # 05:34:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:30 INFO - 2016-05-12 05:34:30.295 screenresolution[1679:10313] starting screenresolution argv=screenresolution get 05:34:30 INFO - 2016-05-12 05:34:30.298 screenresolution[1679:10313] Display 0: 1600x1200x32@60 05:34:30 INFO - 2016-05-12 05:34:30.304 screenresolution[1680:10316] starting screenresolution argv=screenresolution list 05:34:30 INFO - Available Modes on Display 0 05:34:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:30 INFO - Intel Iris: 05:34:30 INFO - Chipset Model: Intel Iris 05:34:30 INFO - Type: GPU 05:34:30 INFO - Bus: Built-In 05:34:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:30 INFO - Vendor: Intel (0x8086) 05:34:30 INFO - Device ID: 0x0a2e 05:34:30 INFO - Revision ID: 0x0009 05:34:30 INFO - Displays: 05:34:30 INFO - Display: 05:34:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:30 INFO - Display Serial Number: 1600x1200 60 05:34:30 INFO - Main Display: Yes 05:34:30 INFO - Mirror: Off 05:34:30 INFO - Online: Yes 05:34:30 INFO - Rotation: Supported 05:34:30 INFO - Return code: 0 05:34:30 INFO - Running post-action listener: _resource_record_post_action 05:34:30 INFO - ##### 05:34:30 INFO - ##### Finished run-tests step (success) 05:34:30 INFO - ##### 05:34:30 INFO - Running post-run listener: _resource_record_post_run 05:34:31 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32579072; Write bytes: 695547392; Read time: 326; Write time: 1872 05:34:31 INFO - TinderboxPrint: CPU usage
53.7% 05:34:31 INFO - TinderboxPrint: I/O read bytes / time
32,579,072 / 326 05:34:31 INFO - TinderboxPrint: I/O write bytes / time
695,547,392 / 1,872 05:34:31 INFO - TinderboxPrint: CPU idle
2,248.3 (46.3%) 05:34:31 INFO - TinderboxPrint: CPU system
965.7 (19.9%) 05:34:31 INFO - TinderboxPrint: CPU user
1,639.6 (33.8%) 05:34:31 INFO - TinderboxPrint: Swap in / out
422,457,344 / 0 05:34:31 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375634432; Write bytes: 369677824; Read time: 28206; Write time: 733 05:34:31 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31649280; Write bytes: 298921984; Read time: 312; Write time: 1080 05:34:31 INFO - Running post-run listener: _upload_blobber_files 05:34:31 INFO - Blob upload gear active. 05:34:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:34:31 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'] 05:34:31 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 05:34:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:34:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:34:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:33 INFO - (blobuploader) - INFO - Done attempting. 05:34:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:34:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:34:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:34 INFO - (blobuploader) - INFO - Done attempting. 05:34:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:34 INFO - Return code: 0 05:34:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/832e95cfc7251362d657bcbef5f631cd928eae5eba4028f48fe4697821d43f4ace7936d74346b3b8089f8a1d73dca84c99238e79123b76b6d5d285c27d00d45d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/823d8205f60f61e056d4c87e3d07bef647c10992fc7c2e0e659511f2c131c73cd4cbe0e238d6488c447c635f4f2091b83a729b2f6f18a9a553d8397fdcbc0c63"} 05:34:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:34 INFO - Contents: 05:34:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/832e95cfc7251362d657bcbef5f631cd928eae5eba4028f48fe4697821d43f4ace7936d74346b3b8089f8a1d73dca84c99238e79123b76b6d5d285c27d00d45d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/823d8205f60f61e056d4c87e3d07bef647c10992fc7c2e0e659511f2c131c73cd4cbe0e238d6488c447c635f4f2091b83a729b2f6f18a9a553d8397fdcbc0c63"} 05:34:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:34 INFO - Copying logs to upload dir... 05:34:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1316.198412 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-05-12 05:34:34.308639) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:34.316329) ========= 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.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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/832e95cfc7251362d657bcbef5f631cd928eae5eba4028f48fe4697821d43f4ace7936d74346b3b8089f8a1d73dca84c99238e79123b76b6d5d285c27d00d45d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/823d8205f60f61e056d4c87e3d07bef647c10992fc7c2e0e659511f2c131c73cd4cbe0e238d6488c447c635f4f2091b83a729b2f6f18a9a553d8397fdcbc0c63"} build_url:https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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/832e95cfc7251362d657bcbef5f631cd928eae5eba4028f48fe4697821d43f4ace7936d74346b3b8089f8a1d73dca84c99238e79123b76b6d5d285c27d00d45d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/823d8205f60f61e056d4c87e3d07bef647c10992fc7c2e0e659511f2c131c73cd4cbe0e238d6488c447c635f4f2091b83a729b2f6f18a9a553d8397fdcbc0c63"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:34.346985) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:34.347299) ========= 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.SAdDw3reCD/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.d3V4DbagwA/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005087 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:34.396065) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:34:34.396367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:34:34.396721) ========= ========= Total master_lag: 0.18 =========