builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1462533659.06 results: success (0) buildid: 20160506013623 builduid: 3e330e94e72a44aabac68afc380e9ed1 revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.055535) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.055974) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.056252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.073469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.073840) ========= 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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 57.0M=0s 2016-05-06 04:20:59 (57.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.172949) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.173269) ========= 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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.020716 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:20:59.222031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 04:20:59.222374) ========= 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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:59,295 truncating revision to first 12 chars 2016-05-06 04:20:59,295 Setting DEBUG logging. 2016-05-06 04:20:59,295 attempt 1/10 2016-05-06 04:20:59,296 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:21:01,190 unpacking tar archive at: mozilla-aurora-b61ad3a6049c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.140315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 04:21:01.379152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:21:01.379471) ========= script_repo_revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:21:01.379845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:21:01.380118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:21:01.392919) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-06 04:21:01.393218) ========= /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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:21:01 INFO - MultiFileLogger online at 20160506 04:21:01 in /builds/slave/test 04:21:01 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:21:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:21:01 INFO - {'append_to_log': False, 04:21:01 INFO - 'base_work_dir': '/builds/slave/test', 04:21:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:21:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:21:01 INFO - 'branch': 'Mozilla-Aurora', 04:21:01 INFO - 'buildbot_json_path': 'buildprops.json', 04:21:01 INFO - 'config_files': ('talos/mac_config.py',), 04:21:01 INFO - 'default_actions': ('clobber', 04:21:01 INFO - 'read-buildbot-config', 04:21:01 INFO - 'download-and-extract', 04:21:01 INFO - 'populate-webroot', 04:21:01 INFO - 'create-virtualenv', 04:21:01 INFO - 'install', 04:21:01 INFO - 'run-tests'), 04:21:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:21:01 INFO - 'download_minidump_stackwalk': True, 04:21:01 INFO - 'download_symbols': 'ondemand', 04:21:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:21:01 INFO - 'tooltool.py': '/tools/tooltool.py', 04:21:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:21:01 INFO - '/tools/misc-python/virtualenv.py')}, 04:21:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:21:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:21:01 INFO - 'installer_path': 'installer.exe', 04:21:01 INFO - 'log_level': 'info', 04:21:01 INFO - 'log_name': 'talos', 04:21:01 INFO - 'log_to_console': True, 04:21:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:21:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:21:01 INFO - 'opt_config_files': (), 04:21:01 INFO - 'pip_index': False, 04:21:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:21:01 INFO - 'cmd': ('bash', 04:21:01 INFO - '-c', 04:21:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:21:01 INFO - 'enabled': True, 04:21:01 INFO - 'halt_on_failure': False, 04:21:01 INFO - 'name': 'check_screen_resolution'},), 04:21:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:21:01 INFO - 'cmd': ('bash', 04:21:01 INFO - '-c', 04:21:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:21:01 INFO - 'enabled': True, 04:21:01 INFO - 'halt_on_failure': False, 04:21:01 INFO - 'name': 'check_screen_resolution'},), 04:21:01 INFO - 'run_cmd_checks_enabled': True, 04:21:01 INFO - 'sps_profile': False, 04:21:01 INFO - 'sps_profile_interval': 0, 04:21:01 INFO - 'suite': 'dromaeojs', 04:21:01 INFO - 'system_bits': '32', 04:21:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:21:01 INFO - 'title': 't-yosemite-r7-0167', 04:21:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:21:01 INFO - 'use_talos_json': True, 04:21:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:21:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:21:01 INFO - 'work_dir': 'build'} 04:21:01 INFO - ##### 04:21:01 INFO - ##### Running clobber step. 04:21:01 INFO - ##### 04:21:01 INFO - Running pre-action listener: _resource_record_pre_action 04:21:01 INFO - Running main action method: clobber 04:21:01 INFO - rmtree: /builds/slave/test/build 04:21:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:21:02 INFO - Running post-action listener: _resource_record_post_action 04:21:02 INFO - ##### 04:21:02 INFO - ##### Running read-buildbot-config step. 04:21:02 INFO - ##### 04:21:02 INFO - Running pre-action listener: _resource_record_pre_action 04:21:02 INFO - Running main action method: read_buildbot_config 04:21:02 INFO - Using buildbot properties: 04:21:02 INFO - { 04:21:02 INFO - "project": "", 04:21:02 INFO - "product": "firefox", 04:21:02 INFO - "script_repo_revision": "production", 04:21:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:21:02 INFO - "repository": "", 04:21:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:21:02 INFO - "buildid": "20160506013623", 04:21:02 INFO - "pgo_build": "False", 04:21:02 INFO - "basedir": "/builds/slave/test", 04:21:02 INFO - "buildnumber": 1, 04:21:02 INFO - "slavename": "t-yosemite-r7-0167", 04:21:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:21:02 INFO - "platform": "yosemite_r7", 04:21:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:21:02 INFO - "branch": "mozilla-aurora", 04:21:02 INFO - "repo_path": "releases/mozilla-aurora", 04:21:02 INFO - "revision": "b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f", 04:21:02 INFO - "stage_platform": "macosx64", 04:21:02 INFO - "builduid": "3e330e94e72a44aabac68afc380e9ed1", 04:21:02 INFO - "slavebuilddir": "test" 04:21:02 INFO - } 04:21:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 04:21:02 INFO - Running post-action listener: _resource_record_post_action 04:21:02 INFO - ##### 04:21:02 INFO - ##### Running download-and-extract step. 04:21:02 INFO - ##### 04:21:02 INFO - Running pre-action listener: _resource_record_pre_action 04:21:02 INFO - Running main action method: download_and_extract 04:21:02 INFO - mkdir: /builds/slave/test/build/tests 04:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:02 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:21:02 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:21:02 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:21:02 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:21:02 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:21:03 INFO - Downloaded 1199 bytes. 04:21:03 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:21:03 INFO - Using the following test package requirements: 04:21:03 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:21:03 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 04:21:03 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 04:21:03 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'jsshell-mac.zip'], 04:21:03 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 04:21:03 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:21:03 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 04:21:03 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 04:21:03 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 04:21:03 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:21:03 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 04:21:03 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 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.common.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.common.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.common.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.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.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.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:21:05 INFO - Downloaded 18088815 bytes. 04:21:05 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:21:05 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:08 INFO - Return code: 0 04:21:08 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:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:08 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:21:08 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:21:08 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:21:08 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:21:08 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:21:09 INFO - Downloaded 18088815 bytes. 04:21:09 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:21:09 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:13 INFO - Return code: 0 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 - 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:13 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:13 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:13 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:13 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:14 INFO - Downloaded 10840145 bytes. 04:21:14 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:14 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:14 INFO - Return code: 0 04:21:14 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:14 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:14 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:14 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:14 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:14 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:17 INFO - Downloaded 114083715 bytes. 04:21:17 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:17 INFO - mkdir: /builds/slave/test/properties 04:21:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:21:17 INFO - Writing to file /builds/slave/test/properties/build_url 04:21:17 INFO - Contents: 04:21:17 INFO - build_url:https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:21:19 INFO - Running post-action listener: _resource_record_post_action 04:21:19 INFO - Running post-action listener: set_extra_try_arguments 04:21:19 INFO - ##### 04:21:19 INFO - ##### Running populate-webroot step. 04:21:19 INFO - ##### 04:21:19 INFO - Running pre-action listener: _resource_record_pre_action 04:21:19 INFO - Running main action method: populate_webroot 04:21:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:21:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:21:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:21:19 INFO - '--tppagecycles', 04:21:19 INFO - '7'], 04:21:19 INFO - 'tests': ['tsvgm']}}, 04:21:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:21:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:21:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:21:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:21:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['damp', 'tps']}, 04:21:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'tests': ['damp', 'tps']}, 04:21:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['dromaeo_dom']}, 04:21:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:21:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'other-e10s': {'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['a11yr', 04:21:19 INFO - 'ts_paint', 04:21:19 INFO - 'tpaint', 04:21:19 INFO - 'sessionrestore', 04:21:19 INFO - 'sessionrestore_no_auto_restore', 04:21:19 INFO - 'tabpaint']}, 04:21:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['tsvgx', 04:21:19 INFO - 'tsvgr_opacity', 04:21:19 INFO - 'tart', 04:21:19 INFO - 'tscrollx', 04:21:19 INFO - 'cart']}, 04:21:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:21:19 INFO - 'tsvgr_opacity', 04:21:19 INFO - 'tart', 04:21:19 INFO - 'tscrollx', 04:21:19 INFO - 'cart']}, 04:21:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'talos_options': ['--disable-e10s'], 04:21:19 INFO - 'tests': ['tp5o']}, 04:21:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'tests': ['tp5o']}, 04:21:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'talos_options': ['--disable-e10s', 04:21:19 INFO - '--xperf_path', 04:21:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:19 INFO - 'tests': ['tp5n']}, 04:21:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:19 INFO - 'talos_options': ['--xperf_path', 04:21:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:19 INFO - 'tests': ['tp5n']}}, 04:21:19 INFO - 'talos.zip': {'path': '', 04:21:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:21:19 INFO - Running post-action listener: _resource_record_post_action 04:21:19 INFO - ##### 04:21:19 INFO - ##### Running create-virtualenv step. 04:21:19 INFO - ##### 04:21:19 INFO - Running pre-action listener: _resource_record_pre_action 04:21:19 INFO - Running main action method: create_virtualenv 04:21:19 INFO - Creating virtualenv /builds/slave/test/build/venv 04:21:19 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:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:21:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:21:19 INFO - Using real prefix '/tools/python27' 04:21:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:21:19 INFO - Installing distribute.............................................................................................................................................................................................done. 04:21:22 INFO - Installing pip.................done. 04:21:22 INFO - Return code: 0 04:21:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:21:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:22 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:22 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:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:22 INFO - 'HOME': '/Users/cltbld', 04:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:22 INFO - 'LOGNAME': 'cltbld', 04:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:22 INFO - 'MOZ_NO_REMOTE': '1', 04:21:22 INFO - 'NO_EM_RESTART': '1', 04:21:22 INFO - 'PAGER': '/bin/cat', 04:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:22 INFO - 'PWD': '/builds/slave/test', 04:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:22 INFO - 'SHELL': '/bin/bash', 04:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:22 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:22 INFO - 'USER': 'cltbld', 04:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:22 INFO - 'XPC_FLAGS': '0x0', 04:21:22 INFO - 'XPC_SERVICE_NAME': '0', 04:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:23 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:23 INFO - Cleaning up... 04:21:23 INFO - Return code: 0 04:21:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:23 INFO - 'HOME': '/Users/cltbld', 04:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:23 INFO - 'LOGNAME': 'cltbld', 04:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:23 INFO - 'MOZ_NO_REMOTE': '1', 04:21:23 INFO - 'NO_EM_RESTART': '1', 04:21:23 INFO - 'PAGER': '/bin/cat', 04:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:23 INFO - 'PWD': '/builds/slave/test', 04:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:23 INFO - 'SHELL': '/bin/bash', 04:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:23 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:23 INFO - 'USER': 'cltbld', 04:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:23 INFO - 'XPC_FLAGS': '0x0', 04:21:23 INFO - 'XPC_SERVICE_NAME': '0', 04:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:23 INFO - Downloading/unpacking psutil>=0.7.1 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:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:21:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:26 INFO - Installing collected packages: psutil 04:21:26 INFO - Running setup.py install for psutil 04:21:26 INFO - building 'psutil._psutil_osx' extension 04:21:26 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:26 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:26 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:26 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:27 INFO - building 'psutil._psutil_posix' extension 04:21:27 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:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:21:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:21:27 INFO - ^ 04:21:27 INFO - 1 warning generated. 04:21:27 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:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:27 INFO - Successfully installed psutil 04:21:27 INFO - Cleaning up... 04:21:27 INFO - Return code: 0 04:21:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:27 INFO - 'HOME': '/Users/cltbld', 04:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:27 INFO - 'LOGNAME': 'cltbld', 04:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:27 INFO - 'MOZ_NO_REMOTE': '1', 04:21:27 INFO - 'NO_EM_RESTART': '1', 04:21:27 INFO - 'PAGER': '/bin/cat', 04:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:27 INFO - 'PWD': '/builds/slave/test', 04:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:27 INFO - 'SHELL': '/bin/bash', 04:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:27 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:27 INFO - 'USER': 'cltbld', 04:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:27 INFO - 'XPC_FLAGS': '0x0', 04:21:27 INFO - 'XPC_SERVICE_NAME': '0', 04:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:21:27 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:27 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:27 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:27 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:27 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:27 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:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:21:30 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:30 INFO - Installing collected packages: mozsystemmonitor 04:21:30 INFO - Running setup.py install for mozsystemmonitor 04:21:30 INFO - Successfully installed mozsystemmonitor 04:21:30 INFO - Cleaning up... 04:21:30 INFO - Return code: 0 04:21:30 INFO - Installing blobuploader==1.2.4 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', '--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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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', '--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:30 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:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 04:21:30 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 - Downloading/unpacking blobuploader==1.2.4 04:21:30 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:30 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:30 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:30 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:30 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:30 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 - Downloading blobuploader-1.2.4.tar.gz 04:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:21:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:21:33 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:33 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:33 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:33 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:33 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:33 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:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:21:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:21:33 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:33 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:33 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:33 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:33 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:33 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 docopt-0.6.1.tar.gz 04:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:21:34 INFO - Installing collected packages: blobuploader, requests, docopt 04:21:34 INFO - Running setup.py install for blobuploader 04:21:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:21:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:21:34 INFO - Running setup.py install for requests 04:21:34 INFO - Running setup.py install for docopt 04:21:35 INFO - Successfully installed blobuploader requests docopt 04:21:35 INFO - Cleaning up... 04:21:35 INFO - Return code: 0 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', '--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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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', '--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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 04:21:35 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qmYvBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-e1nyZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-d8raMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-uU2Daw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-lCcOe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E_36a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-lA0QxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-TnxKVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dDdQjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oHrvpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ltCKp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-2_x_CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oMPoqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pXwTMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-iT9J2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-R5FqPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yK53YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:21:37 INFO - Running setup.py install for manifestparser 04:21:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozcrash 04:21:37 INFO - Running setup.py install for mozdebug 04:21:37 INFO - Running setup.py install for mozdevice 04:21:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozfile 04:21:37 INFO - Running setup.py install for mozhttpd 04:21:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozinfo 04:21:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozInstall 04:21:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozleak 04:21:38 INFO - Running setup.py install for mozlog 04:21:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for moznetwork 04:21:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozprocess 04:21:38 INFO - Running setup.py install for mozprofile 04:21:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Running setup.py install for mozrunner 04:21:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Running setup.py install for mozscreenshot 04:21:39 INFO - Running setup.py install for moztest 04:21:39 INFO - Running setup.py install for mozversion 04:21:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:21:39 INFO - Cleaning up... 04:21:39 INFO - Return code: 0 04:21:39 INFO - Installing None 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', '-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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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', '-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:39 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:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 04:21:39 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-OE2ATG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-nv_VkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Z2AbWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-iywlx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gWYgwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-s_HO5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DBS6Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qnVZCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Qi8iZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:40 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:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HWjZRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-fUxIRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mhHxzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qlcvVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Ipkgq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oFEcOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-LWpqnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:41 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:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-23fNJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:41 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:41 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:41 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:41 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:41 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:41 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:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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:44 INFO - Downloading blessings-1.6.tar.gz 04:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:44 INFO - Installing collected packages: blessings 04:21:44 INFO - Running setup.py install for blessings 04:21:44 INFO - Successfully installed blessings 04:21:44 INFO - Cleaning up... 04:21:44 INFO - Return code: 0 04:21:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:44 INFO - Reading from file tmpfile_stdout 04:21:44 INFO - Current package versions: 04:21:44 INFO - blessings == 1.6 04:21:44 INFO - blobuploader == 1.2.4 04:21:44 INFO - docopt == 0.6.1 04:21:44 INFO - manifestparser == 1.1 04:21:44 INFO - mozInstall == 1.12 04:21:44 INFO - mozcrash == 0.17 04:21:44 INFO - mozdebug == 0.1 04:21:44 INFO - mozdevice == 0.48 04:21:44 INFO - mozfile == 1.2 04:21:44 INFO - mozhttpd == 0.7 04:21:44 INFO - mozinfo == 0.9 04:21:44 INFO - mozleak == 0.1 04:21:44 INFO - mozlog == 3.1 04:21:44 INFO - moznetwork == 0.27 04:21:44 INFO - mozprocess == 0.22 04:21:44 INFO - mozprofile == 0.28 04:21:44 INFO - mozrunner == 6.11 04:21:44 INFO - mozscreenshot == 0.1 04:21:44 INFO - mozsystemmonitor == 0.0 04:21:44 INFO - moztest == 0.7 04:21:44 INFO - mozversion == 1.4 04:21:44 INFO - psutil == 3.1.1 04:21:44 INFO - requests == 1.2.3 04:21:44 INFO - wsgiref == 0.1.2 04:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44 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:44 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:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:44 INFO - 'HOME': '/Users/cltbld', 04:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:44 INFO - 'LOGNAME': 'cltbld', 04:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:44 INFO - 'MOZ_NO_REMOTE': '1', 04:21:44 INFO - 'NO_EM_RESTART': '1', 04:21:44 INFO - 'PAGER': '/bin/cat', 04:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:44 INFO - 'PWD': '/builds/slave/test', 04:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:44 INFO - 'SHELL': '/bin/bash', 04:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:44 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:44 INFO - 'USER': 'cltbld', 04:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:44 INFO - 'XPC_FLAGS': '0x0', 04:21:44 INFO - 'XPC_SERVICE_NAME': '0', 04:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:45 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:45 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:45 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:45 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:45 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:45 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:45 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:45 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:45 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:45 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:45 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:45 INFO - Cleaning up... 04:21:45 INFO - Return code: 0 04:21:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:45 INFO - 'HOME': '/Users/cltbld', 04:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:45 INFO - 'LOGNAME': 'cltbld', 04:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:45 INFO - 'MOZ_NO_REMOTE': '1', 04:21:45 INFO - 'NO_EM_RESTART': '1', 04:21:45 INFO - 'PAGER': '/bin/cat', 04:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:45 INFO - 'PWD': '/builds/slave/test', 04:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:45 INFO - 'SHELL': '/bin/bash', 04:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:45 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:45 INFO - 'USER': 'cltbld', 04:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:45 INFO - 'XPC_FLAGS': '0x0', 04:21:45 INFO - 'XPC_SERVICE_NAME': '0', 04:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:45 INFO - Downloading/unpacking jsonschema 04:21:45 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:45 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:45 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:45 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:45 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:45 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:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:21:47 INFO - Downloading/unpacking functools32 (from jsonschema) 04:21:47 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:47 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:47 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:47 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:47 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:47 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:48 INFO - Downloading functools32-3.2.3-2.tar.gz 04:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:21:48 INFO - warning: no files found matching '*.txt' 04:21:48 INFO - no previously-included directories found matching 'build' 04:21:48 INFO - no previously-included directories found matching 'dist' 04:21:48 INFO - no previously-included directories found matching '.git*' 04:21:48 INFO - Installing collected packages: jsonschema, functools32 04:21:48 INFO - Running setup.py install for functools32 04:21:48 INFO - warning: no files found matching '*.txt' 04:21:48 INFO - no previously-included directories found matching 'build' 04:21:48 INFO - no previously-included directories found matching 'dist' 04:21:48 INFO - no previously-included directories found matching '.git*' 04:21:48 INFO - Successfully installed jsonschema functools32 04:21:48 INFO - Cleaning up... 04:21:48 INFO - Return code: 0 04:21:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:21:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:48 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 0x103881a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103891420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa138592a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, '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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:48 INFO - 'HOME': '/Users/cltbld', 04:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:48 INFO - 'LOGNAME': 'cltbld', 04:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:48 INFO - 'MOZ_NO_REMOTE': '1', 04:21:48 INFO - 'NO_EM_RESTART': '1', 04:21:48 INFO - 'PAGER': '/bin/cat', 04:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:48 INFO - 'PWD': '/builds/slave/test', 04:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:48 INFO - 'SHELL': '/bin/bash', 04:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:21:48 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:48 INFO - 'USER': 'cltbld', 04:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:48 INFO - 'XPC_FLAGS': '0x0', 04:21:48 INFO - 'XPC_SERVICE_NAME': '0', 04:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:48 INFO - Downloading/unpacking flake8 04:21:48 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:48 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:48 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:48 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:48 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:48 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:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:21:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:21:51 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:51 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:51 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:51 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:51 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:51 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:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:21:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:21:51 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:51 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:51 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:51 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:51 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:51 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:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:21:51 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:51 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:51 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:51 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:51 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:51 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:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:21:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:21:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:21:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:21:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:21:52 INFO - no previously-included directories found matching 'docs/_build' 04:21:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:21:52 INFO - Running setup.py install for pep8 04:21:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:21:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:21:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:21:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:21:52 INFO - no previously-included directories found matching 'docs/_build' 04:21:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:21:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:21:52 INFO - Cleaning up... 04:21:52 INFO - Return code: 0 04:21:52 INFO - Running post-action listener: _resource_record_post_action 04:21:52 INFO - Running post-action listener: _start_resource_monitoring 04:21:52 INFO - Starting resource monitoring. 04:21:52 INFO - ##### 04:21:52 INFO - ##### Running install step. 04:21:52 INFO - ##### 04:21:52 INFO - Running pre-action listener: _resource_record_pre_action 04:21:52 INFO - Running main action method: install 04:21:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:53 INFO - Reading from file tmpfile_stdout 04:21:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:53 INFO - Reading from file tmpfile_stdout 04:21:53 INFO - Output received: 04:21:53 INFO - Usage: mozinstall [options] installer 04:21:53 INFO - Options: 04:21:53 INFO - -h, --help show this help message and exit 04:21:53 INFO - -d DEST, --destination=DEST 04:21:53 INFO - Directory to install application into. [default: 04:21:53 INFO - "/builds/slave/test"] 04:21:53 INFO - --app=APP Application being installed. [default: firefox] 04:21:53 INFO - mkdir: /builds/slave/test/build/application 04:21:53 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:53 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:25 INFO - Reading from file tmpfile_stdout 04:22:25 INFO - Output received: 04:22:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:22:25 INFO - Running post-action listener: _resource_record_post_action 04:22:25 INFO - ##### 04:22:25 INFO - ##### Running run-tests step. 04:22:25 INFO - ##### 04:22:25 INFO - Running pre-action listener: _resource_record_pre_action 04:22:25 INFO - Running main action method: run_tests 04:22:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:25 INFO - 2016-05-06 04:22:25.812 screenresolution[1925:12363] starting screenresolution argv=screenresolution get 04:22:25 INFO - 2016-05-06 04:22:25.815 screenresolution[1925:12363] Display 0: 1600x1200x32@60 04:22:25 INFO - 2016-05-06 04:22:25.821 screenresolution[1926:12365] starting screenresolution argv=screenresolution list 04:22:25 INFO - Available Modes on Display 0 04:22:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:26 INFO - Intel Iris: 04:22:26 INFO - Chipset Model: Intel Iris 04:22:26 INFO - Type: GPU 04:22:26 INFO - Bus: Built-In 04:22:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:26 INFO - Vendor: Intel (0x8086) 04:22:26 INFO - Device ID: 0x0a2e 04:22:26 INFO - Revision ID: 0x0009 04:22:26 INFO - Displays: 04:22:26 INFO - Display: 04:22:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:26 INFO - Display Serial Number: 1600x1200 60 04:22:26 INFO - Main Display: Yes 04:22:26 INFO - Mirror: Off 04:22:26 INFO - Online: Yes 04:22:26 INFO - Rotation: Supported 04:22:26 INFO - Return code: 0 04:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:22:26 INFO - Python 2.7.3 04:22:26 INFO - Return code: 0 04:22:26 INFO - grabbing minidump binary from tooltool 04:22:26 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:26 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 0x103720e00>, '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 0x103589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10371bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:22:26 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:26 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:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:22:26 INFO - Return code: 0 04:22:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:22:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:22:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:22:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:22:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:22:27 INFO - Return code: 0 04:22:27 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-0167', '--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:27 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-0167 --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:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3d7ZKx9qG/Render', 04:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:22:27 INFO - 'HOME': '/Users/cltbld', 04:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:22:27 INFO - 'LOGNAME': 'cltbld', 04:22:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:22:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:27 INFO - 'MOZ_NO_REMOTE': '1', 04:22:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:22:27 INFO - 'NO_EM_RESTART': '1', 04:22:27 INFO - 'PAGER': '/bin/cat', 04:22:27 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:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:27 INFO - 'PWD': '/builds/slave/test', 04:22:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:22:27 INFO - 'SHELL': '/bin/bash', 04:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pKaNJtkkqB/Listeners', 04:22:27 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:22:27 INFO - 'USER': 'cltbld', 04:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:27 INFO - 'XPC_FLAGS': '0x0', 04:22:27 INFO - 'XPC_SERVICE_NAME': '0', 04:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:22:27 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-0167', '--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:27 INFO - mozversion application_buildid: 20160506013623 04:22:27 INFO - mozversion application_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:22:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:22:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:22:27 INFO - mozversion application_name: Firefox 04:22:27 INFO - mozversion application_remotingname: firefox-dev 04:22:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:22:27 INFO - mozversion application_vendor: Mozilla 04:22:27 INFO - mozversion application_version: 48.0a2 04:22:27 INFO - mozversion platform_buildid: 20160506013623 04:22:27 INFO - mozversion platform_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:22:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:22:27 INFO - mozversion platform_version: 48.0a2 04:22:27 INFO - using testdate: 1462533747 04:22:27 INFO - actual date: 1462533747 04:22:27 INFO - starting webserver on 'localhost:51953' 04:22:27 INFO - SUITE-START | Running 2 tests 04:22:27 INFO - TEST-START | dromaeo_css 04:22:27 INFO - operating with platform_type : mac_ 04:22:27 INFO - Initialising browser for dromaeo_css test... 04:22:27 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpVkoZs7/profile http://localhost:51953/getInfo.html) 04:22:29 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 04:22:29 INFO - PROCESS | 1944 | colorDepth:24 04:22:29 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 04:22:29 INFO - PROCESS | 1944 | __metrics 04:22:29 INFO - PROCESS | 1944 | 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:29 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:22:30 INFO - TEST-INFO | 1944: exit 0 04:22:30 INFO - Browser initialized. 04:22:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:22:30 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpVkoZs7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:42 INFO - PROCESS | 1946 | RSS: Main: 211214336 04:22:42 INFO - PROCESS | 1946 | 04:23:33 INFO - PROCESS | 1946 | 1462533813395 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:45 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51953/tests/dromaeo/cssquery-ext.html) 04:25:47 INFO - PROCESS | 1946 | RSS: Main: 353067008 04:25:47 INFO - PROCESS | 1946 | 04:28:50 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-ext.html (next: http://localhost:51953/tests/dromaeo/cssquery-jquery.html) 04:28:50 INFO - PROCESS | 1946 | RSS: Main: 356929536 04:28:50 INFO - PROCESS | 1946 | 04:31:52 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51953/tests/dromaeo/cssquery-mootools.html) 04:31:53 INFO - PROCESS | 1946 | RSS: Main: 354971648 04:31:53 INFO - PROCESS | 1946 | 04:34:55 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51953/tests/dromaeo/cssquery-prototype.html) 04:34:56 INFO - PROCESS | 1946 | RSS: Main: 360796160 04:34:56 INFO - PROCESS | 1946 | 04:37:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51953/tests/dromaeo/cssquery-yui.html) 04:38:00 INFO - PROCESS | 1946 | RSS: Main: 328245248 04:38:00 INFO - PROCESS | 1946 | 04:41:02 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51953/tests/dromaeo/cssquery-yui.html (next: http://localhost:51953/tests/dromaeo/cssquery-dojo.html) 04:41:03 INFO - PROCESS | 1946 | RSS: Main: 369512448 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | __start_tp_report 04:41:03 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 04:41:03 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 04:41:03 INFO - PROCESS | 1946 | |i|pagename|runs| 04:41:03 INFO - PROCESS | 1946 | |0;dojo.html;3149.7165255127043;3447.517567445999;3456.170519075422;3469.5142680024815;3494.91262718432;5776.335721392036;5786.161006654043;5791.102379131231;5803.3906439823795;5825.475707186358;4845.3216549683075;5794.5943783935145;5805.970970145154;5850.76596936122;5873.35393106759;5170.327857378555;5234.607404444663;5237.528622423981;5353.7590808413715;5391.73041347933;5301.840944771644;5317.734113294339;5318.042752304584;5336.59975501837;5339.359276886759;5250.4487028861995;5282.07563676357;5299.993001329754;5314.627976041678;5318.601104917147;5309.3363329584;5319.893602127936;5326.174442961308;5345.893082138336;5363.222332761767;5131.307273518065;5161;5180.067587834226;5237.16205407133;5255.421903590602;5620.201535508639;5623.803166889139;5635.154726790973;5685.914711279334;5698.287714035746;6316.084167795644;6318.589291696025;6347.87304253917;6357.523185761041;6358.300586935434;5062.594992400599;6252.187215661935;6302.9369706302805;6334.3032266450655;6354.332795056499;5884.146798714248;5909.409059094056;5917.112433135021;5950.256217972754;5953.3748956359395;6306.716197771111;6340.8414789629705;6353.30113687494;6359.777407790705;6361.586496877688;6029.60807547508;6120.081455037911;6140.662263575547;6259.467945224706;6269;6283.8114856554375;6305.274893387227;6318.115463835067;6324.3043265240785;6344.048392741033;6211.627302361872;6279.0895320178315;6280.214973128359;6287.528435367366;6307.337729538424;6140.04415716754;6243.656598887014;6262.843428914313;6334.809955701337;6343.238811342669;4939.481354457802;4966.329545511382;4966.751662416865;4970.478099799541;4974.552290293818;4500.459944806644;4524.524924882906;4543.454785425704;4544.88637784058;4553.362529245908;5713.428657134336;5787.102999035072;5789.478946894799;5793.420657934173;5832.154337621021;6225.439710426083;6299.1811064562235;6310.242770867433;6328.778492752732;6330.746770129143;5555.972220138873;5678.4037676044245;5771.769129234867;5783.739731712083;5787.160861675034;5438.483344082385;5451.100568406168;5461.208124821878;5464.26232458621;5464.398916119224;6343.904841427382;6382.776602818879;6387.808365749035;6414.711337989895;6433.646149461824;6294.087357333253;6303.968480157753;6314.936850631435;6322.841928951813;6347.96826015867;6264.029075493306;6454.548181627241;6472.935270647422;6478.060681201106;6498.187726534184;5183.222516622537;5239.685618862881;5716.79991200306;5730.882477916766;6153.600015998945;6301.506039067657;6310.432061114522;6314.526410519321;6332.651704156131;6337.6197428154455;6141.946315431794;6167.506599472121;6175.722092505938;6185.443310102112;6209.254889413119;5958.225430694156;6052.122442245762;6147.862645410613;6169.84575385619;6190.442860142609;6091.9814604840985;6092.786752463642;6099.146119543356;6103.908441373472;6104.145419641342;6201.658908760061;6220.066989951543;6222.097795819672;6222.657753823402;6225.221847269091;5784.913226301693;5964.850878728066;5973.522118230618;5987.910181347196;6085.756569737338 04:41:03 INFO - PROCESS | 1946 | |1;ext.html;499.9175136102543;509.5541401273885;518.4064246437829;523.6256076905012;583.8803045375698;31163;31744.68255317446;31804.727810887565;39556.604433955654;39824.601753982526;42417.72746817593;42891.142177156435;43079;43092.78453607736;43197.56802431975;7915.208479152082;9724.416535007922;9730.221582273402;9733.659321923735;9740.999999999965;8608.784780380478;8813.73558793234;10870.184736144782;10982.231243812936;11531.654050378502;11528.135389845753;11597.710057248594;11606.303621782678;11631.127665425085;11632.243904146246;8602.569871506463;11447.141464390163;11533.480993355319;11550.4802283897;11651.59219427316;11347.773044539063;11470.139739519569;11484.310941343547;11501.712457188638;11505.367204803817;38746.80626596849;39349;39372.60627393718;39421.60578394208;39575.40636890449;3238.3523295341024;3266.6080070391704;3456.2569047654624;3512.701420379271;3794.658480736747;23732.64401033986;27950.580741288897;28901.42197156066;28909.855450722822;29551.556726649116;2725.5775354820034;2753.7246275372504;2754.476649436601;2885.9855700721573;2897.174305322994;354289;475133;476407;477205;477248;36363.63636363656;36723.4491482628;36767.81616091956;62934.685326573075;64357.356426436076;35654.28691426157;35673.10817229538;35679;35718;35780.82109589488;15296.464623738339;15418.306176222097;15458.922705386625;15459;15459.227038648023;12037.638870833889;12132;12978.221306721627;13072.019598530149;13174.341282935815;7935.206479352134;7954.960225198837;7980.600969951479;8007.879881801778;8063.395245356622;3313.370459612666;3321.368939901411;3329.267561136546;3333.633300336961;3335.5163501292177;867.3364875869964;931.0308697105573;935.7239614313794;979.8089372572423;1004.6885465505717;28289.858550707526;29473.85263073671;29651.1104666856;30211.848940755157;30289;882.2144574133299;931.8415869302235;932.4032619123766;1109.7891400633853;1178.8231765235112;14533.709325813636;14673.92663036678;14808.925955370154;15019.173945432887;15042.774358384633;611639;612675;621066;621954;623797;60566;61330.69334653298;61421;61427.692861535405;61478.69260653668;1325.8806707396382;1470.5661829760033;1497.1391449916123;1498.5654160293611;1510.1392206442442;23776.643350350103;28420.14739557734;28749.137525874256;29312;29360.559591605717;47688.761556191996;47786;48175.5182448171;48185;48205.7948551289;368718;370948;371368;371445;372514;23805.285841424786;26769.33076673099;27024.1892743218;27224.863875680494;27293.317667058483;34484.482732759534;41304.554340599134;42391.7880410596;43005;43891;35223.47164792479;35543.93368198958;35898.64101358953;35913.46129808003;36421.81789091037;29140.417191655622;35438;35959.741409050555;35964.46053309255;36326.636733632324;36027;36514.8174259127;36719;37103;37307.88076357714;31852.68147318497;32471.512927306583;32654.857080002086;32716.509252360756;32720.854770082933;24516.632250516614;24604.507909841344;24762.25713228606;25027.74972250327;25093 04:41:03 INFO - PROCESS | 1946 | |2;jquery.html;365.8829174664108;371.7824125987391;397.5150316613732;407.9938800917987;410.07528024305185;16479.670406591875;18601.813981860196;20952.476188095305;21957.01193446295;21992.560148797016;19917;20758.584828303425;21275.14899404022;21572.460688482795;21575.784242157573;5316.388615309241;5452.236686863842;5493.642913210649;5521.723913804314;5526.917096243559;4482.843100491484;5409.513143817056;5453.182022696587;5453.618246722731;5464.398916119244;5438.782448702047;5455.318085239345;5458.4814442628085;5460.972695136538;5462.180672899057;5326.973365133169;6012.729427175787;6013.6993150342305;6015.669138197398;6018.337982821886;5311.070562651559;5361.517463428271;5368.892622147535;5373.194020896858;5375.408705042442;25138.748612513824;25591.61612575813;25979.74020259811;25999.350016249555;26090.34774130643;2128.6594144936735;2184.683220932972;2429.866357350345;2687.9920029988753;2867.16852112887;9622.951885240598;9808.362456440309;10216.080552750214;12500.312482813442;14902.859420145396;1725.8791884568086;1799.4691565987935;1811.293595497757;1812.7824661040759;1915.0520492356234;97655;114809.42595287053;119087.40456297663;121380.39309803482;124001.13998290033;11650.76698466043;11736.471858766376;11782.764344713058;11789.387860619341;11799.646010619696;14878.256087195598;14950.252487375588;14967;14986.325615347117;14995.800335973096;8826.293896488265;8873.778155546033;8927.464352138892;9017.954910225539;9064.864027039599;8988.685396011108;9009.054049324857;9023.639054437881;9114.817703646022;9140.542972851465;4244.766537840448;4552.840650577214;4638.443386793607;4654.4414670239785;4734.763261836894;1769.6054413926217;1877.249100359867;1976.416956997689;1986.2750096214777;2018.3541266794484;877.4033657113097;993.4436715439233;1033.6020632056711;1036.4765793274348;1051.8369652703843;18712.625747484974;18829.905850470936;19324.420267392;19768.505787355145;19827.40517784469;950.3775027357092;973.9269554783419;983.4640121133999;992.6029588164791;1002.2332915319786;10454.63408780689;10579.31234469757;10638;10642.627508037182;10751.78496430067;623340;625266;635281;639537;640025;118738.40630796964;119864;121510;122301.10795568256;122329;1009.4700282351824;1019.7297716105238;1026.9229807764598;1033.8655974723388;1037.7509397744639;17244.965302081917;17319.56701082483;17409.21658525344;17477.213525391133;17526.561835954202;37047;49574;49626;50304;50758;64491;69359.65320173367;70189.29810702032;70252;70767.29232707813;7310.232425595236;8003.4797738146835;8074.313683336982;8134.87797683047;8451.070382257823;11294.096472282034;12650;12763.744725105631;12957.611271661865;12977.091603587658;10667.733306667395;11006.724831879266;11206.38364889939;11315.038221751243;11395.487203075716;10789.946050269698;10952.835707464235;11063.944680276547;11241.04451121631;11269.436528173723;5654.604177707521;6758.763443279461;10035.69892903214;11229.7754044917;11368.147388946027;7903.56530390834;8005.239502247277;8113.472624279403;8185.754427367188;8537.274331681629;8809.471431714117;11111.777764444505;11118.388488633203;11188.384638844942;11236.101111911192 04:41:03 INFO - PROCESS | 1946 | |3;mootools.html;1259.9055070869676;1578.3765412661999;1603.5349748572914;1839.264294282287;1960.6960921057241;7884.211578842113;8481.109483504228;8712.651493940242;8715.520646364448;8721.258693011101;8532.744017679464;8582.656693732259;8653.480791152535;8678.218960293572;8692.30461563075;2160.0819651648067;2380.071772008918;2441.035790862604;2468.4445999650115;2469.49375378048;2472.4807790363957;2478.7769100780924;2482.491089326693;2486.005597760892;2486.105002199207;2385.582523058458;2405.122130422392;2423.5516429460517;2438.6829712137487;2446.8409553378974;2148.581026699794;2388.641703744435;2447.8041756659422;2447.816413768957;2459.102427613917;2415.1667674652217;2457.0908763757525;2457.6313552967017;2457.9754202457925;2462.963055554168;8601.354898382646;8675.175858293453;8680.30557555394;8690.782730431727;8697.304215662733;5271.683698978073;5386;5414.539764120054;5424.457554244544;5426.511613954763;3405.5743032120986;3459.3081383723106;3602.189507360823;3607.549056367954;3617.5116359291424;1032.5405194688367;1034.4414016431117;1035.8342665173611;1050.4432650695144;1062.5802807890916;11733.706657333634;11785.941070294593;11808.586699465563;11822.940885295518;11825.231359961661;4764.595009424238;4790.616750659939;4828.348172996671;4892.315075889378;4911.533404326655;9997.550110245056;10068.244881633907;10069.647562335284;10069.748256293651;10073.29486935922;5428.511433970962;5432.375276843135;5473.425290344536;5483.835484935458;5487.204355368529;4411.602955733999;4525.004499010212;4776.020915712271;4935.7532123393685;4979.676321039194;3476.42638964568;3750.1749615084636;3773.2264885698364;3997.2405243003736;4094.856680016245;1347.0839828916235;1354.688421663023;1355.8440779310506;1369.9589012329645;1371.2389623758909;834.6369329341634;881.8280435315052;900.599233341157;908.4730856103445;930.5812384427035;5682.147677848356;5814.651120932757;5852.853678658068;5869.178315035899;5960.046392577254;880.5201165364891;947.8530827721714;964.6671898195111;987.2151639446657;1030.05749738988;3716.5168528091717;3803.087259057806;3828.8085595720104;3829.2341531693774;3862.7682339059747;10191.23565732573;10448.947755261328;10482.10902073317;10634.73413164677;10658.360498370123;10510.894891050992;10537.473126343652;10545.630902918361;10670;10674.679759607225;8017.919820801834;8129.227723366271;8262.710805121791;8349.290310323691;8358.247757701836;5600.971995140161;5646.209530665628;5675.205471233948;5727.971360143172;5801.535877129735;9292.62829486813;9446.811063778548;9567.186789122725;9573.18627916607;9590.712278631652;9229.723108306762;9551.761205969906;9689;9746.805063898824;9754.9024509754;6773.864522709617;6973.093497845248;6975.511714179958;7396.260373962776;7570.05374328209;8035.718749843727;8177.304929081095;8204.302634275915;8236.299914507093;8255.091939886488;7232.204457509775;7341.632918353954;7380.483366164317;7427.740029098955;7496.287852653989;7244.573756759536;7319.670614822239;7322.524035937647;7374.815629609303;7385.187629360874;6922.5154239204785;6951.652417379212;6956.304369563003;6959.89560156588;6962.303769622998;4160.209560183555;4671.346011558317;4684.203685373426;4703.3415321855655;4845.394325709286;4764.213904705824;4817.156997525349;4857.635677324144;4863.294822250683;5001.174806156943 04:41:03 INFO - PROCESS | 1946 | |4;prototype.html;326.1910462054106;331.0961076261805;336.69192688689844;336.9477730951702;339.97450191235663;6549.279579246279;6605.910024845894;6609.537332386735;6644.402003819655;6651.600903945755;5356.223347614594;6558.049082882979;6564.606123632585;6581;6626.039224312472;4620.099080679269;4709.128811169928;4710.811567537295;4717.1037502874515;4878.243872199815;3998.400239964014;4798.544138306873;4853.077915196119;4902.014684052964;4920.434150072751;4728.983268597251;4836.830710925118;4837.854864354075;4839.177339852216;4850.296706977486;4612.3542704021465;4750.406199225097;4774.140654682155;4827.8551643450755;4839.540243676827;4003.11931375097;4071.89820254496;4083.9183216335505;4092.406601042862;4120.958790412087;5090.949090509084;6285;6287.182666253358;6291;6306.432421082125;4669.696469729457;5665.951798917214;5676.262085928803;5676.545876329925;5713.800016999385;6957.130358705162;6976.965115174442;6989.161300643905;6989.965050174767;6992.895106573405;4380.102079073782;4534.274516077445;4535.342375355588;4661.347411362412;4671.019085991912;6313.842153946188;7182.850743880954;7364.410847132217;7367.852642947165;7453.776386708408;4625.4911959684405;4628.190066738374;4630.027694184192;4635;4635.675502714777;4691.577758001797;4694.201985662377;4698.436187657503;4711.316859055418;4724.480307166209;4553.612942899825;4676.298555216745;5584.162375643605;5595.216669666169;5596.972015139898;6761.526693131434;6981.406580440618;7029.191642961023;7047.92952070483;7052.224255331909;1617.765424013535;1629.3564042203263;1642.89321194122;1646.1275523972313;1650.917454127289;852.6845067325005;1021.8351079769069;1043.1848583464716;1045.5399624165343;1047.5652604169336;6313.305536390993;6507.414332710079;6508.153939987866;6530.575512591667;6538.1827271591055;7183.173934997438;7297;7310.195878453366;7366.77899663011;7382.630868456556;5759;6224.159738435344;6559.311272316433;6582.76960306387;6594.8351291218105;3837.2325534893134;3881.8641347553266;3884.747491413049;3892.8832135035996;3895.0457138000806;7344.412447004227;7712.691492340244;7717.922820771832;7725.613719314124;7750.457467977188;6158.846028849225;7489.438292128112;7566.35685966688;7573.659185336673;7586.355159811367;6229.345918678474;7670.117936437381;7673.11759147694;7677.270659287359;7734.03324584427;6579.572327798678;6636.668166591748;7310.268973102648;7315.780526584211;7355.485116041826;6211.720472578835;7210.026646402668;7210.639468026473;7214.819629509304;7219.60292183935;7681.462297639335;7747.845043099219;7754.573498457414;7761;7767.495112817649;4616.930746038879;4664.650151238603;4682.391289132527;4689.273162659793;4693.741844198602;7303.707851685873;7304.817379565553;7305.561666300039;7316.634168291671;7336.156342020736;6776.322367763381;7022.578645281299;7052.717891284291;7092.6099064551945;7117.857642847218;7015.508914375945;7043.36609705129;7057.576545407292;7075.469339799639;7076.25699301566;6935.514513983972;6935.791926242221;6944.895826562707;6958.512904096665;6962.686679099554;7077.292270772881;7094.148702155774;7095.46783991213;7098;7103.25415831351;6672.766453174309;6928.1339832520935;6942.51402401827;6957.930420695729;6976.511644184859 04:41:03 INFO - PROCESS | 1946 | |5;yui.html;587.389115320067;642.31593353079;742.6992068212376;745.0872680965817;751.7857410637965;15090.849091509082;15297.235138243084;15335.156566388858;15381.615459613487;16999;15789.684206315895;15891.44379946702;15917;15928.362865485396;15932.920335398336;723.2405973727591;739.5156172706859;889.8353804546164;929.2055292724724;932.7388331267256;904.3714618340256;926.6247169896203;927.7355953553239;929.7071422501924;930.2418528898926;963.2294164668273;964.3587014635259;968.1818863060703;969.8884628267733;970.3013830042394;755.5504474837463;936.8454205056157;963.5086106085912;965.4303960663173;966.0725703324781;709.5423451873564;710.1442761472414;710.406810313389;713.3401603516778;714.9749758758419;15172.241387930559;15286.923565382212;15288.082715037133;15294.923525382303;15328.693426131415;12227.14409991301;12427.627171184879;12531.122821402505;12578.953374714034;12618.948903796943;9929.75175620608;10094.89905100947;10212.642557510526;10407.063364297173;10429.217808664305;3398.8130652814084;3448.3620530201742;3450.551428314328;3512.1746389598425;3512.648735126467;78710.60644696797;79925.60037199777;80406.19593804046;80707;80871.19128808695;8271.007479102425;8712.477251364939;8727.734478500583;8949.731508054769;9211.40125891815;19602;20843.687344689843;20938.790612093988;20990.685139722915;21061.15755369768;7310.744123955636;7344.48588598793;7418.629068546551;7430.219826918204;7836.373090152774;4023.7384570002855;4079.7348172369293;4143.834246630161;4189.727667701651;4325.178216138924;3126.3590963852357;3623.655752703489;3661.9267614647556;3686.5391826021746;3687;645.2059810198523;663.7521459655816;667.6094484726392;670.038494760021;686.7973947685445;468.50572645858944;502.68582136164895;530.9867253318698;592.295168749187;603.9335673075958;8003.079645840687;8045.115037345888;8113.229243221885;8218.342532597379;8225.259726624632;514.0498727232301;605.6366180291877;607.8936186167404;612.8008397270905;614.1095411653121;3637.563492380931;3765.548134223856;3777.3578491656485;3824.2733880562605;4027.134166154291;81062.59468702618;81082;81681;81754;82799;71748;72944;73268.53462930818;73922.26077739154;76316.61841690756;53908.38274851761;66117.34706632372;68189;68885.65557172181;69378;8213.425060245965;8298.21166989135;8391.58042097905;8466.153384661731;8543.359248056297;45180.87047823699;47320.52679473161;47488;47507.574772756874;48488.27267591059;65684;67908.6604566974;68328;69529;70373.64813175901;10739.785204296026;11227.831582526105;11250.943745281222;11271.49278282463;11349.375784331942;11504.309741415542;11978.58074967372;11982.760344792881;12256.816147757612;12365.814512782135;11303.660890173307;11353.943230283796;11418.257813242113;11432.142589306002;11665.88334116648;11079.5568177272;11246.325220486797;11388.658340249805;11413.429328533706;11590.246633968765;10479.738006549896;10997.945010274898;11567.305961641992;11792;12010.858968397988;9632.229421646392;9731.97814229524;9767.560459779184;9817.410955342417;9902;9457.33798634089;9656.468894210886;9691.12779849817;9706.029397060238;9740.902590974 04:41:03 INFO - PROCESS | 1946 | __end_tp_report 04:41:03 INFO - PROCESS | 1946 | __start_cc_report 04:41:03 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1235 04:41:03 INFO - PROCESS | 1946 | __end_cc_report 04:41:03 INFO - PROCESS | 1946 | __startTimestamp1462534863087__endTimestamp 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | ------- Summary: start ------- 04:41:03 INFO - PROCESS | 1946 | Number of tests: 6 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5786.80 Median:5964.85 stddev:648.31 (10.9%) stddev-sans-first:616.41 04:41:03 INFO - PROCESS | 1946 | Values: 3149.7 3447.5 3456.2 3469.5 3494.9 5776.3 5786.2 5791.1 5803.4 5825.5 4845.3 5794.6 5806.0 5850.8 5873.4 5170.3 5234.6 5237.5 5353.8 5391.7 5301.8 5317.7 5318.0 5336.6 5339.4 5250.4 5282.1 5300.0 5314.6 5318.6 5309.3 5319.9 5326.2 5345.9 5363.2 5131.3 5161.0 5180.1 5237.2 5255.4 5620.2 5623.8 5635.2 5685.9 5698.3 6316.1 6318.6 6347.9 6357.5 6358.3 5062.6 6252.2 6302.9 6334.3 6354.3 5884.1 5909.4 5917.1 5950.3 5953.4 6306.7 6340.8 6353.3 6359.8 6361.6 6029.6 6120.1 6140.7 6259.5 6269.0 6283.8 6305.3 6318.1 6324.3 6344.0 6211.6 6279.1 6280.2 6287.5 6307.3 6140.0 6243.7 6262.8 6334.8 6343.2 4939.5 4966.3 4966.8 4970.5 4974.6 4500.5 4524.5 4543.5 4544.9 4553.4 5713.4 5787.1 5789.5 5793.4 5832.2 6225.4 6299.2 6310.2 6328.8 6330.7 5556.0 5678.4 5771.8 5783.7 5787.2 5438.5 5451.1 5461.2 5464.3 5464.4 6343.9 6382.8 6387.8 6414.7 6433.6 6294.1 6304.0 6314.9 6322.8 6348.0 6264.0 6454.5 6472.9 6478.1 6498.2 5183.2 5239.7 5716.8 5730.9 6153.6 6301.5 6310.4 6314.5 6332.7 6337.6 6141.9 6167.5 6175.7 6185.4 6209.3 5958.2 6052.1 6147.9 6169.8 6190.4 6092.0 6092.8 6099.1 6103.9 6104.1 6201.7 6220.1 6222.1 6222.7 6225.2 5784.9 5964.9 5973.5 5987.9 6085.8 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:60717.31 Median:194863.50 stddev:131589.21 (67.5%) stddev-sans-first:131880.68 04:41:03 INFO - PROCESS | 1946 | Values: 499.9 509.6 518.4 523.6 583.9 31163.0 31744.7 31804.7 39556.6 39824.6 42417.7 42891.1 43079.0 43092.8 43197.6 7915.2 9724.4 9730.2 9733.7 9741.0 8608.8 8813.7 10870.2 10982.2 11531.7 11528.1 11597.7 11606.3 11631.1 11632.2 8602.6 11447.1 11533.5 11550.5 11651.6 11347.8 11470.1 11484.3 11501.7 11505.4 38746.8 39349.0 39372.6 39421.6 39575.4 3238.4 3266.6 3456.3 3512.7 3794.7 23732.6 27950.6 28901.4 28909.9 29551.6 2725.6 2753.7 2754.5 2886.0 2897.2 354289.0 475133.0 476407.0 477205.0 477248.0 36363.6 36723.4 36767.8 62934.7 64357.4 35654.3 35673.1 35679.0 35718.0 35780.8 15296.5 15418.3 15458.9 15459.0 15459.2 12037.6 12132.0 12978.2 13072.0 13174.3 7935.2 7955.0 7980.6 8007.9 8063.4 3313.4 3321.4 3329.3 3333.6 3335.5 867.3 931.0 935.7 979.8 1004.7 28289.9 29473.9 29651.1 30211.8 30289.0 882.2 931.8 932.4 1109.8 1178.8 14533.7 14673.9 14808.9 15019.2 15042.8 611639.0 612675.0 621066.0 621954.0 623797.0 60566.0 61330.7 61421.0 61427.7 61478.7 1325.9 1470.6 1497.1 1498.6 1510.1 23776.6 28420.1 28749.1 29312.0 29360.6 47688.8 47786.0 48175.5 48185.0 48205.8 368718.0 370948.0 371368.0 371445.0 372514.0 23805.3 26769.3 27024.2 27224.9 27293.3 34484.5 41304.6 42391.8 43005.0 43891.0 35223.5 35543.9 35898.6 35913.5 36421.8 29140.4 35438.0 35959.7 35964.5 36326.6 36027.0 36514.8 36719.0 37103.0 37307.9 31852.7 32471.5 32654.9 32716.5 32720.9 24516.6 24604.5 24762.3 25027.7 25093.0 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:35216.99 Median:13784.31 stddev:104995.08 (761.7%) stddev-sans-first:105257.01 04:41:03 INFO - PROCESS | 1946 | Values: 365.9 371.8 397.5 408.0 410.1 16479.7 18601.8 20952.5 21957.0 21992.6 19917.0 20758.6 21275.1 21572.5 21575.8 5316.4 5452.2 5493.6 5521.7 5526.9 4482.8 5409.5 5453.2 5453.6 5464.4 5438.8 5455.3 5458.5 5461.0 5462.2 5327.0 6012.7 6013.7 6015.7 6018.3 5311.1 5361.5 5368.9 5373.2 5375.4 25138.7 25591.6 25979.7 25999.4 26090.3 2128.7 2184.7 2429.9 2688.0 2867.2 9623.0 9808.4 10216.1 12500.3 14902.9 1725.9 1799.5 1811.3 1812.8 1915.1 97655.0 114809.4 119087.4 121380.4 124001.1 11650.8 11736.5 11782.8 11789.4 11799.6 14878.3 14950.3 14967.0 14986.3 14995.8 8826.3 8873.8 8927.5 9018.0 9064.9 8988.7 9009.1 9023.6 9114.8 9140.5 4244.8 4552.8 4638.4 4654.4 4734.8 1769.6 1877.2 1976.4 1986.3 2018.4 877.4 993.4 1033.6 1036.5 1051.8 18712.6 18829.9 19324.4 19768.5 19827.4 950.4 973.9 983.5 992.6 1002.2 10454.6 10579.3 10638.0 10642.6 10751.8 623340.0 625266.0 635281.0 639537.0 640025.0 118738.4 119864.0 121510.0 122301.1 122329.0 1009.5 1019.7 1026.9 1033.9 1037.8 17245.0 17319.6 17409.2 17477.2 17526.6 37047.0 49574.0 49626.0 50304.0 50758.0 64491.0 69359.7 70189.3 70252.0 70767.3 7310.2 8003.5 8074.3 8134.9 8451.1 11294.1 12650.0 12763.7 12957.6 12977.1 10667.7 11006.7 11206.4 11315.0 11395.5 10789.9 10952.8 11063.9 11241.0 11269.4 5654.6 6758.8 10035.7 11229.8 11368.1 7903.6 8005.2 8113.5 8185.8 8537.3 8809.5 11111.8 11118.4 11188.4 11236.1 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5655.56 Median:4901.92 stddev:3133.94 (63.9%) stddev-sans-first:3125.32 04:41:03 INFO - PROCESS | 1946 | Values: 1259.9 1578.4 1603.5 1839.3 1960.7 7884.2 8481.1 8712.7 8715.5 8721.3 8532.7 8582.7 8653.5 8678.2 8692.3 2160.1 2380.1 2441.0 2468.4 2469.5 2472.5 2478.8 2482.5 2486.0 2486.1 2385.6 2405.1 2423.6 2438.7 2446.8 2148.6 2388.6 2447.8 2447.8 2459.1 2415.2 2457.1 2457.6 2458.0 2463.0 8601.4 8675.2 8680.3 8690.8 8697.3 5271.7 5386.0 5414.5 5424.5 5426.5 3405.6 3459.3 3602.2 3607.5 3617.5 1032.5 1034.4 1035.8 1050.4 1062.6 11733.7 11785.9 11808.6 11822.9 11825.2 4764.6 4790.6 4828.3 4892.3 4911.5 9997.6 10068.2 10069.6 10069.7 10073.3 5428.5 5432.4 5473.4 5483.8 5487.2 4411.6 4525.0 4776.0 4935.8 4979.7 3476.4 3750.2 3773.2 3997.2 4094.9 1347.1 1354.7 1355.8 1370.0 1371.2 834.6 881.8 900.6 908.5 930.6 5682.1 5814.7 5852.9 5869.2 5960.0 880.5 947.9 964.7 987.2 1030.1 3716.5 3803.1 3828.8 3829.2 3862.8 10191.2 10448.9 10482.1 10634.7 10658.4 10510.9 10537.5 10545.6 10670.0 10674.7 8017.9 8129.2 8262.7 8349.3 8358.2 5601.0 5646.2 5675.2 5728.0 5801.5 9292.6 9446.8 9567.2 9573.2 9590.7 9229.7 9551.8 9689.0 9746.8 9754.9 6773.9 6973.1 6975.5 7396.3 7570.1 8035.7 8177.3 8204.3 8236.3 8255.1 7232.2 7341.6 7380.5 7427.7 7496.3 7244.6 7319.7 7322.5 7374.8 7385.2 6922.5 6951.7 6956.3 6959.9 6962.3 4160.2 4671.3 4684.2 4703.3 4845.4 4764.2 4817.2 4857.6 4863.3 5001.2 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:5717.46 Median:6543.73 stddev:1848.29 (28.2%) stddev-sans-first:1808.63 04:41:03 INFO - PROCESS | 1946 | Values: 326.2 331.1 336.7 336.9 340.0 6549.3 6605.9 6609.5 6644.4 6651.6 5356.2 6558.0 6564.6 6581.0 6626.0 4620.1 4709.1 4710.8 4717.1 4878.2 3998.4 4798.5 4853.1 4902.0 4920.4 4729.0 4836.8 4837.9 4839.2 4850.3 4612.4 4750.4 4774.1 4827.9 4839.5 4003.1 4071.9 4083.9 4092.4 4121.0 5090.9 6285.0 6287.2 6291.0 6306.4 4669.7 5666.0 5676.3 5676.5 5713.8 6957.1 6977.0 6989.2 6990.0 6992.9 4380.1 4534.3 4535.3 4661.3 4671.0 6313.8 7182.9 7364.4 7367.9 7453.8 4625.5 4628.2 4630.0 4635.0 4635.7 4691.6 4694.2 4698.4 4711.3 4724.5 4553.6 4676.3 5584.2 5595.2 5597.0 6761.5 6981.4 7029.2 7047.9 7052.2 1617.8 1629.4 1642.9 1646.1 1650.9 852.7 1021.8 1043.2 1045.5 1047.6 6313.3 6507.4 6508.2 6530.6 6538.2 7183.2 7297.0 7310.2 7366.8 7382.6 5759.0 6224.2 6559.3 6582.8 6594.8 3837.2 3881.9 3884.7 3892.9 3895.0 7344.4 7712.7 7717.9 7725.6 7750.5 6158.8 7489.4 7566.4 7573.7 7586.4 6229.3 7670.1 7673.1 7677.3 7734.0 6579.6 6636.7 7310.3 7315.8 7355.5 6211.7 7210.0 7210.6 7214.8 7219.6 7681.5 7747.8 7754.6 7761.0 7767.5 4616.9 4664.7 4682.4 4689.3 4693.7 7303.7 7304.8 7305.6 7316.6 7336.2 6776.3 7022.6 7052.7 7092.6 7117.9 7015.5 7043.4 7057.6 7075.5 7076.3 6935.5 6935.8 6944.9 6958.5 6962.7 7077.3 7094.1 7095.5 7098.0 7103.3 6672.8 6928.1 6942.5 6957.9 6976.5 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:17716.60 Median:628.21 stddev:24229.00 (3856.8%) stddev-sans-first:24262.83 04:41:03 INFO - PROCESS | 1946 | Values: 587.4 642.3 742.7 745.1 751.8 15090.8 15297.2 15335.2 15381.6 16999.0 15789.7 15891.4 15917.0 15928.4 15932.9 723.2 739.5 889.8 929.2 932.7 904.4 926.6 927.7 929.7 930.2 963.2 964.4 968.2 969.9 970.3 755.6 936.8 963.5 965.4 966.1 709.5 710.1 710.4 713.3 715.0 15172.2 15286.9 15288.1 15294.9 15328.7 12227.1 12427.6 12531.1 12579.0 12618.9 9929.8 10094.9 10212.6 10407.1 10429.2 3398.8 3448.4 3450.6 3512.2 3512.6 78710.6 79925.6 80406.2 80707.0 80871.2 8271.0 8712.5 8727.7 8949.7 9211.4 19602.0 20843.7 20938.8 20990.7 21061.2 7310.7 7344.5 7418.6 7430.2 7836.4 4023.7 4079.7 4143.8 4189.7 4325.2 3126.4 3623.7 3661.9 3686.5 3687.0 645.2 663.8 667.6 670.0 686.8 468.5 502.7 531.0 592.3 603.9 8003.1 8045.1 8113.2 8218.3 8225.3 514.0 605.6 607.9 612.8 614.1 3637.6 3765.5 3777.4 3824.3 4027.1 81062.6 81082.0 81681.0 81754.0 82799.0 71748.0 72944.0 73268.5 73922.3 76316.6 53908.4 66117.3 68189.0 68885.7 69378.0 8213.4 8298.2 8391.6 8466.2 8543.4 45180.9 47320.5 47488.0 47507.6 48488.3 65684.0 67908.7 68328.0 69529.0 70373.6 10739.8 11227.8 11250.9 11271.5 11349.4 11504.3 11978.6 11982.8 12256.8 12365.8 11303.7 11353.9 11418.3 11432.1 11665.9 11079.6 11246.3 11388.7 11413.4 11590.2 10479.7 10997.9 11567.3 11792.0 12010.9 9632.2 9732.0 9767.6 9817.4 9902.0 9457.3 9656.5 9691.1 9706.0 9740.9 04:41:03 INFO - PROCESS | 1946 | -------- Summary: end -------- 04:41:03 INFO - PROCESS | 1946 | 04:41:03 INFO - TEST-INFO | 1946: exit 0 04:41:03 INFO - TEST-OK | dromaeo_css | took 1116226ms 04:41:03 INFO - TEST-START | kraken 04:41:03 INFO - operating with platform_type : mac_ 04:41:03 INFO - Initialising browser for kraken test... 04:41:03 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp1BaBN9/profile http://localhost:51953/getInfo.html) 04:41:05 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 04:41:05 INFO - PROCESS | 1951 | colorDepth:24 04:41:05 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 04:41:05 INFO - PROCESS | 1951 | __metrics 04:41:06 INFO - PROCESS | 1951 | 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:41:06 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:41:06 INFO - TEST-INFO | 1951: exit 0 04:41:06 INFO - Browser initialized. 04:41:06 INFO - Running cycle 1/1 for kraken test... 04:41:06 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp1BaBN9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:18 INFO - PROCESS | 1953 | RSS: Main: 211431424 04:41:18 INFO - PROCESS | 1953 | 04:41:21 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51953/tests/kraken/driver.html?testName=audio-beat-detection) 04:41:22 INFO - PROCESS | 1953 | RSS: Main: 271855616 04:41:22 INFO - PROCESS | 1953 | 04:41:25 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51953/tests/kraken/driver.html?testName=audio-dft) 04:41:25 INFO - PROCESS | 1953 | RSS: Main: 286244864 04:41:25 INFO - PROCESS | 1953 | 04:41:28 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51953/tests/kraken/driver.html?testName=audio-fft) 04:41:29 INFO - PROCESS | 1953 | RSS: Main: 375529472 04:41:29 INFO - PROCESS | 1953 | 04:41:31 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51953/tests/kraken/driver.html?testName=audio-oscillator) 04:41:32 INFO - PROCESS | 1953 | RSS: Main: 279457792 04:41:32 INFO - PROCESS | 1953 | 04:41:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51953/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:41:34 INFO - PROCESS | 1953 | RSS: Main: 279973888 04:41:34 INFO - PROCESS | 1953 | 04:41:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51953/tests/kraken/driver.html?testName=imaging-darkroom) 04:41:38 INFO - PROCESS | 1953 | RSS: Main: 319098880 04:41:38 INFO - PROCESS | 1953 | 04:41:41 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51953/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:41 INFO - PROCESS | 1953 | RSS: Main: 319598592 04:41:41 INFO - PROCESS | 1953 | 04:41:44 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51953/tests/kraken/driver.html?testName=json-parse-financial) 04:41:45 INFO - PROCESS | 1953 | RSS: Main: 318316544 04:41:45 INFO - PROCESS | 1953 | 04:41:46 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51953/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:47 INFO - PROCESS | 1953 | RSS: Main: 309469184 04:41:47 INFO - PROCESS | 1953 | 04:41:49 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:49 INFO - PROCESS | 1953 | RSS: Main: 315715584 04:41:49 INFO - PROCESS | 1953 | 04:41:52 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:52 INFO - PROCESS | 1953 | RSS: Main: 319139840 04:41:52 INFO - PROCESS | 1953 | 04:41:55 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:56 INFO - PROCESS | 1953 | RSS: Main: 321200128 04:41:56 INFO - PROCESS | 1953 | 04:41:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:59 INFO - PROCESS | 1953 | RSS: Main: 317767680 04:41:59 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51953/tests/kraken/driver.html?testName=ai-astar) 04:42:01 INFO - PROCESS | 1953 | RSS: Main: 381779968 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | __start_tp_report 04:42:01 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 04:42:01 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 04:42:01 INFO - PROCESS | 1953 | |i|pagename|runs| 04:42:01 INFO - PROCESS | 1953 | |0;ai-astar;114;113;119;115;113;112;114;114;114;134 04:42:01 INFO - PROCESS | 1953 | |1;audio-beat-detection;162;150;171;142;123;171;145;174;161;120 04:42:01 INFO - PROCESS | 1953 | |2;audio-dft;183;184;188;186;187;184;185;188;185;186 04:42:01 INFO - PROCESS | 1953 | |3;audio-fft;69;79;76;151;56;56;64;68;159;81 04:42:01 INFO - PROCESS | 1953 | |4;audio-oscillator;90;88;141;77;82;86;124;76;76;122 04:42:01 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;90;87;91;88;91;88;92;83;89;87 04:42:01 INFO - PROCESS | 1953 | |6;imaging-darkroom;100;149;109;97;95;95;96;95;95;108 04:42:01 INFO - PROCESS | 1953 | |7;imaging-desaturate;84;86;86;85;84;85;85;84;83;84 04:42:01 INFO - PROCESS | 1953 | |8;json-parse-financial;59;64;62;63;63;65;64;79;61;59 04:42:01 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;66;69;73;78;79;72;70;72;68;62 04:42:01 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;82;82;83;80;82;81;94;110;106;86 04:42:01 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;132;131;127;128;128;125;130;130;175;208 04:42:01 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;171;169;169;171;165;169;170;168;160;170 04:42:01 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;67;68;70;68;68;67;68;68;66;68 04:42:01 INFO - PROCESS | 1953 | __end_tp_report 04:42:01 INFO - PROCESS | 1953 | __start_cc_report 04:42:01 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2111 04:42:01 INFO - PROCESS | 1953 | __end_cc_report 04:42:01 INFO - PROCESS | 1953 | __startTimestamp1462534921786__endTimestamp 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | ------- Summary: start ------- 04:42:01 INFO - PROCESS | 1953 | Number of tests: 14 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:116.20 Median:114.00 stddev:6.53 (5.7%) stddev-sans-first:6.88 04:42:01 INFO - PROCESS | 1953 | Values: 114.0 113.0 119.0 115.0 113.0 112.0 114.0 114.0 114.0 134.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:161.50 stddev:19.45 (12.0%) stddev-sans-first:20.28 04:42:01 INFO - PROCESS | 1953 | Values: 162.0 150.0 171.0 142.0 123.0 171.0 145.0 174.0 161.0 120.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.00 stddev:1.71 (0.9%) stddev-sans-first:1.54 04:42:01 INFO - PROCESS | 1953 | Values: 183.0 184.0 188.0 186.0 187.0 184.0 185.0 188.0 185.0 186.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:85.90 Median:68.50 stddev:37.45 (54.7%) stddev-sans-first:39.22 04:42:01 INFO - PROCESS | 1953 | Values: 69.0 79.0 76.0 151.0 56.0 56.0 64.0 68.0 159.0 81.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:79.50 stddev:23.67 (29.8%) stddev-sans-first:25.00 04:42:01 INFO - PROCESS | 1953 | Values: 90.0 88.0 141.0 77.0 82.0 86.0 124.0 76.0 76.0 122.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:2.63 (2.9%) stddev-sans-first:2.74 04:42:01 INFO - PROCESS | 1953 | Values: 90.0 87.0 91.0 88.0 91.0 88.0 92.0 83.0 89.0 87.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:95.00 stddev:16.73 (17.6%) stddev-sans-first:17.68 04:42:01 INFO - PROCESS | 1953 | Values: 100.0 149.0 109.0 97.0 95.0 95.0 96.0 95.0 95.0 108.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 04:42:01 INFO - PROCESS | 1953 | Values: 84.0 86.0 86.0 85.0 84.0 85.0 85.0 84.0 83.0 84.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:63.50 stddev:5.69 (9.0%) stddev-sans-first:5.75 04:42:01 INFO - PROCESS | 1953 | Values: 59.0 64.0 62.0 63.0 63.0 65.0 64.0 79.0 61.0 59.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:5.15 (7.2%) stddev-sans-first:5.15 04:42:01 INFO - PROCESS | 1953 | Values: 66.0 69.0 73.0 78.0 79.0 72.0 70.0 72.0 68.0 62.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:82.00 stddev:11.01 (13.4%) stddev-sans-first:11.41 04:42:01 INFO - PROCESS | 1953 | Values: 82.0 82.0 83.0 80.0 82.0 81.0 94.0 110.0 106.0 86.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:130.50 stddev:27.60 (21.2%) stddev-sans-first:29.07 04:42:01 INFO - PROCESS | 1953 | Values: 132.0 131.0 127.0 128.0 128.0 125.0 130.0 130.0 175.0 208.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:169.50 stddev:3.36 (2.0%) stddev-sans-first:3.41 04:42:01 INFO - PROCESS | 1953 | Values: 171.0 169.0 169.0 171.0 165.0 169.0 170.0 168.0 160.0 170.0 04:42:01 INFO - PROCESS | 1953 | 04:42:01 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 04:42:01 INFO - PROCESS | 1953 | Values: 67.0 68.0 70.0 68.0 68.0 67.0 68.0 68.0 66.0 68.0 04:42:01 INFO - PROCESS | 1953 | -------- Summary: end -------- 04:42:01 INFO - PROCESS | 1953 | 04:42:02 INFO - TEST-INFO | 1953: exit 0 04:42:02 INFO - TEST-OK | kraken | took 58879ms 04:42:02 INFO - SUITE-END | took 1175s 04:42:02 INFO - Completed test suite (00:19:35) 04:42:02 INFO - javascript benchmark 04:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.7165255127043, 3447.517567445999, 3456.170519075422, 3469.5142680024815, 3494.91262718432, 5776.335721392036, 5786.161006654043, 5791.102379131231, 5803.3906439823795, 5825.475707186358, 4845.3216549683075, 5794.5943783935145, 5805.970970145154, 5850.76596936122, 5873.35393106759, 5170.327857378555, 5234.607404444663, 5237.528622423981, 5353.7590808413715, 5391.73041347933, 5301.840944771644, 5317.734113294339, 5318.042752304584, 5336.59975501837, 5339.359276886759, 5250.4487028861995, 5282.07563676357, 5299.993001329754, 5314.627976041678, 5318.601104917147, 5309.3363329584, 5319.893602127936, 5326.174442961308, 5345.893082138336, 5363.222332761767, 5131.307273518065, 5161.0, 5180.067587834226, 5237.16205407133, 5255.421903590602, 5620.201535508639, 5623.803166889139, 5635.154726790973, 5685.914711279334, 5698.287714035746, 6316.084167795644, 6318.589291696025, 6347.87304253917, 6357.523185761041, 6358.300586935434, 5062.594992400599, 6252.187215661935, 6302.9369706302805, 6334.3032266450655, 6354.332795056499, 5884.146798714248, 5909.409059094056, 5917.112433135021, 5950.256217972754, 5953.3748956359395, 6306.716197771111, 6340.8414789629705, 6353.30113687494, 6359.777407790705, 6361.586496877688, 6029.60807547508, 6120.081455037911, 6140.662263575547, 6259.467945224706, 6269.0, 6283.8114856554375, 6305.274893387227, 6318.115463835067, 6324.3043265240785, 6344.048392741033, 6211.627302361872, 6279.0895320178315, 6280.214973128359, 6287.528435367366, 6307.337729538424, 6140.04415716754, 6243.656598887014, 6262.843428914313, 6334.809955701337, 6343.238811342669, 4939.481354457802, 4966.329545511382, 4966.751662416865, 4970.478099799541, 4974.552290293818, 4500.459944806644, 4524.524924882906, 4543.454785425704, 4544.88637784058, 4553.362529245908, 5713.428657134336, 5787.102999035072, 5789.478946894799, 5793.420657934173, 5832.154337621021, 6225.439710426083, 6299.1811064562235, 6310.242770867433, 6328.778492752732, 6330.746770129143, 5555.972220138873, 5678.4037676044245, 5771.769129234867, 5783.739731712083, 5787.160861675034, 5438.483344082385, 5451.100568406168, 5461.208124821878, 5464.26232458621, 5464.398916119224, 6343.904841427382, 6382.776602818879, 6387.808365749035, 6414.711337989895, 6433.646149461824, 6294.087357333253, 6303.968480157753, 6314.936850631435, 6322.841928951813, 6347.96826015867, 6264.029075493306, 6454.548181627241, 6472.935270647422, 6478.060681201106, 6498.187726534184, 5183.222516622537, 5239.685618862881, 5716.79991200306, 5730.882477916766, 6153.600015998945, 6301.506039067657, 6310.432061114522, 6314.526410519321, 6332.651704156131, 6337.6197428154455, 6141.946315431794, 6167.506599472121, 6175.722092505938, 6185.443310102112, 6209.254889413119, 5958.225430694156, 6052.122442245762, 6147.862645410613, 6169.84575385619, 6190.442860142609, 6091.9814604840985, 6092.786752463642, 6099.146119543356, 6103.908441373472, 6104.145419641342, 6201.658908760061, 6220.066989951543, 6222.097795819672, 6222.657753823402, 6225.221847269091, 5784.913226301693, 5964.850878728066, 5973.522118230618, 5987.910181347196, 6085.756569737338], "value": 5745.906868060829, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.9175136102543, 509.5541401273885, 518.4064246437829, 523.6256076905012, 583.8803045375698, 31163.0, 31744.68255317446, 31804.727810887565, 39556.604433955654, 39824.601753982526, 42417.72746817593, 42891.142177156435, 43079.0, 43092.78453607736, 43197.56802431975, 7915.208479152082, 9724.416535007922, 9730.221582273402, 9733.659321923735, 9740.999999999965, 8608.784780380478, 8813.73558793234, 10870.184736144782, 10982.231243812936, 11531.654050378502, 11528.135389845753, 11597.710057248594, 11606.303621782678, 11631.127665425085, 11632.243904146246, 8602.569871506463, 11447.141464390163, 11533.480993355319, 11550.4802283897, 11651.59219427316, 11347.773044539063, 11470.139739519569, 11484.310941343547, 11501.712457188638, 11505.367204803817, 38746.80626596849, 39349.0, 39372.60627393718, 39421.60578394208, 39575.40636890449, 3238.3523295341024, 3266.6080070391704, 3456.2569047654624, 3512.701420379271, 3794.658480736747, 23732.64401033986, 27950.580741288897, 28901.42197156066, 28909.855450722822, 29551.556726649116, 2725.5775354820034, 2753.7246275372504, 2754.476649436601, 2885.9855700721573, 2897.174305322994, 354289.0, 475133.0, 476407.0, 477205.0, 477248.0, 36363.63636363656, 36723.4491482628, 36767.81616091956, 62934.685326573075, 64357.356426436076, 35654.28691426157, 35673.10817229538, 35679.0, 35718.0, 35780.82109589488, 15296.464623738339, 15418.306176222097, 15458.922705386625, 15459.0, 15459.227038648023, 12037.638870833889, 12132.0, 12978.221306721627, 13072.019598530149, 13174.341282935815, 7935.206479352134, 7954.960225198837, 7980.600969951479, 8007.879881801778, 8063.395245356622, 3313.370459612666, 3321.368939901411, 3329.267561136546, 3333.633300336961, 3335.5163501292177, 867.3364875869964, 931.0308697105573, 935.7239614313794, 979.8089372572423, 1004.6885465505717, 28289.858550707526, 29473.85263073671, 29651.1104666856, 30211.848940755157, 30289.0, 882.2144574133299, 931.8415869302235, 932.4032619123766, 1109.7891400633853, 1178.8231765235112, 14533.709325813636, 14673.92663036678, 14808.925955370154, 15019.173945432887, 15042.774358384633, 611639.0, 612675.0, 621066.0, 621954.0, 623797.0, 60566.0, 61330.69334653298, 61421.0, 61427.692861535405, 61478.69260653668, 1325.8806707396382, 1470.5661829760033, 1497.1391449916123, 1498.5654160293611, 1510.1392206442442, 23776.643350350103, 28420.14739557734, 28749.137525874256, 29312.0, 29360.559591605717, 47688.761556191996, 47786.0, 48175.5182448171, 48185.0, 48205.7948551289, 368718.0, 370948.0, 371368.0, 371445.0, 372514.0, 23805.285841424786, 26769.33076673099, 27024.1892743218, 27224.863875680494, 27293.317667058483, 34484.482732759534, 41304.554340599134, 42391.7880410596, 43005.0, 43891.0, 35223.47164792479, 35543.93368198958, 35898.64101358953, 35913.46129808003, 36421.81789091037, 29140.417191655622, 35438.0, 35959.741409050555, 35964.46053309255, 36326.636733632324, 36027.0, 36514.8174259127, 36719.0, 37103.0, 37307.88076357714, 31852.68147318497, 32471.512927306583, 32654.857080002086, 32716.509252360756, 32720.854770082933, 24516.632250516614, 24604.507909841344, 24762.25713228606, 25027.74972250327, 25093.0], "value": 18153.18201167331, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.8829174664108, 371.7824125987391, 397.5150316613732, 407.9938800917987, 410.07528024305185, 16479.670406591875, 18601.813981860196, 20952.476188095305, 21957.01193446295, 21992.560148797016, 19917.0, 20758.584828303425, 21275.14899404022, 21572.460688482795, 21575.784242157573, 5316.388615309241, 5452.236686863842, 5493.642913210649, 5521.723913804314, 5526.917096243559, 4482.843100491484, 5409.513143817056, 5453.182022696587, 5453.618246722731, 5464.398916119244, 5438.782448702047, 5455.318085239345, 5458.4814442628085, 5460.972695136538, 5462.180672899057, 5326.973365133169, 6012.729427175787, 6013.6993150342305, 6015.669138197398, 6018.337982821886, 5311.070562651559, 5361.517463428271, 5368.892622147535, 5373.194020896858, 5375.408705042442, 25138.748612513824, 25591.61612575813, 25979.74020259811, 25999.350016249555, 26090.34774130643, 2128.6594144936735, 2184.683220932972, 2429.866357350345, 2687.9920029988753, 2867.16852112887, 9622.951885240598, 9808.362456440309, 10216.080552750214, 12500.312482813442, 14902.859420145396, 1725.8791884568086, 1799.4691565987935, 1811.293595497757, 1812.7824661040759, 1915.0520492356234, 97655.0, 114809.42595287053, 119087.40456297663, 121380.39309803482, 124001.13998290033, 11650.76698466043, 11736.471858766376, 11782.764344713058, 11789.387860619341, 11799.646010619696, 14878.256087195598, 14950.252487375588, 14967.0, 14986.325615347117, 14995.800335973096, 8826.293896488265, 8873.778155546033, 8927.464352138892, 9017.954910225539, 9064.864027039599, 8988.685396011108, 9009.054049324857, 9023.639054437881, 9114.817703646022, 9140.542972851465, 4244.766537840448, 4552.840650577214, 4638.443386793607, 4654.4414670239785, 4734.763261836894, 1769.6054413926217, 1877.249100359867, 1976.416956997689, 1986.2750096214777, 2018.3541266794484, 877.4033657113097, 993.4436715439233, 1033.6020632056711, 1036.4765793274348, 1051.8369652703843, 18712.625747484974, 18829.905850470936, 19324.420267392, 19768.505787355145, 19827.40517784469, 950.3775027357092, 973.9269554783419, 983.4640121133999, 992.6029588164791, 1002.2332915319786, 10454.63408780689, 10579.31234469757, 10638.0, 10642.627508037182, 10751.78496430067, 623340.0, 625266.0, 635281.0, 639537.0, 640025.0, 118738.40630796964, 119864.0, 121510.0, 122301.10795568256, 122329.0, 1009.4700282351824, 1019.7297716105238, 1026.9229807764598, 1033.8655974723388, 1037.7509397744639, 17244.965302081917, 17319.56701082483, 17409.21658525344, 17477.213525391133, 17526.561835954202, 37047.0, 49574.0, 49626.0, 50304.0, 50758.0, 64491.0, 69359.65320173367, 70189.29810702032, 70252.0, 70767.29232707813, 7310.232425595236, 8003.4797738146835, 8074.313683336982, 8134.87797683047, 8451.070382257823, 11294.096472282034, 12650.0, 12763.744725105631, 12957.611271661865, 12977.091603587658, 10667.733306667395, 11006.724831879266, 11206.38364889939, 11315.038221751243, 11395.487203075716, 10789.946050269698, 10952.835707464235, 11063.944680276547, 11241.04451121631, 11269.436528173723, 5654.604177707521, 6758.763443279461, 10035.69892903214, 11229.7754044917, 11368.147388946027, 7903.56530390834, 8005.239502247277, 8113.472624279403, 8185.754427367188, 8537.274331681629, 8809.471431714117, 11111.777764444505, 11118.388488633203, 11188.384638844942, 11236.101111911192], "value": 9456.107056451654, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.9055070869676, 1578.3765412661999, 1603.5349748572914, 1839.264294282287, 1960.6960921057241, 7884.211578842113, 8481.109483504228, 8712.651493940242, 8715.520646364448, 8721.258693011101, 8532.744017679464, 8582.656693732259, 8653.480791152535, 8678.218960293572, 8692.30461563075, 2160.0819651648067, 2380.071772008918, 2441.035790862604, 2468.4445999650115, 2469.49375378048, 2472.4807790363957, 2478.7769100780924, 2482.491089326693, 2486.005597760892, 2486.105002199207, 2385.582523058458, 2405.122130422392, 2423.5516429460517, 2438.6829712137487, 2446.8409553378974, 2148.581026699794, 2388.641703744435, 2447.8041756659422, 2447.816413768957, 2459.102427613917, 2415.1667674652217, 2457.0908763757525, 2457.6313552967017, 2457.9754202457925, 2462.963055554168, 8601.354898382646, 8675.175858293453, 8680.30557555394, 8690.782730431727, 8697.304215662733, 5271.683698978073, 5386.0, 5414.539764120054, 5424.457554244544, 5426.511613954763, 3405.5743032120986, 3459.3081383723106, 3602.189507360823, 3607.549056367954, 3617.5116359291424, 1032.5405194688367, 1034.4414016431117, 1035.8342665173611, 1050.4432650695144, 1062.5802807890916, 11733.706657333634, 11785.941070294593, 11808.586699465563, 11822.940885295518, 11825.231359961661, 4764.595009424238, 4790.616750659939, 4828.348172996671, 4892.315075889378, 4911.533404326655, 9997.550110245056, 10068.244881633907, 10069.647562335284, 10069.748256293651, 10073.29486935922, 5428.511433970962, 5432.375276843135, 5473.425290344536, 5483.835484935458, 5487.204355368529, 4411.602955733999, 4525.004499010212, 4776.020915712271, 4935.7532123393685, 4979.676321039194, 3476.42638964568, 3750.1749615084636, 3773.2264885698364, 3997.2405243003736, 4094.856680016245, 1347.0839828916235, 1354.688421663023, 1355.8440779310506, 1369.9589012329645, 1371.2389623758909, 834.6369329341634, 881.8280435315052, 900.599233341157, 908.4730856103445, 930.5812384427035, 5682.147677848356, 5814.651120932757, 5852.853678658068, 5869.178315035899, 5960.046392577254, 880.5201165364891, 947.8530827721714, 964.6671898195111, 987.2151639446657, 1030.05749738988, 3716.5168528091717, 3803.087259057806, 3828.8085595720104, 3829.2341531693774, 3862.7682339059747, 10191.23565732573, 10448.947755261328, 10482.10902073317, 10634.73413164677, 10658.360498370123, 10510.894891050992, 10537.473126343652, 10545.630902918361, 10670.0, 10674.679759607225, 8017.919820801834, 8129.227723366271, 8262.710805121791, 8349.290310323691, 8358.247757701836, 5600.971995140161, 5646.209530665628, 5675.205471233948, 5727.971360143172, 5801.535877129735, 9292.62829486813, 9446.811063778548, 9567.186789122725, 9573.18627916607, 9590.712278631652, 9229.723108306762, 9551.761205969906, 9689.0, 9746.805063898824, 9754.9024509754, 6773.864522709617, 6973.093497845248, 6975.511714179958, 7396.260373962776, 7570.05374328209, 8035.718749843727, 8177.304929081095, 8204.302634275915, 8236.299914507093, 8255.091939886488, 7232.204457509775, 7341.632918353954, 7380.483366164317, 7427.740029098955, 7496.287852653989, 7244.573756759536, 7319.670614822239, 7322.524035937647, 7374.815629609303, 7385.187629360874, 6922.5154239204785, 6951.652417379212, 6956.304369563003, 6959.89560156588, 6962.303769622998, 4160.209560183555, 4671.346011558317, 4684.203685373426, 4703.3415321855655, 4845.394325709286, 4764.213904705824, 4817.156997525349, 4857.635677324144, 4863.294822250683, 5001.174806156943], "value": 4574.967136050065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.1910462054106, 331.0961076261805, 336.69192688689844, 336.9477730951702, 339.97450191235663, 6549.279579246279, 6605.910024845894, 6609.537332386735, 6644.402003819655, 6651.600903945755, 5356.223347614594, 6558.049082882979, 6564.606123632585, 6581.0, 6626.039224312472, 4620.099080679269, 4709.128811169928, 4710.811567537295, 4717.1037502874515, 4878.243872199815, 3998.400239964014, 4798.544138306873, 4853.077915196119, 4902.014684052964, 4920.434150072751, 4728.983268597251, 4836.830710925118, 4837.854864354075, 4839.177339852216, 4850.296706977486, 4612.3542704021465, 4750.406199225097, 4774.140654682155, 4827.8551643450755, 4839.540243676827, 4003.11931375097, 4071.89820254496, 4083.9183216335505, 4092.406601042862, 4120.958790412087, 5090.949090509084, 6285.0, 6287.182666253358, 6291.0, 6306.432421082125, 4669.696469729457, 5665.951798917214, 5676.262085928803, 5676.545876329925, 5713.800016999385, 6957.130358705162, 6976.965115174442, 6989.161300643905, 6989.965050174767, 6992.895106573405, 4380.102079073782, 4534.274516077445, 4535.342375355588, 4661.347411362412, 4671.019085991912, 6313.842153946188, 7182.850743880954, 7364.410847132217, 7367.852642947165, 7453.776386708408, 4625.4911959684405, 4628.190066738374, 4630.027694184192, 4635.0, 4635.675502714777, 4691.577758001797, 4694.201985662377, 4698.436187657503, 4711.316859055418, 4724.480307166209, 4553.612942899825, 4676.298555216745, 5584.162375643605, 5595.216669666169, 5596.972015139898, 6761.526693131434, 6981.406580440618, 7029.191642961023, 7047.92952070483, 7052.224255331909, 1617.765424013535, 1629.3564042203263, 1642.89321194122, 1646.1275523972313, 1650.917454127289, 852.6845067325005, 1021.8351079769069, 1043.1848583464716, 1045.5399624165343, 1047.5652604169336, 6313.305536390993, 6507.414332710079, 6508.153939987866, 6530.575512591667, 6538.1827271591055, 7183.173934997438, 7297.0, 7310.195878453366, 7366.77899663011, 7382.630868456556, 5759.0, 6224.159738435344, 6559.311272316433, 6582.76960306387, 6594.8351291218105, 3837.2325534893134, 3881.8641347553266, 3884.747491413049, 3892.8832135035996, 3895.0457138000806, 7344.412447004227, 7712.691492340244, 7717.922820771832, 7725.613719314124, 7750.457467977188, 6158.846028849225, 7489.438292128112, 7566.35685966688, 7573.659185336673, 7586.355159811367, 6229.345918678474, 7670.117936437381, 7673.11759147694, 7677.270659287359, 7734.03324584427, 6579.572327798678, 6636.668166591748, 7310.268973102648, 7315.780526584211, 7355.485116041826, 6211.720472578835, 7210.026646402668, 7210.639468026473, 7214.819629509304, 7219.60292183935, 7681.462297639335, 7747.845043099219, 7754.573498457414, 7761.0, 7767.495112817649, 4616.930746038879, 4664.650151238603, 4682.391289132527, 4689.273162659793, 4693.741844198602, 7303.707851685873, 7304.817379565553, 7305.561666300039, 7316.634168291671, 7336.156342020736, 6776.322367763381, 7022.578645281299, 7052.717891284291, 7092.6099064551945, 7117.857642847218, 7015.508914375945, 7043.36609705129, 7057.576545407292, 7075.469339799639, 7076.25699301566, 6935.514513983972, 6935.791926242221, 6944.895826562707, 6958.512904096665, 6962.686679099554, 7077.292270772881, 7094.148702155774, 7095.46783991213, 7098.0, 7103.25415831351, 6672.766453174309, 6928.1339832520935, 6942.51402401827, 6957.930420695729, 6976.511644184859], "value": 5101.207586038117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.389115320067, 642.31593353079, 742.6992068212376, 745.0872680965817, 751.7857410637965, 15090.849091509082, 15297.235138243084, 15335.156566388858, 15381.615459613487, 16999.0, 15789.684206315895, 15891.44379946702, 15917.0, 15928.362865485396, 15932.920335398336, 723.2405973727591, 739.5156172706859, 889.8353804546164, 929.2055292724724, 932.7388331267256, 904.3714618340256, 926.6247169896203, 927.7355953553239, 929.7071422501924, 930.2418528898926, 963.2294164668273, 964.3587014635259, 968.1818863060703, 969.8884628267733, 970.3013830042394, 755.5504474837463, 936.8454205056157, 963.5086106085912, 965.4303960663173, 966.0725703324781, 709.5423451873564, 710.1442761472414, 710.406810313389, 713.3401603516778, 714.9749758758419, 15172.241387930559, 15286.923565382212, 15288.082715037133, 15294.923525382303, 15328.693426131415, 12227.14409991301, 12427.627171184879, 12531.122821402505, 12578.953374714034, 12618.948903796943, 9929.75175620608, 10094.89905100947, 10212.642557510526, 10407.063364297173, 10429.217808664305, 3398.8130652814084, 3448.3620530201742, 3450.551428314328, 3512.1746389598425, 3512.648735126467, 78710.60644696797, 79925.60037199777, 80406.19593804046, 80707.0, 80871.19128808695, 8271.007479102425, 8712.477251364939, 8727.734478500583, 8949.731508054769, 9211.40125891815, 19602.0, 20843.687344689843, 20938.790612093988, 20990.685139722915, 21061.15755369768, 7310.744123955636, 7344.48588598793, 7418.629068546551, 7430.219826918204, 7836.373090152774, 4023.7384570002855, 4079.7348172369293, 4143.834246630161, 4189.727667701651, 4325.178216138924, 3126.3590963852357, 3623.655752703489, 3661.9267614647556, 3686.5391826021746, 3687.0, 645.2059810198523, 663.7521459655816, 667.6094484726392, 670.038494760021, 686.7973947685445, 468.50572645858944, 502.68582136164895, 530.9867253318698, 592.295168749187, 603.9335673075958, 8003.079645840687, 8045.115037345888, 8113.229243221885, 8218.342532597379, 8225.259726624632, 514.0498727232301, 605.6366180291877, 607.8936186167404, 612.8008397270905, 614.1095411653121, 3637.563492380931, 3765.548134223856, 3777.3578491656485, 3824.2733880562605, 4027.134166154291, 81062.59468702618, 81082.0, 81681.0, 81754.0, 82799.0, 71748.0, 72944.0, 73268.53462930818, 73922.26077739154, 76316.61841690756, 53908.38274851761, 66117.34706632372, 68189.0, 68885.65557172181, 69378.0, 8213.425060245965, 8298.21166989135, 8391.58042097905, 8466.153384661731, 8543.359248056297, 45180.87047823699, 47320.52679473161, 47488.0, 47507.574772756874, 48488.27267591059, 65684.0, 67908.6604566974, 68328.0, 69529.0, 70373.64813175901, 10739.785204296026, 11227.831582526105, 11250.943745281222, 11271.49278282463, 11349.375784331942, 11504.309741415542, 11978.58074967372, 11982.760344792881, 12256.816147757612, 12365.814512782135, 11303.660890173307, 11353.943230283796, 11418.257813242113, 11432.142589306002, 11665.88334116648, 11079.5568177272, 11246.325220486797, 11388.658340249805, 11413.429328533706, 11590.246633968765, 10479.738006549896, 10997.945010274898, 11567.305961641992, 11792.0, 12010.858968397988, 9632.229421646392, 9731.97814229524, 9767.560459779184, 9817.410955342417, 9902.0, 9457.33798634089, 9656.468894210886, 9691.12779849817, 9706.029397060238, 9740.902590974], "value": 6848.390914374977, "unit": "score"}], "name": "dromaeo_css", "value": 7349.953700007229, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 119.0, 115.0, 113.0, 112.0, 114.0, 114.0, 114.0, 134.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 150.0, 171.0, 142.0, 123.0, 171.0, 145.0, 174.0, 161.0, 120.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 188.0, 186.0, 187.0, 184.0, 185.0, 188.0, 185.0, 186.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 76.0, 151.0, 56.0, 56.0, 64.0, 68.0, 159.0, 81.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 141.0, 77.0, 82.0, 86.0, 124.0, 76.0, 76.0, 122.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 91.0, 88.0, 92.0, 83.0, 89.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 149.0, 109.0, 97.0, 95.0, 95.0, 96.0, 95.0, 95.0, 108.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 85.0, 84.0, 85.0, 85.0, 84.0, 83.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 62.0, 63.0, 63.0, 65.0, 64.0, 79.0, 61.0, 59.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 73.0, 78.0, 79.0, 72.0, 70.0, 72.0, 68.0, 62.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 80.0, 82.0, 81.0, 94.0, 110.0, 106.0, 86.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 131.0, 127.0, 128.0, 128.0, 125.0, 130.0, 130.0, 175.0, 208.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 169.0, 171.0, 165.0, 169.0, 170.0, 168.0, 160.0, 170.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 68.0, 68.0, 67.0, 68.0, 68.0, 66.0, 68.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1513.7, "alertThreshold": 2.0}]} 04:42:03 INFO - Return code: 0 04:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:42:03 ERROR - # TBPL SUCCESS # 04:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:42:03 INFO - 2016-05-06 04:42:03.108 screenresolution[1957:14314] starting screenresolution argv=screenresolution get 04:42:03 INFO - 2016-05-06 04:42:03.110 screenresolution[1957:14314] Display 0: 1600x1200x32@60 04:42:03 INFO - 2016-05-06 04:42:03.116 screenresolution[1958:14317] starting screenresolution argv=screenresolution list 04:42:03 INFO - Available Modes on Display 0 04:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:42:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:42:03 INFO - Intel Iris: 04:42:03 INFO - Chipset Model: Intel Iris 04:42:03 INFO - Type: GPU 04:42:03 INFO - Bus: Built-In 04:42:03 INFO - VRAM (Dynamic, Max): 1536 MB 04:42:03 INFO - Vendor: Intel (0x8086) 04:42:03 INFO - Device ID: 0x0a2e 04:42:03 INFO - Revision ID: 0x0009 04:42:03 INFO - Displays: 04:42:03 INFO - Display: 04:42:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:42:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:42:03 INFO - Display Serial Number: 1600x1200 60 04:42:03 INFO - Main Display: Yes 04:42:03 INFO - Mirror: Off 04:42:03 INFO - Online: Yes 04:42:03 INFO - Rotation: Supported 04:42:03 INFO - Return code: 0 04:42:03 INFO - Running post-action listener: _resource_record_post_action 04:42:03 INFO - Running post-run listener: _resource_record_post_run 04:42:04 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 659456; Write bytes: 650859008; Read time: 13; Write time: 1789 04:42:04 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354345472; Write bytes: 356136960; Read time: 28970; Write time: 672 04:42:04 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 630784; Write bytes: 293874176; Read time: 11; Write time: 1114 04:42:04 INFO - Running post-run listener: _upload_blobber_files 04:42:04 INFO - Blob upload gear active. 04:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:42:04 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:42:04 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:42:04 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:42:04 INFO - (blobuploader) - INFO - Open directory for files ... 04:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:05 INFO - (blobuploader) - INFO - Done attempting. 04:42:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:42:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:07 INFO - (blobuploader) - INFO - Done attempting. 04:42:07 INFO - (blobuploader) - INFO - Iteration through files over. 04:42:07 INFO - Return code: 0 04:42:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:42:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:42:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8d6127096858c534e6855000609572f7955d4f1abf5c445de1cc4c3b27e9d67dd4ba1b802bc80ea5bea0b2a14c8c7df3e5028cfb25e18c46eafecebf222d2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9b01879f65f0dd619040e1b2d872a04eff3bbddff635cd5a0a5c67fa9601f13508a43908a86481c221d17716a8aab52ed4276c0a474d6ace84ef5aea653baae"} 04:42:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:42:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:42:07 INFO - Contents: 04:42:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8d6127096858c534e6855000609572f7955d4f1abf5c445de1cc4c3b27e9d67dd4ba1b802bc80ea5bea0b2a14c8c7df3e5028cfb25e18c46eafecebf222d2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9b01879f65f0dd619040e1b2d872a04eff3bbddff635cd5a0a5c67fa9601f13508a43908a86481c221d17716a8aab52ed4276c0a474d6ace84ef5aea653baae"} 04:42:07 INFO - Running post-run listener: copy_logs_to_upload_dir 04:42:07 INFO - Copying logs to upload dir... 04:42:07 INFO - mkdir: /builds/slave/test/build/upload/logs 04:42:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.668539 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-06 04:42:07.091990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:42:07.095365) ========= 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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/0a8d6127096858c534e6855000609572f7955d4f1abf5c445de1cc4c3b27e9d67dd4ba1b802bc80ea5bea0b2a14c8c7df3e5028cfb25e18c46eafecebf222d2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9b01879f65f0dd619040e1b2d872a04eff3bbddff635cd5a0a5c67fa9601f13508a43908a86481c221d17716a8aab52ed4276c0a474d6ace84ef5aea653baae"} 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.011132 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/0a8d6127096858c534e6855000609572f7955d4f1abf5c445de1cc4c3b27e9d67dd4ba1b802bc80ea5bea0b2a14c8c7df3e5028cfb25e18c46eafecebf222d2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9b01879f65f0dd619040e1b2d872a04eff3bbddff635cd5a0a5c67fa9601f13508a43908a86481c221d17716a8aab52ed4276c0a474d6ace84ef5aea653baae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:42:07.122209) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:42:07.122514) ========= 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.Z3d7ZKx9qG/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.pKaNJtkkqB/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005222 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:42:07.170768) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:42:07.171089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:42:07.171377) ========= ========= Total master_lag: 0.15 =========