builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0012 starttime: 1462533650.4 results: success (0) buildid: 20160506013623 builduid: 3e330e94e72a44aabac68afc380e9ed1 revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.396210) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.396643) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.396913) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.414311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.414616) ========= 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.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-06 04:20:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 218M=0s 2016-05-06 04:20:50 (218 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.561904) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.562211) ========= 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.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.020442 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:50.612369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:20:50.612700) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-06 04:20:50,690 truncating revision to first 12 chars 2016-05-06 04:20:50,690 Setting DEBUG logging. 2016-05-06 04:20:50,690 attempt 1/10 2016-05-06 04:20:50,691 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b61ad3a6049c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 04:20:51,426 unpacking tar archive at: mozilla-aurora-b61ad3a6049c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.991114 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:20:51.620676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:51.620990) ========= script_repo_revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:51.621360) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:51.621633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:51.634778) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 04:20:51.635070) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:20:51 INFO - MultiFileLogger online at 20160506 04:20:51 in /builds/slave/test 04:20:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:20:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:20:51 INFO - {'append_to_log': False, 04:20:51 INFO - 'base_work_dir': '/builds/slave/test', 04:20:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:20:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:20:51 INFO - 'branch': 'Mozilla-Aurora', 04:20:51 INFO - 'buildbot_json_path': 'buildprops.json', 04:20:51 INFO - 'config_files': ('talos/mac_config.py',), 04:20:51 INFO - 'default_actions': ('clobber', 04:20:51 INFO - 'read-buildbot-config', 04:20:51 INFO - 'download-and-extract', 04:20:51 INFO - 'populate-webroot', 04:20:51 INFO - 'create-virtualenv', 04:20:51 INFO - 'install', 04:20:51 INFO - 'run-tests'), 04:20:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:20:51 INFO - 'download_minidump_stackwalk': True, 04:20:51 INFO - 'download_symbols': 'ondemand', 04:20:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:20:51 INFO - 'tooltool.py': '/tools/tooltool.py', 04:20:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:20:51 INFO - '/tools/misc-python/virtualenv.py')}, 04:20:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:20:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:20:51 INFO - 'installer_path': 'installer.exe', 04:20:51 INFO - 'log_level': 'info', 04:20:51 INFO - 'log_name': 'talos', 04:20:51 INFO - 'log_to_console': True, 04:20:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:20:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:20:51 INFO - 'opt_config_files': (), 04:20:51 INFO - 'pip_index': False, 04:20:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:51 INFO - 'cmd': ('bash', 04:20:51 INFO - '-c', 04:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:51 INFO - 'enabled': True, 04:20:51 INFO - 'halt_on_failure': False, 04:20:51 INFO - 'name': 'check_screen_resolution'},), 04:20:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:51 INFO - 'cmd': ('bash', 04:20:51 INFO - '-c', 04:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:51 INFO - 'enabled': True, 04:20:51 INFO - 'halt_on_failure': False, 04:20:51 INFO - 'name': 'check_screen_resolution'},), 04:20:51 INFO - 'run_cmd_checks_enabled': True, 04:20:51 INFO - 'sps_profile': False, 04:20:51 INFO - 'sps_profile_interval': 0, 04:20:51 INFO - 'suite': 'dromaeojs', 04:20:51 INFO - 'system_bits': '32', 04:20:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:20:51 INFO - 'title': 't-yosemite-r7-0012', 04:20:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:20:51 INFO - 'use_talos_json': True, 04:20:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:20:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:20:51 INFO - 'work_dir': 'build'} 04:20:51 INFO - ##### 04:20:51 INFO - ##### Running clobber step. 04:20:51 INFO - ##### 04:20:51 INFO - Running pre-action listener: _resource_record_pre_action 04:20:51 INFO - Running main action method: clobber 04:20:51 INFO - rmtree: /builds/slave/test/build 04:20:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:20:52 INFO - Running post-action listener: _resource_record_post_action 04:20:52 INFO - ##### 04:20:52 INFO - ##### Running read-buildbot-config step. 04:20:52 INFO - ##### 04:20:52 INFO - Running pre-action listener: _resource_record_pre_action 04:20:52 INFO - Running main action method: read_buildbot_config 04:20:52 INFO - Using buildbot properties: 04:20:52 INFO - { 04:20:52 INFO - "project": "", 04:20:52 INFO - "product": "firefox", 04:20:52 INFO - "script_repo_revision": "production", 04:20:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:20:52 INFO - "repository": "", 04:20:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:20:52 INFO - "buildid": "20160506013623", 04:20:52 INFO - "pgo_build": "False", 04:20:52 INFO - "basedir": "/builds/slave/test", 04:20:52 INFO - "buildnumber": 3, 04:20:52 INFO - "slavename": "t-yosemite-r7-0012", 04:20:52 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:20:52 INFO - "platform": "yosemite_r7", 04:20:52 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:20:52 INFO - "branch": "mozilla-aurora", 04:20:52 INFO - "repo_path": "releases/mozilla-aurora", 04:20:52 INFO - "revision": "b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f", 04:20:52 INFO - "stage_platform": "macosx64", 04:20:52 INFO - "builduid": "3e330e94e72a44aabac68afc380e9ed1", 04:20:52 INFO - "slavebuilddir": "test" 04:20:52 INFO - } 04:20:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 04:20:52 INFO - Running post-action listener: _resource_record_post_action 04:20:52 INFO - ##### 04:20:52 INFO - ##### Running download-and-extract step. 04:20:52 INFO - ##### 04:20:52 INFO - Running pre-action listener: _resource_record_pre_action 04:20:52 INFO - Running main action method: download_and_extract 04:20:52 INFO - mkdir: /builds/slave/test/build/tests 04:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:20:52 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:20:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:20:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:20:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 04:20:53 INFO - Downloaded 1199 bytes. 04:20:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:20:53 INFO - Using the following test package requirements: 04:20:53 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:20:53 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 04:20:53 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 04:20:53 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'jsshell-mac.zip'], 04:20:53 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 04:20:53 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:20:53 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 04:20:53 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 04:20:53 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 04:20:53 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 04:20:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:20:53 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:20:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:20:54 INFO - Downloaded 18088815 bytes. 04:20:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:20:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:20:58 INFO - Return code: 0 04:20:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:20: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')]} 04:20:58 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:20:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:20:59 INFO - Downloaded 18088815 bytes. 04:20:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:20:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:21:03 INFO - Return code: 0 04:21: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')]} 04:21:03 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:21:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:21:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:21:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:21:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:21:04 INFO - Downloaded 10840145 bytes. 04:21:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:21:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:21:04 INFO - Return code: 0 04:21: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')]} 04:21:04 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 04:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 04:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 04:21:08 INFO - Downloaded 114083715 bytes. 04:21:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:21:08 INFO - mkdir: /builds/slave/test/properties 04:21:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:21:08 INFO - Writing to file /builds/slave/test/properties/build_url 04:21:08 INFO - Contents: 04:21:08 INFO - build_url:https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:21:09 INFO - Running post-action listener: _resource_record_post_action 04:21:09 INFO - Running post-action listener: set_extra_try_arguments 04:21:09 INFO - ##### 04:21:09 INFO - ##### Running populate-webroot step. 04:21:09 INFO - ##### 04:21:09 INFO - Running pre-action listener: _resource_record_pre_action 04:21:09 INFO - Running main action method: populate_webroot 04:21:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:21:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:21:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:21:09 INFO - '--tppagecycles', 04:21:09 INFO - '7'], 04:21:09 INFO - 'tests': ['tsvgm']}}, 04:21:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:21:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:21:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:21:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:21:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['damp', 'tps']}, 04:21:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'tests': ['damp', 'tps']}, 04:21:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['dromaeo_dom']}, 04:21:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:21:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['a11yr', 04:21:09 INFO - 'ts_paint', 04:21:09 INFO - 'tpaint', 04:21:09 INFO - 'sessionrestore', 04:21:09 INFO - 'sessionrestore_no_auto_restore', 04:21:09 INFO - 'tabpaint']}, 04:21:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['tsvgx', 04:21:09 INFO - 'tsvgr_opacity', 04:21:09 INFO - 'tart', 04:21:09 INFO - 'tscrollx', 04:21:09 INFO - 'cart']}, 04:21:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:21:09 INFO - 'tsvgr_opacity', 04:21:09 INFO - 'tart', 04:21:09 INFO - 'tscrollx', 04:21:09 INFO - 'cart']}, 04:21:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'talos_options': ['--disable-e10s'], 04:21:09 INFO - 'tests': ['tp5o']}, 04:21:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'tests': ['tp5o']}, 04:21:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'talos_options': ['--disable-e10s', 04:21:09 INFO - '--xperf_path', 04:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:09 INFO - 'tests': ['tp5n']}, 04:21:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:09 INFO - 'talos_options': ['--xperf_path', 04:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:09 INFO - 'tests': ['tp5n']}}, 04:21:09 INFO - 'talos.zip': {'path': '', 04:21:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:21:09 INFO - Running post-action listener: _resource_record_post_action 04:21:09 INFO - ##### 04:21:09 INFO - ##### Running create-virtualenv step. 04:21:09 INFO - ##### 04:21:09 INFO - Running pre-action listener: _resource_record_pre_action 04:21:09 INFO - Running main action method: create_virtualenv 04:21:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:21:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:21:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:21:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:21:09 INFO - Using real prefix '/tools/python27' 04:21:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:21:10 INFO - Installing distribute.............................................................................................................................................................................................done. 04:21:13 INFO - Installing pip.................done. 04:21:13 INFO - Return code: 0 04:21:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:13 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:13 INFO - 'HOME': '/Users/cltbld', 04:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:13 INFO - 'LOGNAME': 'cltbld', 04:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:13 INFO - 'MOZ_NO_REMOTE': '1', 04:21:13 INFO - 'NO_EM_RESTART': '1', 04:21:13 INFO - 'PAGER': '/bin/cat', 04:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:13 INFO - 'PWD': '/builds/slave/test', 04:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:13 INFO - 'SHELL': '/bin/bash', 04:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:13 INFO - 'USER': 'cltbld', 04:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:13 INFO - 'XPC_FLAGS': '0x0', 04:21:13 INFO - 'XPC_SERVICE_NAME': '0', 04:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:21:13 INFO - Cleaning up... 04:21:13 INFO - Return code: 0 04:21:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:13 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:13 INFO - 'HOME': '/Users/cltbld', 04:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:13 INFO - 'LOGNAME': 'cltbld', 04:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:13 INFO - 'MOZ_NO_REMOTE': '1', 04:21:13 INFO - 'NO_EM_RESTART': '1', 04:21:13 INFO - 'PAGER': '/bin/cat', 04:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:13 INFO - 'PWD': '/builds/slave/test', 04:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:13 INFO - 'SHELL': '/bin/bash', 04:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:13 INFO - 'USER': 'cltbld', 04:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:13 INFO - 'XPC_FLAGS': '0x0', 04:21:13 INFO - 'XPC_SERVICE_NAME': '0', 04:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:13 INFO - Downloading/unpacking psutil>=0.7.1 04:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:21:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:16 INFO - Installing collected packages: psutil 04:21:16 INFO - Running setup.py install for psutil 04:21:16 INFO - building 'psutil._psutil_osx' extension 04:21:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:21:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:21:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:21:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:21:17 INFO - building 'psutil._psutil_posix' extension 04:21:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:21:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:21:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:21:17 INFO - ^ 04:21:17 INFO - 1 warning generated. 04:21:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:21:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:17 INFO - Successfully installed psutil 04:21:17 INFO - Cleaning up... 04:21:17 INFO - Return code: 0 04:21:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:17 INFO - 'HOME': '/Users/cltbld', 04:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:17 INFO - 'LOGNAME': 'cltbld', 04:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:17 INFO - 'MOZ_NO_REMOTE': '1', 04:21:17 INFO - 'NO_EM_RESTART': '1', 04:21:17 INFO - 'PAGER': '/bin/cat', 04:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:17 INFO - 'PWD': '/builds/slave/test', 04:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:17 INFO - 'SHELL': '/bin/bash', 04:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:17 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:17 INFO - 'USER': 'cltbld', 04:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:17 INFO - 'XPC_FLAGS': '0x0', 04:21:17 INFO - 'XPC_SERVICE_NAME': '0', 04:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:21:20 INFO - Installing collected packages: mozsystemmonitor 04:21:20 INFO - Running setup.py install for mozsystemmonitor 04:21:20 INFO - Successfully installed mozsystemmonitor 04:21:20 INFO - Cleaning up... 04:21:20 INFO - Return code: 0 04:21:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:20 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:20 INFO - 'HOME': '/Users/cltbld', 04:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:20 INFO - 'LOGNAME': 'cltbld', 04:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:20 INFO - 'MOZ_NO_REMOTE': '1', 04:21:20 INFO - 'NO_EM_RESTART': '1', 04:21:20 INFO - 'PAGER': '/bin/cat', 04:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:20 INFO - 'PWD': '/builds/slave/test', 04:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:20 INFO - 'SHELL': '/bin/bash', 04:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:20 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:20 INFO - 'USER': 'cltbld', 04:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:20 INFO - 'XPC_FLAGS': '0x0', 04:21:20 INFO - 'XPC_SERVICE_NAME': '0', 04:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:21:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:21:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:24 INFO - Downloading docopt-0.6.1.tar.gz 04:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:21:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:21:24 INFO - Running setup.py install for blobuploader 04:21:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:21:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:21:24 INFO - Running setup.py install for requests 04:21:25 INFO - Running setup.py install for docopt 04:21:25 INFO - Successfully installed blobuploader requests docopt 04:21:25 INFO - Cleaning up... 04:21:25 INFO - Return code: 0 04:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:25 INFO - 'HOME': '/Users/cltbld', 04:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:25 INFO - 'LOGNAME': 'cltbld', 04:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:25 INFO - 'MOZ_NO_REMOTE': '1', 04:21:25 INFO - 'NO_EM_RESTART': '1', 04:21:25 INFO - 'PAGER': '/bin/cat', 04:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:25 INFO - 'PWD': '/builds/slave/test', 04:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:25 INFO - 'SHELL': '/bin/bash', 04:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:25 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:25 INFO - 'USER': 'cltbld', 04:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:25 INFO - 'XPC_FLAGS': '0x0', 04:21:25 INFO - 'XPC_SERVICE_NAME': '0', 04:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:25 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-dnGCk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:25 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-PMEr99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:25 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4p9XfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-vtuM_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BQd9_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XfaACm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-vr44AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-b8Y8qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-feEEzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ZZaOmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-2yeHnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-isHe9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Q3ZeyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-jVqAA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-pfUxaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-nogwgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-pYxjAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:21:27 INFO - Running setup.py install for manifestparser 04:21:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:21:27 INFO - Running setup.py install for mozcrash 04:21:27 INFO - Running setup.py install for mozdebug 04:21:28 INFO - Running setup.py install for mozdevice 04:21:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Running setup.py install for mozfile 04:21:28 INFO - Running setup.py install for mozhttpd 04:21:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Running setup.py install for mozinfo 04:21:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Running setup.py install for mozInstall 04:21:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:21:28 INFO - Running setup.py install for mozleak 04:21:28 INFO - Running setup.py install for mozlog 04:21:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Running setup.py install for moznetwork 04:21:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Running setup.py install for mozprocess 04:21:29 INFO - Running setup.py install for mozprofile 04:21:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Running setup.py install for mozrunner 04:21:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:21:29 INFO - Running setup.py install for mozscreenshot 04:21:29 INFO - Running setup.py install for moztest 04:21:30 INFO - Running setup.py install for mozversion 04:21:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:21:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:21:30 INFO - Cleaning up... 04:21:30 INFO - Return code: 0 04:21:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21: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')]} 04:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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')]} 04:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:30 INFO - 'HOME': '/Users/cltbld', 04:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:30 INFO - 'LOGNAME': 'cltbld', 04:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:30 INFO - 'MOZ_NO_REMOTE': '1', 04:21:30 INFO - 'NO_EM_RESTART': '1', 04:21:30 INFO - 'PAGER': '/bin/cat', 04:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:30 INFO - 'PWD': '/builds/slave/test', 04:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:30 INFO - 'SHELL': '/bin/bash', 04:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:30 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:30 INFO - 'USER': 'cltbld', 04:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:30 INFO - 'XPC_FLAGS': '0x0', 04:21:30 INFO - 'XPC_SERVICE_NAME': '0', 04:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-SMun6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aJWuGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-WNEYzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-C80LlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:21:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-paZDfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-qybo_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-PzAWHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-3o5CXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-x8jQcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-j9z6Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-oAXCne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RXjb2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-dOwaA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-CjZbq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FAGR9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FRKxYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-yjqaIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:21:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:34 INFO - Downloading blessings-1.6.tar.gz 04:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:34 INFO - Installing collected packages: blessings 04:21:34 INFO - Running setup.py install for blessings 04:21:34 INFO - Successfully installed blessings 04:21:34 INFO - Cleaning up... 04:21:35 INFO - Return code: 0 04:21:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:35 INFO - Reading from file tmpfile_stdout 04:21:35 INFO - Current package versions: 04:21:35 INFO - blessings == 1.6 04:21:35 INFO - blobuploader == 1.2.4 04:21:35 INFO - docopt == 0.6.1 04:21:35 INFO - manifestparser == 1.1 04:21:35 INFO - mozInstall == 1.12 04:21:35 INFO - mozcrash == 0.17 04:21:35 INFO - mozdebug == 0.1 04:21:35 INFO - mozdevice == 0.48 04:21:35 INFO - mozfile == 1.2 04:21:35 INFO - mozhttpd == 0.7 04:21:35 INFO - mozinfo == 0.9 04:21:35 INFO - mozleak == 0.1 04:21:35 INFO - mozlog == 3.1 04:21:35 INFO - moznetwork == 0.27 04:21:35 INFO - mozprocess == 0.22 04:21:35 INFO - mozprofile == 0.28 04:21:35 INFO - mozrunner == 6.11 04:21:35 INFO - mozscreenshot == 0.1 04:21:35 INFO - mozsystemmonitor == 0.0 04:21:35 INFO - moztest == 0.7 04:21:35 INFO - mozversion == 1.4 04:21:35 INFO - psutil == 3.1.1 04:21:35 INFO - requests == 1.2.3 04:21:35 INFO - wsgiref == 0.1.2 04:21:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:35 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:35 INFO - 'HOME': '/Users/cltbld', 04:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:35 INFO - 'LOGNAME': 'cltbld', 04:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:35 INFO - 'MOZ_NO_REMOTE': '1', 04:21:35 INFO - 'NO_EM_RESTART': '1', 04:21:35 INFO - 'PAGER': '/bin/cat', 04:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:35 INFO - 'PWD': '/builds/slave/test', 04:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:35 INFO - 'SHELL': '/bin/bash', 04:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:35 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:35 INFO - 'USER': 'cltbld', 04:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:35 INFO - 'XPC_FLAGS': '0x0', 04:21:35 INFO - 'XPC_SERVICE_NAME': '0', 04:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:21:35 INFO - Cleaning up... 04:21:35 INFO - Return code: 0 04:21:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:35 INFO - 'HOME': '/Users/cltbld', 04:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:35 INFO - 'LOGNAME': 'cltbld', 04:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:35 INFO - 'MOZ_NO_REMOTE': '1', 04:21:35 INFO - 'NO_EM_RESTART': '1', 04:21:35 INFO - 'PAGER': '/bin/cat', 04:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:35 INFO - 'PWD': '/builds/slave/test', 04:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:35 INFO - 'SHELL': '/bin/bash', 04:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:35 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:35 INFO - 'USER': 'cltbld', 04:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:35 INFO - 'XPC_FLAGS': '0x0', 04:21:35 INFO - 'XPC_SERVICE_NAME': '0', 04:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:35 INFO - Downloading/unpacking jsonschema 04:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:21:38 INFO - Downloading/unpacking functools32 (from jsonschema) 04:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:38 INFO - Downloading functools32-3.2.3-2.tar.gz 04:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:21:38 INFO - warning: no files found matching '*.txt' 04:21:38 INFO - no previously-included directories found matching 'build' 04:21:38 INFO - no previously-included directories found matching 'dist' 04:21:38 INFO - no previously-included directories found matching '.git*' 04:21:38 INFO - Installing collected packages: jsonschema, functools32 04:21:38 INFO - Running setup.py install for functools32 04:21:39 INFO - warning: no files found matching '*.txt' 04:21:39 INFO - no previously-included directories found matching 'build' 04:21:39 INFO - no previously-included directories found matching 'dist' 04:21:39 INFO - no previously-included directories found matching '.git*' 04:21:39 INFO - Successfully installed jsonschema functools32 04:21:39 INFO - Cleaning up... 04:21:39 INFO - Return code: 0 04:21:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:39 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 0x10d2cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd669ccaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, '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.7nd56NBZHw/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.zF5DOenz7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:39 INFO - 'HOME': '/Users/cltbld', 04:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:39 INFO - 'LOGNAME': 'cltbld', 04:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:39 INFO - 'MOZ_NO_REMOTE': '1', 04:21:39 INFO - 'NO_EM_RESTART': '1', 04:21:39 INFO - 'PAGER': '/bin/cat', 04:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:39 INFO - 'PWD': '/builds/slave/test', 04:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:39 INFO - 'SHELL': '/bin/bash', 04:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:21:39 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:39 INFO - 'USER': 'cltbld', 04:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:39 INFO - 'XPC_FLAGS': '0x0', 04:21:39 INFO - 'XPC_SERVICE_NAME': '0', 04:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:39 INFO - Downloading/unpacking flake8 04:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:21:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:21:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:21:43 INFO - no previously-included directories found matching 'docs/_build' 04:21:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:21:43 INFO - Running setup.py install for pep8 04:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:21:43 INFO - no previously-included directories found matching 'docs/_build' 04:21:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:21:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:21:43 INFO - Cleaning up... 04:21:43 INFO - Return code: 0 04:21:43 INFO - Running post-action listener: _resource_record_post_action 04:21:43 INFO - Running post-action listener: _start_resource_monitoring 04:21:43 INFO - Starting resource monitoring. 04:21:43 INFO - ##### 04:21:43 INFO - ##### Running install step. 04:21:43 INFO - ##### 04:21:43 INFO - Running pre-action listener: _resource_record_pre_action 04:21:43 INFO - Running main action method: install 04:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:43 INFO - Reading from file tmpfile_stdout 04:21:43 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:43 INFO - Reading from file tmpfile_stdout 04:21:43 INFO - Output received: 04:21:43 INFO - Usage: mozinstall [options] installer 04:21:43 INFO - Options: 04:21:43 INFO - -h, --help show this help message and exit 04:21:43 INFO - -d DEST, --destination=DEST 04:21:43 INFO - Directory to install application into. [default: 04:21:43 INFO - "/builds/slave/test"] 04:21:43 INFO - --app=APP Application being installed. [default: firefox] 04:21:43 INFO - mkdir: /builds/slave/test/build/application 04:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:22:15 INFO - Reading from file tmpfile_stdout 04:22:15 INFO - Output received: 04:22:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:22:15 INFO - Running post-action listener: _resource_record_post_action 04:22:15 INFO - ##### 04:22:15 INFO - ##### Running run-tests step. 04:22:15 INFO - ##### 04:22:15 INFO - Running pre-action listener: _resource_record_pre_action 04:22:15 INFO - Running main action method: run_tests 04:22:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:15 INFO - 2016-05-06 04:22:15.675 screenresolution[7428:142692] starting screenresolution argv=screenresolution get 04:22:15 INFO - 2016-05-06 04:22:15.678 screenresolution[7428:142692] Display 0: 1600x1200x32@60 04:22:15 INFO - 2016-05-06 04:22:15.685 screenresolution[7429:142694] starting screenresolution argv=screenresolution list 04:22:15 INFO - Available Modes on Display 0 04:22:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:15 INFO - Intel Iris: 04:22:15 INFO - Chipset Model: Intel Iris 04:22:15 INFO - Type: GPU 04:22:15 INFO - Bus: Built-In 04:22:15 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:15 INFO - Vendor: Intel (0x8086) 04:22:15 INFO - Device ID: 0x0a2e 04:22:15 INFO - Revision ID: 0x0009 04:22:15 INFO - Displays: 04:22:15 INFO - Display: 04:22:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:15 INFO - Display Serial Number: 1600x1200 60 04:22:15 INFO - Main Display: Yes 04:22:15 INFO - Mirror: Off 04:22:15 INFO - Online: Yes 04:22:15 INFO - Rotation: Supported 04:22:15 INFO - Return code: 0 04:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:22:15 INFO - Python 2.7.3 04:22:15 INFO - Return code: 0 04:22:15 INFO - grabbing minidump binary from tooltool 04:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16be00>, '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 0x10cfd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d166b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:22:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:22:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:22:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:22:16 INFO - Return code: 0 04:22:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:22:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:22:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:22:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:22:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:22:17 INFO - Return code: 0 04:22:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7nd56NBZHw/Render', 04:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:22:17 INFO - 'HOME': '/Users/cltbld', 04:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:22:17 INFO - 'LOGNAME': 'cltbld', 04:22:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:22:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:17 INFO - 'MOZ_NO_REMOTE': '1', 04:22:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:22:17 INFO - 'NO_EM_RESTART': '1', 04:22:17 INFO - 'PAGER': '/bin/cat', 04:22:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:17 INFO - 'PWD': '/builds/slave/test', 04:22:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:22:17 INFO - 'SHELL': '/bin/bash', 04:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zF5DOenz7e/Listeners', 04:22:17 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:22:17 INFO - 'USER': 'cltbld', 04:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:17 INFO - 'XPC_FLAGS': '0x0', 04:22:17 INFO - 'XPC_SERVICE_NAME': '0', 04:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:22:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:22:17 INFO - mozversion application_buildid: 20160506013623 04:22:17 INFO - mozversion application_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:22:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:22:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:22:17 INFO - mozversion application_name: Firefox 04:22:17 INFO - mozversion application_remotingname: firefox-dev 04:22:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:22:17 INFO - mozversion application_vendor: Mozilla 04:22:17 INFO - mozversion application_version: 48.0a2 04:22:17 INFO - mozversion platform_buildid: 20160506013623 04:22:17 INFO - mozversion platform_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:22:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:22:17 INFO - mozversion platform_version: 48.0a2 04:22:17 INFO - using testdate: 1462533737 04:22:17 INFO - actual date: 1462533737 04:22:17 INFO - starting webserver on 'localhost:64064' 04:22:17 INFO - SUITE-START | Running 2 tests 04:22:17 INFO - TEST-START | dromaeo_css 04:22:17 INFO - operating with platform_type : mac_ 04:22:17 INFO - Initialising browser for dromaeo_css test... 04:22:17 INFO - TEST-INFO | started process 7447 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpgwPL3m/profile http://localhost:64064/getInfo.html) 04:22:19 INFO - PROCESS | 7447 | __metrics Screen width/height:1600/1200 04:22:19 INFO - PROCESS | 7447 | colorDepth:24 04:22:19 INFO - PROCESS | 7447 | Browser inner width/height: 1024/689 04:22:19 INFO - PROCESS | 7447 | __metrics 04:22:19 INFO - PROCESS | 7447 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:22:19 ERROR - PROCESS | 7447 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:22:20 INFO - TEST-INFO | 7447: exit 0 04:22:20 INFO - Browser initialized. 04:22:20 INFO - Running cycle 1/1 for dromaeo_css test... 04:22:20 INFO - TEST-INFO | started process 7449 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpgwPL3m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:32 INFO - PROCESS | 7449 | RSS: Main: 211505152 04:22:32 INFO - PROCESS | 7449 | 04:23:24 INFO - PROCESS | 7449 | 1462533804260 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:25:34 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64064/tests/dromaeo/cssquery-ext.html) 04:25:36 INFO - PROCESS | 7449 | RSS: Main: 305061888 04:25:36 INFO - PROCESS | 7449 | 04:28:38 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-ext.html (next: http://localhost:64064/tests/dromaeo/cssquery-jquery.html) 04:28:39 INFO - PROCESS | 7449 | RSS: Main: 352043008 04:28:39 INFO - PROCESS | 7449 | 04:31:41 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64064/tests/dromaeo/cssquery-mootools.html) 04:31:42 INFO - PROCESS | 7449 | RSS: Main: 359698432 04:31:42 INFO - PROCESS | 7449 | 04:34:44 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64064/tests/dromaeo/cssquery-prototype.html) 04:34:44 INFO - PROCESS | 7449 | RSS: Main: 360574976 04:34:44 INFO - PROCESS | 7449 | 04:37:48 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64064/tests/dromaeo/cssquery-yui.html) 04:37:48 INFO - PROCESS | 7449 | RSS: Main: 332779520 04:37:48 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | Cycle 1(1): loaded http://localhost:64064/tests/dromaeo/cssquery-yui.html (next: http://localhost:64064/tests/dromaeo/cssquery-dojo.html) 04:40:51 INFO - PROCESS | 7449 | RSS: Main: 410726400 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | __start_tp_report 04:40:51 INFO - PROCESS | 7449 | _x_x_mozilla_page_load 04:40:51 INFO - PROCESS | 7449 | _x_x_mozilla_page_load_details 04:40:51 INFO - PROCESS | 7449 | |i|pagename|runs| 04:40:51 INFO - PROCESS | 7449 | |0;dojo.html;3318.651541588133;3376.7298616110697;3383.4924761285806;3407.5229467874487;3444.9138771530706;5668.603197776152;5707;5721.313442386914;5723.227364305818;5725.685087320196;5806.042003069499;5840.211571437854;5840.853978650536;5849.883002339961;5855.853603659911;5153.252778347138;5192.844214673556;5209.114450543398;5242.737863106849;5274.709890955995;5243.2659427680155;5248.422673505911;5253.500917412859;5255.894882102356;5259.842204733845;5205.60957928155;5217.295665085203;5258.6318957672975;5258.815941442051;5267.973660131694;5270.920936185979;5306.310179676656;5309.083681176719;5316.946830531722;5332.733363331818;5106.66806657566;5122.257272695476;5218.269442278084;5218.478152184789;5221.425643179247;5570.972145139289;5629.127485239795;5636.070048442002;5653.293338332708;5661.5187709044785;6197;6197.845053873644;6223.626582405071;6228.159198508189;6228.750849965996;5850.853728656818;6130.917941863022;6170.389491537249;6223.3776622337855;6233.750649973996;5790.305163380379;5807.8548036299435;5821.88356232873;5877.500412464946;5885.352611212763;5780.653160810365;6037.641530655567;6265.592736472115;6269.185005949289;6278.30938596754;6014.1580178775;6107.188815080191;6143.170671959318;6146.416090471398;6174.5060395168275;5164.612654050886;5601.551875849922;6213.782517611862;6235.3452887446165;6241.282252540925;5845.298564172326;6157.168782214433;6165.722542485599;6168.321484636686;6179.258488981351;6112.786052488142;6164.6917654118015;6180.47465965389;6210.627362358273;6214.782482613177;4920.827771027997;4941.160002799533;4944.307796908436;4958.925616115761;4989.650724449326;3779.7732136071922;4402;4438.733675979387;4453.554644535585;4491.685582009228;5562.638428502134;5659.405762394971;5675.31896172462;5691.658500489983;5705.62913410627;6183.041628547582;6195.411435913581;6216.844578885473;6228.750849966042;6242.250929888442;4230.957690423056;5669.886602267931;5677.51741102011;5697.202391665171;5733.312002559636;4538.818447262072;5302.814401495929;5380.515753582196;5427.348718153847;5428.70142142186;6309.716062777186;6330.936690633035;6338.397852204033;6339.080833279057;6355.428011479082;6207.565470417208;6251.343608920998;6253.593516421418;6258.530610204162;6275.937240627535;6221.595596286227;6284.340144284967;6324.019776934582;6332.683365831598;6360.363963603602;6163.691815409122;6172.5679202457195;6180.66006369636;6194.132821405097;6198.659073750807;6141.785037523844;6277.654728989949;6295.496360291258;6297.527685423704;6301.464375528131;5971.671558064349;6080.4831589313635;6107.144999700135;6125.418085281891;6131.774283661862;6105.084237364431;6107.450329470369;6108.267007959073;6127;6135.9079613806725;5925.881482370242;5974.880502389841;5982.880342393215;5990.430909063632;5995.91006134917;5945.464908158287;5993.9700301498215;6096.5732398733435;6108.8778224436155;6115.990861507979;5977.641341519523;5989.940100599112;5990.910136348046;6007.519398448027;6013.9699301503215 04:40:51 INFO - PROCESS | 7449 | |1;ext.html;476.6853876441549;490.8674657842383;505.2118694836056;509.6025100421668;531.3597115475852;30278.39443211137;30997;37407.690730824426;38370;38454.42318365227;40674;41089.794551027204;41110;41111.383329250086;41180.97047573812;9372.437653740762;9581.568829402659;9593.376430532027;9609.855852162224;9677.467739274338;9483.81032379352;9573.42559446432;9615.471149086798;9630.566624501878;9633.855492167622;9576.712698619052;9702.902970970306;9703.95148024259;9704.223662107015;9714.27142964277;9588.472634005127;9650.90349096514;9661.661841835503;9669.129778319915;9670.274729395253;9468;9576.856347154799;9585.90414095864;9594.808103837953;9613.663521776703;38970.610293896985;39423.21153576941;39460.210795783925;39490.407643885366;39503.20993580141;3379.3410284994434;3421.9486707699402;3429.537012503305;3599.6040435552068;3749.8125093745202;27435.725642743517;27494.037708680302;27912.860435697894;28249.71750282512;28421.431571368663;2646.325187077283;2692.5961105834085;2716.0358072884037;2746.917592472229;2759.1722483254944;486838;490177;490727.5463622695;490861;491044;35073.473897891556;35661.64338356583;57451.13823292654;58571.82856342849;58595.70702146462;27878;34927;34947.9515614532;35098.12254693602;35140.29719405597;15076.396944122112;15274.007189532645;15278.923605382124;15283.770743438858;15301.9234903827;10956.671299861016;12886.80669789954;12956.35218239103;12967;13054;7273.199948005715;7623.313901748869;7888.40836937232;8048.436609437284;8119.918800811916;2996.625421822272;3262.8368581570826;3399.354122716725;3399.9490007649906;3428.502867084259;994.9005099490138;1017.4302390661253;1075.720312718683;1122.1527746551465;1126.864776226858;29017.98437054693;29116.57109204633;29608;29699.554506682416;29829.955951541593;714.8570285942832;1035.694470131298;1096.5339730614457;1104.6575561575937;1114.7547539541288;13875.167489950434;14150.575482735534;14310.928445357707;14482.710345793024;14533.78199327011;632602;645032;645205;646361.913713884;646942;58191.709041454524;58561;58650.12024819631;58782.41217587855;58816.41183588195;1337.81270622113;1469.4636457692866;1492.79847220626;1507.6457032597332;1514.8409417011276;23866.164684236664;27098.72901270962;28426.147215583565;29079;29183.70816291895;44959.55040449554;45272;45813.77093114513;45851.31223031724;45891.77054114708;373074;373116;374985;649589;823283;25147.49705005926;25832.870835645703;25849;25851.741482584934;25889.482210355312;41859.953501162716;42007.94980125399;42211.577884220766;43833.56166438295;44021.89945251266;34516;34659.653403466655;35955.46066809052;36655.45016824803;36732.44901326429;30075.849620751756;30866.691333086383;36126;37580.060498486666;37657.2468550622;29217.85391073116;35732.46401304034;36861;36887.63112368949;36935.076623083565;32255.838720807184;33157.66842331546;33215.501767473026;33251;33284.33431331311;24467.510649787262;24502.87748561246;24798.628020580065;24968.500629986935;25007.624885627094 04:40:51 INFO - PROCESS | 7449 | |2;jquery.html;407.3035109961964;412.84518305635385;415.4308597221807;418.08230933101834;419.314420921793;19636.214551417925;20097.698534521995;20678.379648610546;20709.68935465969;20735;20051.997400130007;20285.492862678442;20447.693284600744;21347.6797848032;21392;5391.757370918299;5579.637323573975;5891.587588868781;6070.908936365936;6074.331823499411;6044.788432404866;6074.665893375863;6084.787032453865;6104.389561043905;6111.052786818051;5866.178734977107;5991.880162396749;6062.03007518797;6064.181335519715;6068.302145253286;5236.607254455931;6067.423594758491;6085.330613632541;6096.268447786249;6100.755969761204;5418.566514678856;5675.659460432387;5989.760409583611;5994.2806863176265;5994.940050599482;24671.01315947378;25066.624000640004;25249.010200056822;26205;26281.21156365312;2144.3031014920057;2366.372911178539;2508.9121880734087;2527.2165628654993;2572.8842202100764;9569.808603827885;9795.706128816148;9889.258305627107;10108.292419530635;10154.89845101547;1635.7628143919185;1726.2490816494865;1741.3905133203405;1742.686316463036;1802.8467580255563;111857;120047.79952200541;120715.3964230182;123513;124577.37711311477;9674.887387950475;10284.948575257076;11732.882671173349;11786.528538858522;11830.17188796776;15332.386704531693;15387.769183462256;15420.228988550527;15436.919415640798;15438.5368438947;8587.270082043096;8954.104589541124;8967.327450441113;9042.954785226031;9073;7462.552246865205;8997.910020899839;9005.594748236213;9006.369554131148;9064.682736104205;3633.6003039665616;4740.739259340769;4744.264638980963;4748.762561871893;4790;1973.6447439461035;2012.9597408051757;2016.294296996069;2023.676211806104;2025.868318559289;721.9566825990457;903.4443817052465;944.1078181118914;1000.2847963705985;1024.8975102489692;19565.021748912783;20040.198392064154;20690.482737931674;20760.772452071233;21175.576488470146;944.4652439785349;1022.3354819367322;1039.3815679670583;1066.2429674930731;1071.8231491803922;9512.714618561316;10718.946405267923;10826.783464330669;10899.346039237671;11011.724706882233;548717;617111;624541;629987;631276;101271;123990;124818;127661.3616931928;135424;970.6990832841842;983;994.3735446668552;1001.7946321004176;1002.3735165521695;17390.391336303168;17417;17534.974305097567;18034.458966230508;18213.089345532935;50904.23643645422;50918.745406272734;51090.744546277034;51594.96810063702;51694.74152629213;75263.24736752782;77710.6114469424;77863.61068194623;81264;81556.59221703853;6730.697118629576;8250.133735957637;8293.751187464386;8324.583770811556;8418.326533877153;9551.283653725859;10954.671359859218;12403.93798031004;12669.74660506803;12731.681707957376;9047.230985366317;9958.701238962843;11163.776724465628;11524.366159861289;11662.300261984308;10628.681139565826;11094.389808560607;11265.436728163395;11471.024962877915;11472.541098356307;10818.242723009314;10956.616518421817;11407.771844562896;11654.359010254517;11799.292042477478;6597.0764093026055;8373.204545568162;8411.074781774123;8557.35819813504;8782.648694052166;8287.709930152416;10522.053015228666;11201.431799547907;11525.423728813694;11562.537498500302 04:40:51 INFO - PROCESS | 7449 | |3;mootools.html;1368.445779459319;1399.6500874781304;1566.678830839678;1745.354218938992;1935.9516012099705;7814.41391895608;8501.06488286288;8673.826523469526;8676.913230867689;8682.262007729343;8445.873311900326;8760.824783504326;8763.737087887357;8765.693200737975;8767.298616110711;2134.583756167548;2178.58606864696;2198.967015494769;2423.9272821815375;2448.5837407640743;2202.9008694608783;2391;2456.8648724320155;2460.015993602555;2460.1512478194995;2363.9881800590974;2412.8793560322;2415.4685969086772;2416.516696660666;2418.37122348189;2125.936221913345;2433.111914151331;2440.511288114249;2448.2288079254977;2456.8894399751966;2403.591389463795;2472.8516289022537;2476.9380765481005;2478.0954951442595;2478.5042991401792;8522.232999030055;8593.785155371103;8599.785005374853;8600.698975535888;8613.741587752314;5215.921761173586;5330.946690533084;5343.332083489564;5364.034473794715;5375.22059301403;3423.2639982403653;3502.1594817243677;3565.2512972275854;3568.892933212008;3585.982070089659;1007.5113569918584;1027.4143738069304;1033.966033966034;1053.3837704942619;1070.1492313610697;11761.823572646417;11777.234479758874;11784.410779461079;11803.940980295043;11875.287482751062;4651.976740116278;4710.458297295786;4804.183288840835;4834.323194752737;4862.805487780449;9919.355241909252;9920.156786673213;9940.502974851228;9940.502974851228;9949.502524873727;5373.032854086302;5387.380451248078;5421.593380496479;5428.240046393508;5429.294191755047;4312.245357062501;4477.977610111994;4888;4937.654364194544;4961.032598643302;3959.3467077932396;4031.213913286881;4043.110515686544;4048.7975601219823;4075.2664520386616;1136.1876258475065;1315.8618345073821;1337.5118081900036;1338.297393868227;1356.2540602668494;794.1026639896879;857.8027053777666;862.5644049754834;899.8830152080187;934.0566028311318;5626.127950167731;5643.661380317194;5654.095344744821;5804.94195058044;5909.763609455574;990.346371394876;998.1066945084109;1006.9144122749502;1007.3049595778889;1030.896910308963;3634.509341238952;3693.90765230866;3702.407614781622;3767.1147280389087;3787.3561494545993;10244.48777561119;10546.841797373045;10596.417197054081;10635.840462393071;10684.412357320267;9048.321375896703;10198.592056317813;10430.95690430966;10579.994900484442;10703.25077244601;8275.172482751677;8317.792055198668;8342.833143337099;8434.451760635538;8445.451045682012;5266.262723218676;5313.9468605315;5329.73351332424;5420.024395608828;5448.073827449422;9451.905480945103;9575.186109180519;9582.28132890022;9584;9696.70909872705;9171.220446262143;9681.128698417177;9695.66065187715;9702.981186975267;9708.6116555337;6731.4951378647775;6905.723771049303;7314.780556583311;7406.185319614946;7450.776476705708;7363.77908662741;7668.539887606761;7910.644021018953;8130.430869839054;8148.796280093045;7133.108361454818;7329.267073292628;7376.889346659697;7410.5183163094225;7491.8501629966;6077.6353418795015;7133.785986420416;7215.891761623685;7248.710051597877;7281.963590182015;6155.384461553809;6204.914140025568;6394.1687580614225;6602.933970660232;6975.441964642918;4618.56123668251;4730.314104454766;4751.239801631722;4773.878138637348;4821.059893320768;4252.851150209728;4759.50025247344;4810.13417584838;4850.708957462563;4938.234573641092 04:40:51 INFO - PROCESS | 7449 | |4;prototype.html;270.7360323684408;333.1371747174817;334.7773730469238;335.64757005144605;342.0184071714178;5824.301083869942;5909.349971503131;6206.565540412173;6624.768133115342;6659.600423974565;6085;6552.836179095525;6570.671466426684;6572.507061970347;6587.143671322721;4206.810693518783;4700.694454860441;4738.099761045391;4802.039592081597;4816.229403295473;4764.237721964487;4821;4823.758812059383;4832.009438065188;4850.660453768238;3999.7200195986293;4725.763711814413;4763.666543341968;4778.354922085508;4789.353437285974;4552.931706024412;4630.467496237925;4738.336632871401;4741.217699079648;4773.737444440554;4106.609872062179;4107.774072426016;4112.403701463271;4116.938245926313;4119.670426365884;4906.460289368167;6238.189035425366;6238.563300568962;6239.875202495925;6249.0626406039;5583.469570390806;5598.44015598441;5604.971975140139;5620.803271885506;5630.324361076697;6867.828304292383;6868.587884726932;6909.792706218822;6910.032595436643;6917.51577389583;4271.359296105609;4448.599626033672;4450.310201918675;4456.97489577399;4476.955230447719;7088.858222835514;7260.745873894389;7267.345938865475;7272.527285726411;7276.0718409621495;4584.060267645192;4588.105319462739;4592.081583683277;4594.19601569724;4594.218982772936;3948.486696729407;4640.907181856344;4646.09401166776;4647.883802904887;4649.139909116757;5473.124300111963;5485.753141108659;5497.587680923947;5505.752241149158;5524.944750552443;6649.03588979605;6926.757563485254;6928.965355173192;6943.861122777516;6970.965145174343;1585.0014490870678;1599.2643384043238;1613.5643376288572;1615.9515214543583;1620.586750378646;957.9808403831885;963.7783309838776;1003.7440452684665;1006.5219020964983;1037.5175543372368;6304.999999999908;6475.255345635218;6510.544261901621;6537.673116344164;6543.476521878239;7237.601931893796;7251.601161936144;7254.492185546961;7258.346748792634;7294.598797066213;6470.967645161838;6471.223453185554;6474.514411419163;6484.319146489645;6515.4787616990525;3683.2633473305445;3771.73597848148;3819.1597848473293;3823.5602905665646;3833.1758671885677;6344.968275158594;7474.962625186839;7608.972788673458;7638.732644357421;7704.191059938737;6106.755729770759;7476.439267054992;7543.283388078124;7543.396528277724;7551.207123252089;7620.695172193161;7626.23737626244;7637.274458926393;7656.540607563564;7660.387169026464;6948.722051117898;7245.094363204599;7277.963610181915;7282.089738782653;7296.160941491797;5849.415058494116;7108.928910710827;7138.107736532934;7153.785386438415;7184.173820010766;7640.426967977535;7647.808804779925;7649.923500764921;7650.27322404393;7650.846983060419;4556.384888040205;4616.353710480468;4617.607503362252;4621.676482646317;4629.791659375269;5873.794417195378;7001.828426805626;7190.280971902768;7205.135383753774;7211.350978411759;6840.31596840312;6970.163580370388;6984.2317345093015;7008.859822803617;7048.964755176191;6898.896516552356;6963.895541566779;6969.128858892638;6969.337912898267;6974.860502790018;5827.76688932438;6817.693203805939;6821.488388370793;6835.453163746789;6859.07402500676;6663.13379260693;6800.081988931431;6969.686364113729;6970.442364610718;6993.965030174817;5327.413984461628;6665.200175978914;6724.025016372696;6749.5612785168805;6836 04:40:51 INFO - PROCESS | 7449 | |5;yui.html;680.1905732178707;712.3446429285059;740.7517736834527;747.6075060593191;754.0800223727055;14876.925615371922;14918.328675209614;15437.45968891089;15448.845511544881;15474.226288685577;13801.723965520683;14021.509247176351;14103.929480352585;14124.29378531072;14126.152430854156;752.4582300743456;763.5876626621646;909.3361845852531;934.5747684803398;938.1931538876561;926.9768255793591;927.2628260532892;934.607464864755;935.2751617496426;937.3672770879663;987.3582171588489;996.2727209137335;996.7159359582521;998.9550470228821;1004.467632154959;781.8631739445602;972.2319367699508;979.5151400056982;986.6349450703214;991.2070343724992;735.4961851131992;738;738.1732459645178;738.564247094217;739.3161325773692;14988.325525351276;14989.175595342253;15105.54683359501;15116.470923517623;15122.941394102418;12461.1900226485;12597.496100155982;12698.111132220749;12857.035722320863;12879.227246365248;10023.949880250624;10091.949540252324;10191.23565732573;10226.386416815016;10238.948805255926;3400.7959522428487;3407.148212946763;3552.3605750964816;3567.1795487038125;3567.946480802764;61751.69124154351;78396.60801696012;78707.6064619679;78768;78916.21083789204;9548.856767148498;9590.280728945358;9636.987932677108;9660.033996600283;9879.555420006118;21843.344699659036;21881.67177492339;21904.78095219059;21955.341339759834;21955.67066494004;7372.705091796375;7404.925950740531;7449.2923172299625;7573.772786816294;7689.692412303445;4014.337634290368;4118.320477121301;4122.989867482426;4197.160130459022;4214.4099826024385;3565.8395372208315;3627.4196128619296;3629.8003609801713;3631.1103779573646;3632.600413954463;636.933122022181;637.7162162837493;642.5758999060597;652.8726898254795;655.4919937048825;561.2507302750873;566.3402136510919;587.5417174603816;595.7259660556193;608.7595399817047;8026.638801253957;8247.793805154919;8252.422330436812;8275.366871606153;8441.95779021101;496.76155445386416;532.9680219186861;558.804418453538;597.357840321656;602.105872778923;3674.8346324415465;3793.5447746270625;3806.695464362844;3898.4737060497037;3998.660113890294;81921;90304.5484772585;90793;90850;90887.54556227177;76510;77036;77298.2270177291;78226.74186065102;78453;63699;64658.67670661711;64879.53885303156;65067.67466162639;65619.34380656228;7555.773326800205;7611.581363025087;7617.162112167768;7717.729879454192;7741.651625676971;46647.6005719829;49252.26121608107;49435.01129977308;49466.01067978692;50759;49427;63571.68214158899;64751.35248647642;65870.01194981983;67159;10161.390316581028;10361.533730982275;10428.843567346345;10488.632897848538;10542.683719488428;10519.474026298807;10763.46182690878;10943.28868623537;10996.505157267782;11493.712657183638;9858.260630452889;9974.102330790263;10060.748481287734;10136.280291392217;10151.289409741246;9703.07820757027;10208.948955255177;10420.426876521864;10438.37369757817;10601.946990265;9866.574958756384;10068.295219334575;10084.344517606625;10142.594296228068;10835.133189344991;8285.490149051055;8469.703560375357;8625.568721564023;8682.652693892174;8776.385653004483;8306.501609903426;8481.745547633582;8575.356848236532;8711.47731136134;8727.34544909147 04:40:51 INFO - PROCESS | 7449 | __end_tp_report 04:40:51 INFO - PROCESS | 7449 | __start_cc_report 04:40:51 INFO - PROCESS | 7449 | _x_x_mozilla_cycle_collect,1269 04:40:51 INFO - PROCESS | 7449 | __end_cc_report 04:40:51 INFO - PROCESS | 7449 | __startTimestamp1462534851736__endTimestamp 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | ------- Summary: start ------- 04:40:51 INFO - PROCESS | 7449 | Number of tests: 6 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#0] dojo.html Cycles:165 Average:5716.81 Median:5971.67 stddev:649.06 (10.9%) stddev-sans-first:623.19 04:40:51 INFO - PROCESS | 7449 | Values: 3318.7 3376.7 3383.5 3407.5 3444.9 5668.6 5707.0 5721.3 5723.2 5725.7 5806.0 5840.2 5840.9 5849.9 5855.9 5153.3 5192.8 5209.1 5242.7 5274.7 5243.3 5248.4 5253.5 5255.9 5259.8 5205.6 5217.3 5258.6 5258.8 5268.0 5270.9 5306.3 5309.1 5316.9 5332.7 5106.7 5122.3 5218.3 5218.5 5221.4 5571.0 5629.1 5636.1 5653.3 5661.5 6197.0 6197.8 6223.6 6228.2 6228.8 5850.9 6130.9 6170.4 6223.4 6233.8 5790.3 5807.9 5821.9 5877.5 5885.4 5780.7 6037.6 6265.6 6269.2 6278.3 6014.2 6107.2 6143.2 6146.4 6174.5 5164.6 5601.6 6213.8 6235.3 6241.3 5845.3 6157.2 6165.7 6168.3 6179.3 6112.8 6164.7 6180.5 6210.6 6214.8 4920.8 4941.2 4944.3 4958.9 4989.7 3779.8 4402.0 4438.7 4453.6 4491.7 5562.6 5659.4 5675.3 5691.7 5705.6 6183.0 6195.4 6216.8 6228.8 6242.3 4231.0 5669.9 5677.5 5697.2 5733.3 4538.8 5302.8 5380.5 5427.3 5428.7 6309.7 6330.9 6338.4 6339.1 6355.4 6207.6 6251.3 6253.6 6258.5 6275.9 6221.6 6284.3 6324.0 6332.7 6360.4 6163.7 6172.6 6180.7 6194.1 6198.7 6141.8 6277.7 6295.5 6297.5 6301.5 5971.7 6080.5 6107.1 6125.4 6131.8 6105.1 6107.5 6108.3 6127.0 6135.9 5925.9 5974.9 5982.9 5990.4 5995.9 5945.5 5994.0 6096.6 6108.9 6116.0 5977.6 5989.9 5990.9 6007.5 6014.0 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#1] ext.html Cycles:180 Average:66173.73 Median:36796.72 stddev:151809.50 (412.6%) stddev-sans-first:152155.23 04:40:51 INFO - PROCESS | 7449 | Values: 476.7 490.9 505.2 509.6 531.4 30278.4 30997.0 37407.7 38370.0 38454.4 40674.0 41089.8 41110.0 41111.4 41181.0 9372.4 9581.6 9593.4 9609.9 9677.5 9483.8 9573.4 9615.5 9630.6 9633.9 9576.7 9702.9 9704.0 9704.2 9714.3 9588.5 9650.9 9661.7 9669.1 9670.3 9468.0 9576.9 9585.9 9594.8 9613.7 38970.6 39423.2 39460.2 39490.4 39503.2 3379.3 3421.9 3429.5 3599.6 3749.8 27435.7 27494.0 27912.9 28249.7 28421.4 2646.3 2692.6 2716.0 2746.9 2759.2 486838.0 490177.0 490727.5 490861.0 491044.0 35073.5 35661.6 57451.1 58571.8 58595.7 27878.0 34927.0 34948.0 35098.1 35140.3 15076.4 15274.0 15278.9 15283.8 15301.9 10956.7 12886.8 12956.4 12967.0 13054.0 7273.2 7623.3 7888.4 8048.4 8119.9 2996.6 3262.8 3399.4 3399.9 3428.5 994.9 1017.4 1075.7 1122.2 1126.9 29018.0 29116.6 29608.0 29699.6 29830.0 714.9 1035.7 1096.5 1104.7 1114.8 13875.2 14150.6 14310.9 14482.7 14533.8 632602.0 645032.0 645205.0 646361.9 646942.0 58191.7 58561.0 58650.1 58782.4 58816.4 1337.8 1469.5 1492.8 1507.6 1514.8 23866.2 27098.7 28426.1 29079.0 29183.7 44959.6 45272.0 45813.8 45851.3 45891.8 373074.0 373116.0 374985.0 649589.0 823283.0 25147.5 25832.9 25849.0 25851.7 25889.5 41860.0 42007.9 42211.6 43833.6 44021.9 34516.0 34659.7 35955.5 36655.5 36732.4 30075.8 30866.7 36126.0 37580.1 37657.2 29217.9 35732.5 36861.0 36887.6 36935.1 32255.8 33157.7 33215.5 33251.0 33284.3 24467.5 24502.9 24798.6 24968.5 25007.6 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#2] jquery.html Cycles:180 Average:35213.76 Median:2550.05 stddev:101827.39 (3993.2%) stddev-sans-first:102079.50 04:40:51 INFO - PROCESS | 7449 | Values: 407.3 412.8 415.4 418.1 419.3 19636.2 20097.7 20678.4 20709.7 20735.0 20052.0 20285.5 20447.7 21347.7 21392.0 5391.8 5579.6 5891.6 6070.9 6074.3 6044.8 6074.7 6084.8 6104.4 6111.1 5866.2 5991.9 6062.0 6064.2 6068.3 5236.6 6067.4 6085.3 6096.3 6100.8 5418.6 5675.7 5989.8 5994.3 5994.9 24671.0 25066.6 25249.0 26205.0 26281.2 2144.3 2366.4 2508.9 2527.2 2572.9 9569.8 9795.7 9889.3 10108.3 10154.9 1635.8 1726.2 1741.4 1742.7 1802.8 111857.0 120047.8 120715.4 123513.0 124577.4 9674.9 10284.9 11732.9 11786.5 11830.2 15332.4 15387.8 15420.2 15436.9 15438.5 8587.3 8954.1 8967.3 9043.0 9073.0 7462.6 8997.9 9005.6 9006.4 9064.7 3633.6 4740.7 4744.3 4748.8 4790.0 1973.6 2013.0 2016.3 2023.7 2025.9 722.0 903.4 944.1 1000.3 1024.9 19565.0 20040.2 20690.5 20760.8 21175.6 944.5 1022.3 1039.4 1066.2 1071.8 9512.7 10718.9 10826.8 10899.3 11011.7 548717.0 617111.0 624541.0 629987.0 631276.0 101271.0 123990.0 124818.0 127661.4 135424.0 970.7 983.0 994.4 1001.8 1002.4 17390.4 17417.0 17535.0 18034.5 18213.1 50904.2 50918.7 51090.7 51595.0 51694.7 75263.2 77710.6 77863.6 81264.0 81556.6 6730.7 8250.1 8293.8 8324.6 8418.3 9551.3 10954.7 12403.9 12669.7 12731.7 9047.2 9958.7 11163.8 11524.4 11662.3 10628.7 11094.4 11265.4 11471.0 11472.5 10818.2 10956.6 11407.8 11654.4 11799.3 6597.1 8373.2 8411.1 8557.4 8782.6 8287.7 10522.1 11201.4 11525.4 11562.5 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#3] mootools.html Cycles:180 Average:5593.04 Median:4949.63 stddev:3115.15 (62.9%) stddev-sans-first:3107.71 04:40:51 INFO - PROCESS | 7449 | Values: 1368.4 1399.7 1566.7 1745.4 1936.0 7814.4 8501.1 8673.8 8676.9 8682.3 8445.9 8760.8 8763.7 8765.7 8767.3 2134.6 2178.6 2199.0 2423.9 2448.6 2202.9 2391.0 2456.9 2460.0 2460.2 2364.0 2412.9 2415.5 2416.5 2418.4 2125.9 2433.1 2440.5 2448.2 2456.9 2403.6 2472.9 2476.9 2478.1 2478.5 8522.2 8593.8 8599.8 8600.7 8613.7 5215.9 5330.9 5343.3 5364.0 5375.2 3423.3 3502.2 3565.3 3568.9 3586.0 1007.5 1027.4 1034.0 1053.4 1070.1 11761.8 11777.2 11784.4 11803.9 11875.3 4652.0 4710.5 4804.2 4834.3 4862.8 9919.4 9920.2 9940.5 9940.5 9949.5 5373.0 5387.4 5421.6 5428.2 5429.3 4312.2 4478.0 4888.0 4937.7 4961.0 3959.3 4031.2 4043.1 4048.8 4075.3 1136.2 1315.9 1337.5 1338.3 1356.3 794.1 857.8 862.6 899.9 934.1 5626.1 5643.7 5654.1 5804.9 5909.8 990.3 998.1 1006.9 1007.3 1030.9 3634.5 3693.9 3702.4 3767.1 3787.4 10244.5 10546.8 10596.4 10635.8 10684.4 9048.3 10198.6 10431.0 10580.0 10703.3 8275.2 8317.8 8342.8 8434.5 8445.5 5266.3 5313.9 5329.7 5420.0 5448.1 9451.9 9575.2 9582.3 9584.0 9696.7 9171.2 9681.1 9695.7 9703.0 9708.6 6731.5 6905.7 7314.8 7406.2 7450.8 7363.8 7668.5 7910.6 8130.4 8148.8 7133.1 7329.3 7376.9 7410.5 7491.9 6077.6 7133.8 7215.9 7248.7 7282.0 6155.4 6204.9 6394.2 6602.9 6975.4 4618.6 4730.3 4751.2 4773.9 4821.1 4252.9 4759.5 4810.1 4850.7 4938.2 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#4] prototype.html Cycles:180 Average:5653.44 Median:6472.87 stddev:1827.16 (28.2%) stddev-sans-first:1787.06 04:40:51 INFO - PROCESS | 7449 | Values: 270.7 333.1 334.8 335.6 342.0 5824.3 5909.3 6206.6 6624.8 6659.6 6085.0 6552.8 6570.7 6572.5 6587.1 4206.8 4700.7 4738.1 4802.0 4816.2 4764.2 4821.0 4823.8 4832.0 4850.7 3999.7 4725.8 4763.7 4778.4 4789.4 4552.9 4630.5 4738.3 4741.2 4773.7 4106.6 4107.8 4112.4 4116.9 4119.7 4906.5 6238.2 6238.6 6239.9 6249.1 5583.5 5598.4 5605.0 5620.8 5630.3 6867.8 6868.6 6909.8 6910.0 6917.5 4271.4 4448.6 4450.3 4457.0 4477.0 7088.9 7260.7 7267.3 7272.5 7276.1 4584.1 4588.1 4592.1 4594.2 4594.2 3948.5 4640.9 4646.1 4647.9 4649.1 5473.1 5485.8 5497.6 5505.8 5524.9 6649.0 6926.8 6929.0 6943.9 6971.0 1585.0 1599.3 1613.6 1616.0 1620.6 958.0 963.8 1003.7 1006.5 1037.5 6305.0 6475.3 6510.5 6537.7 6543.5 7237.6 7251.6 7254.5 7258.3 7294.6 6471.0 6471.2 6474.5 6484.3 6515.5 3683.3 3771.7 3819.2 3823.6 3833.2 6345.0 7475.0 7609.0 7638.7 7704.2 6106.8 7476.4 7543.3 7543.4 7551.2 7620.7 7626.2 7637.3 7656.5 7660.4 6948.7 7245.1 7278.0 7282.1 7296.2 5849.4 7108.9 7138.1 7153.8 7184.2 7640.4 7647.8 7649.9 7650.3 7650.8 4556.4 4616.4 4617.6 4621.7 4629.8 5873.8 7001.8 7190.3 7205.1 7211.4 6840.3 6970.2 6984.2 7008.9 7049.0 6898.9 6963.9 6969.1 6969.3 6974.9 5827.8 6817.7 6821.5 6835.5 6859.1 6663.1 6800.1 6969.7 6970.4 6994.0 5327.4 6665.2 6724.0 6749.6 6836.0 04:40:51 INFO - PROCESS | 7449 | 04:40:51 INFO - PROCESS | 7449 | [#5] yui.html Cycles:180 Average:17528.14 Median:32167.97 stddev:24523.49 (76.2%) stddev-sans-first:24559.65 04:40:51 INFO - PROCESS | 7449 | Values: 680.2 712.3 740.8 747.6 754.1 14876.9 14918.3 15437.5 15448.8 15474.2 13801.7 14021.5 14103.9 14124.3 14126.2 752.5 763.6 909.3 934.6 938.2 927.0 927.3 934.6 935.3 937.4 987.4 996.3 996.7 999.0 1004.5 781.9 972.2 979.5 986.6 991.2 735.5 738.0 738.2 738.6 739.3 14988.3 14989.2 15105.5 15116.5 15122.9 12461.2 12597.5 12698.1 12857.0 12879.2 10023.9 10091.9 10191.2 10226.4 10238.9 3400.8 3407.1 3552.4 3567.2 3567.9 61751.7 78396.6 78707.6 78768.0 78916.2 9548.9 9590.3 9637.0 9660.0 9879.6 21843.3 21881.7 21904.8 21955.3 21955.7 7372.7 7404.9 7449.3 7573.8 7689.7 4014.3 4118.3 4123.0 4197.2 4214.4 3565.8 3627.4 3629.8 3631.1 3632.6 636.9 637.7 642.6 652.9 655.5 561.3 566.3 587.5 595.7 608.8 8026.6 8247.8 8252.4 8275.4 8442.0 496.8 533.0 558.8 597.4 602.1 3674.8 3793.5 3806.7 3898.5 3998.7 81921.0 90304.5 90793.0 90850.0 90887.5 76510.0 77036.0 77298.2 78226.7 78453.0 63699.0 64658.7 64879.5 65067.7 65619.3 7555.8 7611.6 7617.2 7717.7 7741.7 46647.6 49252.3 49435.0 49466.0 50759.0 49427.0 63571.7 64751.4 65870.0 67159.0 10161.4 10361.5 10428.8 10488.6 10542.7 10519.5 10763.5 10943.3 10996.5 11493.7 9858.3 9974.1 10060.7 10136.3 10151.3 9703.1 10208.9 10420.4 10438.4 10601.9 9866.6 10068.3 10084.3 10142.6 10835.1 8285.5 8469.7 8625.6 8682.7 8776.4 8306.5 8481.7 8575.4 8711.5 8727.3 04:40:51 INFO - PROCESS | 7449 | -------- Summary: end -------- 04:40:51 INFO - PROCESS | 7449 | 04:40:52 INFO - TEST-INFO | 7449: exit 0 04:40:52 INFO - TEST-OK | dromaeo_css | took 1114992ms 04:40:52 INFO - TEST-START | kraken 04:40:52 INFO - operating with platform_type : mac_ 04:40:52 INFO - Initialising browser for kraken test... 04:40:52 INFO - TEST-INFO | started process 7454 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpSWK3xE/profile http://localhost:64064/getInfo.html) 04:40:54 INFO - PROCESS | 7454 | __metrics Screen width/height:1600/1200 04:40:54 INFO - PROCESS | 7454 | colorDepth:24 04:40:54 INFO - PROCESS | 7454 | Browser inner width/height: 1024/689 04:40:54 INFO - PROCESS | 7454 | __metrics 04:40:54 INFO - PROCESS | 7454 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:40:54 ERROR - PROCESS | 7454 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:40:55 INFO - TEST-INFO | 7454: exit 0 04:40:55 INFO - Browser initialized. 04:40:55 INFO - Running cycle 1/1 for kraken test... 04:40:55 INFO - TEST-INFO | started process 7456 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpSWK3xE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:07 INFO - PROCESS | 7456 | RSS: Main: 210771968 04:41:07 INFO - PROCESS | 7456 | 04:41:09 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64064/tests/kraken/driver.html?testName=audio-beat-detection) 04:41:10 INFO - PROCESS | 7456 | RSS: Main: 271777792 04:41:10 INFO - PROCESS | 7456 | 04:41:13 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64064/tests/kraken/driver.html?testName=audio-dft) 04:41:14 INFO - PROCESS | 7456 | RSS: Main: 286388224 04:41:14 INFO - PROCESS | 7456 | 04:41:17 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64064/tests/kraken/driver.html?testName=audio-fft) 04:41:17 INFO - PROCESS | 7456 | RSS: Main: 373800960 04:41:17 INFO - PROCESS | 7456 | 04:41:20 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64064/tests/kraken/driver.html?testName=audio-oscillator) 04:41:20 INFO - PROCESS | 7456 | RSS: Main: 279703552 04:41:20 INFO - PROCESS | 7456 | 04:41:22 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64064/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:41:23 INFO - PROCESS | 7456 | RSS: Main: 280162304 04:41:23 INFO - PROCESS | 7456 | 04:41:26 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64064/tests/kraken/driver.html?testName=imaging-darkroom) 04:41:26 INFO - PROCESS | 7456 | RSS: Main: 319483904 04:41:26 INFO - PROCESS | 7456 | 04:41:29 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64064/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:30 INFO - PROCESS | 7456 | RSS: Main: 318754816 04:41:30 INFO - PROCESS | 7456 | 04:41:33 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64064/tests/kraken/driver.html?testName=json-parse-financial) 04:41:33 INFO - PROCESS | 7456 | RSS: Main: 317730816 04:41:33 INFO - PROCESS | 7456 | 04:41:35 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64064/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:35 INFO - PROCESS | 7456 | RSS: Main: 309362688 04:41:35 INFO - PROCESS | 7456 | 04:41:37 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:38 INFO - PROCESS | 7456 | RSS: Main: 315981824 04:41:38 INFO - PROCESS | 7456 | 04:41:40 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:41 INFO - PROCESS | 7456 | RSS: Main: 319131648 04:41:41 INFO - PROCESS | 7456 | 04:41:44 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:44 INFO - PROCESS | 7456 | RSS: Main: 320942080 04:41:44 INFO - PROCESS | 7456 | 04:41:47 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:48 INFO - PROCESS | 7456 | RSS: Main: 318869504 04:41:48 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | Cycle 1(1): loaded http://localhost:64064/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64064/tests/kraken/driver.html?testName=ai-astar) 04:41:50 INFO - PROCESS | 7456 | RSS: Main: 383139840 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | __start_tp_report 04:41:50 INFO - PROCESS | 7456 | _x_x_mozilla_page_load 04:41:50 INFO - PROCESS | 7456 | _x_x_mozilla_page_load_details 04:41:50 INFO - PROCESS | 7456 | |i|pagename|runs| 04:41:50 INFO - PROCESS | 7456 | |0;ai-astar;113;114;120;112;114;112;112;112;115;134 04:41:50 INFO - PROCESS | 7456 | |1;audio-beat-detection;159;122;158;156;142;140;119;155;157;120 04:41:50 INFO - PROCESS | 7456 | |2;audio-dft;182;163;261;267;186;183;186;189;188;185 04:41:50 INFO - PROCESS | 7456 | |3;audio-fft;76;94;106;194;56;55;56;64;142;73 04:41:50 INFO - PROCESS | 7456 | |4;audio-oscillator;91;93;151;78;81;81;127;113;118;170 04:41:50 INFO - PROCESS | 7456 | |5;imaging-gaussian-blur;87;85;86;84;86;84;86;84;86;83 04:41:50 INFO - PROCESS | 7456 | |6;imaging-darkroom;96;91;92;92;92;92;93;93;92;93 04:41:50 INFO - PROCESS | 7456 | |7;imaging-desaturate;83;86;85;84;83;84;84;86;85;85 04:41:50 INFO - PROCESS | 7456 | |8;json-parse-financial;59;63;63;63;65;64;64;81;56;58 04:41:50 INFO - PROCESS | 7456 | |9;json-stringify-tinderbox;64;64;76;77;74;73;70;71;72;72 04:41:50 INFO - PROCESS | 7456 | |10;stanford-crypto-aes;81;80;81;81;80;70;76;85;103;111 04:41:50 INFO - PROCESS | 7456 | |11;stanford-crypto-ccm;147;150;152;129;127;128;133;139;164;187 04:41:50 INFO - PROCESS | 7456 | |12;stanford-crypto-pbkdf2;163;168;165;177;168;185;216;167;163;165 04:41:50 INFO - PROCESS | 7456 | |13;stanford-crypto-sha256-iterative;68;67;70;68;70;68;70;70;90;73 04:41:50 INFO - PROCESS | 7456 | __end_tp_report 04:41:50 INFO - PROCESS | 7456 | __start_cc_report 04:41:50 INFO - PROCESS | 7456 | _x_x_mozilla_cycle_collect,2009 04:41:50 INFO - PROCESS | 7456 | __end_cc_report 04:41:50 INFO - PROCESS | 7456 | __startTimestamp1462534910400__endTimestamp 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | ------- Summary: start ------- 04:41:50 INFO - PROCESS | 7456 | Number of tests: 14 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:6.84 (6.0%) stddev-sans-first:7.18 04:41:50 INFO - PROCESS | 7456 | Values: 113.0 114.0 120.0 112.0 114.0 112.0 112.0 112.0 115.0 134.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:155.50 stddev:16.82 (10.8%) stddev-sans-first:16.79 04:41:50 INFO - PROCESS | 7456 | Values: 159.0 122.0 158.0 156.0 142.0 140.0 119.0 155.0 157.0 120.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#2] audio-dft Cycles:10 Average:199.00 Median:187.00 stddev:35.06 (18.7%) stddev-sans-first:36.65 04:41:50 INFO - PROCESS | 7456 | Values: 182.0 163.0 261.0 267.0 186.0 183.0 186.0 189.0 188.0 185.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#3] audio-fft Cycles:10 Average:91.60 Median:60.00 stddev:45.29 (75.5%) stddev-sans-first:47.69 04:41:50 INFO - PROCESS | 7456 | Values: 76.0 94.0 106.0 194.0 56.0 55.0 56.0 64.0 142.0 73.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:79.50 stddev:31.62 (39.8%) stddev-sans-first:32.76 04:41:50 INFO - PROCESS | 7456 | Values: 91.0 93.0 151.0 78.0 81.0 81.0 127.0 113.0 118.0 170.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.29 (1.5%) stddev-sans-first:1.17 04:41:50 INFO - PROCESS | 7456 | Values: 87.0 85.0 86.0 84.0 86.0 84.0 86.0 84.0 86.0 83.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:92.50 stddev:1.35 (1.5%) stddev-sans-first:0.67 04:41:50 INFO - PROCESS | 7456 | Values: 96.0 91.0 92.0 92.0 92.0 92.0 93.0 93.0 92.0 93.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.08 (1.3%) stddev-sans-first:1.00 04:41:50 INFO - PROCESS | 7456 | Values: 83.0 86.0 85.0 84.0 83.0 84.0 84.0 86.0 85.0 85.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.50 stddev:6.80 (10.7%) stddev-sans-first:7.01 04:41:50 INFO - PROCESS | 7456 | Values: 59.0 63.0 63.0 63.0 65.0 64.0 64.0 81.0 56.0 58.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:4.40 (6.1%) stddev-sans-first:3.79 04:41:50 INFO - PROCESS | 7456 | Values: 64.0 64.0 76.0 77.0 74.0 73.0 70.0 71.0 72.0 72.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:80.50 stddev:12.49 (15.5%) stddev-sans-first:13.17 04:41:50 INFO - PROCESS | 7456 | Values: 81.0 80.0 81.0 81.0 80.0 70.0 76.0 85.0 103.0 111.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:148.50 stddev:19.00 (12.8%) stddev-sans-first:20.14 04:41:50 INFO - PROCESS | 7456 | Values: 147.0 150.0 152.0 129.0 127.0 128.0 133.0 139.0 164.0 187.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:168.00 stddev:16.39 (9.8%) stddev-sans-first:16.92 04:41:50 INFO - PROCESS | 7456 | Values: 163.0 168.0 165.0 177.0 168.0 185.0 216.0 167.0 163.0 165.0 04:41:50 INFO - PROCESS | 7456 | 04:41:50 INFO - PROCESS | 7456 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:70.00 stddev:6.75 (9.6%) stddev-sans-first:7.05 04:41:50 INFO - PROCESS | 7456 | Values: 68.0 67.0 70.0 68.0 70.0 68.0 70.0 70.0 90.0 73.0 04:41:50 INFO - PROCESS | 7456 | -------- Summary: end -------- 04:41:50 INFO - PROCESS | 7456 | 04:41:51 INFO - TEST-INFO | 7456: exit 0 04:41:51 INFO - TEST-OK | kraken | took 58874ms 04:41:51 INFO - SUITE-END | took 1173s 04:41:51 INFO - Completed test suite (00:19:34) 04:41:51 INFO - javascript benchmark 04:41:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.651541588133, 3376.7298616110697, 3383.4924761285806, 3407.5229467874487, 3444.9138771530706, 5668.603197776152, 5707.0, 5721.313442386914, 5723.227364305818, 5725.685087320196, 5806.042003069499, 5840.211571437854, 5840.853978650536, 5849.883002339961, 5855.853603659911, 5153.252778347138, 5192.844214673556, 5209.114450543398, 5242.737863106849, 5274.709890955995, 5243.2659427680155, 5248.422673505911, 5253.500917412859, 5255.894882102356, 5259.842204733845, 5205.60957928155, 5217.295665085203, 5258.6318957672975, 5258.815941442051, 5267.973660131694, 5270.920936185979, 5306.310179676656, 5309.083681176719, 5316.946830531722, 5332.733363331818, 5106.66806657566, 5122.257272695476, 5218.269442278084, 5218.478152184789, 5221.425643179247, 5570.972145139289, 5629.127485239795, 5636.070048442002, 5653.293338332708, 5661.5187709044785, 6197.0, 6197.845053873644, 6223.626582405071, 6228.159198508189, 6228.750849965996, 5850.853728656818, 6130.917941863022, 6170.389491537249, 6223.3776622337855, 6233.750649973996, 5790.305163380379, 5807.8548036299435, 5821.88356232873, 5877.500412464946, 5885.352611212763, 5780.653160810365, 6037.641530655567, 6265.592736472115, 6269.185005949289, 6278.30938596754, 6014.1580178775, 6107.188815080191, 6143.170671959318, 6146.416090471398, 6174.5060395168275, 5164.612654050886, 5601.551875849922, 6213.782517611862, 6235.3452887446165, 6241.282252540925, 5845.298564172326, 6157.168782214433, 6165.722542485599, 6168.321484636686, 6179.258488981351, 6112.786052488142, 6164.6917654118015, 6180.47465965389, 6210.627362358273, 6214.782482613177, 4920.827771027997, 4941.160002799533, 4944.307796908436, 4958.925616115761, 4989.650724449326, 3779.7732136071922, 4402.0, 4438.733675979387, 4453.554644535585, 4491.685582009228, 5562.638428502134, 5659.405762394971, 5675.31896172462, 5691.658500489983, 5705.62913410627, 6183.041628547582, 6195.411435913581, 6216.844578885473, 6228.750849966042, 6242.250929888442, 4230.957690423056, 5669.886602267931, 5677.51741102011, 5697.202391665171, 5733.312002559636, 4538.818447262072, 5302.814401495929, 5380.515753582196, 5427.348718153847, 5428.70142142186, 6309.716062777186, 6330.936690633035, 6338.397852204033, 6339.080833279057, 6355.428011479082, 6207.565470417208, 6251.343608920998, 6253.593516421418, 6258.530610204162, 6275.937240627535, 6221.595596286227, 6284.340144284967, 6324.019776934582, 6332.683365831598, 6360.363963603602, 6163.691815409122, 6172.5679202457195, 6180.66006369636, 6194.132821405097, 6198.659073750807, 6141.785037523844, 6277.654728989949, 6295.496360291258, 6297.527685423704, 6301.464375528131, 5971.671558064349, 6080.4831589313635, 6107.144999700135, 6125.418085281891, 6131.774283661862, 6105.084237364431, 6107.450329470369, 6108.267007959073, 6127.0, 6135.9079613806725, 5925.881482370242, 5974.880502389841, 5982.880342393215, 5990.430909063632, 5995.91006134917, 5945.464908158287, 5993.9700301498215, 6096.5732398733435, 6108.8778224436155, 6115.990861507979, 5977.641341519523, 5989.940100599112, 5990.910136348046, 6007.519398448027, 6013.9699301503215], "value": 5675.8794618123775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.6853876441549, 490.8674657842383, 505.2118694836056, 509.6025100421668, 531.3597115475852, 30278.39443211137, 30997.0, 37407.690730824426, 38370.0, 38454.42318365227, 40674.0, 41089.794551027204, 41110.0, 41111.383329250086, 41180.97047573812, 9372.437653740762, 9581.568829402659, 9593.376430532027, 9609.855852162224, 9677.467739274338, 9483.81032379352, 9573.42559446432, 9615.471149086798, 9630.566624501878, 9633.855492167622, 9576.712698619052, 9702.902970970306, 9703.95148024259, 9704.223662107015, 9714.27142964277, 9588.472634005127, 9650.90349096514, 9661.661841835503, 9669.129778319915, 9670.274729395253, 9468.0, 9576.856347154799, 9585.90414095864, 9594.808103837953, 9613.663521776703, 38970.610293896985, 39423.21153576941, 39460.210795783925, 39490.407643885366, 39503.20993580141, 3379.3410284994434, 3421.9486707699402, 3429.537012503305, 3599.6040435552068, 3749.8125093745202, 27435.725642743517, 27494.037708680302, 27912.860435697894, 28249.71750282512, 28421.431571368663, 2646.325187077283, 2692.5961105834085, 2716.0358072884037, 2746.917592472229, 2759.1722483254944, 486838.0, 490177.0, 490727.5463622695, 490861.0, 491044.0, 35073.473897891556, 35661.64338356583, 57451.13823292654, 58571.82856342849, 58595.70702146462, 27878.0, 34927.0, 34947.9515614532, 35098.12254693602, 35140.29719405597, 15076.396944122112, 15274.007189532645, 15278.923605382124, 15283.770743438858, 15301.9234903827, 10956.671299861016, 12886.80669789954, 12956.35218239103, 12967.0, 13054.0, 7273.199948005715, 7623.313901748869, 7888.40836937232, 8048.436609437284, 8119.918800811916, 2996.625421822272, 3262.8368581570826, 3399.354122716725, 3399.9490007649906, 3428.502867084259, 994.9005099490138, 1017.4302390661253, 1075.720312718683, 1122.1527746551465, 1126.864776226858, 29017.98437054693, 29116.57109204633, 29608.0, 29699.554506682416, 29829.955951541593, 714.8570285942832, 1035.694470131298, 1096.5339730614457, 1104.6575561575937, 1114.7547539541288, 13875.167489950434, 14150.575482735534, 14310.928445357707, 14482.710345793024, 14533.78199327011, 632602.0, 645032.0, 645205.0, 646361.913713884, 646942.0, 58191.709041454524, 58561.0, 58650.12024819631, 58782.41217587855, 58816.41183588195, 1337.81270622113, 1469.4636457692866, 1492.79847220626, 1507.6457032597332, 1514.8409417011276, 23866.164684236664, 27098.72901270962, 28426.147215583565, 29079.0, 29183.70816291895, 44959.55040449554, 45272.0, 45813.77093114513, 45851.31223031724, 45891.77054114708, 373074.0, 373116.0, 374985.0, 649589.0, 823283.0, 25147.49705005926, 25832.870835645703, 25849.0, 25851.741482584934, 25889.482210355312, 41859.953501162716, 42007.94980125399, 42211.577884220766, 43833.56166438295, 44021.89945251266, 34516.0, 34659.653403466655, 35955.46066809052, 36655.45016824803, 36732.44901326429, 30075.849620751756, 30866.691333086383, 36126.0, 37580.060498486666, 37657.2468550622, 29217.85391073116, 35732.46401304034, 36861.0, 36887.63112368949, 36935.076623083565, 32255.838720807184, 33157.66842331546, 33215.501767473026, 33251.0, 33284.33431331311, 24467.510649787262, 24502.87748561246, 24798.628020580065, 24968.500629986935, 25007.624885627094], "value": 18009.043849741705, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.3035109961964, 412.84518305635385, 415.4308597221807, 418.08230933101834, 419.314420921793, 19636.214551417925, 20097.698534521995, 20678.379648610546, 20709.68935465969, 20735.0, 20051.997400130007, 20285.492862678442, 20447.693284600744, 21347.6797848032, 21392.0, 5391.757370918299, 5579.637323573975, 5891.587588868781, 6070.908936365936, 6074.331823499411, 6044.788432404866, 6074.665893375863, 6084.787032453865, 6104.389561043905, 6111.052786818051, 5866.178734977107, 5991.880162396749, 6062.03007518797, 6064.181335519715, 6068.302145253286, 5236.607254455931, 6067.423594758491, 6085.330613632541, 6096.268447786249, 6100.755969761204, 5418.566514678856, 5675.659460432387, 5989.760409583611, 5994.2806863176265, 5994.940050599482, 24671.01315947378, 25066.624000640004, 25249.010200056822, 26205.0, 26281.21156365312, 2144.3031014920057, 2366.372911178539, 2508.9121880734087, 2527.2165628654993, 2572.8842202100764, 9569.808603827885, 9795.706128816148, 9889.258305627107, 10108.292419530635, 10154.89845101547, 1635.7628143919185, 1726.2490816494865, 1741.3905133203405, 1742.686316463036, 1802.8467580255563, 111857.0, 120047.79952200541, 120715.3964230182, 123513.0, 124577.37711311477, 9674.887387950475, 10284.948575257076, 11732.882671173349, 11786.528538858522, 11830.17188796776, 15332.386704531693, 15387.769183462256, 15420.228988550527, 15436.919415640798, 15438.5368438947, 8587.270082043096, 8954.104589541124, 8967.327450441113, 9042.954785226031, 9073.0, 7462.552246865205, 8997.910020899839, 9005.594748236213, 9006.369554131148, 9064.682736104205, 3633.6003039665616, 4740.739259340769, 4744.264638980963, 4748.762561871893, 4790.0, 1973.6447439461035, 2012.9597408051757, 2016.294296996069, 2023.676211806104, 2025.868318559289, 721.9566825990457, 903.4443817052465, 944.1078181118914, 1000.2847963705985, 1024.8975102489692, 19565.021748912783, 20040.198392064154, 20690.482737931674, 20760.772452071233, 21175.576488470146, 944.4652439785349, 1022.3354819367322, 1039.3815679670583, 1066.2429674930731, 1071.8231491803922, 9512.714618561316, 10718.946405267923, 10826.783464330669, 10899.346039237671, 11011.724706882233, 548717.0, 617111.0, 624541.0, 629987.0, 631276.0, 101271.0, 123990.0, 124818.0, 127661.3616931928, 135424.0, 970.6990832841842, 983.0, 994.3735446668552, 1001.7946321004176, 1002.3735165521695, 17390.391336303168, 17417.0, 17534.974305097567, 18034.458966230508, 18213.089345532935, 50904.23643645422, 50918.745406272734, 51090.744546277034, 51594.96810063702, 51694.74152629213, 75263.24736752782, 77710.6114469424, 77863.61068194623, 81264.0, 81556.59221703853, 6730.697118629576, 8250.133735957637, 8293.751187464386, 8324.583770811556, 8418.326533877153, 9551.283653725859, 10954.671359859218, 12403.93798031004, 12669.74660506803, 12731.681707957376, 9047.230985366317, 9958.701238962843, 11163.776724465628, 11524.366159861289, 11662.300261984308, 10628.681139565826, 11094.389808560607, 11265.436728163395, 11471.024962877915, 11472.541098356307, 10818.242723009314, 10956.616518421817, 11407.771844562896, 11654.359010254517, 11799.292042477478, 6597.0764093026055, 8373.204545568162, 8411.074781774123, 8557.35819813504, 8782.648694052166, 8287.709930152416, 10522.053015228666, 11201.431799547907, 11525.423728813694, 11562.537498500302], "value": 9603.849528571012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.445779459319, 1399.6500874781304, 1566.678830839678, 1745.354218938992, 1935.9516012099705, 7814.41391895608, 8501.06488286288, 8673.826523469526, 8676.913230867689, 8682.262007729343, 8445.873311900326, 8760.824783504326, 8763.737087887357, 8765.693200737975, 8767.298616110711, 2134.583756167548, 2178.58606864696, 2198.967015494769, 2423.9272821815375, 2448.5837407640743, 2202.9008694608783, 2391.0, 2456.8648724320155, 2460.015993602555, 2460.1512478194995, 2363.9881800590974, 2412.8793560322, 2415.4685969086772, 2416.516696660666, 2418.37122348189, 2125.936221913345, 2433.111914151331, 2440.511288114249, 2448.2288079254977, 2456.8894399751966, 2403.591389463795, 2472.8516289022537, 2476.9380765481005, 2478.0954951442595, 2478.5042991401792, 8522.232999030055, 8593.785155371103, 8599.785005374853, 8600.698975535888, 8613.741587752314, 5215.921761173586, 5330.946690533084, 5343.332083489564, 5364.034473794715, 5375.22059301403, 3423.2639982403653, 3502.1594817243677, 3565.2512972275854, 3568.892933212008, 3585.982070089659, 1007.5113569918584, 1027.4143738069304, 1033.966033966034, 1053.3837704942619, 1070.1492313610697, 11761.823572646417, 11777.234479758874, 11784.410779461079, 11803.940980295043, 11875.287482751062, 4651.976740116278, 4710.458297295786, 4804.183288840835, 4834.323194752737, 4862.805487780449, 9919.355241909252, 9920.156786673213, 9940.502974851228, 9940.502974851228, 9949.502524873727, 5373.032854086302, 5387.380451248078, 5421.593380496479, 5428.240046393508, 5429.294191755047, 4312.245357062501, 4477.977610111994, 4888.0, 4937.654364194544, 4961.032598643302, 3959.3467077932396, 4031.213913286881, 4043.110515686544, 4048.7975601219823, 4075.2664520386616, 1136.1876258475065, 1315.8618345073821, 1337.5118081900036, 1338.297393868227, 1356.2540602668494, 794.1026639896879, 857.8027053777666, 862.5644049754834, 899.8830152080187, 934.0566028311318, 5626.127950167731, 5643.661380317194, 5654.095344744821, 5804.94195058044, 5909.763609455574, 990.346371394876, 998.1066945084109, 1006.9144122749502, 1007.3049595778889, 1030.896910308963, 3634.509341238952, 3693.90765230866, 3702.407614781622, 3767.1147280389087, 3787.3561494545993, 10244.48777561119, 10546.841797373045, 10596.417197054081, 10635.840462393071, 10684.412357320267, 9048.321375896703, 10198.592056317813, 10430.95690430966, 10579.994900484442, 10703.25077244601, 8275.172482751677, 8317.792055198668, 8342.833143337099, 8434.451760635538, 8445.451045682012, 5266.262723218676, 5313.9468605315, 5329.73351332424, 5420.024395608828, 5448.073827449422, 9451.905480945103, 9575.186109180519, 9582.28132890022, 9584.0, 9696.70909872705, 9171.220446262143, 9681.128698417177, 9695.66065187715, 9702.981186975267, 9708.6116555337, 6731.4951378647775, 6905.723771049303, 7314.780556583311, 7406.185319614946, 7450.776476705708, 7363.77908662741, 7668.539887606761, 7910.644021018953, 8130.430869839054, 8148.796280093045, 7133.108361454818, 7329.267073292628, 7376.889346659697, 7410.5183163094225, 7491.8501629966, 6077.6353418795015, 7133.785986420416, 7215.891761623685, 7248.710051597877, 7281.963590182015, 6155.384461553809, 6204.914140025568, 6394.1687580614225, 6602.933970660232, 6975.441964642918, 4618.56123668251, 4730.314104454766, 4751.239801631722, 4773.878138637348, 4821.059893320768, 4252.851150209728, 4759.50025247344, 4810.13417584838, 4850.708957462563, 4938.234573641092], "value": 4522.359966187218, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.7360323684408, 333.1371747174817, 334.7773730469238, 335.64757005144605, 342.0184071714178, 5824.301083869942, 5909.349971503131, 6206.565540412173, 6624.768133115342, 6659.600423974565, 6085.0, 6552.836179095525, 6570.671466426684, 6572.507061970347, 6587.143671322721, 4206.810693518783, 4700.694454860441, 4738.099761045391, 4802.039592081597, 4816.229403295473, 4764.237721964487, 4821.0, 4823.758812059383, 4832.009438065188, 4850.660453768238, 3999.7200195986293, 4725.763711814413, 4763.666543341968, 4778.354922085508, 4789.353437285974, 4552.931706024412, 4630.467496237925, 4738.336632871401, 4741.217699079648, 4773.737444440554, 4106.609872062179, 4107.774072426016, 4112.403701463271, 4116.938245926313, 4119.670426365884, 4906.460289368167, 6238.189035425366, 6238.563300568962, 6239.875202495925, 6249.0626406039, 5583.469570390806, 5598.44015598441, 5604.971975140139, 5620.803271885506, 5630.324361076697, 6867.828304292383, 6868.587884726932, 6909.792706218822, 6910.032595436643, 6917.51577389583, 4271.359296105609, 4448.599626033672, 4450.310201918675, 4456.97489577399, 4476.955230447719, 7088.858222835514, 7260.745873894389, 7267.345938865475, 7272.527285726411, 7276.0718409621495, 4584.060267645192, 4588.105319462739, 4592.081583683277, 4594.19601569724, 4594.218982772936, 3948.486696729407, 4640.907181856344, 4646.09401166776, 4647.883802904887, 4649.139909116757, 5473.124300111963, 5485.753141108659, 5497.587680923947, 5505.752241149158, 5524.944750552443, 6649.03588979605, 6926.757563485254, 6928.965355173192, 6943.861122777516, 6970.965145174343, 1585.0014490870678, 1599.2643384043238, 1613.5643376288572, 1615.9515214543583, 1620.586750378646, 957.9808403831885, 963.7783309838776, 1003.7440452684665, 1006.5219020964983, 1037.5175543372368, 6304.999999999908, 6475.255345635218, 6510.544261901621, 6537.673116344164, 6543.476521878239, 7237.601931893796, 7251.601161936144, 7254.492185546961, 7258.346748792634, 7294.598797066213, 6470.967645161838, 6471.223453185554, 6474.514411419163, 6484.319146489645, 6515.4787616990525, 3683.2633473305445, 3771.73597848148, 3819.1597848473293, 3823.5602905665646, 3833.1758671885677, 6344.968275158594, 7474.962625186839, 7608.972788673458, 7638.732644357421, 7704.191059938737, 6106.755729770759, 7476.439267054992, 7543.283388078124, 7543.396528277724, 7551.207123252089, 7620.695172193161, 7626.23737626244, 7637.274458926393, 7656.540607563564, 7660.387169026464, 6948.722051117898, 7245.094363204599, 7277.963610181915, 7282.089738782653, 7296.160941491797, 5849.415058494116, 7108.928910710827, 7138.107736532934, 7153.785386438415, 7184.173820010766, 7640.426967977535, 7647.808804779925, 7649.923500764921, 7650.27322404393, 7650.846983060419, 4556.384888040205, 4616.353710480468, 4617.607503362252, 4621.676482646317, 4629.791659375269, 5873.794417195378, 7001.828426805626, 7190.280971902768, 7205.135383753774, 7211.350978411759, 6840.31596840312, 6970.163580370388, 6984.2317345093015, 7008.859822803617, 7048.964755176191, 6898.896516552356, 6963.895541566779, 6969.128858892638, 6969.337912898267, 6974.860502790018, 5827.76688932438, 6817.693203805939, 6821.488388370793, 6835.453163746789, 6859.07402500676, 6663.13379260693, 6800.081988931431, 6969.686364113729, 6970.442364610718, 6993.965030174817, 5327.413984461628, 6665.200175978914, 6724.025016372696, 6749.5612785168805, 6836.0], "value": 5041.141315025383, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.1905732178707, 712.3446429285059, 740.7517736834527, 747.6075060593191, 754.0800223727055, 14876.925615371922, 14918.328675209614, 15437.45968891089, 15448.845511544881, 15474.226288685577, 13801.723965520683, 14021.509247176351, 14103.929480352585, 14124.29378531072, 14126.152430854156, 752.4582300743456, 763.5876626621646, 909.3361845852531, 934.5747684803398, 938.1931538876561, 926.9768255793591, 927.2628260532892, 934.607464864755, 935.2751617496426, 937.3672770879663, 987.3582171588489, 996.2727209137335, 996.7159359582521, 998.9550470228821, 1004.467632154959, 781.8631739445602, 972.2319367699508, 979.5151400056982, 986.6349450703214, 991.2070343724992, 735.4961851131992, 738.0, 738.1732459645178, 738.564247094217, 739.3161325773692, 14988.325525351276, 14989.175595342253, 15105.54683359501, 15116.470923517623, 15122.941394102418, 12461.1900226485, 12597.496100155982, 12698.111132220749, 12857.035722320863, 12879.227246365248, 10023.949880250624, 10091.949540252324, 10191.23565732573, 10226.386416815016, 10238.948805255926, 3400.7959522428487, 3407.148212946763, 3552.3605750964816, 3567.1795487038125, 3567.946480802764, 61751.69124154351, 78396.60801696012, 78707.6064619679, 78768.0, 78916.21083789204, 9548.856767148498, 9590.280728945358, 9636.987932677108, 9660.033996600283, 9879.555420006118, 21843.344699659036, 21881.67177492339, 21904.78095219059, 21955.341339759834, 21955.67066494004, 7372.705091796375, 7404.925950740531, 7449.2923172299625, 7573.772786816294, 7689.692412303445, 4014.337634290368, 4118.320477121301, 4122.989867482426, 4197.160130459022, 4214.4099826024385, 3565.8395372208315, 3627.4196128619296, 3629.8003609801713, 3631.1103779573646, 3632.600413954463, 636.933122022181, 637.7162162837493, 642.5758999060597, 652.8726898254795, 655.4919937048825, 561.2507302750873, 566.3402136510919, 587.5417174603816, 595.7259660556193, 608.7595399817047, 8026.638801253957, 8247.793805154919, 8252.422330436812, 8275.366871606153, 8441.95779021101, 496.76155445386416, 532.9680219186861, 558.804418453538, 597.357840321656, 602.105872778923, 3674.8346324415465, 3793.5447746270625, 3806.695464362844, 3898.4737060497037, 3998.660113890294, 81921.0, 90304.5484772585, 90793.0, 90850.0, 90887.54556227177, 76510.0, 77036.0, 77298.2270177291, 78226.74186065102, 78453.0, 63699.0, 64658.67670661711, 64879.53885303156, 65067.67466162639, 65619.34380656228, 7555.773326800205, 7611.581363025087, 7617.162112167768, 7717.729879454192, 7741.651625676971, 46647.6005719829, 49252.26121608107, 49435.01129977308, 49466.01067978692, 50759.0, 49427.0, 63571.68214158899, 64751.35248647642, 65870.01194981983, 67159.0, 10161.390316581028, 10361.533730982275, 10428.843567346345, 10488.632897848538, 10542.683719488428, 10519.474026298807, 10763.46182690878, 10943.28868623537, 10996.505157267782, 11493.712657183638, 9858.260630452889, 9974.102330790263, 10060.748481287734, 10136.280291392217, 10151.289409741246, 9703.07820757027, 10208.948955255177, 10420.426876521864, 10438.37369757817, 10601.946990265, 9866.574958756384, 10068.295219334575, 10084.344517606625, 10142.594296228068, 10835.133189344991, 8285.490149051055, 8469.703560375357, 8625.568721564023, 8682.652693892174, 8776.385653004483, 8306.501609903426, 8481.745547633582, 8575.356848236532, 8711.47731136134, 8727.34544909147], "value": 6730.092350751988, "unit": "score"}], "name": "dromaeo_css", "value": 7294.349795860202, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 120.0, 112.0, 114.0, 112.0, 112.0, 112.0, 115.0, 134.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 158.0, 156.0, 142.0, 140.0, 119.0, 155.0, 157.0, 120.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 163.0, 261.0, 267.0, 186.0, 183.0, 186.0, 189.0, 188.0, 185.0], "value": 199.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 94.0, 106.0, 194.0, 56.0, 55.0, 56.0, 64.0, 142.0, 73.0], "value": 91.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 151.0, 78.0, 81.0, 81.0, 127.0, 113.0, 118.0, 170.0], "value": 110.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 86.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 91.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 84.0, 83.0, 84.0, 84.0, 86.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 63.0, 63.0, 65.0, 64.0, 64.0, 81.0, 56.0, 58.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 76.0, 77.0, 74.0, 73.0, 70.0, 71.0, 72.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 81.0, 80.0, 70.0, 76.0, 85.0, 103.0, 111.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 152.0, 129.0, 127.0, 128.0, 133.0, 139.0, 164.0, 187.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 165.0, 177.0, 168.0, 185.0, 216.0, 167.0, 163.0, 165.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 68.0, 70.0, 68.0, 70.0, 70.0, 90.0, 73.0], "value": 71.4, "unit": "score"}], "name": "kraken", "value": 1532.1000000000001, "alertThreshold": 2.0}]} 04:41:51 INFO - Return code: 0 04:41:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:41:51 ERROR - # TBPL SUCCESS # 04:41:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:51 INFO - 2016-05-06 04:41:51.701 screenresolution[7460:144665] starting screenresolution argv=screenresolution get 04:41:51 INFO - 2016-05-06 04:41:51.703 screenresolution[7460:144665] Display 0: 1600x1200x32@60 04:41:51 INFO - 2016-05-06 04:41:51.709 screenresolution[7461:144668] starting screenresolution argv=screenresolution list 04:41:51 INFO - Available Modes on Display 0 04:41:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:41:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:41:51 INFO - Intel Iris: 04:41:51 INFO - Chipset Model: Intel Iris 04:41:51 INFO - Type: GPU 04:41:51 INFO - Bus: Built-In 04:41:51 INFO - VRAM (Dynamic, Max): 1536 MB 04:41:51 INFO - Vendor: Intel (0x8086) 04:41:51 INFO - Device ID: 0x0a2e 04:41:51 INFO - Revision ID: 0x0009 04:41:51 INFO - Displays: 04:41:51 INFO - Display: 04:41:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:41:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:41:51 INFO - Display Serial Number: 1600x1200 60 04:41:51 INFO - Main Display: Yes 04:41:51 INFO - Mirror: Off 04:41:51 INFO - Online: Yes 04:41:51 INFO - Rotation: Supported 04:41:51 INFO - Return code: 0 04:41:51 INFO - Running post-action listener: _resource_record_post_action 04:41:51 INFO - Running post-run listener: _resource_record_post_run 04:41:52 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 0; Write bytes: 652573184; Read time: 0; Write time: 1886 04:41:52 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354253312; Write bytes: 356419072; Read time: 27390; Write time: 763 04:41:53 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 0; Write bytes: 295146496; Read time: 0; Write time: 1118 04:41:53 INFO - Running post-run listener: _upload_blobber_files 04:41:53 INFO - Blob upload gear active. 04:41:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:41:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:41:53 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:41:53 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:41:53 INFO - (blobuploader) - INFO - Open directory for files ... 04:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:54 INFO - (blobuploader) - INFO - Done attempting. 04:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:55 INFO - (blobuploader) - INFO - Done attempting. 04:41:55 INFO - (blobuploader) - INFO - Iteration through files over. 04:41:55 INFO - Return code: 0 04:41:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:41:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:41:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/748bae02b3fe20016e8f21a0d15d5c2fb15003b3b2b234d5396e82051bbf30aa05cc2abc9056edd73ee9b2fa75df94fa349352be5edcf0f6a4b3d4eea1deed39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ff363e4829ba159365ba2591a6d023c38ac979bba0cf6ec112d7e762fc731e45dfe9fa580dd77104fee7c136625958aa4a36b4abc224caae5220a10826b2f26"} 04:41:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:41:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:41:55 INFO - Contents: 04:41:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/748bae02b3fe20016e8f21a0d15d5c2fb15003b3b2b234d5396e82051bbf30aa05cc2abc9056edd73ee9b2fa75df94fa349352be5edcf0f6a4b3d4eea1deed39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ff363e4829ba159365ba2591a6d023c38ac979bba0cf6ec112d7e762fc731e45dfe9fa580dd77104fee7c136625958aa4a36b4abc224caae5220a10826b2f26"} 04:41:55 INFO - Running post-run listener: copy_logs_to_upload_dir 04:41:55 INFO - Copying logs to upload dir... 04:41:55 INFO - mkdir: /builds/slave/test/build/upload/logs 04:41:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.180734 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 04:41:55.853426) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:41:55.856935) ========= 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.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/Mozilla-Aurora/sha512/748bae02b3fe20016e8f21a0d15d5c2fb15003b3b2b234d5396e82051bbf30aa05cc2abc9056edd73ee9b2fa75df94fa349352be5edcf0f6a4b3d4eea1deed39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ff363e4829ba159365ba2591a6d023c38ac979bba0cf6ec112d7e762fc731e45dfe9fa580dd77104fee7c136625958aa4a36b4abc224caae5220a10826b2f26"} build_url:https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011552 build_url: 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/748bae02b3fe20016e8f21a0d15d5c2fb15003b3b2b234d5396e82051bbf30aa05cc2abc9056edd73ee9b2fa75df94fa349352be5edcf0f6a4b3d4eea1deed39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ff363e4829ba159365ba2591a6d023c38ac979bba0cf6ec112d7e762fc731e45dfe9fa580dd77104fee7c136625958aa4a36b4abc224caae5220a10826b2f26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:41:55.888605) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:41:55.888941) ========= 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.7nd56NBZHw/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.zF5DOenz7e/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005085 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:41:55.937182) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:41:55.937576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:41:55.938050) ========= ========= Total master_lag: 0.16 =========