builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0045 starttime: 1465219065.6 results: success (0) buildid: 20160602140837 builduid: 893fdbbbce3544769bc98c5128e2bb0b revision: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:45.601985) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:45.602327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:45.602822) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:45.681019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:45.684302) ========= 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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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-06-06 06:17:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-06-06 06:17:46 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.497085 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:46.207349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:17:46.207655) ========= 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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.028089 ========= master_lag: 1.43 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:17:47.664507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:17:47.664845) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea --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-beta --rev 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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-06-06 06:17:47,747 truncating revision to first 12 chars 2016-06-06 06:17:47,748 Setting DEBUG logging. 2016-06-06 06:17:47,748 attempt 1/10 2016-06-06 06:17:47,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6e2399cf03e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 06:17:48,507 unpacking tar archive at: mozilla-beta-6e2399cf03e2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036161 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 06:17:48.733053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:48.733809) ========= script_repo_revision: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:48.734789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:48.735415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 06:17:48.755048) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-06 06:17:48.755717) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:17:48 INFO - MultiFileLogger online at 20160606 06:17:48 in /builds/slave/test 06:17:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 06:17:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:17:48 INFO - {'append_to_log': False, 06:17:48 INFO - 'base_work_dir': '/builds/slave/test', 06:17:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:17:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:17:48 INFO - 'branch': 'Mozilla-Beta', 06:17:48 INFO - 'buildbot_json_path': 'buildprops.json', 06:17:48 INFO - 'config_files': ('talos/mac_config.py',), 06:17:48 INFO - 'default_actions': ('clobber', 06:17:48 INFO - 'read-buildbot-config', 06:17:48 INFO - 'download-and-extract', 06:17:48 INFO - 'populate-webroot', 06:17:48 INFO - 'create-virtualenv', 06:17:48 INFO - 'install', 06:17:48 INFO - 'run-tests'), 06:17:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:17:48 INFO - 'download_minidump_stackwalk': True, 06:17:48 INFO - 'download_symbols': 'ondemand', 06:17:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:17:48 INFO - 'tooltool.py': '/tools/tooltool.py', 06:17:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:17:48 INFO - '/tools/misc-python/virtualenv.py')}, 06:17:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:17:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:17:48 INFO - 'installer_path': 'installer.exe', 06:17:48 INFO - 'log_level': 'info', 06:17:48 INFO - 'log_name': 'talos', 06:17:48 INFO - 'log_to_console': True, 06:17:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:17:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:17:48 INFO - 'opt_config_files': (), 06:17:48 INFO - 'pip_index': False, 06:17:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:48 INFO - 'cmd': ('bash', 06:17:48 INFO - '-c', 06:17:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:48 INFO - 'enabled': True, 06:17:48 INFO - 'halt_on_failure': False, 06:17:48 INFO - 'name': 'check_screen_resolution'},), 06:17:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:48 INFO - 'cmd': ('bash', 06:17:48 INFO - '-c', 06:17:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:48 INFO - 'enabled': True, 06:17:48 INFO - 'halt_on_failure': False, 06:17:48 INFO - 'name': 'check_screen_resolution'},), 06:17:48 INFO - 'run_cmd_checks_enabled': True, 06:17:48 INFO - 'sps_profile': False, 06:17:48 INFO - 'sps_profile_interval': 0, 06:17:48 INFO - 'suite': 'dromaeojs', 06:17:48 INFO - 'system_bits': '32', 06:17:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:17:48 INFO - 'title': 't-yosemite-r7-0045', 06:17:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:17:48 INFO - 'use_talos_json': True, 06:17:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:17:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:17:48 INFO - 'work_dir': 'build'} 06:17:48 INFO - ##### 06:17:48 INFO - ##### Running clobber step. 06:17:48 INFO - ##### 06:17:48 INFO - Running pre-action listener: _resource_record_pre_action 06:17:48 INFO - Running main action method: clobber 06:17:48 INFO - rmtree: /builds/slave/test/build 06:17:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:17:51 INFO - Running post-action listener: _resource_record_post_action 06:17:51 INFO - ##### 06:17:51 INFO - ##### Running read-buildbot-config step. 06:17:51 INFO - ##### 06:17:51 INFO - Running pre-action listener: _resource_record_pre_action 06:17:51 INFO - Running main action method: read_buildbot_config 06:17:51 INFO - Using buildbot properties: 06:17:51 INFO - { 06:17:51 INFO - "project": "", 06:17:51 INFO - "product": "firefox", 06:17:51 INFO - "script_repo_revision": "production", 06:17:51 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 06:17:51 INFO - "repository": "", 06:17:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 06:17:51 INFO - "buildid": "20160602140837", 06:17:51 INFO - "pgo_build": "False", 06:17:51 INFO - "basedir": "/builds/slave/test", 06:17:51 INFO - "buildnumber": 3, 06:17:51 INFO - "slavename": "t-yosemite-r7-0045", 06:17:51 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:17:51 INFO - "platform": "yosemite_r7", 06:17:51 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 06:17:51 INFO - "branch": "mozilla-beta", 06:17:51 INFO - "repo_path": "releases/mozilla-beta", 06:17:51 INFO - "revision": "6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea", 06:17:51 INFO - "stage_platform": "macosx64", 06:17:51 INFO - "builduid": "893fdbbbce3544769bc98c5128e2bb0b", 06:17:51 INFO - "slavebuilddir": "test" 06:17:51 INFO - } 06:17:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 06:17:51 INFO - Running post-action listener: _resource_record_post_action 06:17:51 INFO - ##### 06:17:51 INFO - ##### Running download-and-extract step. 06:17:51 INFO - ##### 06:17:51 INFO - Running pre-action listener: _resource_record_pre_action 06:17:51 INFO - Running main action method: download_and_extract 06:17:51 INFO - mkdir: /builds/slave/test/build/tests 06:17:51 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')]} 06:17:51 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:17:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 06:17:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 06:17:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 06:17:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 06:17:52 INFO - Downloaded 1241 bytes. 06:17:52 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 06:17:52 INFO - Using the following test package requirements: 06:17:52 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 06:17:52 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 06:17:52 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 06:17:52 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:17:52 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 06:17:52 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 06:17:52 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 06:17:52 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 06:17:52 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 06:17:52 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 06:17:52 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 06:17:52 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 06:17:52 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 06:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:17:52 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:17:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 06:17:54 INFO - Downloaded 17816944 bytes. 06:17:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:17:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:17:57 INFO - Return code: 0 06:17:57 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 06:17:57 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')]} 06:17:57 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:17:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 06:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 06:18:12 INFO - Downloaded 17816944 bytes. 06:18:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:18:16 INFO - Return code: 0 06:18:16 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')]} 06:18:16 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 06:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 06:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 06:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 06:18:17 INFO - Downloaded 10845194 bytes. 06:18:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:18:18 INFO - Return code: 0 06:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:18 INFO - https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 06:18:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 06:18:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 06:18:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 06:18:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 06:18:20 INFO - Downloaded 88466458 bytes. 06:18:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 06:18:20 INFO - mkdir: /builds/slave/test/properties 06:18:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:18:20 INFO - Writing to file /builds/slave/test/properties/build_url 06:18:20 INFO - Contents: 06:18:20 INFO - build_url:https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 06:18:21 INFO - Running post-action listener: _resource_record_post_action 06:18:21 INFO - Running post-action listener: set_extra_try_arguments 06:18:21 INFO - ##### 06:18:21 INFO - ##### Running populate-webroot step. 06:18:21 INFO - ##### 06:18:21 INFO - Running pre-action listener: _resource_record_pre_action 06:18:21 INFO - Running main action method: populate_webroot 06:18:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:18:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:18:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:18:21 INFO - '--tppagecycles', 06:18:21 INFO - '7'], 06:18:21 INFO - 'tests': ['tsvgm']}}, 06:18:21 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:18:21 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:18:21 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 06:18:21 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:18:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'tests': ['damp', 'tps']}, 06:18:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['damp', 'tps']}, 06:18:21 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:18:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:18:21 INFO - 'other': {'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'other_l64': {'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'other_nol64': {'tests': ['a11yr', 06:18:21 INFO - 'ts_paint', 06:18:21 INFO - 'tpaint', 06:18:21 INFO - 'sessionrestore', 06:18:21 INFO - 'sessionrestore_no_auto_restore', 06:18:21 INFO - 'tabpaint']}, 06:18:21 INFO - 'svgr': {'tests': ['tsvgx', 06:18:21 INFO - 'tsvgr_opacity', 06:18:21 INFO - 'tart', 06:18:21 INFO - 'tscrollx', 06:18:21 INFO - 'cart']}, 06:18:21 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['tsvgx', 06:18:21 INFO - 'tsvgr_opacity', 06:18:21 INFO - 'tart', 06:18:21 INFO - 'tscrollx', 06:18:21 INFO - 'cart']}, 06:18:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'tests': ['tp5o']}, 06:18:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'talos_options': ['--e10s'], 06:18:21 INFO - 'tests': ['tp5o']}, 06:18:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'talos_options': ['--xperf_path', 06:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:21 INFO - 'tests': ['tp5n']}, 06:18:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:21 INFO - 'talos_options': ['--e10s', 06:18:21 INFO - '--xperf_path', 06:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:21 INFO - 'tests': ['tp5n']}}, 06:18:21 INFO - 'talos.zip': {'path': '', 06:18:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:18:21 INFO - Running post-action listener: _resource_record_post_action 06:18:21 INFO - ##### 06:18:21 INFO - ##### Running create-virtualenv step. 06:18:21 INFO - ##### 06:18:21 INFO - Running pre-action listener: _resource_record_pre_action 06:18:21 INFO - Running main action method: create_virtualenv 06:18:21 INFO - Creating virtualenv /builds/slave/test/build/venv 06:18:21 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 06:18:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:18:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:18:21 INFO - Using real prefix '/tools/python27' 06:18:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:18:22 INFO - Installing distribute.............................................................................................................................................................................................done. 06:18:25 INFO - Installing pip.................done. 06:18:25 INFO - Return code: 0 06:18:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:25 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:25 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 06:18:25 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 06:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:25 INFO - 'HOME': '/Users/cltbld', 06:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:25 INFO - 'LOGNAME': 'cltbld', 06:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:25 INFO - 'MOZ_NO_REMOTE': '1', 06:18:25 INFO - 'NO_EM_RESTART': '1', 06:18:25 INFO - 'PAGER': '/bin/cat', 06:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:25 INFO - 'PWD': '/builds/slave/test', 06:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:25 INFO - 'SHELL': '/bin/bash', 06:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:25 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:25 INFO - 'USER': 'cltbld', 06:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:25 INFO - 'XPC_FLAGS': '0x0', 06:18:25 INFO - 'XPC_SERVICE_NAME': '0', 06:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:25 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 06:18:25 INFO - Cleaning up... 06:18:25 INFO - Return code: 0 06:18:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:25 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:25 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 06:18:25 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 06:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:25 INFO - 'HOME': '/Users/cltbld', 06:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:25 INFO - 'LOGNAME': 'cltbld', 06:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:25 INFO - 'MOZ_NO_REMOTE': '1', 06:18:25 INFO - 'NO_EM_RESTART': '1', 06:18:25 INFO - 'PAGER': '/bin/cat', 06:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:25 INFO - 'PWD': '/builds/slave/test', 06:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:25 INFO - 'SHELL': '/bin/bash', 06:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:25 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:25 INFO - 'USER': 'cltbld', 06:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:25 INFO - 'XPC_FLAGS': '0x0', 06:18:25 INFO - 'XPC_SERVICE_NAME': '0', 06:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:25 INFO - Downloading/unpacking psutil>=0.7.1 06:18:25 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 06:18:25 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 06:18:25 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 06:18:25 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 06:18:25 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 06:18:25 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 06:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:18:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:29 INFO - Installing collected packages: psutil 06:18:29 INFO - Running setup.py install for psutil 06:18:29 INFO - building 'psutil._psutil_osx' extension 06:18:29 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 06:18:29 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 06:18:29 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 06:18:29 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 06:18:29 INFO - building 'psutil._psutil_posix' extension 06:18:29 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 06:18:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:18:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:18:29 INFO - ^ 06:18:30 INFO - 1 warning generated. 06:18:30 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 06:18:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:30 INFO - Successfully installed psutil 06:18:30 INFO - Cleaning up... 06:18:30 INFO - Return code: 0 06:18:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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', '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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:18: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 mozsystemmonitor==0.0.0 06:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:30 INFO - 'HOME': '/Users/cltbld', 06:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:30 INFO - 'LOGNAME': 'cltbld', 06:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:30 INFO - 'MOZ_NO_REMOTE': '1', 06:18:30 INFO - 'NO_EM_RESTART': '1', 06:18:30 INFO - 'PAGER': '/bin/cat', 06:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:30 INFO - 'PWD': '/builds/slave/test', 06:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:30 INFO - 'SHELL': '/bin/bash', 06:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:30 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:30 INFO - 'USER': 'cltbld', 06:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:30 INFO - 'XPC_FLAGS': '0x0', 06:18:30 INFO - 'XPC_SERVICE_NAME': '0', 06:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:18:32 INFO - Installing collected packages: mozsystemmonitor 06:18:32 INFO - Running setup.py install for mozsystemmonitor 06:18:33 INFO - Successfully installed mozsystemmonitor 06:18:33 INFO - Cleaning up... 06:18:33 INFO - Return code: 0 06:18:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:18:33 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')]} 06:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:33 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')]} 06:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:33 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:33 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 06:18:33 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 06:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:33 INFO - 'HOME': '/Users/cltbld', 06:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:33 INFO - 'LOGNAME': 'cltbld', 06:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:33 INFO - 'MOZ_NO_REMOTE': '1', 06:18:33 INFO - 'NO_EM_RESTART': '1', 06:18:33 INFO - 'PAGER': '/bin/cat', 06:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:33 INFO - 'PWD': '/builds/slave/test', 06:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:33 INFO - 'SHELL': '/bin/bash', 06:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:33 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:33 INFO - 'USER': 'cltbld', 06:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:33 INFO - 'XPC_FLAGS': '0x0', 06:18:33 INFO - 'XPC_SERVICE_NAME': '0', 06:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:33 INFO - Downloading/unpacking blobuploader==1.2.4 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:35 INFO - Downloading blobuploader-1.2.4.tar.gz 06:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:18:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:18:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:18:36 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 06:18:36 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 06:18:36 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 06:18:36 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 06:18:36 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 06:18:36 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 06:18:37 INFO - Downloading docopt-0.6.1.tar.gz 06:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:18:37 INFO - Installing collected packages: blobuploader, requests, docopt 06:18:37 INFO - Running setup.py install for blobuploader 06:18:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:18:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:18:37 INFO - Running setup.py install for requests 06:18:37 INFO - Running setup.py install for docopt 06:18:37 INFO - Successfully installed blobuploader requests docopt 06:18:37 INFO - Cleaning up... 06:18:38 INFO - Return code: 0 06:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18:38 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')]} 06:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:38 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')]} 06:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:38 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:38 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 06:18:38 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 06:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:38 INFO - 'HOME': '/Users/cltbld', 06:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:38 INFO - 'LOGNAME': 'cltbld', 06:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:38 INFO - 'MOZ_NO_REMOTE': '1', 06:18:38 INFO - 'NO_EM_RESTART': '1', 06:18:38 INFO - 'PAGER': '/bin/cat', 06:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:38 INFO - 'PWD': '/builds/slave/test', 06:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:38 INFO - 'SHELL': '/bin/bash', 06:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:38 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:38 INFO - 'USER': 'cltbld', 06:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:38 INFO - 'XPC_FLAGS': '0x0', 06:18:38 INFO - 'XPC_SERVICE_NAME': '0', 06:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-FYtvdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-8yJYzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-ccmI1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-7vQBaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-2M3J1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-rOIhfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:18:38 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-KAHfVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-rFRgew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Sn6_bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-3atsBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-sPTsV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Rs2niN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Wr2md4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-1hWogX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-mkj6PC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-_9Q3fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:18:39 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-bAkFLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:18:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:18:40 INFO - Running setup.py install for manifestparser 06:18:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:18:40 INFO - Running setup.py install for mozcrash 06:18:40 INFO - Running setup.py install for mozdebug 06:18:40 INFO - Running setup.py install for mozdevice 06:18:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:18:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:18:40 INFO - Running setup.py install for mozfile 06:18:40 INFO - Running setup.py install for mozhttpd 06:18:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:18:40 INFO - Running setup.py install for mozinfo 06:18:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Running setup.py install for mozInstall 06:18:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Running setup.py install for mozleak 06:18:41 INFO - Running setup.py install for mozlog 06:18:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Running setup.py install for moznetwork 06:18:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Running setup.py install for mozprocess 06:18:41 INFO - Running setup.py install for mozprofile 06:18:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:18:41 INFO - Running setup.py install for mozrunner 06:18:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:18:42 INFO - Running setup.py install for mozscreenshot 06:18:42 INFO - Running setup.py install for moztest 06:18:42 INFO - Running setup.py install for mozversion 06:18:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:18:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:18:42 INFO - Cleaning up... 06:18:42 INFO - Return code: 0 06:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18:42 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')]} 06:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:42 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')]} 06:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:42 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:42 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 06:18:42 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 06:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:42 INFO - 'HOME': '/Users/cltbld', 06:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:42 INFO - 'LOGNAME': 'cltbld', 06:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:42 INFO - 'MOZ_NO_REMOTE': '1', 06:18:42 INFO - 'NO_EM_RESTART': '1', 06:18:42 INFO - 'PAGER': '/bin/cat', 06:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:42 INFO - 'PWD': '/builds/slave/test', 06:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:42 INFO - 'SHELL': '/bin/bash', 06:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:42 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:42 INFO - 'USER': 'cltbld', 06:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:42 INFO - 'XPC_FLAGS': '0x0', 06:18:42 INFO - 'XPC_SERVICE_NAME': '0', 06:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:18:42 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Nuy7TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:18:42 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)) 06:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:18:42 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-A5ixHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-UD4yFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-vZYjGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-J7nzDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-nxZYtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jLQwfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-078gNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-n9c3bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-UQXaNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:18:43 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)) 06:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:18:43 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-IGpFyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-crs29y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-OZEoBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-397_Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-K2wXq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-6QBdt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:18:44 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)) 06:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:18:44 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jq9ofJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:18:44 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)) 06:18:44 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)) 06:18:44 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)) 06:18:44 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)) 06:18:44 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)) 06:18:44 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)) 06:18:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:18:44 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 06:18:44 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 06:18:44 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 06:18:44 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 06:18:44 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 06:18:44 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 06:18:47 INFO - Downloading blessings-1.6.tar.gz 06:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:18:47 INFO - Installing collected packages: blessings 06:18:47 INFO - Running setup.py install for blessings 06:18:47 INFO - Successfully installed blessings 06:18:47 INFO - Cleaning up... 06:18:47 INFO - Return code: 0 06:18:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:18:47 INFO - Reading from file tmpfile_stdout 06:18:47 INFO - Current package versions: 06:18:47 INFO - blessings == 1.6 06:18:47 INFO - blobuploader == 1.2.4 06:18:47 INFO - docopt == 0.6.1 06:18:47 INFO - manifestparser == 1.1 06:18:47 INFO - mozInstall == 1.12 06:18:47 INFO - mozcrash == 0.17 06:18:47 INFO - mozdebug == 0.1 06:18:47 INFO - mozdevice == 0.48 06:18:47 INFO - mozfile == 1.2 06:18:47 INFO - mozhttpd == 0.7 06:18:47 INFO - mozinfo == 0.9 06:18:47 INFO - mozleak == 0.1 06:18:47 INFO - mozlog == 3.1 06:18:47 INFO - moznetwork == 0.27 06:18:47 INFO - mozprocess == 0.22 06:18:47 INFO - mozprofile == 0.28 06:18:47 INFO - mozrunner == 6.11 06:18:47 INFO - mozscreenshot == 0.1 06:18:47 INFO - mozsystemmonitor == 0.0 06:18:47 INFO - moztest == 0.7 06:18:47 INFO - mozversion == 1.4 06:18:47 INFO - psutil == 3.1.1 06:18:47 INFO - requests == 1.2.3 06:18:47 INFO - wsgiref == 0.1.2 06:18:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:47 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:47 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 06:18:47 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 06:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:47 INFO - 'HOME': '/Users/cltbld', 06:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:47 INFO - 'LOGNAME': 'cltbld', 06:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:47 INFO - 'MOZ_NO_REMOTE': '1', 06:18:47 INFO - 'NO_EM_RESTART': '1', 06:18:47 INFO - 'PAGER': '/bin/cat', 06:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:47 INFO - 'PWD': '/builds/slave/test', 06:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:47 INFO - 'SHELL': '/bin/bash', 06:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:47 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:47 INFO - 'USER': 'cltbld', 06:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:47 INFO - 'XPC_FLAGS': '0x0', 06:18:47 INFO - 'XPC_SERVICE_NAME': '0', 06:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 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)) 06:18:47 INFO - Cleaning up... 06:18:47 INFO - Return code: 0 06:18:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:47 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:47 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 06:18:47 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 06:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:47 INFO - 'HOME': '/Users/cltbld', 06:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:47 INFO - 'LOGNAME': 'cltbld', 06:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:47 INFO - 'MOZ_NO_REMOTE': '1', 06:18:47 INFO - 'NO_EM_RESTART': '1', 06:18:47 INFO - 'PAGER': '/bin/cat', 06:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:47 INFO - 'PWD': '/builds/slave/test', 06:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:47 INFO - 'SHELL': '/bin/bash', 06:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:47 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:47 INFO - 'USER': 'cltbld', 06:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:47 INFO - 'XPC_FLAGS': '0x0', 06:18:47 INFO - 'XPC_SERVICE_NAME': '0', 06:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:48 INFO - Downloading/unpacking jsonschema 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:18:50 INFO - Downloading/unpacking functools32 (from jsonschema) 06:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:50 INFO - Downloading functools32-3.2.3-2.tar.gz 06:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:18:51 INFO - warning: no files found matching '*.txt' 06:18:51 INFO - no previously-included directories found matching 'build' 06:18:51 INFO - no previously-included directories found matching 'dist' 06:18:51 INFO - no previously-included directories found matching '.git*' 06:18:51 INFO - Installing collected packages: jsonschema, functools32 06:18:51 INFO - Running setup.py install for functools32 06:18:51 INFO - warning: no files found matching '*.txt' 06:18:51 INFO - no previously-included directories found matching 'build' 06:18:51 INFO - no previously-included directories found matching 'dist' 06:18:51 INFO - no previously-included directories found matching '.git*' 06:18:51 INFO - Successfully installed jsonschema functools32 06:18:51 INFO - Cleaning up... 06:18:51 INFO - Return code: 0 06:18:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:18:51 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')]} 06:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:51 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')]} 06:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:51 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 0x10f38fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3964c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d2da3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, '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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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 06:18:51 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 06:18:51 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 06:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:51 INFO - 'HOME': '/Users/cltbld', 06:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:51 INFO - 'LOGNAME': 'cltbld', 06:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:51 INFO - 'MOZ_NO_REMOTE': '1', 06:18:51 INFO - 'NO_EM_RESTART': '1', 06:18:51 INFO - 'PAGER': '/bin/cat', 06:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:51 INFO - 'PWD': '/builds/slave/test', 06:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:51 INFO - 'SHELL': '/bin/bash', 06:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:18:51 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:51 INFO - 'USER': 'cltbld', 06:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:51 INFO - 'XPC_FLAGS': '0x0', 06:18:51 INFO - 'XPC_SERVICE_NAME': '0', 06:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:51 INFO - Downloading/unpacking flake8 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:18:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:18:54 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 06:18:54 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 06:18:54 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 06:18:54 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 06:18:54 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 06:18:54 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 06:18:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:18:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 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 06:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:18:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:18:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:18:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:18:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:18:56 INFO - no previously-included directories found matching 'docs/_build' 06:18:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:18:56 INFO - Running setup.py install for pep8 06:18:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:18:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:18:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:18:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:18:56 INFO - no previously-included directories found matching 'docs/_build' 06:18:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:18:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:18:56 INFO - Cleaning up... 06:18:56 INFO - Return code: 0 06:18:56 INFO - Running post-action listener: _resource_record_post_action 06:18:56 INFO - Running post-action listener: _start_resource_monitoring 06:18:56 INFO - Starting resource monitoring. 06:18:56 INFO - ##### 06:18:56 INFO - ##### Running install step. 06:18:56 INFO - ##### 06:18:56 INFO - Running pre-action listener: _resource_record_pre_action 06:18:56 INFO - Running main action method: install 06:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:18:56 INFO - Reading from file tmpfile_stdout 06:18:56 INFO - Detecting whether we're running mozinstall >=1.0... 06:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:18:56 INFO - Reading from file tmpfile_stdout 06:18:56 INFO - Output received: 06:18:56 INFO - Usage: mozinstall [options] installer 06:18:56 INFO - Options: 06:18:56 INFO - -h, --help show this help message and exit 06:18:56 INFO - -d DEST, --destination=DEST 06:18:56 INFO - Directory to install application into. [default: 06:18:56 INFO - "/builds/slave/test"] 06:18:56 INFO - --app=APP Application being installed. [default: firefox] 06:18:56 INFO - mkdir: /builds/slave/test/build/application 06:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 06:19:19 INFO - Reading from file tmpfile_stdout 06:19:19 INFO - Output received: 06:19:19 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:19:19 INFO - Running post-action listener: _resource_record_post_action 06:19:19 INFO - ##### 06:19:19 INFO - ##### Running run-tests step. 06:19:19 INFO - ##### 06:19:19 INFO - Running pre-action listener: _resource_record_pre_action 06:19:19 INFO - Running main action method: run_tests 06:19:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:19 INFO - 2016-06-06 06:19:19.116 screenresolution[1650:8187] starting screenresolution argv=screenresolution get 06:19:19 INFO - 2016-06-06 06:19:19.119 screenresolution[1650:8187] Display 0: 1600x1200x32@60 06:19:19 INFO - 2016-06-06 06:19:19.125 screenresolution[1651:8190] starting screenresolution argv=screenresolution list 06:19:19 INFO - Available Modes on Display 0 06:19:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:19 INFO - Intel Iris: 06:19:19 INFO - Chipset Model: Intel Iris 06:19:19 INFO - Type: GPU 06:19:19 INFO - Bus: Built-In 06:19:19 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:19 INFO - Vendor: Intel (0x8086) 06:19:19 INFO - Device ID: 0x0a2e 06:19:19 INFO - Revision ID: 0x0009 06:19:19 INFO - Displays: 06:19:19 INFO - Display: 06:19:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:19 INFO - Display Serial Number: 1600x1200 60 06:19:19 INFO - Main Display: Yes 06:19:19 INFO - Mirror: Off 06:19:19 INFO - Online: Yes 06:19:19 INFO - Rotation: Supported 06:19:19 INFO - Return code: 0 06:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:19:19 INFO - Python 2.7.3 06:19:19 INFO - Return code: 0 06:19:19 INFO - grabbing minidump binary from tooltool 06:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:19 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 0x10f233d30>, '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 0x10f097db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f22ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:19:19 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 06:19:19 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 06:19:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:19:19 INFO - Return code: 0 06:19:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:19:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:19:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:19:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:19:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:19:20 INFO - Return code: 0 06:19:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--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 06:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0045 --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 06:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AKr2x2PJ13/Render', 06:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:20 INFO - 'HOME': '/Users/cltbld', 06:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:20 INFO - 'LOGNAME': 'cltbld', 06:19:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:19:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:20 INFO - 'MOZ_NO_REMOTE': '1', 06:19:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:19:20 INFO - 'NO_EM_RESTART': '1', 06:19:20 INFO - 'PAGER': '/bin/cat', 06:19:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:20 INFO - 'PWD': '/builds/slave/test', 06:19:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:20 INFO - 'SHELL': '/bin/bash', 06:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mJtgW89Jz/Listeners', 06:19:20 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 06:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:20 INFO - 'USER': 'cltbld', 06:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:20 INFO - 'XPC_FLAGS': '0x0', 06:19:20 INFO - 'XPC_SERVICE_NAME': '0', 06:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--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 06:19:20 INFO - mozversion application_buildid: 20160602140837 06:19:20 INFO - mozversion application_changeset: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea 06:19:20 INFO - mozversion application_display_name: Firefox 06:19:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:19:20 INFO - mozversion application_name: Firefox 06:19:20 INFO - mozversion application_remotingname: firefox 06:19:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:19:20 INFO - mozversion application_vendor: Mozilla 06:19:20 INFO - mozversion application_version: 47.0 06:19:20 INFO - mozversion platform_buildid: 20160602140837 06:19:20 INFO - mozversion platform_changeset: 6e2399cf03e2f260740f6a0fc3f1fd0bc53b78ea 06:19:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:19:20 INFO - mozversion platform_version: 47.0 06:19:20 INFO - using testdate: 1465219160 06:19:20 INFO - actual date: 1465219160 06:19:20 INFO - starting webserver on 'localhost:49275' 06:19:20 INFO - SUITE-START | Running 2 tests 06:19:20 INFO - TEST-START | dromaeo_css 06:19:20 INFO - operating with platform_type : mac_ 06:19:20 INFO - Initialising browser for dromaeo_css test... 06:19:21 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpkxFt4I/profile http://localhost:49275/getInfo.html) 06:19:23 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 06:19:23 INFO - PROCESS | 1669 | colorDepth:24 06:19:23 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 06:19:23 INFO - PROCESS | 1669 | __metrics 06:19:23 INFO - PROCESS | 1669 | 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" 06:19:23 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:19:23 INFO - TEST-INFO | 1669: exit 0 06:19:23 INFO - Browser initialized. 06:19:23 INFO - Running cycle 1/1 for dromaeo_css test... 06:19:23 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpkxFt4I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:35 INFO - PROCESS | 1673 | RSS: Main: 157077504 06:19:35 INFO - PROCESS | 1673 | 06:20:26 INFO - PROCESS | 1673 | 1465219226315 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] 06:22:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 06:22:37 INFO - PROCESS | 1673 | RSS: Main: 332546048 06:22:37 INFO - PROCESS | 1673 | 06:25:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 06:25:40 INFO - PROCESS | 1673 | RSS: Main: 296271872 06:25:40 INFO - PROCESS | 1673 | 06:28:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 06:28:42 INFO - PROCESS | 1673 | RSS: Main: 297033728 06:28:42 INFO - PROCESS | 1673 | 06:31:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:31:45 INFO - PROCESS | 1673 | RSS: Main: 260730880 06:31:45 INFO - PROCESS | 1673 | 06:34:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:34:48 INFO - PROCESS | 1673 | RSS: Main: 258031616 06:34:48 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:37:51 INFO - PROCESS | 1673 | RSS: Main: 302452736 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | __start_tp_report 06:37:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 06:37:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 06:37:51 INFO - PROCESS | 1673 | |i|pagename|runs| 06:37:51 INFO - PROCESS | 1673 | |0;dojo.html;3589.5513060867393;3641.7268704847143;3660.9633903660974;3661.9450708239365;3715.8513659453624;5842.561807864406;5878.970605146979;5880.9411905880925;5906.025505791539;5952.077427998656;5829.825105246839;5876.11858221267;5884.205632239647;5890.499307558863;5906.5570082243785;5209.8249255347255;5277.6305658603915;5321.760520776555;5359.383670877841;5394.811181608645;5378.435264297251;5399.568034557226;5411.675299482024;5419;5423.376311724143;4347.3261644445;5384.40771515135;5387.272718183054;5390.029794636964;5415.64798288112;5395.325584301962;5431.293931788842;5433.483819037205;5438.945610543923;5476.26070480484;4051.6758659307184;5334.493223143795;5343.145096784535;5347.010803001457;5358.705271210082;4884.560389564921;5588.972055139739;5691.6585004899425;5748.540116790647;5754.165645981351;6182.227221597301;6275.8744825103695;6283.874322513524;6285;6287.9685601572155;5252.71110088945;6257.5932564383165;6267.2479302484;6307.684615769239;6313.021481670348;5993.130996005555;6021.2473440819895;6033.3966603340195;6036.456718895321;6052.697365131726;6248.812535623939;6397.360263973565;6420.101185833987;6429.678516074177;6444.967775161094;6224.657643829632;6299.779507717208;6311.33730958252;6341;6350.682465876687;6282.560220784592;6289.213848268966;6289.55973081889;6296.5592408531875;6296.937030629727;6181.412765787243;6213.37866213375;6213.782517611862;6275.811725648238;6277.843053923689;6227.09707092469;6238.968805155945;6266.937330626726;6267.811965641038;6269.122322874801;5073.34046573943;5073.416557095981;5076.36545431821;5077.8730531737;5099.413567439718;4501.144782491317;4577.359169716243;4583.747893865802;4615.515370886009;4620.67655264135;5975.312839023481;5977.133315669291;6004.609700369462;6063.151158837766;6075.936711075544;6275.905861412082;6280.623162610258;6282.089097080898;6289.528285378615;6435.967820160869;5821.7089145543405;5977.641341519523;6034.336223015464;6036.63780173191;6048.546359023091;5491.313585801775;5533.363663178706;5553.028220061473;5578.888422231533;5586.692731899784;6408.6475243861105;6413.230412350548;6416.197975253187;6418.935810641927;6434.163558737334;5158.767855446439;6214.1921550198185;6229.12792209082;6280.717367718372;6297.716602752978;6416.647084410216;6429;6432.742690292524;6445.548811583144;6452.645104519296;6264.436200741955;6299.937000630119;6300.0234963580715;6308.716107775253;6313;6194.101855231056;6204.193454850841;6216.098665693358;6259.12372267892;6282.591631543935;6273.341299163704;6285.088662144055;6288.371162883675;6294.244690637152;6312.368763123651;6270.937290627217;6278.968605156945;6279.560430769802;6289.962156244169;6310.810675679737;6075.088736689355;6140.048292514667;6171.3828617139725;6176.598521096114;6183.659898705614;6103.298120716175;6257.092721555258;6277.654728989949;6284.937150628435;6291.528135389955;5934.109883517507;6197.535184861244;6202.689865506797;6211.3478084802255;6223.533235007483 06:37:51 INFO - PROCESS | 1673 | |1;ext.html;523.4817530644663;572.6134858970194;601.868487243981;675.4393853101926;677.4309579952838;41663.37504937425;42178.57821421785;42294.73115806523;42303.36544951824;42480.57519424805;45186;45799.77100114503;45824;45915;45927.77036114823;8235.794105147374;9965.750856228617;10071.345362551447;10087.747806304827;10092.94953525235;9930.155936745348;10073.093421592055;10087.394756314608;10087.546060427298;10091.444970526618;8509.319254459659;10250.538725757322;10264.589416423334;10271.435071071088;10304;10146.54340554673;10210.132138768213;10214.642487512901;10225.23310751693;10226.79546409075;9904.356216845954;10044.547995340226;10056.245781566411;10058.647947321808;10089.747756306078;43766;44357;44357.556424435665;44383.77808110971;44439.33340999888;2789.5955086512545;3402.7447941404494;3407.6762707542744;3421.555197824292;3487.232808782064;27959.30101747452;28020.859895700596;28083.157505274874;28326.716732832614;29021.12936611905;2812.4515719434717;2818.4644917465616;2853.42931413716;2866.899658511942;2880.3807181456086;399660;400332.9999999971;402850;403348;403688;48397;48818.511814882106;48867;48929.7767555814;71902.28097718956;47929;48076.79808004758;48275.31036413708;48287.517124829006;48362.51637483651;14748;17612.383566575107;17790.37733679315;17804;17814.10929453522;14162.858371416154;14195.574132776033;14203.502877399242;14231.146131232159;14381;15280.847191528164;15292.00601960869;15371.385144594315;15445.922770386302;15566.533004009898;5715.199872017921;6479.546431749733;6509.316521765241;6653.1018312527185;6660.400563949171;1013.5388398278766;1031.4945676618404;1122.7944219254075;1167.9299242045504;1240.503798480615;31152.844235778677;31153.844230779156;31313.530297045563;31769.36461270808;31806.84096579502;1155.4338374196584;1206.2460961898814;1227.7728620205291;1233.907456940715;1251.0992085698283;13519.324033798468;15955.042697438192;16052.598685032968;16098.275577599035;16469.752953705705;543538;543881;546796;547650;554126;57826;61784.146475605805;64447.03329450062;65185;65580;1421.7085497472992;1542.4447199008348;1559.5087547422524;1584.841515848429;1587.2539906244278;24209.63685544683;30146.397072058873;30452.70354257303;31617.5257371135;31672.366552668358;52489.737551312;53274;53641.39075827732;53681.46318536765;53872.73063634657;368217;372657;372712;372950;373958;30749.538756918217;35236.647633524364;35668;36129.819350903075;36278.4558231632;44154.77922610367;45043.54956450524;45249.86875328143;45302.54697452983;45706.31440528328;36967.63032369749;37169;37330.813345934184;37692.6807561734;38023.239535209694;37474.437883432314;38107.618923810405;38112.809435952644;38552;39059.82820515389;38385.04037398976;38731.80634096811;38771;38778.836634900996;41360;30836.383272334875;31373;33017.50249929113;34355.31289374149;35017.29965400627;26057.218283451526;26224.01743668697;26302.079427219956;26552.601710974737;26950.59574106351 06:37:51 INFO - PROCESS | 1673 | |2;jquery.html;322.59353214949164;375.52121045666775;375.7275974918183;381.5478657790517;385.1353710918987;20287.98560071996;20682.96585170741;21386.037628306723;21747.80387078712;21762;21148;21158.57682846342;21888.23391181309;22028.77971220287;22107.557848843015;5929.940700592982;6140.140380346755;6197.039458883858;6203.720832562545;6203.782867599635;4108.548059713429;4292.720973136736;4294.291441912081;4301.397804307399;5650.886982260352;4293.978530107345;5699.572532060112;5975.43233392827;5999.610025348338;6004.219451471325;5898.587098903078;5961.761529538813;6488.8702225955685;6522.7390904364;6529.249136349333;4006.959930400688;5624.662520248758;5721.6853073080965;5730.484256416942;5743.138529220609;24947.251582452554;25191.874040629864;26014.21957341264;26084.86957565219;26135.08527201539;2084.5309805293687;2149.258505815491;2164.805167534914;2352.3531028967;2660.7605315521696;9794.608215671364;10215.795684086352;10411.583536658525;11702.473388697445;15592.454264100816;1763.5943732941366;1767.8320559546823;1777.297967302921;1802.9549261268573;1856.4059500959697;105995;112568;113926.43036784763;115211;115213.42393288064;11308.264962777394;11520.963113319674;11550.133739969537;11793.528258869721;12024.879751202376;11878.40607969598;14504.34730437133;14542.127472351482;14547.05444146127;14588.270586470633;8929.33030022751;9046.909530904739;9082.500462474627;9086.091390860862;9156.108653822099;9370.29722770795;9394.530273486434;9398.389104708172;9421.76445588852;9481.194098501566;4510.954890451119;4547.431571053618;4554.590086892195;4557.111363284128;4621.237495813153;1630.6901688679113;1783.0465535663388;1797.8112298208762;1877.9061046947597;1931.4688460673426;933.3699752667059;963.7783309838776;998.2912132386087;1008.178334657252;1100.2463312630873;19663.402205501912;21015.474613134487;21119.78880211209;21274.36176914695;21286.78713212879;837.3426859914938;1015.9034891685278;1023.2376879225025;1048.2295512798087;1065.3714308557987;11103.167262455348;11411.828822567668;11457.598984035685;11465.426728663533;11543.76912461746;625907;661051;670133;678545;688930;121016.39491802485;122280;124245.75754242342;124665;125529;1056.2606175677056;1068.3696618994986;1069.3476979042778;1087.9184061195442;1089.5968491658136;14466;18255.178516967033;19438;19515.21939122476;19804.40586782399;45898.54101458943;47830;48555;49957.50127496181;51735.74132129315;50818.74590627023;64456.67771661111;66549.66725166343;66764;66776;6638.966805165944;8066.83866322682;8307.6676932922;8503.74488765338;8851.867221991546;12488.313142776871;13196.076274660682;13813.723725525635;13910.374033168735;14293.714125717635;11022.393768342818;11269.830952535554;11801.586944457245;11806.940965295118;12318.753624927273;11801.114916381477;11814.881851181379;11918.880811191777;11953.402329883646;12105.394730263628;11963.461644225858;12013.978811801095;12017.09871759604;12291;12304.200226985216;7053.6825842838225;8184.713535026483;8289.212524810133;8299.17418168002;8492.660293588187;8653.913460865562;11098.167637427387;11900.40497975115;11921.463534141158;12049.216800907912 06:37:51 INFO - PROCESS | 1673 | |3;mootools.html;1523.299282330128;1642.7207374746292;1653.3469279634542;1992.7807941126473;2069.3171253486353;7296.854062918738;7411.184769675331;8148.225918537737;8762.693305734301;8961.59672814724;8728.127187281269;8805.427647202927;8851.95574022129;8860.601272942717;8880.33397495189;2531.164715643833;2552.1960582416573;2559.424129570841;2560.4637217669438;2571.742825717432;2508.1973768394123;2564.6024866145776;2576.621507493491;2579.1746641074865;2592.5333439980795;2548.362909272682;2565.9486810263784;2586.612008198776;2587.2109006752935;2588.5470042742445;2550.0564791027346;2558.3604098975256;2571.459993401388;2573.318070711263;2575.8068144889207;2510.9874450627813;2542.4660821227562;2547.949041019188;2558.4243545202366;2559.0147793099604;9042.547872606343;9077.228435582983;9086.409383390059;9089.272858171396;9316.487593182372;5541.0580201365865;5690.06113991191;5742.799002034951;5749.1663708762;5759.971200143972;3647.270545890806;3680.4479328100733;3760.9623903760885;3802.923941521154;3805.429185622151;1071.9464026798628;1087.3910610058313;1088.086007753483;1103.431732657682;1124.5614210457927;12338.568150114703;12348.814767778491;12370.381480925918;12374.257544547267;12381;4916.115099282092;4946.752662366867;5003.2995380646735;5010.924836127461;5054.4692807254705;10427.635032773816;10458.843117353246;10464.52909619069;10465.110465610509;10479.42363170033;5662.971685141548;5676.914846277309;5681.914771278434;5711.514521265739;5725.599208055396;4579.107074120581;4759.143354196278;4911.386076740408;5109.56568691658;5154.484551544891;3979.8408063677707;4030.858919937788;4036.656884164879;4069.5116586009867;4083.1017176221185;1355.2681551962028;1366.7403193393422;1367.3026756354332;1380.1443105274593;1381.88944884409;1271.5867343113525;1430.9141451512942;1457.0674768148394;1484.2578710644677;1489.1660670024821;8292.958535207285;8490.617922193516;8515.872261916076;8516.190961858738;8516.574171291411;1182.798924182891;1233.7224124571899;1284.7494738526084;1291.4124073546514;1299.7660421124287;6356.173697419398;6370.394812492806;6421.614703117828;6429.58207716497;6433.195850518685;10845.51195196218;11044.282121662067;11327.943360283145;11328.830067548828;11333.773324533464;10940.671779846618;10959.780804383867;11003.33979961205;11060.22578419519;11100.278481898651;8432.241098301183;8559.358048146414;8559.758834968894;8579.65681372738;8636.22273995331;8013.6794528218215;8058.919410806051;8082.393820463371;8156.0620528639965;8166.265036146538;9241.537923103953;9730.95134524323;9731.221502279659;9756.85364719544;10003.649872254437;9574.186194173573;9997.950010249902;10012.39925604466;10019;10024.498775061364;7564.621768911643;7569.3566046884425;7577.583232922242;7666.4633475656165;7670.0795904491815;7027.472939529658;8301.211384918422;8307.6676932922;8371.916280837357;8375.916240837514;7468.253174682488;7678.61606919663;7740.380769538311;7762.5730584818375;7800.648970796215;7344.15542212631;7424.814379640552;7459.813504662427;7474.953506509202;7560.810979725551;7939.642716077906;7974.641141148778;8010.198980101944;8021.598920054091;8032.518048917083;7188.496805223794;7193.496455248082;7236.89144662841;7276.8908466374105;7283.271672832674;7306.561606303639;7307.817304567216;7357.963210183915;7359.264073592598;7366.337029667356 06:37:51 INFO - PROCESS | 1673 | |4;prototype.html;332.78701630956186;340.66955053598;341.6054457102048;343.0326479328294;346.05354355836766;6660.76687315944;6712.496562757794;6713.832154196148;6715.160604924385;6756.628385438799;5503.257060296859;6585.374389433008;6607.108040414542;6639.867202655944;6680.799576012728;4818.132736107499;4885.193942999401;4886.926696099561;4893.584045356131;4990.800367985285;4885;4903.215485522317;4917.877053073666;4976.950230497685;4996.075725990685;4949.826756063539;4953.9504604953845;4965.255211718253;5005.124103281911;5008.449070602231;4996.375453068366;5000.449950505442;5010.298558201873;5015.172496538067;5020.698758074527;4095.7098513968003;4188.560201178893;4276.2516559601945;4281.165172791307;4292.313229883235;6318.589291696071;6386.329435409308;6387.712552935129;6392.264889537716;6407.455366293871;5906.3798301178185;5913;5915.290165180163;5916.349201587822;5927.644341339534;7102.183248926388;7124.8575028499135;7132.358087772125;7133.607651579161;7134.286571342876;4492.438445194351;4800.135975524404;4805.399325084364;4806.447258565275;4809.326694262806;6073.240844894388;7399.038125043764;7508.136564295067;7532.322091011835;7594.5822979736095;4738.241881298975;4741.762911854394;4746.240601503743;4746.935083238802;4755.405574303212;4829.468758436569;4833.299171620095;4833.951660483421;4839.467658557556;4852.272159176081;5733.569982251348;5739.799107031317;5742.31092268922;5752.9424705753245;5763.077907534775;6924.3421874921805;7156.821079473055;7163.7134514619875;7177.1746249182015;7177.282271772781;1647.2669662000535;1660.2031025107774;1676.5808547863035;1678.429334026437;1682.6634673065437;1052.7999680060764;1055.076807793181;1073.1468482556231;1078.1374900079904;1079.4710591809956;5541.695206763667;6586.473082153416;6755.256921738604;6791.524593278522;6824.283450237654;7287.599182045038;7474.588897610575;7484.925150748531;7486.13909400415;7522.548647081193;6838.965805171042;6850.828729281808;6851.143607049119;6857.7256909723055;6871.759488417882;3926.8429262829736;3955.3869150281753;3961.069148750041;3961.564227934925;3969.801509924492;7491.325780679765;7806.960965195138;7820.061592608809;7837.294643482;7866.174051724601;7649.349805266504;7706.653200605986;7712.5758083305955;7719.729809456642;7727.690892364355;7803.765887023284;7815;7836.764897053097;7841.176676448891;7928.603569821601;6016.09758536223;7430.517016394133;7436.665350059152;7445.255474452511;7475.2151024141685;7103.680334385069;7314.122305323395;7322.707091716272;7323.963380183065;7326.267373262631;7224.494285399972;7701.922980770345;7752.186020467995;7760.573168475788;7763.68945242184;4735.266033764772;4770.165221086227;4787.473377928355;4787.976060119677;4788.688735232199;7418.66616002292;7428.70285188608;7432.777016689508;7439.107307123179;7440.3675687567165;6964.756233531802;7158.928410716035;7185.101862267216;7188.921661750779;7221.241769614115;7075.23119220195;7134.714611415693;7140.9285907140265;7142.357187853118;7143.6428178591905;6953.478489113236;6957.199922009034;6967.885138377836;6976.930230697831;6984.511084224056;6924.965375173092;7194.460415468965;7197.172325182671;7201.459890508338;7212.2427145149;6770.695318710768;7068.257832927468;7075.964620176866;7086.185088714864;7104.289571042855 06:37:51 INFO - PROCESS | 1673 | |5;yui.html;727.3054233207284;768.258630421643;801.3428988229654;803.4496369986558;829.5644786487095;15431.305591248392;15438.84561154388;15765;15777.921110394447;15808.209589520535;14730.779038314407;14843.554693359189;14848.925755371209;14849.777253341208;14878.032927859682;808.2079562029215;818.7134502923959;819.3854609043218;952.5380190607567;999.5901680311074;818.5007145641155;921.5668635741224;1012.3976234140674;1014.7716763728139;1016.8169729448696;1052.468503405783;1053.5785685725732;1054.4464156317956;1055.2560444886376;1056.1128651932374;837.6440012994453;1041.7968496143255;1044.7022598559413;1045.263089521889;1046.0428707732415;761.4479502360799;768.5350363030342;768.876979683248;768.9730859419893;769.9307062364358;15734.055956642638;15748.685026299525;15819.209039547977;15824.76262856058;15829.050256984618;13694.726105477836;13735.862641373558;13765.174089554559;13893.235872027035;13916;10340.482975851177;10451.738706532322;10452.634157804441;10705.571777128906;11214.607488737936;3622.710183185365;3633.8909832705062;3673.1184515716304;3715.1640880801874;3885.8639947601982;87137;87238.5638071812;88187.99999999936;89532.55233723855;89813;8263.338932885355;9651.951740241253;10018.048285412875;10197.133243674223;10324.019218174262;21671.67492487614;21862.12551497954;21885.154929859335;21890.671639925415;21915.561688766134;7825;7850.450468467153;7885.053793544811;7931.920680793233;8120.553369564613;3704.4628528863705;4267.1892340455215;4365.170617582649;4409.911801763947;4641.419822522184;3809.409541521013;3868.858686687434;3869.400242962345;3869.4776205212497;3871.341871881787;669.4008862068422;688.1295161620554;690.623610132479;693.7987983484834;710.989335159973;431.47791172681406;549.0227395236485;598.9820305390846;603.3966033966034;637.6238019568477;7208.891866622005;7964.761057168294;8233.958830205811;8452.154784521621;8560;617.4473845907887;617.9258488981261;626.9561130720897;630.3822254190827;636.4399328590811;3733.8133093345223;3929.9214015719526;4038.838446462167;4053.088055187618;4063.6342729154517;83475.74786378209;83652;84471.31057378819;84692.57653711815;97832.51083744536;74735.62632186804;75212.62393687997;79123.20876791273;79354.25488833398;79903;73611.63194183995;73749.6312518434;74320;74900.62549687325;75260.24739752641;8612.267957223707;8635.913640863511;8663.95668021656;8932.46405215689;9168.344894458172;44342.334864977696;46417.76791116023;47583.76208118937;48527.272090917955;50030;72065;72335.63832180806;73993.63003184949;75352.24647753453;76873.23126768661;11163.888361116286;11210;11398.316101033965;11524.59663911759;11797.88202117968;11769.624335569722;12093;12140.271583705005;12163.2702037878;12915.160514566523;8432.873506897278;11157.776844462904;11667.358295293501;11667.883321167019;11899.345535995604;11451.656450306504;11624;11787.941060294643;12012.279263244232;12089.15375923677;11159;11336.659900203007;11487.695304735074;11623.941880290882;12182.390880455765;9683.322167448492;9801;9872.654457093968;9951.402915825074;10088.495575221357;9932.801343972935;10085.546150423395;10086.344387614781;10101.44442055694;10128.544215510172 06:37:51 INFO - PROCESS | 1673 | __end_tp_report 06:37:51 INFO - PROCESS | 1673 | __start_cc_report 06:37:51 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1490 06:37:51 INFO - PROCESS | 1673 | __end_cc_report 06:37:51 INFO - PROCESS | 1673 | __startTimestamp1465220271507__endTimestamp 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | ------- Summary: start ------- 06:37:51 INFO - PROCESS | 1673 | Number of tests: 6 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5857.26 Median:6176.60 stddev:633.39 (10.3%) stddev-sans-first:609.84 06:37:51 INFO - PROCESS | 1673 | Values: 3589.6 3641.7 3661.0 3661.9 3715.9 5842.6 5879.0 5880.9 5906.0 5952.1 5829.8 5876.1 5884.2 5890.5 5906.6 5209.8 5277.6 5321.8 5359.4 5394.8 5378.4 5399.6 5411.7 5419.0 5423.4 4347.3 5384.4 5387.3 5390.0 5415.6 5395.3 5431.3 5433.5 5438.9 5476.3 4051.7 5334.5 5343.1 5347.0 5358.7 4884.6 5589.0 5691.7 5748.5 5754.2 6182.2 6275.9 6283.9 6285.0 6288.0 5252.7 6257.6 6267.2 6307.7 6313.0 5993.1 6021.2 6033.4 6036.5 6052.7 6248.8 6397.4 6420.1 6429.7 6445.0 6224.7 6299.8 6311.3 6341.0 6350.7 6282.6 6289.2 6289.6 6296.6 6296.9 6181.4 6213.4 6213.8 6275.8 6277.8 6227.1 6239.0 6266.9 6267.8 6269.1 5073.3 5073.4 5076.4 5077.9 5099.4 4501.1 4577.4 4583.7 4615.5 4620.7 5975.3 5977.1 6004.6 6063.2 6075.9 6275.9 6280.6 6282.1 6289.5 6436.0 5821.7 5977.6 6034.3 6036.6 6048.5 5491.3 5533.4 5553.0 5578.9 5586.7 6408.6 6413.2 6416.2 6418.9 6434.2 5158.8 6214.2 6229.1 6280.7 6297.7 6416.6 6429.0 6432.7 6445.5 6452.6 6264.4 6299.9 6300.0 6308.7 6313.0 6194.1 6204.2 6216.1 6259.1 6282.6 6273.3 6285.1 6288.4 6294.2 6312.4 6270.9 6279.0 6279.6 6290.0 6310.8 6075.1 6140.0 6171.4 6176.6 6183.7 6103.3 6257.1 6277.7 6284.9 6291.5 5934.1 6197.5 6202.7 6211.3 6223.5 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:59560.81 Median:35126.97 stddev:118574.83 (337.6%) stddev-sans-first:118824.61 06:37:51 INFO - PROCESS | 1673 | Values: 523.5 572.6 601.9 675.4 677.4 41663.4 42178.6 42294.7 42303.4 42480.6 45186.0 45799.8 45824.0 45915.0 45927.8 8235.8 9965.8 10071.3 10087.7 10092.9 9930.2 10073.1 10087.4 10087.5 10091.4 8509.3 10250.5 10264.6 10271.4 10304.0 10146.5 10210.1 10214.6 10225.2 10226.8 9904.4 10044.5 10056.2 10058.6 10089.7 43766.0 44357.0 44357.6 44383.8 44439.3 2789.6 3402.7 3407.7 3421.6 3487.2 27959.3 28020.9 28083.2 28326.7 29021.1 2812.5 2818.5 2853.4 2866.9 2880.4 399660.0 400333.0 402850.0 403348.0 403688.0 48397.0 48818.5 48867.0 48929.8 71902.3 47929.0 48076.8 48275.3 48287.5 48362.5 14748.0 17612.4 17790.4 17804.0 17814.1 14162.9 14195.6 14203.5 14231.1 14381.0 15280.8 15292.0 15371.4 15445.9 15566.5 5715.2 6479.5 6509.3 6653.1 6660.4 1013.5 1031.5 1122.8 1167.9 1240.5 31152.8 31153.8 31313.5 31769.4 31806.8 1155.4 1206.2 1227.8 1233.9 1251.1 13519.3 15955.0 16052.6 16098.3 16469.8 543538.0 543881.0 546796.0 547650.0 554126.0 57826.0 61784.1 64447.0 65185.0 65580.0 1421.7 1542.4 1559.5 1584.8 1587.3 24209.6 30146.4 30452.7 31617.5 31672.4 52489.7 53274.0 53641.4 53681.5 53872.7 368217.0 372657.0 372712.0 372950.0 373958.0 30749.5 35236.6 35668.0 36129.8 36278.5 44154.8 45043.5 45249.9 45302.5 45706.3 36967.6 37169.0 37330.8 37692.7 38023.2 37474.4 38107.6 38112.8 38552.0 39059.8 38385.0 38731.8 38771.0 38778.8 41360.0 30836.4 31373.0 33017.5 34355.3 35017.3 26057.2 26224.0 26302.1 26552.6 26950.6 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:36275.72 Median:2157.03 stddev:110071.03 (5102.9%) stddev-sans-first:110346.70 06:37:51 INFO - PROCESS | 1673 | Values: 322.6 375.5 375.7 381.5 385.1 20288.0 20683.0 21386.0 21747.8 21762.0 21148.0 21158.6 21888.2 22028.8 22107.6 5929.9 6140.1 6197.0 6203.7 6203.8 4108.5 4292.7 4294.3 4301.4 5650.9 4294.0 5699.6 5975.4 5999.6 6004.2 5898.6 5961.8 6488.9 6522.7 6529.2 4007.0 5624.7 5721.7 5730.5 5743.1 24947.3 25191.9 26014.2 26084.9 26135.1 2084.5 2149.3 2164.8 2352.4 2660.8 9794.6 10215.8 10411.6 11702.5 15592.5 1763.6 1767.8 1777.3 1803.0 1856.4 105995.0 112568.0 113926.4 115211.0 115213.4 11308.3 11521.0 11550.1 11793.5 12024.9 11878.4 14504.3 14542.1 14547.1 14588.3 8929.3 9046.9 9082.5 9086.1 9156.1 9370.3 9394.5 9398.4 9421.8 9481.2 4511.0 4547.4 4554.6 4557.1 4621.2 1630.7 1783.0 1797.8 1877.9 1931.5 933.4 963.8 998.3 1008.2 1100.2 19663.4 21015.5 21119.8 21274.4 21286.8 837.3 1015.9 1023.2 1048.2 1065.4 11103.2 11411.8 11457.6 11465.4 11543.8 625907.0 661051.0 670133.0 678545.0 688930.0 121016.4 122280.0 124245.8 124665.0 125529.0 1056.3 1068.4 1069.3 1087.9 1089.6 14466.0 18255.2 19438.0 19515.2 19804.4 45898.5 47830.0 48555.0 49957.5 51735.7 50818.7 64456.7 66549.7 66764.0 66776.0 6639.0 8066.8 8307.7 8503.7 8851.9 12488.3 13196.1 13813.7 13910.4 14293.7 11022.4 11269.8 11801.6 11806.9 12318.8 11801.1 11814.9 11918.9 11953.4 12105.4 11963.5 12014.0 12017.1 12291.0 12304.2 7053.7 8184.7 8289.2 8299.2 8492.7 8653.9 11098.2 11900.4 11921.5 12049.2 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:6233.30 Median:5032.70 stddev:3241.67 (64.4%) stddev-sans-first:3231.43 06:37:51 INFO - PROCESS | 1673 | Values: 1523.3 1642.7 1653.3 1992.8 2069.3 7296.9 7411.2 8148.2 8762.7 8961.6 8728.1 8805.4 8852.0 8860.6 8880.3 2531.2 2552.2 2559.4 2560.5 2571.7 2508.2 2564.6 2576.6 2579.2 2592.5 2548.4 2565.9 2586.6 2587.2 2588.5 2550.1 2558.4 2571.5 2573.3 2575.8 2511.0 2542.5 2547.9 2558.4 2559.0 9042.5 9077.2 9086.4 9089.3 9316.5 5541.1 5690.1 5742.8 5749.2 5760.0 3647.3 3680.4 3761.0 3802.9 3805.4 1071.9 1087.4 1088.1 1103.4 1124.6 12338.6 12348.8 12370.4 12374.3 12381.0 4916.1 4946.8 5003.3 5010.9 5054.5 10427.6 10458.8 10464.5 10465.1 10479.4 5663.0 5676.9 5681.9 5711.5 5725.6 4579.1 4759.1 4911.4 5109.6 5154.5 3979.8 4030.9 4036.7 4069.5 4083.1 1355.3 1366.7 1367.3 1380.1 1381.9 1271.6 1430.9 1457.1 1484.3 1489.2 8293.0 8490.6 8515.9 8516.2 8516.6 1182.8 1233.7 1284.7 1291.4 1299.8 6356.2 6370.4 6421.6 6429.6 6433.2 10845.5 11044.3 11327.9 11328.8 11333.8 10940.7 10959.8 11003.3 11060.2 11100.3 8432.2 8559.4 8559.8 8579.7 8636.2 8013.7 8058.9 8082.4 8156.1 8166.3 9241.5 9731.0 9731.2 9756.9 10003.6 9574.2 9998.0 10012.4 10019.0 10024.5 7564.6 7569.4 7577.6 7666.5 7670.1 7027.5 8301.2 8307.7 8371.9 8375.9 7468.3 7678.6 7740.4 7762.6 7800.6 7344.2 7424.8 7459.8 7475.0 7560.8 7939.6 7974.6 8010.2 8021.6 8032.5 7188.5 7193.5 7236.9 7276.9 7283.3 7306.6 7307.8 7358.0 7359.3 7366.3 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5884.25 Median:6713.16 stddev:1865.60 (27.8%) stddev-sans-first:1823.71 06:37:51 INFO - PROCESS | 1673 | Values: 332.8 340.7 341.6 343.0 346.1 6660.8 6712.5 6713.8 6715.2 6756.6 5503.3 6585.4 6607.1 6639.9 6680.8 4818.1 4885.2 4886.9 4893.6 4990.8 4885.0 4903.2 4917.9 4977.0 4996.1 4949.8 4954.0 4965.3 5005.1 5008.4 4996.4 5000.4 5010.3 5015.2 5020.7 4095.7 4188.6 4276.3 4281.2 4292.3 6318.6 6386.3 6387.7 6392.3 6407.5 5906.4 5913.0 5915.3 5916.3 5927.6 7102.2 7124.9 7132.4 7133.6 7134.3 4492.4 4800.1 4805.4 4806.4 4809.3 6073.2 7399.0 7508.1 7532.3 7594.6 4738.2 4741.8 4746.2 4746.9 4755.4 4829.5 4833.3 4834.0 4839.5 4852.3 5733.6 5739.8 5742.3 5752.9 5763.1 6924.3 7156.8 7163.7 7177.2 7177.3 1647.3 1660.2 1676.6 1678.4 1682.7 1052.8 1055.1 1073.1 1078.1 1079.5 5541.7 6586.5 6755.3 6791.5 6824.3 7287.6 7474.6 7484.9 7486.1 7522.5 6839.0 6850.8 6851.1 6857.7 6871.8 3926.8 3955.4 3961.1 3961.6 3969.8 7491.3 7807.0 7820.1 7837.3 7866.2 7649.3 7706.7 7712.6 7719.7 7727.7 7803.8 7815.0 7836.8 7841.2 7928.6 6016.1 7430.5 7436.7 7445.3 7475.2 7103.7 7314.1 7322.7 7324.0 7326.3 7224.5 7701.9 7752.2 7760.6 7763.7 4735.3 4770.2 4787.5 4788.0 4788.7 7418.7 7428.7 7432.8 7439.1 7440.4 6964.8 7158.9 7185.1 7188.9 7221.2 7075.2 7134.7 7140.9 7142.4 7143.6 6953.5 6957.2 6967.9 6976.9 6984.5 6925.0 7194.5 7197.2 7201.5 7212.2 6770.7 7068.3 7076.0 7086.2 7104.3 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18800.57 Median:2349.33 stddev:26271.60 (1118.3%) stddev-sans-first:26310.25 06:37:51 INFO - PROCESS | 1673 | Values: 727.3 768.3 801.3 803.4 829.6 15431.3 15438.8 15765.0 15777.9 15808.2 14730.8 14843.6 14848.9 14849.8 14878.0 808.2 818.7 819.4 952.5 999.6 818.5 921.6 1012.4 1014.8 1016.8 1052.5 1053.6 1054.4 1055.3 1056.1 837.6 1041.8 1044.7 1045.3 1046.0 761.4 768.5 768.9 769.0 769.9 15734.1 15748.7 15819.2 15824.8 15829.1 13694.7 13735.9 13765.2 13893.2 13916.0 10340.5 10451.7 10452.6 10705.6 11214.6 3622.7 3633.9 3673.1 3715.2 3885.9 87137.0 87238.6 88188.0 89532.6 89813.0 8263.3 9652.0 10018.0 10197.1 10324.0 21671.7 21862.1 21885.2 21890.7 21915.6 7825.0 7850.5 7885.1 7931.9 8120.6 3704.5 4267.2 4365.2 4409.9 4641.4 3809.4 3868.9 3869.4 3869.5 3871.3 669.4 688.1 690.6 693.8 711.0 431.5 549.0 599.0 603.4 637.6 7208.9 7964.8 8234.0 8452.2 8560.0 617.4 617.9 627.0 630.4 636.4 3733.8 3929.9 4038.8 4053.1 4063.6 83475.7 83652.0 84471.3 84692.6 97832.5 74735.6 75212.6 79123.2 79354.3 79903.0 73611.6 73749.6 74320.0 74900.6 75260.2 8612.3 8635.9 8664.0 8932.5 9168.3 44342.3 46417.8 47583.8 48527.3 50030.0 72065.0 72335.6 73993.6 75352.2 76873.2 11163.9 11210.0 11398.3 11524.6 11797.9 11769.6 12093.0 12140.3 12163.3 12915.2 8432.9 11157.8 11667.4 11667.9 11899.3 11451.7 11624.0 11787.9 12012.3 12089.2 11159.0 11336.7 11487.7 11623.9 12182.4 9683.3 9801.0 9872.7 9951.4 10088.5 9932.8 10085.5 10086.3 10101.4 10128.5 06:37:51 INFO - PROCESS | 1673 | -------- Summary: end -------- 06:37:51 INFO - PROCESS | 1673 | 06:37:51 INFO - TEST-INFO | 1673: exit 0 06:37:52 INFO - TEST-OK | dromaeo_css | took 1111152ms 06:37:52 INFO - TEST-START | kraken 06:37:52 INFO - operating with platform_type : mac_ 06:37:52 INFO - Initialising browser for kraken test... 06:37:52 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpAs9iJK/profile http://localhost:49275/getInfo.html) 06:37:54 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 06:37:54 INFO - PROCESS | 1679 | colorDepth:24 06:37:54 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 06:37:54 INFO - PROCESS | 1679 | __metrics 06:37:54 INFO - PROCESS | 1679 | 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" 06:37:54 INFO - PROCESS | 1679 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:37:54 INFO - TEST-INFO | 1679: exit 0 06:37:54 INFO - Browser initialized. 06:37:54 INFO - Running cycle 1/1 for kraken test... 06:37:54 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpAs9iJK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:06 INFO - PROCESS | 1682 | RSS: Main: 157245440 06:38:06 INFO - PROCESS | 1682 | 06:38:09 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:09 INFO - PROCESS | 1682 | RSS: Main: 218042368 06:38:09 INFO - PROCESS | 1682 | 06:38:12 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:38:13 INFO - PROCESS | 1682 | RSS: Main: 228286464 06:38:13 INFO - PROCESS | 1682 | 06:38:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:38:16 INFO - PROCESS | 1682 | RSS: Main: 218857472 06:38:16 INFO - PROCESS | 1682 | 06:38:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:38:19 INFO - PROCESS | 1682 | RSS: Main: 219398144 06:38:19 INFO - PROCESS | 1682 | 06:38:21 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:38:22 INFO - PROCESS | 1682 | RSS: Main: 220098560 06:38:22 INFO - PROCESS | 1682 | 06:38:25 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:38:25 INFO - PROCESS | 1682 | RSS: Main: 219975680 06:38:25 INFO - PROCESS | 1682 | 06:38:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:38:28 INFO - PROCESS | 1682 | RSS: Main: 220151808 06:38:28 INFO - PROCESS | 1682 | 06:38:31 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:38:32 INFO - PROCESS | 1682 | RSS: Main: 220356608 06:38:32 INFO - PROCESS | 1682 | 06:38:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:38:34 INFO - PROCESS | 1682 | RSS: Main: 255205376 06:38:34 INFO - PROCESS | 1682 | 06:38:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:38:36 INFO - PROCESS | 1682 | RSS: Main: 256417792 06:38:36 INFO - PROCESS | 1682 | 06:38:38 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:38:39 INFO - PROCESS | 1682 | RSS: Main: 261353472 06:38:39 INFO - PROCESS | 1682 | 06:38:41 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:38:42 INFO - PROCESS | 1682 | RSS: Main: 262447104 06:38:42 INFO - PROCESS | 1682 | 06:38:44 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:38:45 INFO - PROCESS | 1682 | RSS: Main: 262643712 06:38:45 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:38:47 INFO - PROCESS | 1682 | RSS: Main: 326148096 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | __start_tp_report 06:38:47 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 06:38:47 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 06:38:47 INFO - PROCESS | 1682 | |i|pagename|runs| 06:38:47 INFO - PROCESS | 1682 | |0;ai-astar;112;133;164;147;112;111;130;111;108;110 06:38:47 INFO - PROCESS | 1682 | |1;audio-beat-detection;154;119;148;147;102;139;137;179;155;135 06:38:47 INFO - PROCESS | 1682 | |2;audio-dft;182;184;183;184;182;184;183;184;183;184 06:38:47 INFO - PROCESS | 1682 | |3;audio-fft;70;74;75;118;54;56;58;60;119;82 06:38:47 INFO - PROCESS | 1682 | |4;audio-oscillator;89;93;140;105;119;126;114;81;114;159 06:38:47 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;86;84;87;84;86;83;87;84;86;83 06:38:47 INFO - PROCESS | 1682 | |6;imaging-darkroom;93;102;94;93;92;92;93;93;92;93 06:38:47 INFO - PROCESS | 1682 | |7;imaging-desaturate;86;86;85;84;84;86;85;86;86;86 06:38:47 INFO - PROCESS | 1682 | |8;json-parse-financial;55;59;57;57;57;57;58;58;68;54 06:38:47 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;62;61;68;70;71;68;64;62;70;65 06:38:47 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;79;80;80;78;80;81;79;79;80;84 06:38:47 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;126;134;128;124;120;124;131;126;129;125 06:38:47 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;133;133;134;129;134;122;127;132;128;133 06:38:47 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;66;67;65;66;64;65;67;65;66;64 06:38:47 INFO - PROCESS | 1682 | __end_tp_report 06:38:47 INFO - PROCESS | 1682 | __start_cc_report 06:38:47 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1206 06:38:47 INFO - PROCESS | 1682 | __end_cc_report 06:38:47 INFO - PROCESS | 1682 | __startTimestamp1465220327406__endTimestamp 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | ------- Summary: start ------- 06:38:47 INFO - PROCESS | 1682 | Number of tests: 14 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:123.80 Median:121.00 stddev:19.22 (15.9%) stddev-sans-first:19.90 06:38:47 INFO - PROCESS | 1682 | Values: 112.0 133.0 164.0 147.0 112.0 111.0 130.0 111.0 108.0 110.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:147.50 stddev:20.96 (14.2%) stddev-sans-first:21.73 06:38:47 INFO - PROCESS | 1682 | Values: 154.0 119.0 148.0 147.0 102.0 139.0 137.0 179.0 155.0 135.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:0.82 (0.4%) stddev-sans-first:0.73 06:38:47 INFO - PROCESS | 1682 | Values: 182.0 184.0 183.0 184.0 182.0 184.0 183.0 184.0 183.0 184.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:76.60 Median:65.00 stddev:23.92 (36.8%) stddev-sans-first:25.25 06:38:47 INFO - PROCESS | 1682 | Values: 70.0 74.0 75.0 118.0 54.0 56.0 58.0 60.0 119.0 82.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:114.00 Median:149.50 stddev:23.82 (15.9%) stddev-sans-first:23.48 06:38:47 INFO - PROCESS | 1682 | Values: 89.0 93.0 140.0 105.0 119.0 126.0 114.0 81.0 114.0 159.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 06:38:47 INFO - PROCESS | 1682 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 87.0 84.0 86.0 83.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.00 stddev:2.98 (3.2%) stddev-sans-first:3.15 06:38:47 INFO - PROCESS | 1682 | Values: 93.0 102.0 94.0 93.0 92.0 92.0 93.0 93.0 92.0 93.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 06:38:47 INFO - PROCESS | 1682 | Values: 86.0 86.0 85.0 84.0 84.0 86.0 85.0 86.0 86.0 86.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:57.50 stddev:3.80 (6.6%) stddev-sans-first:3.87 06:38:47 INFO - PROCESS | 1682 | Values: 55.0 59.0 57.0 57.0 57.0 57.0 58.0 58.0 68.0 54.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:68.00 stddev:3.75 (5.5%) stddev-sans-first:3.68 06:38:47 INFO - PROCESS | 1682 | Values: 62.0 61.0 68.0 70.0 71.0 68.0 64.0 62.0 70.0 65.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:1.63 (2.0%) stddev-sans-first:1.69 06:38:47 INFO - PROCESS | 1682 | Values: 79.0 80.0 80.0 78.0 80.0 81.0 79.0 79.0 80.0 84.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:127.00 stddev:3.97 (3.1%) stddev-sans-first:4.21 06:38:47 INFO - PROCESS | 1682 | Values: 126.0 134.0 128.0 124.0 120.0 124.0 131.0 126.0 129.0 125.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.50 Median:133.00 stddev:3.92 (2.9%) stddev-sans-first:4.06 06:38:47 INFO - PROCESS | 1682 | Values: 133.0 133.0 134.0 129.0 134.0 122.0 127.0 132.0 128.0 133.0 06:38:47 INFO - PROCESS | 1682 | 06:38:47 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 06:38:47 INFO - PROCESS | 1682 | Values: 66.0 67.0 65.0 66.0 64.0 65.0 67.0 65.0 66.0 64.0 06:38:47 INFO - PROCESS | 1682 | -------- Summary: end -------- 06:38:47 INFO - PROCESS | 1682 | 06:38:48 INFO - TEST-INFO | 1682: exit 0 06:38:48 INFO - TEST-OK | kraken | took 56105ms 06:38:48 INFO - SUITE-END | took 1167s 06:38:48 INFO - Completed test suite (00:19:27) 06:38:48 INFO - javascript benchmark 06:38:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3589.5513060867393, 3641.7268704847143, 3660.9633903660974, 3661.9450708239365, 3715.8513659453624, 5842.561807864406, 5878.970605146979, 5880.9411905880925, 5906.025505791539, 5952.077427998656, 5829.825105246839, 5876.11858221267, 5884.205632239647, 5890.499307558863, 5906.5570082243785, 5209.8249255347255, 5277.6305658603915, 5321.760520776555, 5359.383670877841, 5394.811181608645, 5378.435264297251, 5399.568034557226, 5411.675299482024, 5419.0, 5423.376311724143, 4347.3261644445, 5384.40771515135, 5387.272718183054, 5390.029794636964, 5415.64798288112, 5395.325584301962, 5431.293931788842, 5433.483819037205, 5438.945610543923, 5476.26070480484, 4051.6758659307184, 5334.493223143795, 5343.145096784535, 5347.010803001457, 5358.705271210082, 4884.560389564921, 5588.972055139739, 5691.6585004899425, 5748.540116790647, 5754.165645981351, 6182.227221597301, 6275.8744825103695, 6283.874322513524, 6285.0, 6287.9685601572155, 5252.71110088945, 6257.5932564383165, 6267.2479302484, 6307.684615769239, 6313.021481670348, 5993.130996005555, 6021.2473440819895, 6033.3966603340195, 6036.456718895321, 6052.697365131726, 6248.812535623939, 6397.360263973565, 6420.101185833987, 6429.678516074177, 6444.967775161094, 6224.657643829632, 6299.779507717208, 6311.33730958252, 6341.0, 6350.682465876687, 6282.560220784592, 6289.213848268966, 6289.55973081889, 6296.5592408531875, 6296.937030629727, 6181.412765787243, 6213.37866213375, 6213.782517611862, 6275.811725648238, 6277.843053923689, 6227.09707092469, 6238.968805155945, 6266.937330626726, 6267.811965641038, 6269.122322874801, 5073.34046573943, 5073.416557095981, 5076.36545431821, 5077.8730531737, 5099.413567439718, 4501.144782491317, 4577.359169716243, 4583.747893865802, 4615.515370886009, 4620.67655264135, 5975.312839023481, 5977.133315669291, 6004.609700369462, 6063.151158837766, 6075.936711075544, 6275.905861412082, 6280.623162610258, 6282.089097080898, 6289.528285378615, 6435.967820160869, 5821.7089145543405, 5977.641341519523, 6034.336223015464, 6036.63780173191, 6048.546359023091, 5491.313585801775, 5533.363663178706, 5553.028220061473, 5578.888422231533, 5586.692731899784, 6408.6475243861105, 6413.230412350548, 6416.197975253187, 6418.935810641927, 6434.163558737334, 5158.767855446439, 6214.1921550198185, 6229.12792209082, 6280.717367718372, 6297.716602752978, 6416.647084410216, 6429.0, 6432.742690292524, 6445.548811583144, 6452.645104519296, 6264.436200741955, 6299.937000630119, 6300.0234963580715, 6308.716107775253, 6313.0, 6194.101855231056, 6204.193454850841, 6216.098665693358, 6259.12372267892, 6282.591631543935, 6273.341299163704, 6285.088662144055, 6288.371162883675, 6294.244690637152, 6312.368763123651, 6270.937290627217, 6278.968605156945, 6279.560430769802, 6289.962156244169, 6310.810675679737, 6075.088736689355, 6140.048292514667, 6171.3828617139725, 6176.598521096114, 6183.659898705614, 6103.298120716175, 6257.092721555258, 6277.654728989949, 6284.937150628435, 6291.528135389955, 5934.109883517507, 6197.535184861244, 6202.689865506797, 6211.3478084802255, 6223.533235007483], "unit": "score", "name": "dojo.html", "value": 5820.561853654056}, {"lowerIsBetter": false, "replicates": [523.4817530644663, 572.6134858970194, 601.868487243981, 675.4393853101926, 677.4309579952838, 41663.37504937425, 42178.57821421785, 42294.73115806523, 42303.36544951824, 42480.57519424805, 45186.0, 45799.77100114503, 45824.0, 45915.0, 45927.77036114823, 8235.794105147374, 9965.750856228617, 10071.345362551447, 10087.747806304827, 10092.94953525235, 9930.155936745348, 10073.093421592055, 10087.394756314608, 10087.546060427298, 10091.444970526618, 8509.319254459659, 10250.538725757322, 10264.589416423334, 10271.435071071088, 10304.0, 10146.54340554673, 10210.132138768213, 10214.642487512901, 10225.23310751693, 10226.79546409075, 9904.356216845954, 10044.547995340226, 10056.245781566411, 10058.647947321808, 10089.747756306078, 43766.0, 44357.0, 44357.556424435665, 44383.77808110971, 44439.33340999888, 2789.5955086512545, 3402.7447941404494, 3407.6762707542744, 3421.555197824292, 3487.232808782064, 27959.30101747452, 28020.859895700596, 28083.157505274874, 28326.716732832614, 29021.12936611905, 2812.4515719434717, 2818.4644917465616, 2853.42931413716, 2866.899658511942, 2880.3807181456086, 399660.0, 400332.9999999971, 402850.0, 403348.0, 403688.0, 48397.0, 48818.511814882106, 48867.0, 48929.7767555814, 71902.28097718956, 47929.0, 48076.79808004758, 48275.31036413708, 48287.517124829006, 48362.51637483651, 14748.0, 17612.383566575107, 17790.37733679315, 17804.0, 17814.10929453522, 14162.858371416154, 14195.574132776033, 14203.502877399242, 14231.146131232159, 14381.0, 15280.847191528164, 15292.00601960869, 15371.385144594315, 15445.922770386302, 15566.533004009898, 5715.199872017921, 6479.546431749733, 6509.316521765241, 6653.1018312527185, 6660.400563949171, 1013.5388398278766, 1031.4945676618404, 1122.7944219254075, 1167.9299242045504, 1240.503798480615, 31152.844235778677, 31153.844230779156, 31313.530297045563, 31769.36461270808, 31806.84096579502, 1155.4338374196584, 1206.2460961898814, 1227.7728620205291, 1233.907456940715, 1251.0992085698283, 13519.324033798468, 15955.042697438192, 16052.598685032968, 16098.275577599035, 16469.752953705705, 543538.0, 543881.0, 546796.0, 547650.0, 554126.0, 57826.0, 61784.146475605805, 64447.03329450062, 65185.0, 65580.0, 1421.7085497472992, 1542.4447199008348, 1559.5087547422524, 1584.841515848429, 1587.2539906244278, 24209.63685544683, 30146.397072058873, 30452.70354257303, 31617.5257371135, 31672.366552668358, 52489.737551312, 53274.0, 53641.39075827732, 53681.46318536765, 53872.73063634657, 368217.0, 372657.0, 372712.0, 372950.0, 373958.0, 30749.538756918217, 35236.647633524364, 35668.0, 36129.819350903075, 36278.4558231632, 44154.77922610367, 45043.54956450524, 45249.86875328143, 45302.54697452983, 45706.31440528328, 36967.63032369749, 37169.0, 37330.813345934184, 37692.6807561734, 38023.239535209694, 37474.437883432314, 38107.618923810405, 38112.809435952644, 38552.0, 39059.82820515389, 38385.04037398976, 38731.80634096811, 38771.0, 38778.836634900996, 41360.0, 30836.383272334875, 31373.0, 33017.50249929113, 34355.31289374149, 35017.29965400627, 26057.218283451526, 26224.01743668697, 26302.079427219956, 26552.601710974737, 26950.59574106351], "unit": "score", "name": "ext.html", "value": 19871.508414587734}, {"lowerIsBetter": false, "replicates": [322.59353214949164, 375.52121045666775, 375.7275974918183, 381.5478657790517, 385.1353710918987, 20287.98560071996, 20682.96585170741, 21386.037628306723, 21747.80387078712, 21762.0, 21148.0, 21158.57682846342, 21888.23391181309, 22028.77971220287, 22107.557848843015, 5929.940700592982, 6140.140380346755, 6197.039458883858, 6203.720832562545, 6203.782867599635, 4108.548059713429, 4292.720973136736, 4294.291441912081, 4301.397804307399, 5650.886982260352, 4293.978530107345, 5699.572532060112, 5975.43233392827, 5999.610025348338, 6004.219451471325, 5898.587098903078, 5961.761529538813, 6488.8702225955685, 6522.7390904364, 6529.249136349333, 4006.959930400688, 5624.662520248758, 5721.6853073080965, 5730.484256416942, 5743.138529220609, 24947.251582452554, 25191.874040629864, 26014.21957341264, 26084.86957565219, 26135.08527201539, 2084.5309805293687, 2149.258505815491, 2164.805167534914, 2352.3531028967, 2660.7605315521696, 9794.608215671364, 10215.795684086352, 10411.583536658525, 11702.473388697445, 15592.454264100816, 1763.5943732941366, 1767.8320559546823, 1777.297967302921, 1802.9549261268573, 1856.4059500959697, 105995.0, 112568.0, 113926.43036784763, 115211.0, 115213.42393288064, 11308.264962777394, 11520.963113319674, 11550.133739969537, 11793.528258869721, 12024.879751202376, 11878.40607969598, 14504.34730437133, 14542.127472351482, 14547.05444146127, 14588.270586470633, 8929.33030022751, 9046.909530904739, 9082.500462474627, 9086.091390860862, 9156.108653822099, 9370.29722770795, 9394.530273486434, 9398.389104708172, 9421.76445588852, 9481.194098501566, 4510.954890451119, 4547.431571053618, 4554.590086892195, 4557.111363284128, 4621.237495813153, 1630.6901688679113, 1783.0465535663388, 1797.8112298208762, 1877.9061046947597, 1931.4688460673426, 933.3699752667059, 963.7783309838776, 998.2912132386087, 1008.178334657252, 1100.2463312630873, 19663.402205501912, 21015.474613134487, 21119.78880211209, 21274.36176914695, 21286.78713212879, 837.3426859914938, 1015.9034891685278, 1023.2376879225025, 1048.2295512798087, 1065.3714308557987, 11103.167262455348, 11411.828822567668, 11457.598984035685, 11465.426728663533, 11543.76912461746, 625907.0, 661051.0, 670133.0, 678545.0, 688930.0, 121016.39491802485, 122280.0, 124245.75754242342, 124665.0, 125529.0, 1056.2606175677056, 1068.3696618994986, 1069.3476979042778, 1087.9184061195442, 1089.5968491658136, 14466.0, 18255.178516967033, 19438.0, 19515.21939122476, 19804.40586782399, 45898.54101458943, 47830.0, 48555.0, 49957.50127496181, 51735.74132129315, 50818.74590627023, 64456.67771661111, 66549.66725166343, 66764.0, 66776.0, 6638.966805165944, 8066.83866322682, 8307.6676932922, 8503.74488765338, 8851.867221991546, 12488.313142776871, 13196.076274660682, 13813.723725525635, 13910.374033168735, 14293.714125717635, 11022.393768342818, 11269.830952535554, 11801.586944457245, 11806.940965295118, 12318.753624927273, 11801.114916381477, 11814.881851181379, 11918.880811191777, 11953.402329883646, 12105.394730263628, 11963.461644225858, 12013.978811801095, 12017.09871759604, 12291.0, 12304.200226985216, 7053.6825842838225, 8184.713535026483, 8289.212524810133, 8299.17418168002, 8492.660293588187, 8653.913460865562, 11098.167637427387, 11900.40497975115, 11921.463534141158, 12049.216800907912], "unit": "score", "name": "jquery.html", "value": 9643.60060622099}, {"lowerIsBetter": false, "replicates": [1523.299282330128, 1642.7207374746292, 1653.3469279634542, 1992.7807941126473, 2069.3171253486353, 7296.854062918738, 7411.184769675331, 8148.225918537737, 8762.693305734301, 8961.59672814724, 8728.127187281269, 8805.427647202927, 8851.95574022129, 8860.601272942717, 8880.33397495189, 2531.164715643833, 2552.1960582416573, 2559.424129570841, 2560.4637217669438, 2571.742825717432, 2508.1973768394123, 2564.6024866145776, 2576.621507493491, 2579.1746641074865, 2592.5333439980795, 2548.362909272682, 2565.9486810263784, 2586.612008198776, 2587.2109006752935, 2588.5470042742445, 2550.0564791027346, 2558.3604098975256, 2571.459993401388, 2573.318070711263, 2575.8068144889207, 2510.9874450627813, 2542.4660821227562, 2547.949041019188, 2558.4243545202366, 2559.0147793099604, 9042.547872606343, 9077.228435582983, 9086.409383390059, 9089.272858171396, 9316.487593182372, 5541.0580201365865, 5690.06113991191, 5742.799002034951, 5749.1663708762, 5759.971200143972, 3647.270545890806, 3680.4479328100733, 3760.9623903760885, 3802.923941521154, 3805.429185622151, 1071.9464026798628, 1087.3910610058313, 1088.086007753483, 1103.431732657682, 1124.5614210457927, 12338.568150114703, 12348.814767778491, 12370.381480925918, 12374.257544547267, 12381.0, 4916.115099282092, 4946.752662366867, 5003.2995380646735, 5010.924836127461, 5054.4692807254705, 10427.635032773816, 10458.843117353246, 10464.52909619069, 10465.110465610509, 10479.42363170033, 5662.971685141548, 5676.914846277309, 5681.914771278434, 5711.514521265739, 5725.599208055396, 4579.107074120581, 4759.143354196278, 4911.386076740408, 5109.56568691658, 5154.484551544891, 3979.8408063677707, 4030.858919937788, 4036.656884164879, 4069.5116586009867, 4083.1017176221185, 1355.2681551962028, 1366.7403193393422, 1367.3026756354332, 1380.1443105274593, 1381.88944884409, 1271.5867343113525, 1430.9141451512942, 1457.0674768148394, 1484.2578710644677, 1489.1660670024821, 8292.958535207285, 8490.617922193516, 8515.872261916076, 8516.190961858738, 8516.574171291411, 1182.798924182891, 1233.7224124571899, 1284.7494738526084, 1291.4124073546514, 1299.7660421124287, 6356.173697419398, 6370.394812492806, 6421.614703117828, 6429.58207716497, 6433.195850518685, 10845.51195196218, 11044.282121662067, 11327.943360283145, 11328.830067548828, 11333.773324533464, 10940.671779846618, 10959.780804383867, 11003.33979961205, 11060.22578419519, 11100.278481898651, 8432.241098301183, 8559.358048146414, 8559.758834968894, 8579.65681372738, 8636.22273995331, 8013.6794528218215, 8058.919410806051, 8082.393820463371, 8156.0620528639965, 8166.265036146538, 9241.537923103953, 9730.95134524323, 9731.221502279659, 9756.85364719544, 10003.649872254437, 9574.186194173573, 9997.950010249902, 10012.39925604466, 10019.0, 10024.498775061364, 7564.621768911643, 7569.3566046884425, 7577.583232922242, 7666.4633475656165, 7670.0795904491815, 7027.472939529658, 8301.211384918422, 8307.6676932922, 8371.916280837357, 8375.916240837514, 7468.253174682488, 7678.61606919663, 7740.380769538311, 7762.5730584818375, 7800.648970796215, 7344.15542212631, 7424.814379640552, 7459.813504662427, 7474.953506509202, 7560.810979725551, 7939.642716077906, 7974.641141148778, 8010.198980101944, 8021.598920054091, 8032.518048917083, 7188.496805223794, 7193.496455248082, 7236.89144662841, 7276.8908466374105, 7283.271672832674, 7306.561606303639, 7307.817304567216, 7357.963210183915, 7359.264073592598, 7366.337029667356], "unit": "score", "name": "mootools.html", "value": 5125.5044344409935}, {"lowerIsBetter": false, "replicates": [332.78701630956186, 340.66955053598, 341.6054457102048, 343.0326479328294, 346.05354355836766, 6660.76687315944, 6712.496562757794, 6713.832154196148, 6715.160604924385, 6756.628385438799, 5503.257060296859, 6585.374389433008, 6607.108040414542, 6639.867202655944, 6680.799576012728, 4818.132736107499, 4885.193942999401, 4886.926696099561, 4893.584045356131, 4990.800367985285, 4885.0, 4903.215485522317, 4917.877053073666, 4976.950230497685, 4996.075725990685, 4949.826756063539, 4953.9504604953845, 4965.255211718253, 5005.124103281911, 5008.449070602231, 4996.375453068366, 5000.449950505442, 5010.298558201873, 5015.172496538067, 5020.698758074527, 4095.7098513968003, 4188.560201178893, 4276.2516559601945, 4281.165172791307, 4292.313229883235, 6318.589291696071, 6386.329435409308, 6387.712552935129, 6392.264889537716, 6407.455366293871, 5906.3798301178185, 5913.0, 5915.290165180163, 5916.349201587822, 5927.644341339534, 7102.183248926388, 7124.8575028499135, 7132.358087772125, 7133.607651579161, 7134.286571342876, 4492.438445194351, 4800.135975524404, 4805.399325084364, 4806.447258565275, 4809.326694262806, 6073.240844894388, 7399.038125043764, 7508.136564295067, 7532.322091011835, 7594.5822979736095, 4738.241881298975, 4741.762911854394, 4746.240601503743, 4746.935083238802, 4755.405574303212, 4829.468758436569, 4833.299171620095, 4833.951660483421, 4839.467658557556, 4852.272159176081, 5733.569982251348, 5739.799107031317, 5742.31092268922, 5752.9424705753245, 5763.077907534775, 6924.3421874921805, 7156.821079473055, 7163.7134514619875, 7177.1746249182015, 7177.282271772781, 1647.2669662000535, 1660.2031025107774, 1676.5808547863035, 1678.429334026437, 1682.6634673065437, 1052.7999680060764, 1055.076807793181, 1073.1468482556231, 1078.1374900079904, 1079.4710591809956, 5541.695206763667, 6586.473082153416, 6755.256921738604, 6791.524593278522, 6824.283450237654, 7287.599182045038, 7474.588897610575, 7484.925150748531, 7486.13909400415, 7522.548647081193, 6838.965805171042, 6850.828729281808, 6851.143607049119, 6857.7256909723055, 6871.759488417882, 3926.8429262829736, 3955.3869150281753, 3961.069148750041, 3961.564227934925, 3969.801509924492, 7491.325780679765, 7806.960965195138, 7820.061592608809, 7837.294643482, 7866.174051724601, 7649.349805266504, 7706.653200605986, 7712.5758083305955, 7719.729809456642, 7727.690892364355, 7803.765887023284, 7815.0, 7836.764897053097, 7841.176676448891, 7928.603569821601, 6016.09758536223, 7430.517016394133, 7436.665350059152, 7445.255474452511, 7475.2151024141685, 7103.680334385069, 7314.122305323395, 7322.707091716272, 7323.963380183065, 7326.267373262631, 7224.494285399972, 7701.922980770345, 7752.186020467995, 7760.573168475788, 7763.68945242184, 4735.266033764772, 4770.165221086227, 4787.473377928355, 4787.976060119677, 4788.688735232199, 7418.66616002292, 7428.70285188608, 7432.777016689508, 7439.107307123179, 7440.3675687567165, 6964.756233531802, 7158.928410716035, 7185.101862267216, 7188.921661750779, 7221.241769614115, 7075.23119220195, 7134.714611415693, 7140.9285907140265, 7142.357187853118, 7143.6428178591905, 6953.478489113236, 6957.199922009034, 6967.885138377836, 6976.930230697831, 6984.511084224056, 6924.965375173092, 7194.460415468965, 7197.172325182671, 7201.459890508338, 7212.2427145149, 6770.695318710768, 7068.257832927468, 7075.964620176866, 7086.185088714864, 7104.289571042855], "unit": "score", "name": "prototype.html", "value": 5258.452992565406}, {"lowerIsBetter": false, "replicates": [727.3054233207284, 768.258630421643, 801.3428988229654, 803.4496369986558, 829.5644786487095, 15431.305591248392, 15438.84561154388, 15765.0, 15777.921110394447, 15808.209589520535, 14730.779038314407, 14843.554693359189, 14848.925755371209, 14849.777253341208, 14878.032927859682, 808.2079562029215, 818.7134502923959, 819.3854609043218, 952.5380190607567, 999.5901680311074, 818.5007145641155, 921.5668635741224, 1012.3976234140674, 1014.7716763728139, 1016.8169729448696, 1052.468503405783, 1053.5785685725732, 1054.4464156317956, 1055.2560444886376, 1056.1128651932374, 837.6440012994453, 1041.7968496143255, 1044.7022598559413, 1045.263089521889, 1046.0428707732415, 761.4479502360799, 768.5350363030342, 768.876979683248, 768.9730859419893, 769.9307062364358, 15734.055956642638, 15748.685026299525, 15819.209039547977, 15824.76262856058, 15829.050256984618, 13694.726105477836, 13735.862641373558, 13765.174089554559, 13893.235872027035, 13916.0, 10340.482975851177, 10451.738706532322, 10452.634157804441, 10705.571777128906, 11214.607488737936, 3622.710183185365, 3633.8909832705062, 3673.1184515716304, 3715.1640880801874, 3885.8639947601982, 87137.0, 87238.5638071812, 88187.99999999936, 89532.55233723855, 89813.0, 8263.338932885355, 9651.951740241253, 10018.048285412875, 10197.133243674223, 10324.019218174262, 21671.67492487614, 21862.12551497954, 21885.154929859335, 21890.671639925415, 21915.561688766134, 7825.0, 7850.450468467153, 7885.053793544811, 7931.920680793233, 8120.553369564613, 3704.4628528863705, 4267.1892340455215, 4365.170617582649, 4409.911801763947, 4641.419822522184, 3809.409541521013, 3868.858686687434, 3869.400242962345, 3869.4776205212497, 3871.341871881787, 669.4008862068422, 688.1295161620554, 690.623610132479, 693.7987983484834, 710.989335159973, 431.47791172681406, 549.0227395236485, 598.9820305390846, 603.3966033966034, 637.6238019568477, 7208.891866622005, 7964.761057168294, 8233.958830205811, 8452.154784521621, 8560.0, 617.4473845907887, 617.9258488981261, 626.9561130720897, 630.3822254190827, 636.4399328590811, 3733.8133093345223, 3929.9214015719526, 4038.838446462167, 4053.088055187618, 4063.6342729154517, 83475.74786378209, 83652.0, 84471.31057378819, 84692.57653711815, 97832.51083744536, 74735.62632186804, 75212.62393687997, 79123.20876791273, 79354.25488833398, 79903.0, 73611.63194183995, 73749.6312518434, 74320.0, 74900.62549687325, 75260.24739752641, 8612.267957223707, 8635.913640863511, 8663.95668021656, 8932.46405215689, 9168.344894458172, 44342.334864977696, 46417.76791116023, 47583.76208118937, 48527.272090917955, 50030.0, 72065.0, 72335.63832180806, 73993.63003184949, 75352.24647753453, 76873.23126768661, 11163.888361116286, 11210.0, 11398.316101033965, 11524.59663911759, 11797.88202117968, 11769.624335569722, 12093.0, 12140.271583705005, 12163.2702037878, 12915.160514566523, 8432.873506897278, 11157.776844462904, 11667.358295293501, 11667.883321167019, 11899.345535995604, 11451.656450306504, 11624.0, 11787.941060294643, 12012.279263244232, 12089.15375923677, 11159.0, 11336.659900203007, 11487.695304735074, 11623.941880290882, 12182.390880455765, 9683.322167448492, 9801.0, 9872.654457093968, 9951.402915825074, 10088.495575221357, 9932.801343972935, 10085.546150423395, 10086.344387614781, 10101.44442055694, 10128.544215510172], "unit": "score", "name": "yui.html", "value": 7180.586878274479}], "name": "dromaeo_css", "value": 7745.278931890324}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 133.0, 164.0, 147.0, 112.0, 111.0, 130.0, 111.0, 108.0, 110.0], "unit": "score", "name": "ai-astar", "value": 123.8}, {"lowerIsBetter": true, "replicates": [154.0, 119.0, 148.0, 147.0, 102.0, 139.0, 137.0, 179.0, 155.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 141.5}, {"lowerIsBetter": true, "replicates": [182.0, 184.0, 183.0, 184.0, 182.0, 184.0, 183.0, 184.0, 183.0, 184.0], "unit": "score", "name": "audio-dft", "value": 183.3}, {"lowerIsBetter": true, "replicates": [70.0, 74.0, 75.0, 118.0, 54.0, 56.0, 58.0, 60.0, 119.0, 82.0], "unit": "score", "name": "audio-fft", "value": 76.6}, {"lowerIsBetter": true, "replicates": [89.0, 93.0, 140.0, 105.0, 119.0, 126.0, 114.0, 81.0, 114.0, 159.0], "unit": "score", "name": "audio-oscillator", "value": 114.0}, {"lowerIsBetter": true, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 87.0, 84.0, 86.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.0}, {"lowerIsBetter": true, "replicates": [93.0, 102.0, 94.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.7}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 85.0, 84.0, 84.0, 86.0, 85.0, 86.0, 86.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 85.4}, {"lowerIsBetter": true, "replicates": [55.0, 59.0, 57.0, 57.0, 57.0, 57.0, 58.0, 58.0, 68.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 58.0}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 68.0, 70.0, 71.0, 68.0, 64.0, 62.0, 70.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.1}, {"lowerIsBetter": true, "replicates": [79.0, 80.0, 80.0, 78.0, 80.0, 81.0, 79.0, 79.0, 80.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.0}, {"lowerIsBetter": true, "replicates": [126.0, 134.0, 128.0, 124.0, 120.0, 124.0, 131.0, 126.0, 129.0, 125.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.7}, {"lowerIsBetter": true, "replicates": [133.0, 133.0, 134.0, 129.0, 134.0, 122.0, 127.0, 132.0, 128.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.5}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 65.0, 66.0, 64.0, 65.0, 67.0, 65.0, 66.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.5}], "name": "kraken", "value": 1430.1000000000001}]} 06:38:48 INFO - Return code: 0 06:38:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:38:48 INFO - # TBPL SUCCESS # 06:38:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:38:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:38:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:38:48 INFO - 2016-06-06 06:38:48.748 screenresolution[1687:10486] starting screenresolution argv=screenresolution get 06:38:48 INFO - 2016-06-06 06:38:48.751 screenresolution[1687:10486] Display 0: 1600x1200x32@60 06:38:48 INFO - 2016-06-06 06:38:48.757 screenresolution[1688:10490] starting screenresolution argv=screenresolution list 06:38:48 INFO - Available Modes on Display 0 06:38:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:38:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:38:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:38:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:38:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:38:48 INFO - Intel Iris: 06:38:48 INFO - Chipset Model: Intel Iris 06:38:48 INFO - Type: GPU 06:38:48 INFO - Bus: Built-In 06:38:48 INFO - VRAM (Dynamic, Max): 1536 MB 06:38:48 INFO - Vendor: Intel (0x8086) 06:38:48 INFO - Device ID: 0x0a2e 06:38:48 INFO - Revision ID: 0x0009 06:38:48 INFO - Displays: 06:38:48 INFO - Display: 06:38:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:38:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:38:48 INFO - Display Serial Number: 1600x1200 60 06:38:48 INFO - Main Display: Yes 06:38:48 INFO - Mirror: Off 06:38:48 INFO - Online: Yes 06:38:48 INFO - Rotation: Supported 06:38:48 INFO - Return code: 0 06:38:48 INFO - Running post-action listener: _resource_record_post_action 06:38:48 INFO - Running post-run listener: _resource_record_post_run 06:38:50 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 32796160; Write bytes: 511471616; Read time: 326; Write time: 1665 06:38:50 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198685184; Write bytes: 199045632; Read time: 17920; Write time: 436 06:38:50 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 31333888; Write bytes: 302464512; Read time: 310; Write time: 1201 06:38:50 INFO - Running post-run listener: _upload_blobber_files 06:38:50 INFO - Blob upload gear active. 06:38:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:38:50 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 06:38:50 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:38:50 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:38:50 INFO - (blobuploader) - INFO - Open directory for files ... 06:38:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:38:51 INFO - (blobuploader) - INFO - Done attempting. 06:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:38:52 INFO - (blobuploader) - INFO - Done attempting. 06:38:52 INFO - (blobuploader) - INFO - Iteration through files over. 06:38:52 INFO - Return code: 0 06:38:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:38:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:38:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de42d24286eead61f3dae1a9aea8f26f99caf1e0802279fca13d0caa744ea5dc555be290b39e4dd13fdaa70eeb1b2b171634f54605df1ddbe1a6737d92c99431", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88eb4da28c439d416e5a1fb6c9a68e1afc169de6d88acf38d6a93de497c90a9d0120ce262e3f03622a69d92fac9bef8bfe60f6e734c06f569f1ab341a66b523d"} 06:38:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:38:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:38:52 INFO - Contents: 06:38:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de42d24286eead61f3dae1a9aea8f26f99caf1e0802279fca13d0caa744ea5dc555be290b39e4dd13fdaa70eeb1b2b171634f54605df1ddbe1a6737d92c99431", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88eb4da28c439d416e5a1fb6c9a68e1afc169de6d88acf38d6a93de497c90a9d0120ce262e3f03622a69d92fac9bef8bfe60f6e734c06f569f1ab341a66b523d"} 06:38:52 INFO - Running post-run listener: copy_logs_to_upload_dir 06:38:52 INFO - Copying logs to upload dir... 06:38:52 INFO - mkdir: /builds/slave/test/build/upload/logs 06:38:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.898414 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-06 06:38:52.717666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 06:38:52.725548) ========= 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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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-Beta/sha512/de42d24286eead61f3dae1a9aea8f26f99caf1e0802279fca13d0caa744ea5dc555be290b39e4dd13fdaa70eeb1b2b171634f54605df1ddbe1a6737d92c99431", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88eb4da28c439d416e5a1fb6c9a68e1afc169de6d88acf38d6a93de497c90a9d0120ce262e3f03622a69d92fac9bef8bfe60f6e734c06f569f1ab341a66b523d"} build_url:https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/CZf5QtG-QfWSsAQhoQbuHg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de42d24286eead61f3dae1a9aea8f26f99caf1e0802279fca13d0caa744ea5dc555be290b39e4dd13fdaa70eeb1b2b171634f54605df1ddbe1a6737d92c99431", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88eb4da28c439d416e5a1fb6c9a68e1afc169de6d88acf38d6a93de497c90a9d0120ce262e3f03622a69d92fac9bef8bfe60f6e734c06f569f1ab341a66b523d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 06:38:52.760521) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:38:52.761800) ========= 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.AKr2x2PJ13/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.0mJtgW89Jz/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.005257 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 06:38:52.815468) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 06:38:52.815971) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 06:38:52.816571) ========= ========= Total master_lag: 1.62 =========