builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0146 starttime: 1460731514.46 results: success (0) buildid: 20160415063651 builduid: c4cac0753b6f431b80a8170ee4887662 revision: 5ff5f2178682a3888ada94fa29409d54b8d4716a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.465169) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.465640) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.465931) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.484295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.484673) ========= 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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-04-15 07:45:14-- 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% 11.1M=0.001s 2016-04-15 07:45:14 (11.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.625376) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.625710) ========= 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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.019437 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:14.674306) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 07:45:14.674674) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5ff5f2178682a3888ada94fa29409d54b8d4716a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5ff5f2178682a3888ada94fa29409d54b8d4716a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-04-15 07:45:14,747 truncating revision to first 12 chars 2016-04-15 07:45:14,748 Setting DEBUG logging. 2016-04-15 07:45:14,748 attempt 1/10 2016-04-15 07:45:14,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5ff5f2178682?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 07:45:15,834 unpacking tar archive at: mozilla-aurora-5ff5f2178682/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330186 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 07:45:16.021485) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:16.021833) ========= script_repo_revision: 5ff5f2178682a3888ada94fa29409d54b8d4716a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:16.022220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:16.022486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 07:45:16.036532) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2016-04-15 07:45:16.036906) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:16 INFO - MultiFileLogger online at 20160415 07:45:16 in /builds/slave/test 07:45:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:45:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:45:16 INFO - {'append_to_log': False, 07:45:16 INFO - 'base_work_dir': '/builds/slave/test', 07:45:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:45:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:45:16 INFO - 'branch': 'Mozilla-Aurora', 07:45:16 INFO - 'buildbot_json_path': 'buildprops.json', 07:45:16 INFO - 'config_files': ('talos/mac_config.py',), 07:45:16 INFO - 'default_actions': ('clobber', 07:45:16 INFO - 'read-buildbot-config', 07:45:16 INFO - 'download-and-extract', 07:45:16 INFO - 'populate-webroot', 07:45:16 INFO - 'create-virtualenv', 07:45:16 INFO - 'install', 07:45:16 INFO - 'run-tests'), 07:45:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:45:16 INFO - 'download_minidump_stackwalk': True, 07:45:16 INFO - 'download_symbols': 'ondemand', 07:45:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:45:16 INFO - 'tooltool.py': '/tools/tooltool.py', 07:45:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:45:16 INFO - '/tools/misc-python/virtualenv.py')}, 07:45:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:45:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:45:16 INFO - 'installer_path': 'installer.exe', 07:45:16 INFO - 'log_level': 'info', 07:45:16 INFO - 'log_name': 'talos', 07:45:16 INFO - 'log_to_console': True, 07:45:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:45:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:45:16 INFO - 'opt_config_files': (), 07:45:16 INFO - 'pip_index': False, 07:45:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:45:16 INFO - 'cmd': ('bash', 07:45:16 INFO - '-c', 07:45:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:45:16 INFO - 'enabled': True, 07:45:16 INFO - 'halt_on_failure': False, 07:45:16 INFO - 'name': 'check_screen_resolution'},), 07:45:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:45:16 INFO - 'cmd': ('bash', 07:45:16 INFO - '-c', 07:45:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:45:16 INFO - 'enabled': True, 07:45:16 INFO - 'halt_on_failure': False, 07:45:16 INFO - 'name': 'check_screen_resolution'},), 07:45:16 INFO - 'run_cmd_checks_enabled': True, 07:45:16 INFO - 'sps_profile': False, 07:45:16 INFO - 'sps_profile_interval': 0, 07:45:16 INFO - 'suite': 'dromaeojs', 07:45:16 INFO - 'system_bits': '32', 07:45:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:45:16 INFO - 'title': 't-yosemite-r7-0146', 07:45:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:45:16 INFO - 'use_talos_json': True, 07:45:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:45:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:45:16 INFO - 'work_dir': 'build'} 07:45:16 INFO - ##### 07:45:16 INFO - ##### Running clobber step. 07:45:16 INFO - ##### 07:45:16 INFO - Running pre-action listener: _resource_record_pre_action 07:45:16 INFO - Running main action method: clobber 07:45:16 INFO - rmtree: /builds/slave/test/build 07:45:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:45:17 INFO - Running post-action listener: _resource_record_post_action 07:45:17 INFO - ##### 07:45:17 INFO - ##### Running read-buildbot-config step. 07:45:17 INFO - ##### 07:45:17 INFO - Running pre-action listener: _resource_record_pre_action 07:45:17 INFO - Running main action method: read_buildbot_config 07:45:17 INFO - Using buildbot properties: 07:45:17 INFO - { 07:45:17 INFO - "project": "", 07:45:17 INFO - "product": "firefox", 07:45:17 INFO - "script_repo_revision": "production", 07:45:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:45:17 INFO - "repository": "", 07:45:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:45:17 INFO - "buildid": "20160415063651", 07:45:17 INFO - "pgo_build": "False", 07:45:17 INFO - "basedir": "/builds/slave/test", 07:45:17 INFO - "buildnumber": 182, 07:45:17 INFO - "slavename": "t-yosemite-r7-0146", 07:45:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:45:17 INFO - "platform": "yosemite_r7", 07:45:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:45:17 INFO - "branch": "mozilla-aurora", 07:45:17 INFO - "repo_path": "releases/mozilla-aurora", 07:45:17 INFO - "revision": "5ff5f2178682a3888ada94fa29409d54b8d4716a", 07:45:17 INFO - "stage_platform": "macosx64", 07:45:17 INFO - "builduid": "c4cac0753b6f431b80a8170ee4887662", 07:45:17 INFO - "slavebuilddir": "test" 07:45:17 INFO - } 07:45:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 07:45:17 INFO - Running post-action listener: _resource_record_post_action 07:45:17 INFO - ##### 07:45:17 INFO - ##### Running download-and-extract step. 07:45:17 INFO - ##### 07:45:17 INFO - Running pre-action listener: _resource_record_pre_action 07:45:17 INFO - Running main action method: download_and_extract 07:45:17 INFO - mkdir: /builds/slave/test/build/tests 07:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:17 INFO - https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:45:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 07:45:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 07:45:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 07:45:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 07:45:19 INFO - Downloaded 1277 bytes. 07:45:19 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 07:45:19 INFO - Using the following test package requirements: 07:45:19 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 07:45:19 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 07:45:19 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 07:45:19 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'jsshell-mac.zip'], 07:45:19 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 07:45:19 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 07:45:19 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 07:45:19 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 07:45:19 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 07:45:19 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 07:45:19 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 07:45:19 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 07:45:19 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:45: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')]} 07:45:19 INFO - https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:45:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:45:20 INFO - Downloaded 17910996 bytes. 07:45:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:45:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:45:23 INFO - Return code: 0 07:45:23 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:23 INFO - https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:45:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 07:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:45:24 INFO - Downloaded 17910996 bytes. 07:45:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:45:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:45:28 INFO - Return code: 0 07:45:28 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')]} 07:45:28 INFO - https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:45:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 07:45:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 07:45:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 07:45:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:45:29 INFO - Downloaded 10845168 bytes. 07:45:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:45:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:45:29 INFO - Return code: 0 07:45:29 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')]} 07:45:29 INFO - https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 07:45:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 07:45:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 07:45:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 07:45:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 07:45:32 INFO - Downloaded 115192138 bytes. 07:45:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 07:45:32 INFO - mkdir: /builds/slave/test/properties 07:45:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:45:32 INFO - Writing to file /builds/slave/test/properties/build_url 07:45:32 INFO - Contents: 07:45:32 INFO - build_url:https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 07:45:34 INFO - Running post-action listener: _resource_record_post_action 07:45:34 INFO - Running post-action listener: set_extra_try_arguments 07:45:34 INFO - ##### 07:45:34 INFO - ##### Running populate-webroot step. 07:45:34 INFO - ##### 07:45:34 INFO - Running pre-action listener: _resource_record_pre_action 07:45:34 INFO - Running main action method: populate_webroot 07:45:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:45:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:45:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:45:34 INFO - '--tppagecycles', 07:45:34 INFO - '7'], 07:45:34 INFO - 'tests': ['tsvgm']}}, 07:45:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:45:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:45:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 07:45:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:45:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'tests': ['damp', 'tps']}, 07:45:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['damp', 'tps']}, 07:45:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:45:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:45:34 INFO - 'other': {'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'other_l64': {'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'other_nol64': {'tests': ['a11yr', 07:45:34 INFO - 'ts_paint', 07:45:34 INFO - 'tpaint', 07:45:34 INFO - 'sessionrestore', 07:45:34 INFO - 'sessionrestore_no_auto_restore', 07:45:34 INFO - 'tabpaint']}, 07:45:34 INFO - 'svgr': {'tests': ['tsvgx', 07:45:34 INFO - 'tsvgr_opacity', 07:45:34 INFO - 'tart', 07:45:34 INFO - 'tscrollx', 07:45:34 INFO - 'cart']}, 07:45:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['tsvgx', 07:45:34 INFO - 'tsvgr_opacity', 07:45:34 INFO - 'tart', 07:45:34 INFO - 'tscrollx', 07:45:34 INFO - 'cart']}, 07:45:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'tests': ['tp5o']}, 07:45:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'talos_options': ['--e10s'], 07:45:34 INFO - 'tests': ['tp5o']}, 07:45:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'talos_options': ['--xperf_path', 07:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:34 INFO - 'tests': ['tp5n']}, 07:45:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:45:34 INFO - 'talos_options': ['--e10s', 07:45:34 INFO - '--xperf_path', 07:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:34 INFO - 'tests': ['tp5n']}}, 07:45:34 INFO - 'talos.zip': {'path': '', 07:45:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:45:34 INFO - Running post-action listener: _resource_record_post_action 07:45:34 INFO - ##### 07:45:34 INFO - ##### Running create-virtualenv step. 07:45:34 INFO - ##### 07:45:34 INFO - Running pre-action listener: _resource_record_pre_action 07:45:34 INFO - Running main action method: create_virtualenv 07:45:34 INFO - Creating virtualenv /builds/slave/test/build/venv 07:45:34 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 07:45:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:45:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:45:34 INFO - Using real prefix '/tools/python27' 07:45:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:45:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:45:37 INFO - Installing pip.................done. 07:45:37 INFO - Return code: 0 07:45:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:45:37 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')]} 07:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:37 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')]} 07:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:37 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:37 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 07:45:37 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 07:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:37 INFO - 'HOME': '/Users/cltbld', 07:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:37 INFO - 'LOGNAME': 'cltbld', 07:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:37 INFO - 'MOZ_NO_REMOTE': '1', 07:45:37 INFO - 'NO_EM_RESTART': '1', 07:45:37 INFO - 'PAGER': '/bin/cat', 07:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:37 INFO - 'PWD': '/builds/slave/test', 07:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:37 INFO - 'SHELL': '/bin/bash', 07:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:37 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:37 INFO - 'USER': 'cltbld', 07:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:37 INFO - 'XPC_FLAGS': '0x0', 07:45:37 INFO - 'XPC_SERVICE_NAME': '0', 07:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:38 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 07:45:38 INFO - Cleaning up... 07:45:38 INFO - Return code: 0 07:45:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:38 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:38 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 07:45:38 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 07:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:38 INFO - 'HOME': '/Users/cltbld', 07:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:38 INFO - 'LOGNAME': 'cltbld', 07:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:38 INFO - 'MOZ_NO_REMOTE': '1', 07:45:38 INFO - 'NO_EM_RESTART': '1', 07:45:38 INFO - 'PAGER': '/bin/cat', 07:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:38 INFO - 'PWD': '/builds/slave/test', 07:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:38 INFO - 'SHELL': '/bin/bash', 07:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:38 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:38 INFO - 'USER': 'cltbld', 07:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:38 INFO - 'XPC_FLAGS': '0x0', 07:45:38 INFO - 'XPC_SERVICE_NAME': '0', 07:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:38 INFO - Downloading/unpacking psutil>=0.7.1 07:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:45:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:41 INFO - Installing collected packages: psutil 07:45:41 INFO - Running setup.py install for psutil 07:45:41 INFO - building 'psutil._psutil_osx' extension 07:45:41 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 07:45:41 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 07:45:41 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 07:45:41 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 07:45:41 INFO - building 'psutil._psutil_posix' extension 07:45:41 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 07:45:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:45:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:45:41 INFO - ^ 07:45:41 INFO - 1 warning generated. 07:45:41 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 07:45:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:42 INFO - Successfully installed psutil 07:45:42 INFO - Cleaning up... 07:45:42 INFO - Return code: 0 07:45:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:45: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')]} 07:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45: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')]} 07:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:42 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:42 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 07:45:42 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 07:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:42 INFO - 'HOME': '/Users/cltbld', 07:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:42 INFO - 'LOGNAME': 'cltbld', 07:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:42 INFO - 'MOZ_NO_REMOTE': '1', 07:45:42 INFO - 'NO_EM_RESTART': '1', 07:45:42 INFO - 'PAGER': '/bin/cat', 07:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:42 INFO - 'PWD': '/builds/slave/test', 07:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:42 INFO - 'SHELL': '/bin/bash', 07:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:42 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:42 INFO - 'USER': 'cltbld', 07:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:42 INFO - 'XPC_FLAGS': '0x0', 07:45:42 INFO - 'XPC_SERVICE_NAME': '0', 07:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:45:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:45:44 INFO - Installing collected packages: mozsystemmonitor 07:45:44 INFO - Running setup.py install for mozsystemmonitor 07:45:45 INFO - Successfully installed mozsystemmonitor 07:45:45 INFO - Cleaning up... 07:45:45 INFO - Return code: 0 07:45:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:45 INFO - 'HOME': '/Users/cltbld', 07:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:45 INFO - 'LOGNAME': 'cltbld', 07:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:45 INFO - 'MOZ_NO_REMOTE': '1', 07:45:45 INFO - 'NO_EM_RESTART': '1', 07:45:45 INFO - 'PAGER': '/bin/cat', 07:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:45 INFO - 'PWD': '/builds/slave/test', 07:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:45 INFO - 'SHELL': '/bin/bash', 07:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:45 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:45 INFO - 'USER': 'cltbld', 07:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:45 INFO - 'XPC_FLAGS': '0x0', 07:45:45 INFO - 'XPC_SERVICE_NAME': '0', 07:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:45 INFO - Downloading/unpacking blobuploader==1.2.4 07:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:47 INFO - Downloading blobuploader-1.2.4.tar.gz 07:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:45:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:45:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:45: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 07:45: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 07:45: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 07:45: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 07:45: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 07:45: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 07:45:48 INFO - Downloading docopt-0.6.1.tar.gz 07:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:45:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:45:49 INFO - Running setup.py install for blobuploader 07:45:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:45:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:45:49 INFO - Running setup.py install for requests 07:45:49 INFO - Running setup.py install for docopt 07:45:49 INFO - Successfully installed blobuploader requests docopt 07:45:49 INFO - Cleaning up... 07:45:49 INFO - Return code: 0 07:45:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:49 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:49 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 07:45:49 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 07:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:49 INFO - 'HOME': '/Users/cltbld', 07:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:49 INFO - 'LOGNAME': 'cltbld', 07:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:49 INFO - 'MOZ_NO_REMOTE': '1', 07:45:49 INFO - 'NO_EM_RESTART': '1', 07:45:49 INFO - 'PAGER': '/bin/cat', 07:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:49 INFO - 'PWD': '/builds/slave/test', 07:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:49 INFO - 'SHELL': '/bin/bash', 07:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:49 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:49 INFO - 'USER': 'cltbld', 07:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:49 INFO - 'XPC_FLAGS': '0x0', 07:45:49 INFO - 'XPC_SERVICE_NAME': '0', 07:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Bj2mhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-BdZ034-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ctApCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-980EMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-wwPNot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-wgeWW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-enVuUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-f_MibL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:50 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-mcjCjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-fS3s1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qIthMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ar9r80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-jPJG6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-DpgkKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-00HEcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-LKLOnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:51 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-AeqzJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:45:51 INFO - Running setup.py install for manifestparser 07:45:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:45:52 INFO - Running setup.py install for mozcrash 07:45:52 INFO - Running setup.py install for mozdebug 07:45:52 INFO - Running setup.py install for mozdevice 07:45:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:45:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:45:52 INFO - Running setup.py install for mozfile 07:45:52 INFO - Running setup.py install for mozhttpd 07:45:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:45:52 INFO - Running setup.py install for mozinfo 07:45:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:45:52 INFO - Running setup.py install for mozInstall 07:45:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Running setup.py install for mozleak 07:45:53 INFO - Running setup.py install for mozlog 07:45:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Running setup.py install for moznetwork 07:45:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Running setup.py install for mozprocess 07:45:53 INFO - Running setup.py install for mozprofile 07:45:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:45:53 INFO - Running setup.py install for mozrunner 07:45:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:45:54 INFO - Running setup.py install for mozscreenshot 07:45:54 INFO - Running setup.py install for moztest 07:45:54 INFO - Running setup.py install for mozversion 07:45:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:45:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:45:54 INFO - Cleaning up... 07:45:54 INFO - Return code: 0 07:45:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:54 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')]} 07:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:54 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')]} 07:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:54 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:54 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 07:45:54 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 07:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:54 INFO - 'HOME': '/Users/cltbld', 07:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:54 INFO - 'LOGNAME': 'cltbld', 07:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:54 INFO - 'MOZ_NO_REMOTE': '1', 07:45:54 INFO - 'NO_EM_RESTART': '1', 07:45:54 INFO - 'PAGER': '/bin/cat', 07:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:54 INFO - 'PWD': '/builds/slave/test', 07:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:54 INFO - 'SHELL': '/bin/bash', 07:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:54 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:54 INFO - 'USER': 'cltbld', 07:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:54 INFO - 'XPC_FLAGS': '0x0', 07:45:54 INFO - 'XPC_SERVICE_NAME': '0', 07:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:54 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-K1_2iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:54 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)) 07:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:54 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-DFClkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:54 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)) 07:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:54 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-E7DC65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-rl6b5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-ertlbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-m4JreG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-FeAigh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-mLtrC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-5xRMjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-KtvPzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-WdhMq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-B0j0F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:55 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)) 07:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:55 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-0TwmWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:56 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)) 07:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:56 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-JWViCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:56 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)) 07:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:56 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-tqzFfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:56 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)) 07:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:56 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-EYpcvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:56 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)) 07:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:56 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-IjeLxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:56 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)) 07:45:56 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)) 07:45:56 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)) 07:45:56 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)) 07:45:56 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)) 07:45:56 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)) 07:45:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:58 INFO - Downloading blessings-1.6.tar.gz 07:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:45:59 INFO - Installing collected packages: blessings 07:45:59 INFO - Running setup.py install for blessings 07:45:59 INFO - Successfully installed blessings 07:45:59 INFO - Cleaning up... 07:45:59 INFO - Return code: 0 07:45:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:45:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:59 INFO - Reading from file tmpfile_stdout 07:45:59 INFO - Current package versions: 07:45:59 INFO - blessings == 1.6 07:45:59 INFO - blobuploader == 1.2.4 07:45:59 INFO - docopt == 0.6.1 07:45:59 INFO - manifestparser == 1.1 07:45:59 INFO - mozInstall == 1.12 07:45:59 INFO - mozcrash == 0.17 07:45:59 INFO - mozdebug == 0.1 07:45:59 INFO - mozdevice == 0.48 07:45:59 INFO - mozfile == 1.2 07:45:59 INFO - mozhttpd == 0.7 07:45:59 INFO - mozinfo == 0.9 07:45:59 INFO - mozleak == 0.1 07:45:59 INFO - mozlog == 3.1 07:45:59 INFO - moznetwork == 0.27 07:45:59 INFO - mozprocess == 0.22 07:45:59 INFO - mozprofile == 0.28 07:45:59 INFO - mozrunner == 6.11 07:45:59 INFO - mozscreenshot == 0.1 07:45:59 INFO - mozsystemmonitor == 0.0 07:45:59 INFO - moztest == 0.7 07:45:59 INFO - mozversion == 1.4 07:45:59 INFO - psutil == 3.1.1 07:45:59 INFO - requests == 1.2.3 07:45:59 INFO - wsgiref == 0.1.2 07:45:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:59 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')]} 07:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:59 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')]} 07:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:59 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:59 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 07:45:59 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 07:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:59 INFO - 'HOME': '/Users/cltbld', 07:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:59 INFO - 'LOGNAME': 'cltbld', 07:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:59 INFO - 'MOZ_NO_REMOTE': '1', 07:45:59 INFO - 'NO_EM_RESTART': '1', 07:45:59 INFO - 'PAGER': '/bin/cat', 07:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:59 INFO - 'PWD': '/builds/slave/test', 07:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:59 INFO - 'SHELL': '/bin/bash', 07:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:59 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:59 INFO - 'USER': 'cltbld', 07:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:59 INFO - 'XPC_FLAGS': '0x0', 07:45:59 INFO - 'XPC_SERVICE_NAME': '0', 07:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 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)) 07:45:59 INFO - Cleaning up... 07:45:59 INFO - Return code: 0 07:45:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:45:59 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')]} 07:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:59 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')]} 07:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:45:59 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:45:59 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 07:45:59 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 07:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:59 INFO - 'HOME': '/Users/cltbld', 07:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:59 INFO - 'LOGNAME': 'cltbld', 07:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:59 INFO - 'MOZ_NO_REMOTE': '1', 07:45:59 INFO - 'NO_EM_RESTART': '1', 07:45:59 INFO - 'PAGER': '/bin/cat', 07:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:59 INFO - 'PWD': '/builds/slave/test', 07:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:59 INFO - 'SHELL': '/bin/bash', 07:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:45:59 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:59 INFO - 'USER': 'cltbld', 07:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:59 INFO - 'XPC_FLAGS': '0x0', 07:45:59 INFO - 'XPC_SERVICE_NAME': '0', 07:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:00 INFO - Downloading/unpacking jsonschema 07:46:00 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 07:46:00 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 07:46:00 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 07:46:00 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 07:46:00 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 07:46:00 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 07:46:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:46:02 INFO - Downloading/unpacking functools32 (from jsonschema) 07:46:02 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 07:46:02 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 07:46:02 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 07:46:02 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 07:46:02 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 07:46:02 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 07:46:02 INFO - Downloading functools32-3.2.3-2.tar.gz 07:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:46:02 INFO - warning: no files found matching '*.txt' 07:46:02 INFO - no previously-included directories found matching 'build' 07:46:02 INFO - no previously-included directories found matching 'dist' 07:46:02 INFO - no previously-included directories found matching '.git*' 07:46:02 INFO - Installing collected packages: jsonschema, functools32 07:46:02 INFO - Running setup.py install for functools32 07:46:03 INFO - warning: no files found matching '*.txt' 07:46:03 INFO - no previously-included directories found matching 'build' 07:46:03 INFO - no previously-included directories found matching 'dist' 07:46:03 INFO - no previously-included directories found matching '.git*' 07:46:03 INFO - Successfully installed jsonschema functools32 07:46:03 INFO - Cleaning up... 07:46:03 INFO - Return code: 0 07:46:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:03 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 0x10d920a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8540599240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, '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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 07:46:03 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 07:46:03 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 07:46:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:46:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:03 INFO - 'HOME': '/Users/cltbld', 07:46:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:03 INFO - 'LOGNAME': 'cltbld', 07:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:03 INFO - 'MOZ_NO_REMOTE': '1', 07:46:03 INFO - 'NO_EM_RESTART': '1', 07:46:03 INFO - 'PAGER': '/bin/cat', 07:46:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:03 INFO - 'PWD': '/builds/slave/test', 07:46:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:03 INFO - 'SHELL': '/bin/bash', 07:46:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:46:03 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:46:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:03 INFO - 'USER': 'cltbld', 07:46:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:03 INFO - 'XPC_FLAGS': '0x0', 07:46:03 INFO - 'XPC_SERVICE_NAME': '0', 07:46:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:03 INFO - Downloading/unpacking flake8 07:46:03 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 07:46:03 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 07:46:03 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 07:46:03 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 07:46:03 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 07:46:03 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 07:46:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:46:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:46:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:06 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 07:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:46:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:07 INFO - no previously-included directories found matching 'docs/_build' 07:46:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:46:07 INFO - Running setup.py install for pep8 07:46:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:07 INFO - no previously-included directories found matching 'docs/_build' 07:46:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:46:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:46:07 INFO - Cleaning up... 07:46:07 INFO - Return code: 0 07:46:07 INFO - Running post-action listener: _resource_record_post_action 07:46:07 INFO - Running post-action listener: _start_resource_monitoring 07:46:07 INFO - Starting resource monitoring. 07:46:07 INFO - ##### 07:46:07 INFO - ##### Running install step. 07:46:07 INFO - ##### 07:46:07 INFO - Running pre-action listener: _resource_record_pre_action 07:46:07 INFO - Running main action method: install 07:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:46:07 INFO - Reading from file tmpfile_stdout 07:46:07 INFO - Detecting whether we're running mozinstall >=1.0... 07:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:46:07 INFO - Reading from file tmpfile_stdout 07:46:07 INFO - Output received: 07:46:07 INFO - Usage: mozinstall [options] installer 07:46:07 INFO - Options: 07:46:07 INFO - -h, --help show this help message and exit 07:46:07 INFO - -d DEST, --destination=DEST 07:46:07 INFO - Directory to install application into. [default: 07:46:07 INFO - "/builds/slave/test"] 07:46:07 INFO - --app=APP Application being installed. [default: firefox] 07:46:07 INFO - mkdir: /builds/slave/test/build/application 07:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:46:39 INFO - Reading from file tmpfile_stdout 07:46:39 INFO - Output received: 07:46:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:46:39 INFO - Running post-action listener: _resource_record_post_action 07:46:39 INFO - ##### 07:46:39 INFO - ##### Running run-tests step. 07:46:39 INFO - ##### 07:46:39 INFO - Running pre-action listener: _resource_record_pre_action 07:46:39 INFO - Running main action method: run_tests 07:46:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:39 INFO - 2016-04-15 07:46:39.946 screenresolution[1972:27852] starting screenresolution argv=screenresolution get 07:46:39 INFO - 2016-04-15 07:46:39.949 screenresolution[1972:27852] Display 0: 1600x1200x32@60 07:46:39 INFO - 2016-04-15 07:46:39.954 screenresolution[1973:27855] starting screenresolution argv=screenresolution list 07:46:39 INFO - Available Modes on Display 0 07:46:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:40 INFO - Intel Iris: 07:46:40 INFO - Chipset Model: Intel Iris 07:46:40 INFO - Type: GPU 07:46:40 INFO - Bus: Built-In 07:46:40 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:40 INFO - Vendor: Intel (0x8086) 07:46:40 INFO - Device ID: 0x0a2e 07:46:40 INFO - Revision ID: 0x0009 07:46:40 INFO - Displays: 07:46:40 INFO - Display: 07:46:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:40 INFO - Display Serial Number: 1600x1200 60 07:46:40 INFO - Main Display: Yes 07:46:40 INFO - Mirror: Off 07:46:40 INFO - Online: Yes 07:46:40 INFO - Rotation: Supported 07:46:40 INFO - Return code: 0 07:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:46:40 INFO - Python 2.7.3 07:46:40 INFO - Return code: 0 07:46:40 INFO - grabbing minidump binary from tooltool 07:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:40 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 0x10d7c4d30>, '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 0x10d628db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:46:40 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 07:46:40 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 07:46:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:46:40 INFO - Return code: 0 07:46:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:46:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:46:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:46:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:46:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:46:41 INFO - Return code: 0 07:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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 07:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --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 07:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ff0MpcFDV5/Render', 07:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:41 INFO - 'HOME': '/Users/cltbld', 07:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:41 INFO - 'LOGNAME': 'cltbld', 07:46:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:46:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:41 INFO - 'MOZ_NO_REMOTE': '1', 07:46:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:46:41 INFO - 'NO_EM_RESTART': '1', 07:46:41 INFO - 'PAGER': '/bin/cat', 07:46:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:41 INFO - 'PWD': '/builds/slave/test', 07:46:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:41 INFO - 'SHELL': '/bin/bash', 07:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CZ9TABa1Jh/Listeners', 07:46:41 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 07:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:41 INFO - 'USER': 'cltbld', 07:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:41 INFO - 'XPC_FLAGS': '0x0', 07:46:41 INFO - 'XPC_SERVICE_NAME': '0', 07:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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 07:46:41 INFO - mozversion application_buildid: 20160415063651 07:46:41 INFO - mozversion application_changeset: 5ff5f2178682a3888ada94fa29409d54b8d4716a 07:46:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:46:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:46:41 INFO - mozversion application_name: Firefox 07:46:41 INFO - mozversion application_remotingname: firefox-dev 07:46:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:46:41 INFO - mozversion application_vendor: Mozilla 07:46:41 INFO - mozversion application_version: 47.0a2 07:46:41 INFO - mozversion platform_buildid: 20160415063651 07:46:41 INFO - mozversion platform_changeset: 5ff5f2178682a3888ada94fa29409d54b8d4716a 07:46:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:46:41 INFO - mozversion platform_version: 47.0a2 07:46:41 INFO - using testdate: 1460731601 07:46:41 INFO - actual date: 1460731601 07:46:41 INFO - starting webserver on 'localhost:52663' 07:46:41 INFO - SUITE-START | Running 2 tests 07:46:41 INFO - TEST-START | dromaeo_css 07:46:41 INFO - operating with platform_type : mac_ 07:46:41 INFO - Initialising browser for dromaeo_css test... 07:46:41 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpLEpvLQ/profile http://localhost:52663/getInfo.html) 07:46:43 INFO - PROCESS | 1991 | __metrics Screen width/height:1600/1200 07:46:43 INFO - PROCESS | 1991 | colorDepth:24 07:46:43 INFO - PROCESS | 1991 | Browser inner width/height: 1024/689 07:46:43 INFO - PROCESS | 1991 | __metrics 07:46:43 INFO - PROCESS | 1991 | 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" 07:46:43 INFO - PROCESS | 1991 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:44 INFO - TEST-INFO | 1991: exit 0 07:46:44 INFO - Browser initialized. 07:46:44 INFO - Running cycle 1/1 for dromaeo_css test... 07:46:44 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpLEpvLQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:56 INFO - PROCESS | 1993 | RSS: Main: 207515648 07:46:56 INFO - PROCESS | 1993 | 07:47:47 INFO - PROCESS | 1993 | 1460731667762 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] 07:49:57 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52663/tests/dromaeo/cssquery-ext.html) 07:49:59 INFO - PROCESS | 1993 | RSS: Main: 355610624 07:49:59 INFO - PROCESS | 1993 | 07:53:02 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-ext.html (next: http://localhost:52663/tests/dromaeo/cssquery-jquery.html) 07:53:02 INFO - PROCESS | 1993 | RSS: Main: 334950400 07:53:02 INFO - PROCESS | 1993 | 07:56:04 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52663/tests/dromaeo/cssquery-mootools.html) 07:56:05 INFO - PROCESS | 1993 | RSS: Main: 340381696 07:56:05 INFO - PROCESS | 1993 | 07:59:07 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52663/tests/dromaeo/cssquery-prototype.html) 07:59:08 INFO - PROCESS | 1993 | RSS: Main: 304709632 07:59:08 INFO - PROCESS | 1993 | 08:02:11 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52663/tests/dromaeo/cssquery-yui.html) 08:02:11 INFO - PROCESS | 1993 | RSS: Main: 304369664 08:02:11 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52663/tests/dromaeo/cssquery-yui.html (next: http://localhost:52663/tests/dromaeo/cssquery-dojo.html) 08:05:14 INFO - PROCESS | 1993 | RSS: Main: 351019008 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | __start_tp_report 08:05:14 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 08:05:14 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 08:05:14 INFO - PROCESS | 1993 | |i|pagename|runs| 08:05:14 INFO - PROCESS | 1993 | |0;dojo.html;3419.1964888251255;3469.8265086745655;3501.7723847949865;3510.666486683766;3532.9470057949134;5681.630694004887;5690.2033715279795;5699.116636921279;5705.25831641887;5715.228444160037;5677.943220567793;5734.283214598176;5738.081906894898;5741.425857414257;5751;5106.897862042757;5122.257272695457;5148.2020286855595;5210.947890521084;5234.973825130869;5232.398274198459;5232.45059268777;5244.292020577231;5246.685198888079;5253.789848406059;5179.792808287664;5242.580593552526;5248.0553500369915;5254;5257.815976440826;5239.5546378557865;5272.182811664179;5272.340957380327;5276.1030624793875;5320.015797077553;4975.850724478271;5146.639735218536;5165.22521621756;5181.326427564431;5188.143956247215;5530.253415788858;5595.160725891108;5615.831525054214;5630.493255607015;5639.943600563983;5362.544183744387;6114.327423983403;6228.377162283736;6262.655553944532;6320.083587879778;6191.845203869894;6210.31686514483;6249.781257656005;6307.400796924285;6313.084602732624;5797.217375654275;5839.416058394211;5855.707214639251;5879.67661778602;5887.175795388649;6197;6287.654179020106;6288.182536270256;6299.149614801943;6299.1811064562235;6051.10800558992;6080.197335375712;6080.209572755513;6120.204373431426;6147.754089836446;6246.6876656167005;6279.026750853625;6303.401176888188;6310.62136271816;6314.179156709598;6183;6214.689265536796;6243.313235544087;6244.875102497925;6245.250569931637;6129.664444256008;6138.204480310071;6249.312575616678;6267.373262673696;6271.059341098871;4882.584980276645;4898.730569818623;4913.06651736169;4917.680350777259;4938.111139994836;4404.691671583022;4405.669574781904;4420.513743488214;4436.800343984529;4455.910881782346;5543.002259593312;5628.409017053151;5652.321721393377;5653.717314134276;5685.573581981368;6206.937930620636;6254.906176407358;6255.311915689271;6263.091851681481;6264.780732674334;5622.690752008597;5624.550035997192;5630.465105814941;5632.070708333079;5640;5253.711045892513;5300.2049692546425;5306.363236411656;5325.3875804282225;5405.918911216335;6340.968295158495;6356.586821856657;6366.013267943476;6367.554271200971;6380.649064301322;6272.2159730033745;6281.466075383643;6285.905711414424;6292.244930608356;6303.684815759286;6214.40963108504;6266.373362663697;6294.0558916162945;6326.620402775849;6335.3981371769605;6145.354737752472;6185.381461853779;6201.131841542278;6202.976508875985;6213.844653883689;6233.407826256498;6236.719347629277;6261.78083767066;6275.435210830865;6304.306526282197;5044.243363495505;5889.116632505158;6072.301685306246;6119.167450728499;6156.922538555825;5908.497777688945;6121.173641558333;6122.663253521099;6124.5712800103565;6130.233720784902;5774.3648198697265;5940;5958.910616340845;5973.671448070398;5994.340622531431;5891.58758886891;5952.791652292149;5958.582899197015;6073.939260607514;6161.568690191643;5868.3251426086545;5879.412058794257;5884.911726324023;5888.499477544457;5896.204012458435 08:05:14 INFO - PROCESS | 1993 | |1;ext.html;491.22386629125987;593.5548338745938;600.564590671763;669.5614372585957;671.3186116092168;27953.580696289573;40260.79869600652;40483.59516404835;40525.79737101318;40629.79685101578;43199.78400108003;43822;43824.90437739058;43862.56137438625;43867.341989870096;9682.37064590803;9868.407895526256;9868.703938881808;9873.160781333596;9902.207823374149;9551.474668893208;9679.032096790335;9689;9692.224622030257;9706.466144362057;8203.794905127359;9795.608175672964;9797.216222702202;9809.264305177105;9818.312718109735;9721.659741909;9785.119339259465;9810.264230182765;9813.969533198982;9828.312018158731;9688.854667179998;9815.214782817358;9817.116459518606;9842.409455432697;9857.260705447048;33974;41059.1788164238;41310.586894130975;41542;41552.58447415518;3316.0714999800093;3359.7816141950693;3475.878344257939;3487.2153765402836;3732.5147730795093;29231.707682923112;29425.970091046926;29609.70390296091;30172.8491357544;30177.547336789965;2749.67058648038;2779.66644002721;2780.8331500109857;2790.6651201855907;2792.986035069812;476368;481436.92815359443;481825;481848;482968;71324.28675713176;72100.63949680323;72409.63795181096;72421.6378918102;72528.63735681288;63953.36046639567;74593.25406745971;74924.62537687385;76460.61769691228;76572;18221.54446138857;18320.541986450444;18441.907790460962;18474.722879156823;18508.81491185098;13684.178949263076;13688.52090176839;13702.38339274735;13712.24582647944;13758.793618095737;21928.342149735534;22164.445888852584;22166.780827054343;22175.223867164572;22247.555048898932;7863.685452581833;9050.506666400102;9238.815223695488;9284;9291.07089291079;949.924006079512;971.7522031881966;983.2478154212033;1047.528612124547;1075;29736.40527189444;30448.086557403312;30453.69546304553;31023.68976310253;31093.53359699606;1079.649114037925;1157.1263695909533;1157.5774842182543;1195.1813008089314;1209.7580483903077;13112.475500980045;15508.379664813281;15588.376464941502;15719.135447550267;15809.446669366747;536989;560154;563018.184909081;563122;563245;58107;58527.70736146377;58540;58652.4134758647;58670.11994820081;1324.2319454716242;1330.660681526224;1409.7814838700017;1538.7537993920919;1572.5989872582418;29255.707442925297;29313.413731725672;29535.26161845885;30035;30184.849075754482;46208.30687539622;46394.768026161;46547.53452465432;46597.64127125288;46679;313616;342542;351563;355575;356034;29822.850885745433;34729.305413892085;34840.825795871875;35381.46927796137;35441.46837797487;43135.63126652342;43250.35124473072;43657.34513982229;43862.90342741457;44042;35283.823580882956;35317.117072072375;35354.64645353513;35446;36068.27863442769;35544.46683299701;35651.10872228215;35980.28039439249;36245.81877090598;36366.818165909004;36950.89147325585;37409.251814963005;37689;37693;38164.236715266095;32527.674723252465;32551.18622034468;32847.507287391185;32941.50587741234;33358.33283334368;25001;25387.238382848544;25479.853406596376;26087.73912260929;26976.595351069358 08:05:14 INFO - PROCESS | 1993 | |2;jquery.html;327.7869384899813;354.85983036700503;374.9343864823657;377.8771899132781;378.1057798307003;20338.491537711547;20803.375898723025;21043.36869893902;21209.787902120974;21483.892580537075;18790.436286911416;21500.89249553754;22256;22359;22368.328950131483;5762.3373312069125;5902.911456328158;5929.229200203989;5940.643561386309;5952.494038006775;5594.412586678402;5949.6430214187285;5956.851078723045;5980.910286345708;5986.042233242682;4704.45898721648;5873.911891321633;5896.7936122235515;5902.2622172228475;5907.320658124307;4979.45226025137;5648.943510564883;5722.427757224265;5822.97088514559;5828.504577110951;5081.872953176163;5111.182210846247;5111.948880511221;5122.1804511278015;5124.461931497213;25757;26195.214143575722;26613.46773064528;26666.466670666672;26697.46605067888;2431.0640403444622;2434.926952191437;2440.9869903989825;2542.2119143065524;2852.82883027017;14646.780298295535;14968.401263949429;15035.323410446555;15240.314185861664;15505.379784808594;1712.914354282281;1740.608363118301;1762.6210364771507;1786.490850107713;1829.4145873320542;94250;100317;103044;104805;105411.6752994815;11416.543338266376;11525.962663360338;11610.825837612443;11774.646760597025;12062.638120856389;14493.85506144925;14584.781228281585;14584.979051466296;14597.489087881873;14624.853751462562;8812.603432845537;8993.460392376479;9007.909920900838;9089.636414543476;9121.36150469474;7543.962280188564;9188.63245470187;9240.7689807754;9259.537023148816;9296.628134874665;3808.638179372955;4523.547645235451;4550.13547425988;4565.0641618468135;4573.336866154389;1738.452387497934;1867.9346222882405;1889.461503471504;1894.6305470433408;1900.6388786130867;737.9631018449163;781.7185813107277;890.643742503004;921.5806807902388;987.8886252965414;20306.69539956902;20456.69314960277;20510.589788204154;20718.896405517877;20781.792182078287;981.8036392721341;987.234892957949;1025.2566889005561;1058.751193469534;1071.314358810362;11335.546578136948;11515.884841151481;11629.302241865515;11635.825462618068;11681.474333654836;485974;491008;491833;517763;585703;100603.49698251463;112184;119043.61912761696;124004.279871604;131413;1048.570086264628;1070.0797314309686;1086.8532748078908;1095.4358505369742;1099.2030777686114;17978;18079.90960045244;18298.634027319644;18914.621707566046;19143.808561914204;51774.44676659706;53357;55071.72464137654;55225.72387138039;55487.72256138694;69949.30050699427;78991;80418.19581804107;81099.59450202947;82560.58719706364;8036.839263214586;8206.384521160817;8216.958915205385;8359;8398.160183981552;11964.58123965657;13298.335083245605;13437.260950647416;13536.390862411019;13848.376823043189;10925.726856828644;11456.312621242752;11597.420128993685;11863.228890122115;11990.640280791591;5443.339781366722;9191;10500.36997780105;11174.832377514182;11729.296242225153;11890.524379024742;11903.880961190278;12033.278003319829;12105.636830895088;12282.508699651913;8167.264946154874;8377.204165604258;8407.327413807003;8469;8622.353323500887;11460.140489463156;11794.705132371759;11986.880131198577;12069.517219311481;12132.393380331125 08:05:14 INFO - PROCESS | 1993 | |3;mootools.html;1430.249119212414;1659.145540046876;1686.9325226990918;2015.4659015360924;2071.5753270579535;8012.879806802903;8774.385792994493;8956.059613740554;8961.641534338634;8967.327450441227;8640.438371505848;8935.91064089359;8939.910600893989;8941.195292423681;8948.015718271;2311.2603966730653;2363.7163540375177;2497.113524698733;2538.2131539222855;2571.0872640212738;2403.3390817525146;2596.909108181214;2608.243609353285;2608.5695860183046;2609.6607441032734;2331.8367714260007;2500.8749562521893;2546.121588052128;2563.7308082651334;2568.987155064222;1917.5397904502959;2184.7378314602192;2436.1351720139273;2488.967078662353;2542.6821647294087;2235.821134309251;2363.7990770784504;2475.059477398595;2497.1759319424546;2505.260948020338;8852.380333376666;8938.86591701125;8942.552872356357;8944.463332200025;8955.596998135099;4575.313702944552;5566.443355664442;5604.8038318659055;5607.130894711327;5609.382967873531;3521.1901262709466;3562.5546806649168;3686.2811751708427;3710.109573702319;3711.31340701971;942.3733217410414;1079.7300674831292;1079.96220132295;1110.74452875839;1125.3641692443744;12402.317872516918;12427.93786031073;12441.626751197478;12442.315672638002;12452.31512266825;4843.2250839865455;5014.724190169502;5077.26379674945;5095.745212739348;5097.923531147036;10376.16990640747;10476.947615261875;10502.579896804042;10506.264561480622;10506.369617822955;5645.95549823284;5653.23681303027;5661.065924122474;5661.150827375926;5671.092625179951;4690.835820746258;4789.784459699322;4838.395200599925;4944.826931057468;5132.101882170605;4050.8784736457956;4109.445224894661;4119.443875076826;4155.940235239995;4178.080822219107;1300.7918733002675;1359.7008658095203;1360.8364848054969;1365.3446345754094;1377.572952384764;1395.7557427450358;1425.8146440962817;1429.0853853533747;1438.216172186161;1449.3695242569515;8377.246047855722;8454.239118479367;8487.957560212282;8496.915030849612;8508.617112229964;521.6583138044587;536.7209051293383;538.37548443805;538.5610727257273;539.7921800106988;6264.467520260829;6276.560640755103;6319.905201421982;6333.841653958688;6343.9682801585695;10767.407792571326;11144.2199046066;11271.323720576791;11296.209265351346;11305.378204198849;10629.5216715248;10991.06575941054;11074.944625276983;11099.778004439866;11119.16606254518;8451.154884511623;8642.265407440438;8662.956685216534;8680.131986801396;8696.521691306913;6934.618595977269;8072.757817265491;8129.227723366271;8153.9184608153155;8167.224113709188;9537.332386732862;9760.560774765294;9767.511624418892;9852.556634951588;10003.29976901639;8923.330750193632;9961.053699898497;10477;10483.423411712429;10483.842742358718;7658.349040331412;7670.041244844395;7699.615019249127;7751.922480775345;7774.650140743794;8432.156784321518;8461.576921154041;8527.914720852712;8535.146485351415;8539.615717292612;7486.850262994819;7615.961920190363;7662.540247585162;7714.614269286625;7759;7391.889121663287;7532.246775322424;7547.698092076475;7660.195679453577;7711.498752581064;7981.880271796043;8061.072976607765;8065.637046332812;8069;8077.151899050515;7140.321669441395;7305.926940730525;7314.524555903849;7328.790749526269;7334.3032411920785;7350.77947661571;7357.595332256563;7407.888881666887;7410.5183163094225;7423.851522969619 08:05:14 INFO - PROCESS | 1993 | |4;prototype.html;334.815851281795;335.72302850148634;339.39079352562146;345.3179969560119;349.13414731465963;6618.56979296346;6695.63174025429;6829.795106146811;6880.2431732509385;6911.067005954194;6634.402903738663;6643.003549467582;6646.933530664692;6660.367265109807;6677.933220667792;4725.10223057618;4836.830710925118;4885.316055752198;4974.278729584199;4993.725345106018;4930.3344048553345;4965.975170124144;4968.428630707479;4971.328870602459;4990.176620857555;4881.1213981483315;4896.46138924718;4908.28829819676;4911.066897289522;4921;3730.7015438764834;4799.208130658455;4888.193448081098;4917.893473968365;4952.356193694796;3458.913527161816;4207.97896010518;4213.51544572375;4224.281872081753;4235.3858690490015;6357.078223657544;6401.711922963478;6403.167588213549;6414.967925160391;6435.678216089177;5812;5829.970850145764;5833.883322333572;5842.269716285465;5848.619839710405;6747.898781518281;6995.79012629622;7015.228324884259;7022.262662420423;7035.437165026786;4748.762561871893;4778.139934811733;4782.282657601353;4788.976055119737;4813.735244561548;7257.673404696801;7384.75070968772;7412.332890039922;7414.03617529723;7419.258074192591;4849.25426846335;4854.757262136879;4865.489123641966;4869.220924652039;4869.440014398389;4908;4916.532929371704;4927.310176575283;4936.407631084293;4953.900921981613;5590.608657394025;5824.621399609096;5834.445727655866;5849.853753656107;5850.531957443394;7027.227005029443;7101.964490177516;7113.608751518716;7126.35862772353;7126.35862772353;1404.7331007108821;1668.1659170414794;1685.48592679234;1721.4319274639338;1726.7064599018197;1045.5347370420243;1095.1074873977682;1103.3545375954996;1121.2935850414187;1128.7347473343757;6280.152179455715;6292.874142517216;6301.495880329563;6348.873022539523;6381.872362552723;7174.3184397482155;7337.009503716929;7385.8153546161775;7399.334059934524;7399.482036257518;6575.079488871562;6577.440917522064;6577.539572229994;6593.3736295051895;6612.603243805291;3828.559715632682;3875.457435958968;3886.1644746379666;3887.630675085862;3890.5331360236387;7283.7450689225625;7624.504407213513;7627.199144089791;7651.655675494616;7662.003939487831;7520.210377910241;7537.32424989132;7552.358049565739;7557.470977031555;7567.470277080662;6097.359777223328;7514.436417268617;7592.430567707444;7604.315611594983;7617.390608751176;6951.409130223988;7320.304571065741;7336.082989626297;7340.302671246223;7362.852742945218;7161.641917904188;7216.3505284524645;7234;7240.814041821582;7259.0200322957835;7588.582627955516;7590.886136707843;7596.316331530189;7596.658150383136;7597;4830.2513110467935;4856.611471082376;4858.174110401311;4861.270809378621;4861.6596838220985;5978.893904627658;6529.9020514691365;7063.611501367474;7301.160366557914;7321.926780732125;5933.317668468008;6903.309669033057;6934.653267336512;7036.929630703832;7043.119610048744;5951.345352011189;6734.02356658272;7018.263082376481;7034;7046.612436316052;6445.452136568444;6844.657767111724;6858.251145957749;6873.621950792554;6907.171139463297;6827.897581536182;7034.753783617549;7048.894266586108;7053.647317633995;7064.187618423947;5743.4256574342235;6623.774601698701;6744.662766861736;6828.522003459909;6940.861182776417 08:05:14 INFO - PROCESS | 1993 | |5;yui.html;642.8489305013321;691.3432239372596;708.4155571653386;721.4949535325273;748.9288517590827;15158.999999999985;15210.695786084285;15465.381384744596;15492.302846371898;15630.374785008615;14291.14253144812;14351.64120896978;14417.990740648132;14423.783643245333;14429.927850360733;770.6994272233833;779.7582749347721;941.2375975459865;946.9195118414943;964.4213471916864;944.9669261575846;947.8672985781996;952.2334520710859;952.2857856607544;953.3993584042079;1013.8732658417698;1024.7591815923251;1027.1474676018886;1027.2090490322448;1027.5170669785227;789.498668345599;801.3148757812103;802.8956235689382;804.1074407407773;1012.5291739341205;741.8516296740673;753.6118898767139;754.1854796819476;754.35125791819;755.3390783064818;15330.233488325539;15424.691506169815;15433.614159645986;15440.227988600525;15447.304871280707;12638.2417054977;12684.492620295177;12749.980001599943;12918.935405322913;13058;10085.69742907714;10155.79688406235;10214.387136771817;10238.33450825694;10365.689029329133;3473.7221022318154;3506.333796578642;3514.5079688843584;3593.730470214718;3754.0239537720117;61422.692886535726;83951;84700;87084;87315.56342218249;7939.761807145795;9646.565904534313;9722.319437639297;9740.123388895034;9831.36096153762;22184.778152218594;22429.102835886708;22527.549449010927;22552.887235564045;22677.773222267897;7662.73180438682;7787.532748035136;7922.722704705423;8078.596070196584;8166.020077590727;4203.684723645678;4262.339337402707;4264.552222016644;4277.508086570022;4355.412019377407;3741.551013878352;3780.0738818989525;3785.962140378616;3792.4500947362476;3804.0299723570865;639.6098379988204;644.9161608990801;647.5014239035916;661.6956200147889;666.7266420767462;588.8557303460594;591.7869566955892;601.7141857617596;609.0650850943869;614.9969250153721;6829.692663830139;7773.416993725493;7993.080795708568;8342.457740246864;8368.456050356708;495.88594623236133;596.3380647481292;596.6986671730748;607.975680972765;615.3846153846154;3464.8960531184107;3780.4707340972286;3831.272058308912;3879.991202287369;3928.626780455852;94267;94870;96943;97851.51074244583;98392.50803745935;75171.87242191372;75437;78391.60804195942;79262;79351;71529.6423517879;71622;73642.26357736462;73934;74407.62796186093;7676.155622881367;7815.452918295892;7905.56519391439;7922.247386498273;7940.761777146695;52309;53881.73059134679;53896.92206155934;54247.91504169816;57364;50864.72838178927;67158;70686.64656676889;72227.91658125237;72893.635531822;10272.33229840058;10391.688249352532;10532.157427405942;10689.144868410664;11005.724856878642;8510.27662648657;9454.716358509255;10857.294275872044;10879.728006799893;11088.778224435628;10217;10247.333923294962;10417.114545263737;10429.791404172025;10480.737981550523;10143.847842282225;10338.172946164139;10385.169186464913;10460.372377657364;10847.67456976292;9981.900180998096;11537.769244615229;11563;11724.94137529341;11964.64106076819;9586.808263834824;9730.36752611078;9755.21958243353;9813.313068085166;10085.848712269468;9655.179309758749;9696.418214907128;9710.465924374228;9732.610695572097;9743.171830394496 08:05:14 INFO - PROCESS | 1993 | __end_tp_report 08:05:14 INFO - PROCESS | 1993 | __start_cc_report 08:05:14 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1605 08:05:14 INFO - PROCESS | 1993 | __end_cc_report 08:05:14 INFO - PROCESS | 1993 | __startTimestamp1460732714610__endTimestamp 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | ------- Summary: start ------- 08:05:14 INFO - PROCESS | 1993 | Number of tests: 6 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#0] dojo.html Cycles:165 Average:5731.50 Median:5889.12 stddev:630.05 (10.7%) stddev-sans-first:605.31 08:05:14 INFO - PROCESS | 1993 | Values: 3419.2 3469.8 3501.8 3510.7 3532.9 5681.6 5690.2 5699.1 5705.3 5715.2 5677.9 5734.3 5738.1 5741.4 5751.0 5106.9 5122.3 5148.2 5210.9 5235.0 5232.4 5232.5 5244.3 5246.7 5253.8 5179.8 5242.6 5248.1 5254.0 5257.8 5239.6 5272.2 5272.3 5276.1 5320.0 4975.9 5146.6 5165.2 5181.3 5188.1 5530.3 5595.2 5615.8 5630.5 5639.9 5362.5 6114.3 6228.4 6262.7 6320.1 6191.8 6210.3 6249.8 6307.4 6313.1 5797.2 5839.4 5855.7 5879.7 5887.2 6197.0 6287.7 6288.2 6299.1 6299.2 6051.1 6080.2 6080.2 6120.2 6147.8 6246.7 6279.0 6303.4 6310.6 6314.2 6183.0 6214.7 6243.3 6244.9 6245.3 6129.7 6138.2 6249.3 6267.4 6271.1 4882.6 4898.7 4913.1 4917.7 4938.1 4404.7 4405.7 4420.5 4436.8 4455.9 5543.0 5628.4 5652.3 5653.7 5685.6 6206.9 6254.9 6255.3 6263.1 6264.8 5622.7 5624.6 5630.5 5632.1 5640.0 5253.7 5300.2 5306.4 5325.4 5405.9 6341.0 6356.6 6366.0 6367.6 6380.6 6272.2 6281.5 6285.9 6292.2 6303.7 6214.4 6266.4 6294.1 6326.6 6335.4 6145.4 6185.4 6201.1 6203.0 6213.8 6233.4 6236.7 6261.8 6275.4 6304.3 5044.2 5889.1 6072.3 6119.2 6156.9 5908.5 6121.2 6122.7 6124.6 6130.2 5774.4 5940.0 5958.9 5973.7 5994.3 5891.6 5952.8 5958.6 6073.9 6161.6 5868.3 5879.4 5884.9 5888.5 5896.2 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#1] ext.html Cycles:180 Average:61852.47 Median:37549.13 stddev:124754.75 (332.2%) stddev-sans-first:125019.65 08:05:14 INFO - PROCESS | 1993 | Values: 491.2 593.6 600.6 669.6 671.3 27953.6 40260.8 40483.6 40525.8 40629.8 43199.8 43822.0 43824.9 43862.6 43867.3 9682.4 9868.4 9868.7 9873.2 9902.2 9551.5 9679.0 9689.0 9692.2 9706.5 8203.8 9795.6 9797.2 9809.3 9818.3 9721.7 9785.1 9810.3 9814.0 9828.3 9688.9 9815.2 9817.1 9842.4 9857.3 33974.0 41059.2 41310.6 41542.0 41552.6 3316.1 3359.8 3475.9 3487.2 3732.5 29231.7 29426.0 29609.7 30172.8 30177.5 2749.7 2779.7 2780.8 2790.7 2793.0 476368.0 481436.9 481825.0 481848.0 482968.0 71324.3 72100.6 72409.6 72421.6 72528.6 63953.4 74593.3 74924.6 76460.6 76572.0 18221.5 18320.5 18441.9 18474.7 18508.8 13684.2 13688.5 13702.4 13712.2 13758.8 21928.3 22164.4 22166.8 22175.2 22247.6 7863.7 9050.5 9238.8 9284.0 9291.1 949.9 971.8 983.2 1047.5 1075.0 29736.4 30448.1 30453.7 31023.7 31093.5 1079.6 1157.1 1157.6 1195.2 1209.8 13112.5 15508.4 15588.4 15719.1 15809.4 536989.0 560154.0 563018.2 563122.0 563245.0 58107.0 58527.7 58540.0 58652.4 58670.1 1324.2 1330.7 1409.8 1538.8 1572.6 29255.7 29313.4 29535.3 30035.0 30184.8 46208.3 46394.8 46547.5 46597.6 46679.0 313616.0 342542.0 351563.0 355575.0 356034.0 29822.9 34729.3 34840.8 35381.5 35441.5 43135.6 43250.4 43657.3 43862.9 44042.0 35283.8 35317.1 35354.6 35446.0 36068.3 35544.5 35651.1 35980.3 36245.8 36366.8 36950.9 37409.3 37689.0 37693.0 38164.2 32527.7 32551.2 32847.5 32941.5 33358.3 25001.0 25387.2 25479.9 26087.7 26976.6 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#2] jquery.html Cycles:180 Average:32276.48 Median:2433.00 stddev:86228.76 (3544.1%) stddev-sans-first:86437.28 08:05:14 INFO - PROCESS | 1993 | Values: 327.8 354.9 374.9 377.9 378.1 20338.5 20803.4 21043.4 21209.8 21483.9 18790.4 21500.9 22256.0 22359.0 22368.3 5762.3 5902.9 5929.2 5940.6 5952.5 5594.4 5949.6 5956.9 5980.9 5986.0 4704.5 5873.9 5896.8 5902.3 5907.3 4979.5 5648.9 5722.4 5823.0 5828.5 5081.9 5111.2 5111.9 5122.2 5124.5 25757.0 26195.2 26613.5 26666.5 26697.5 2431.1 2434.9 2441.0 2542.2 2852.8 14646.8 14968.4 15035.3 15240.3 15505.4 1712.9 1740.6 1762.6 1786.5 1829.4 94250.0 100317.0 103044.0 104805.0 105411.7 11416.5 11526.0 11610.8 11774.6 12062.6 14493.9 14584.8 14585.0 14597.5 14624.9 8812.6 8993.5 9007.9 9089.6 9121.4 7544.0 9188.6 9240.8 9259.5 9296.6 3808.6 4523.5 4550.1 4565.1 4573.3 1738.5 1867.9 1889.5 1894.6 1900.6 738.0 781.7 890.6 921.6 987.9 20306.7 20456.7 20510.6 20718.9 20781.8 981.8 987.2 1025.3 1058.8 1071.3 11335.5 11515.9 11629.3 11635.8 11681.5 485974.0 491008.0 491833.0 517763.0 585703.0 100603.5 112184.0 119043.6 124004.3 131413.0 1048.6 1070.1 1086.9 1095.4 1099.2 17978.0 18079.9 18298.6 18914.6 19143.8 51774.4 53357.0 55071.7 55225.7 55487.7 69949.3 78991.0 80418.2 81099.6 82560.6 8036.8 8206.4 8217.0 8359.0 8398.2 11964.6 13298.3 13437.3 13536.4 13848.4 10925.7 11456.3 11597.4 11863.2 11990.6 5443.3 9191.0 10500.4 11174.8 11729.3 11890.5 11903.9 12033.3 12105.6 12282.5 8167.3 8377.2 8407.3 8469.0 8622.4 11460.1 11794.7 11986.9 12069.5 12132.4 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#3] mootools.html Cycles:180 Average:6222.47 Median:537.55 stddev:3324.45 (618.4%) stddev-sans-first:3314.26 08:05:14 INFO - PROCESS | 1993 | Values: 1430.2 1659.1 1686.9 2015.5 2071.6 8012.9 8774.4 8956.1 8961.6 8967.3 8640.4 8935.9 8939.9 8941.2 8948.0 2311.3 2363.7 2497.1 2538.2 2571.1 2403.3 2596.9 2608.2 2608.6 2609.7 2331.8 2500.9 2546.1 2563.7 2569.0 1917.5 2184.7 2436.1 2489.0 2542.7 2235.8 2363.8 2475.1 2497.2 2505.3 8852.4 8938.9 8942.6 8944.5 8955.6 4575.3 5566.4 5604.8 5607.1 5609.4 3521.2 3562.6 3686.3 3710.1 3711.3 942.4 1079.7 1080.0 1110.7 1125.4 12402.3 12427.9 12441.6 12442.3 12452.3 4843.2 5014.7 5077.3 5095.7 5097.9 10376.2 10476.9 10502.6 10506.3 10506.4 5646.0 5653.2 5661.1 5661.2 5671.1 4690.8 4789.8 4838.4 4944.8 5132.1 4050.9 4109.4 4119.4 4155.9 4178.1 1300.8 1359.7 1360.8 1365.3 1377.6 1395.8 1425.8 1429.1 1438.2 1449.4 8377.2 8454.2 8488.0 8496.9 8508.6 521.7 536.7 538.4 538.6 539.8 6264.5 6276.6 6319.9 6333.8 6344.0 10767.4 11144.2 11271.3 11296.2 11305.4 10629.5 10991.1 11074.9 11099.8 11119.2 8451.2 8642.3 8663.0 8680.1 8696.5 6934.6 8072.8 8129.2 8153.9 8167.2 9537.3 9760.6 9767.5 9852.6 10003.3 8923.3 9961.1 10477.0 10483.4 10483.8 7658.3 7670.0 7699.6 7751.9 7774.7 8432.2 8461.6 8527.9 8535.1 8539.6 7486.9 7616.0 7662.5 7714.6 7759.0 7391.9 7532.2 7547.7 7660.2 7711.5 7981.9 8061.1 8065.6 8069.0 8077.2 7140.3 7305.9 7314.5 7328.8 7334.3 7350.8 7357.6 7407.9 7410.5 7423.9 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#4] prototype.html Cycles:180 Average:5784.05 Median:6487.68 stddev:1810.40 (27.9%) stddev-sans-first:1768.67 08:05:14 INFO - PROCESS | 1993 | Values: 334.8 335.7 339.4 345.3 349.1 6618.6 6695.6 6829.8 6880.2 6911.1 6634.4 6643.0 6646.9 6660.4 6677.9 4725.1 4836.8 4885.3 4974.3 4993.7 4930.3 4966.0 4968.4 4971.3 4990.2 4881.1 4896.5 4908.3 4911.1 4921.0 3730.7 4799.2 4888.2 4917.9 4952.4 3458.9 4208.0 4213.5 4224.3 4235.4 6357.1 6401.7 6403.2 6415.0 6435.7 5812.0 5830.0 5833.9 5842.3 5848.6 6747.9 6995.8 7015.2 7022.3 7035.4 4748.8 4778.1 4782.3 4789.0 4813.7 7257.7 7384.8 7412.3 7414.0 7419.3 4849.3 4854.8 4865.5 4869.2 4869.4 4908.0 4916.5 4927.3 4936.4 4953.9 5590.6 5824.6 5834.4 5849.9 5850.5 7027.2 7102.0 7113.6 7126.4 7126.4 1404.7 1668.2 1685.5 1721.4 1726.7 1045.5 1095.1 1103.4 1121.3 1128.7 6280.2 6292.9 6301.5 6348.9 6381.9 7174.3 7337.0 7385.8 7399.3 7399.5 6575.1 6577.4 6577.5 6593.4 6612.6 3828.6 3875.5 3886.2 3887.6 3890.5 7283.7 7624.5 7627.2 7651.7 7662.0 7520.2 7537.3 7552.4 7557.5 7567.5 6097.4 7514.4 7592.4 7604.3 7617.4 6951.4 7320.3 7336.1 7340.3 7362.9 7161.6 7216.4 7234.0 7240.8 7259.0 7588.6 7590.9 7596.3 7596.7 7597.0 4830.3 4856.6 4858.2 4861.3 4861.7 5978.9 6529.9 7063.6 7301.2 7321.9 5933.3 6903.3 6934.7 7036.9 7043.1 5951.3 6734.0 7018.3 7034.0 7046.6 6445.5 6844.7 6858.3 6873.6 6907.2 6827.9 7034.8 7048.9 7053.6 7064.2 5743.4 6623.8 6744.7 6828.5 6940.9 08:05:14 INFO - PROCESS | 1993 | 08:05:14 INFO - PROCESS | 1993 | [#5] yui.html Cycles:180 Average:18541.30 Median:604.84 stddev:26398.20 (4364.5%) stddev-sans-first:26438.05 08:05:14 INFO - PROCESS | 1993 | Values: 642.8 691.3 708.4 721.5 748.9 15159.0 15210.7 15465.4 15492.3 15630.4 14291.1 14351.6 14418.0 14423.8 14429.9 770.7 779.8 941.2 946.9 964.4 945.0 947.9 952.2 952.3 953.4 1013.9 1024.8 1027.1 1027.2 1027.5 789.5 801.3 802.9 804.1 1012.5 741.9 753.6 754.2 754.4 755.3 15330.2 15424.7 15433.6 15440.2 15447.3 12638.2 12684.5 12750.0 12918.9 13058.0 10085.7 10155.8 10214.4 10238.3 10365.7 3473.7 3506.3 3514.5 3593.7 3754.0 61422.7 83951.0 84700.0 87084.0 87315.6 7939.8 9646.6 9722.3 9740.1 9831.4 22184.8 22429.1 22527.5 22552.9 22677.8 7662.7 7787.5 7922.7 8078.6 8166.0 4203.7 4262.3 4264.6 4277.5 4355.4 3741.6 3780.1 3786.0 3792.5 3804.0 639.6 644.9 647.5 661.7 666.7 588.9 591.8 601.7 609.1 615.0 6829.7 7773.4 7993.1 8342.5 8368.5 495.9 596.3 596.7 608.0 615.4 3464.9 3780.5 3831.3 3880.0 3928.6 94267.0 94870.0 96943.0 97851.5 98392.5 75171.9 75437.0 78391.6 79262.0 79351.0 71529.6 71622.0 73642.3 73934.0 74407.6 7676.2 7815.5 7905.6 7922.2 7940.8 52309.0 53881.7 53896.9 54247.9 57364.0 50864.7 67158.0 70686.6 72227.9 72893.6 10272.3 10391.7 10532.2 10689.1 11005.7 8510.3 9454.7 10857.3 10879.7 11088.8 10217.0 10247.3 10417.1 10429.8 10480.7 10143.8 10338.2 10385.2 10460.4 10847.7 9981.9 11537.8 11563.0 11724.9 11964.6 9586.8 9730.4 9755.2 9813.3 10085.8 9655.2 9696.4 9710.5 9732.6 9743.2 08:05:14 INFO - PROCESS | 1993 | -------- Summary: end -------- 08:05:14 INFO - PROCESS | 1993 | 08:05:15 INFO - TEST-INFO | 1993: exit 0 08:05:15 INFO - TEST-OK | dromaeo_css | took 1113521ms 08:05:15 INFO - TEST-START | kraken 08:05:15 INFO - operating with platform_type : mac_ 08:05:15 INFO - Initialising browser for kraken test... 08:05:15 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpyzInSW/profile http://localhost:52663/getInfo.html) 08:05:17 INFO - PROCESS | 1996 | __metrics Screen width/height:1600/1200 08:05:17 INFO - PROCESS | 1996 | colorDepth:24 08:05:17 INFO - PROCESS | 1996 | Browser inner width/height: 1024/689 08:05:17 INFO - PROCESS | 1996 | __metrics 08:05:17 INFO - PROCESS | 1996 | 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" 08:05:17 INFO - PROCESS | 1996 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:18 INFO - TEST-INFO | 1996: exit 0 08:05:18 INFO - Browser initialized. 08:05:18 INFO - Running cycle 1/1 for kraken test... 08:05:18 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpyzInSW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:30 INFO - PROCESS | 1998 | RSS: Main: 207233024 08:05:30 INFO - PROCESS | 1998 | 08:05:32 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52663/tests/kraken/driver.html?testName=audio-beat-detection) 08:05:33 INFO - PROCESS | 1998 | RSS: Main: 262557696 08:05:33 INFO - PROCESS | 1998 | 08:05:36 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52663/tests/kraken/driver.html?testName=audio-dft) 08:05:36 INFO - PROCESS | 1998 | RSS: Main: 272891904 08:05:36 INFO - PROCESS | 1998 | 08:05:39 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52663/tests/kraken/driver.html?testName=audio-fft) 08:05:40 INFO - PROCESS | 1998 | RSS: Main: 263143424 08:05:40 INFO - PROCESS | 1998 | 08:05:42 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52663/tests/kraken/driver.html?testName=audio-oscillator) 08:05:43 INFO - PROCESS | 1998 | RSS: Main: 266141696 08:05:43 INFO - PROCESS | 1998 | 08:05:45 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52663/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:05:45 INFO - PROCESS | 1998 | RSS: Main: 265732096 08:05:45 INFO - PROCESS | 1998 | 08:05:48 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52663/tests/kraken/driver.html?testName=imaging-darkroom) 08:05:49 INFO - PROCESS | 1998 | RSS: Main: 265838592 08:05:49 INFO - PROCESS | 1998 | 08:05:51 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52663/tests/kraken/driver.html?testName=imaging-desaturate) 08:05:52 INFO - PROCESS | 1998 | RSS: Main: 266440704 08:05:52 INFO - PROCESS | 1998 | 08:05:55 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52663/tests/kraken/driver.html?testName=json-parse-financial) 08:05:55 INFO - PROCESS | 1998 | RSS: Main: 266223616 08:05:55 INFO - PROCESS | 1998 | 08:05:57 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52663/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:05:57 INFO - PROCESS | 1998 | RSS: Main: 298893312 08:05:57 INFO - PROCESS | 1998 | 08:05:59 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:06:00 INFO - PROCESS | 1998 | RSS: Main: 299470848 08:06:00 INFO - PROCESS | 1998 | 08:06:02 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:06:03 INFO - PROCESS | 1998 | RSS: Main: 305278976 08:06:03 INFO - PROCESS | 1998 | 08:06:05 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:06 INFO - PROCESS | 1998 | RSS: Main: 307302400 08:06:06 INFO - PROCESS | 1998 | 08:06:09 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:09 INFO - PROCESS | 1998 | RSS: Main: 307023872 08:06:09 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52663/tests/kraken/driver.html?testName=ai-astar) 08:06:11 INFO - PROCESS | 1998 | RSS: Main: 370388992 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | __start_tp_report 08:06:11 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 08:06:11 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 08:06:11 INFO - PROCESS | 1998 | |i|pagename|runs| 08:06:11 INFO - PROCESS | 1998 | |0;ai-astar;114;109;112;111;112;111;121;109;109;111 08:06:11 INFO - PROCESS | 1998 | |1;audio-beat-detection;158;112;156;149;121;171;168;146;161;118 08:06:11 INFO - PROCESS | 1998 | |2;audio-dft;185;187;185;188;187;185;184;183;185;185 08:06:11 INFO - PROCESS | 1998 | |3;audio-fft;76;76;89;138;83;84;57;57;118;56 08:06:11 INFO - PROCESS | 1998 | |4;audio-oscillator;91;95;128;81;77;87;121;76;81;116 08:06:11 INFO - PROCESS | 1998 | |5;imaging-gaussian-blur;90;86;90;87;90;90;133;129;89;85 08:06:11 INFO - PROCESS | 1998 | |6;imaging-darkroom;94;95;95;94;93;94;95;95;94;94 08:06:11 INFO - PROCESS | 1998 | |7;imaging-desaturate;87;84;84;85;85;85;85;87;84;85 08:06:11 INFO - PROCESS | 1998 | |8;json-parse-financial;58;59;60;60;59;61;59;71;53;53 08:06:11 INFO - PROCESS | 1998 | |9;json-stringify-tinderbox;63;64;78;71;68;65;64;66;66;65 08:06:11 INFO - PROCESS | 1998 | |10;stanford-crypto-aes;94;104;108;106;70;92;104;105;104;110 08:06:11 INFO - PROCESS | 1998 | |11;stanford-crypto-ccm;129;128;125;115;116;115;117;120;134;128 08:06:11 INFO - PROCESS | 1998 | |12;stanford-crypto-pbkdf2;135;124;136;135;134;124;134;135;134;133 08:06:11 INFO - PROCESS | 1998 | |13;stanford-crypto-sha256-iterative;68;65;65;65;66;66;65;66;66;64 08:06:11 INFO - PROCESS | 1998 | __end_tp_report 08:06:11 INFO - PROCESS | 1998 | __start_cc_report 08:06:11 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,1237 08:06:11 INFO - PROCESS | 1998 | __end_cc_report 08:06:11 INFO - PROCESS | 1998 | __startTimestamp1460732771554__endTimestamp 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | ------- Summary: start ------- 08:06:11 INFO - PROCESS | 1998 | Number of tests: 14 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:3.57 (3.2%) stddev-sans-first:3.71 08:06:11 INFO - PROCESS | 1998 | Values: 114.0 109.0 112.0 111.0 112.0 111.0 121.0 109.0 109.0 111.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:157.00 stddev:21.48 (13.7%) stddev-sans-first:22.34 08:06:11 INFO - PROCESS | 1998 | Values: 158.0 112.0 156.0 149.0 121.0 171.0 168.0 146.0 161.0 118.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#2] audio-dft Cycles:10 Average:185.40 Median:185.00 stddev:1.51 (0.8%) stddev-sans-first:1.59 08:06:11 INFO - PROCESS | 1998 | Values: 185.0 187.0 185.0 188.0 187.0 185.0 184.0 183.0 185.0 185.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#3] audio-fft Cycles:10 Average:83.40 Median:76.00 stddev:26.80 (35.3%) stddev-sans-first:28.29 08:06:11 INFO - PROCESS | 1998 | Values: 76.0 76.0 89.0 138.0 83.0 84.0 57.0 57.0 118.0 56.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:81.00 stddev:19.33 (23.9%) stddev-sans-first:20.44 08:06:11 INFO - PROCESS | 1998 | Values: 91.0 95.0 128.0 81.0 77.0 87.0 121.0 76.0 81.0 116.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:89.50 stddev:18.09 (20.2%) stddev-sans-first:19.01 08:06:11 INFO - PROCESS | 1998 | Values: 90.0 86.0 90.0 87.0 90.0 90.0 133.0 129.0 89.0 85.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:06:11 INFO - PROCESS | 1998 | Values: 94.0 95.0 95.0 94.0 93.0 94.0 95.0 95.0 94.0 94.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.10 (1.3%) stddev-sans-first:0.93 08:06:11 INFO - PROCESS | 1998 | Values: 87.0 84.0 84.0 85.0 85.0 85.0 85.0 87.0 84.0 85.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:59.50 stddev:4.97 (8.3%) stddev-sans-first:5.25 08:06:11 INFO - PROCESS | 1998 | Values: 58.0 59.0 60.0 60.0 59.0 61.0 59.0 71.0 53.0 53.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:66.00 stddev:4.50 (6.8%) stddev-sans-first:4.53 08:06:11 INFO - PROCESS | 1998 | Values: 63.0 64.0 78.0 71.0 68.0 65.0 64.0 66.0 66.0 65.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#10] stanford-crypto-aes Cycles:10 Average:99.70 Median:109.00 stddev:11.89 (10.9%) stddev-sans-first:12.43 08:06:11 INFO - PROCESS | 1998 | Values: 94.0 104.0 108.0 106.0 70.0 92.0 104.0 105.0 104.0 110.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#11] stanford-crypto-ccm Cycles:10 Average:122.70 Median:126.50 stddev:6.93 (5.5%) stddev-sans-first:6.96 08:06:11 INFO - PROCESS | 1998 | Values: 129.0 128.0 125.0 115.0 116.0 115.0 117.0 120.0 134.0 128.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.40 Median:134.50 stddev:4.50 (3.3%) stddev-sans-first:4.68 08:06:11 INFO - PROCESS | 1998 | Values: 135.0 124.0 136.0 135.0 134.0 124.0 134.0 135.0 134.0 133.0 08:06:11 INFO - PROCESS | 1998 | 08:06:11 INFO - PROCESS | 1998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:0.71 08:06:11 INFO - PROCESS | 1998 | Values: 68.0 65.0 65.0 65.0 66.0 66.0 65.0 66.0 66.0 64.0 08:06:11 INFO - PROCESS | 1998 | -------- Summary: end -------- 08:06:11 INFO - PROCESS | 1998 | 08:06:12 INFO - TEST-INFO | 1998: exit 0 08:06:12 INFO - TEST-OK | kraken | took 57112ms 08:06:12 INFO - SUITE-END | took 1170s 08:06:12 INFO - Completed test suite (00:19:31) 08:06:12 INFO - javascript benchmark 08:06:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3419.1964888251255, 3469.8265086745655, 3501.7723847949865, 3510.666486683766, 3532.9470057949134, 5681.630694004887, 5690.2033715279795, 5699.116636921279, 5705.25831641887, 5715.228444160037, 5677.943220567793, 5734.283214598176, 5738.081906894898, 5741.425857414257, 5751.0, 5106.897862042757, 5122.257272695457, 5148.2020286855595, 5210.947890521084, 5234.973825130869, 5232.398274198459, 5232.45059268777, 5244.292020577231, 5246.685198888079, 5253.789848406059, 5179.792808287664, 5242.580593552526, 5248.0553500369915, 5254.0, 5257.815976440826, 5239.5546378557865, 5272.182811664179, 5272.340957380327, 5276.1030624793875, 5320.015797077553, 4975.850724478271, 5146.639735218536, 5165.22521621756, 5181.326427564431, 5188.143956247215, 5530.253415788858, 5595.160725891108, 5615.831525054214, 5630.493255607015, 5639.943600563983, 5362.544183744387, 6114.327423983403, 6228.377162283736, 6262.655553944532, 6320.083587879778, 6191.845203869894, 6210.31686514483, 6249.781257656005, 6307.400796924285, 6313.084602732624, 5797.217375654275, 5839.416058394211, 5855.707214639251, 5879.67661778602, 5887.175795388649, 6197.0, 6287.654179020106, 6288.182536270256, 6299.149614801943, 6299.1811064562235, 6051.10800558992, 6080.197335375712, 6080.209572755513, 6120.204373431426, 6147.754089836446, 6246.6876656167005, 6279.026750853625, 6303.401176888188, 6310.62136271816, 6314.179156709598, 6183.0, 6214.689265536796, 6243.313235544087, 6244.875102497925, 6245.250569931637, 6129.664444256008, 6138.204480310071, 6249.312575616678, 6267.373262673696, 6271.059341098871, 4882.584980276645, 4898.730569818623, 4913.06651736169, 4917.680350777259, 4938.111139994836, 4404.691671583022, 4405.669574781904, 4420.513743488214, 4436.800343984529, 4455.910881782346, 5543.002259593312, 5628.409017053151, 5652.321721393377, 5653.717314134276, 5685.573581981368, 6206.937930620636, 6254.906176407358, 6255.311915689271, 6263.091851681481, 6264.780732674334, 5622.690752008597, 5624.550035997192, 5630.465105814941, 5632.070708333079, 5640.0, 5253.711045892513, 5300.2049692546425, 5306.363236411656, 5325.3875804282225, 5405.918911216335, 6340.968295158495, 6356.586821856657, 6366.013267943476, 6367.554271200971, 6380.649064301322, 6272.2159730033745, 6281.466075383643, 6285.905711414424, 6292.244930608356, 6303.684815759286, 6214.40963108504, 6266.373362663697, 6294.0558916162945, 6326.620402775849, 6335.3981371769605, 6145.354737752472, 6185.381461853779, 6201.131841542278, 6202.976508875985, 6213.844653883689, 6233.407826256498, 6236.719347629277, 6261.78083767066, 6275.435210830865, 6304.306526282197, 5044.243363495505, 5889.116632505158, 6072.301685306246, 6119.167450728499, 6156.922538555825, 5908.497777688945, 6121.173641558333, 6122.663253521099, 6124.5712800103565, 6130.233720784902, 5774.3648198697265, 5940.0, 5958.910616340845, 5973.671448070398, 5994.340622531431, 5891.58758886891, 5952.791652292149, 5958.582899197015, 6073.939260607514, 6161.568690191643, 5868.3251426086545, 5879.412058794257, 5884.911726324023, 5888.499477544457, 5896.204012458435], "unit": "score", "name": "dojo.html", "value": 5692.432486793872}, {"lowerIsBetter": false, "replicates": [491.22386629125987, 593.5548338745938, 600.564590671763, 669.5614372585957, 671.3186116092168, 27953.580696289573, 40260.79869600652, 40483.59516404835, 40525.79737101318, 40629.79685101578, 43199.78400108003, 43822.0, 43824.90437739058, 43862.56137438625, 43867.341989870096, 9682.37064590803, 9868.407895526256, 9868.703938881808, 9873.160781333596, 9902.207823374149, 9551.474668893208, 9679.032096790335, 9689.0, 9692.224622030257, 9706.466144362057, 8203.794905127359, 9795.608175672964, 9797.216222702202, 9809.264305177105, 9818.312718109735, 9721.659741909, 9785.119339259465, 9810.264230182765, 9813.969533198982, 9828.312018158731, 9688.854667179998, 9815.214782817358, 9817.116459518606, 9842.409455432697, 9857.260705447048, 33974.0, 41059.1788164238, 41310.586894130975, 41542.0, 41552.58447415518, 3316.0714999800093, 3359.7816141950693, 3475.878344257939, 3487.2153765402836, 3732.5147730795093, 29231.707682923112, 29425.970091046926, 29609.70390296091, 30172.8491357544, 30177.547336789965, 2749.67058648038, 2779.66644002721, 2780.8331500109857, 2790.6651201855907, 2792.986035069812, 476368.0, 481436.92815359443, 481825.0, 481848.0, 482968.0, 71324.28675713176, 72100.63949680323, 72409.63795181096, 72421.6378918102, 72528.63735681288, 63953.36046639567, 74593.25406745971, 74924.62537687385, 76460.61769691228, 76572.0, 18221.54446138857, 18320.541986450444, 18441.907790460962, 18474.722879156823, 18508.81491185098, 13684.178949263076, 13688.52090176839, 13702.38339274735, 13712.24582647944, 13758.793618095737, 21928.342149735534, 22164.445888852584, 22166.780827054343, 22175.223867164572, 22247.555048898932, 7863.685452581833, 9050.506666400102, 9238.815223695488, 9284.0, 9291.07089291079, 949.924006079512, 971.7522031881966, 983.2478154212033, 1047.528612124547, 1075.0, 29736.40527189444, 30448.086557403312, 30453.69546304553, 31023.68976310253, 31093.53359699606, 1079.649114037925, 1157.1263695909533, 1157.5774842182543, 1195.1813008089314, 1209.7580483903077, 13112.475500980045, 15508.379664813281, 15588.376464941502, 15719.135447550267, 15809.446669366747, 536989.0, 560154.0, 563018.184909081, 563122.0, 563245.0, 58107.0, 58527.70736146377, 58540.0, 58652.4134758647, 58670.11994820081, 1324.2319454716242, 1330.660681526224, 1409.7814838700017, 1538.7537993920919, 1572.5989872582418, 29255.707442925297, 29313.413731725672, 29535.26161845885, 30035.0, 30184.849075754482, 46208.30687539622, 46394.768026161, 46547.53452465432, 46597.64127125288, 46679.0, 313616.0, 342542.0, 351563.0, 355575.0, 356034.0, 29822.850885745433, 34729.305413892085, 34840.825795871875, 35381.46927796137, 35441.46837797487, 43135.63126652342, 43250.35124473072, 43657.34513982229, 43862.90342741457, 44042.0, 35283.823580882956, 35317.117072072375, 35354.64645353513, 35446.0, 36068.27863442769, 35544.46683299701, 35651.10872228215, 35980.28039439249, 36245.81877090598, 36366.818165909004, 36950.89147325585, 37409.251814963005, 37689.0, 37693.0, 38164.236715266095, 32527.674723252465, 32551.18622034468, 32847.507287391185, 32941.50587741234, 33358.33283334368, 25001.0, 25387.238382848544, 25479.853406596376, 26087.73912260929, 26976.595351069358], "unit": "score", "name": "ext.html", "value": 20160.76769676571}, {"lowerIsBetter": false, "replicates": [327.7869384899813, 354.85983036700503, 374.9343864823657, 377.8771899132781, 378.1057798307003, 20338.491537711547, 20803.375898723025, 21043.36869893902, 21209.787902120974, 21483.892580537075, 18790.436286911416, 21500.89249553754, 22256.0, 22359.0, 22368.328950131483, 5762.3373312069125, 5902.911456328158, 5929.229200203989, 5940.643561386309, 5952.494038006775, 5594.412586678402, 5949.6430214187285, 5956.851078723045, 5980.910286345708, 5986.042233242682, 4704.45898721648, 5873.911891321633, 5896.7936122235515, 5902.2622172228475, 5907.320658124307, 4979.45226025137, 5648.943510564883, 5722.427757224265, 5822.97088514559, 5828.504577110951, 5081.872953176163, 5111.182210846247, 5111.948880511221, 5122.1804511278015, 5124.461931497213, 25757.0, 26195.214143575722, 26613.46773064528, 26666.466670666672, 26697.46605067888, 2431.0640403444622, 2434.926952191437, 2440.9869903989825, 2542.2119143065524, 2852.82883027017, 14646.780298295535, 14968.401263949429, 15035.323410446555, 15240.314185861664, 15505.379784808594, 1712.914354282281, 1740.608363118301, 1762.6210364771507, 1786.490850107713, 1829.4145873320542, 94250.0, 100317.0, 103044.0, 104805.0, 105411.6752994815, 11416.543338266376, 11525.962663360338, 11610.825837612443, 11774.646760597025, 12062.638120856389, 14493.85506144925, 14584.781228281585, 14584.979051466296, 14597.489087881873, 14624.853751462562, 8812.603432845537, 8993.460392376479, 9007.909920900838, 9089.636414543476, 9121.36150469474, 7543.962280188564, 9188.63245470187, 9240.7689807754, 9259.537023148816, 9296.628134874665, 3808.638179372955, 4523.547645235451, 4550.13547425988, 4565.0641618468135, 4573.336866154389, 1738.452387497934, 1867.9346222882405, 1889.461503471504, 1894.6305470433408, 1900.6388786130867, 737.9631018449163, 781.7185813107277, 890.643742503004, 921.5806807902388, 987.8886252965414, 20306.69539956902, 20456.69314960277, 20510.589788204154, 20718.896405517877, 20781.792182078287, 981.8036392721341, 987.234892957949, 1025.2566889005561, 1058.751193469534, 1071.314358810362, 11335.546578136948, 11515.884841151481, 11629.302241865515, 11635.825462618068, 11681.474333654836, 485974.0, 491008.0, 491833.0, 517763.0, 585703.0, 100603.49698251463, 112184.0, 119043.61912761696, 124004.279871604, 131413.0, 1048.570086264628, 1070.0797314309686, 1086.8532748078908, 1095.4358505369742, 1099.2030777686114, 17978.0, 18079.90960045244, 18298.634027319644, 18914.621707566046, 19143.808561914204, 51774.44676659706, 53357.0, 55071.72464137654, 55225.72387138039, 55487.72256138694, 69949.30050699427, 78991.0, 80418.19581804107, 81099.59450202947, 82560.58719706364, 8036.839263214586, 8206.384521160817, 8216.958915205385, 8359.0, 8398.160183981552, 11964.58123965657, 13298.335083245605, 13437.260950647416, 13536.390862411019, 13848.376823043189, 10925.726856828644, 11456.312621242752, 11597.420128993685, 11863.228890122115, 11990.640280791591, 5443.339781366722, 9191.0, 10500.36997780105, 11174.832377514182, 11729.296242225153, 11890.524379024742, 11903.880961190278, 12033.278003319829, 12105.636830895088, 12282.508699651913, 8167.264946154874, 8377.204165604258, 8407.327413807003, 8469.0, 8622.353323500887, 11460.140489463156, 11794.705132371759, 11986.880131198577, 12069.517219311481, 12132.393380331125], "unit": "score", "name": "jquery.html", "value": 9666.611600213648}, {"lowerIsBetter": false, "replicates": [1430.249119212414, 1659.145540046876, 1686.9325226990918, 2015.4659015360924, 2071.5753270579535, 8012.879806802903, 8774.385792994493, 8956.059613740554, 8961.641534338634, 8967.327450441227, 8640.438371505848, 8935.91064089359, 8939.910600893989, 8941.195292423681, 8948.015718271, 2311.2603966730653, 2363.7163540375177, 2497.113524698733, 2538.2131539222855, 2571.0872640212738, 2403.3390817525146, 2596.909108181214, 2608.243609353285, 2608.5695860183046, 2609.6607441032734, 2331.8367714260007, 2500.8749562521893, 2546.121588052128, 2563.7308082651334, 2568.987155064222, 1917.5397904502959, 2184.7378314602192, 2436.1351720139273, 2488.967078662353, 2542.6821647294087, 2235.821134309251, 2363.7990770784504, 2475.059477398595, 2497.1759319424546, 2505.260948020338, 8852.380333376666, 8938.86591701125, 8942.552872356357, 8944.463332200025, 8955.596998135099, 4575.313702944552, 5566.443355664442, 5604.8038318659055, 5607.130894711327, 5609.382967873531, 3521.1901262709466, 3562.5546806649168, 3686.2811751708427, 3710.109573702319, 3711.31340701971, 942.3733217410414, 1079.7300674831292, 1079.96220132295, 1110.74452875839, 1125.3641692443744, 12402.317872516918, 12427.93786031073, 12441.626751197478, 12442.315672638002, 12452.31512266825, 4843.2250839865455, 5014.724190169502, 5077.26379674945, 5095.745212739348, 5097.923531147036, 10376.16990640747, 10476.947615261875, 10502.579896804042, 10506.264561480622, 10506.369617822955, 5645.95549823284, 5653.23681303027, 5661.065924122474, 5661.150827375926, 5671.092625179951, 4690.835820746258, 4789.784459699322, 4838.395200599925, 4944.826931057468, 5132.101882170605, 4050.8784736457956, 4109.445224894661, 4119.443875076826, 4155.940235239995, 4178.080822219107, 1300.7918733002675, 1359.7008658095203, 1360.8364848054969, 1365.3446345754094, 1377.572952384764, 1395.7557427450358, 1425.8146440962817, 1429.0853853533747, 1438.216172186161, 1449.3695242569515, 8377.246047855722, 8454.239118479367, 8487.957560212282, 8496.915030849612, 8508.617112229964, 521.6583138044587, 536.7209051293383, 538.37548443805, 538.5610727257273, 539.7921800106988, 6264.467520260829, 6276.560640755103, 6319.905201421982, 6333.841653958688, 6343.9682801585695, 10767.407792571326, 11144.2199046066, 11271.323720576791, 11296.209265351346, 11305.378204198849, 10629.5216715248, 10991.06575941054, 11074.944625276983, 11099.778004439866, 11119.16606254518, 8451.154884511623, 8642.265407440438, 8662.956685216534, 8680.131986801396, 8696.521691306913, 6934.618595977269, 8072.757817265491, 8129.227723366271, 8153.9184608153155, 8167.224113709188, 9537.332386732862, 9760.560774765294, 9767.511624418892, 9852.556634951588, 10003.29976901639, 8923.330750193632, 9961.053699898497, 10477.0, 10483.423411712429, 10483.842742358718, 7658.349040331412, 7670.041244844395, 7699.615019249127, 7751.922480775345, 7774.650140743794, 8432.156784321518, 8461.576921154041, 8527.914720852712, 8535.146485351415, 8539.615717292612, 7486.850262994819, 7615.961920190363, 7662.540247585162, 7714.614269286625, 7759.0, 7391.889121663287, 7532.246775322424, 7547.698092076475, 7660.195679453577, 7711.498752581064, 7981.880271796043, 8061.072976607765, 8065.637046332812, 8069.0, 8077.151899050515, 7140.321669441395, 7305.926940730525, 7314.524555903849, 7328.790749526269, 7334.3032411920785, 7350.77947661571, 7357.595332256563, 7407.888881666887, 7410.5183163094225, 7423.851522969619], "unit": "score", "name": "mootools.html", "value": 4980.280006416038}, {"lowerIsBetter": false, "replicates": [334.815851281795, 335.72302850148634, 339.39079352562146, 345.3179969560119, 349.13414731465963, 6618.56979296346, 6695.63174025429, 6829.795106146811, 6880.2431732509385, 6911.067005954194, 6634.402903738663, 6643.003549467582, 6646.933530664692, 6660.367265109807, 6677.933220667792, 4725.10223057618, 4836.830710925118, 4885.316055752198, 4974.278729584199, 4993.725345106018, 4930.3344048553345, 4965.975170124144, 4968.428630707479, 4971.328870602459, 4990.176620857555, 4881.1213981483315, 4896.46138924718, 4908.28829819676, 4911.066897289522, 4921.0, 3730.7015438764834, 4799.208130658455, 4888.193448081098, 4917.893473968365, 4952.356193694796, 3458.913527161816, 4207.97896010518, 4213.51544572375, 4224.281872081753, 4235.3858690490015, 6357.078223657544, 6401.711922963478, 6403.167588213549, 6414.967925160391, 6435.678216089177, 5812.0, 5829.970850145764, 5833.883322333572, 5842.269716285465, 5848.619839710405, 6747.898781518281, 6995.79012629622, 7015.228324884259, 7022.262662420423, 7035.437165026786, 4748.762561871893, 4778.139934811733, 4782.282657601353, 4788.976055119737, 4813.735244561548, 7257.673404696801, 7384.75070968772, 7412.332890039922, 7414.03617529723, 7419.258074192591, 4849.25426846335, 4854.757262136879, 4865.489123641966, 4869.220924652039, 4869.440014398389, 4908.0, 4916.532929371704, 4927.310176575283, 4936.407631084293, 4953.900921981613, 5590.608657394025, 5824.621399609096, 5834.445727655866, 5849.853753656107, 5850.531957443394, 7027.227005029443, 7101.964490177516, 7113.608751518716, 7126.35862772353, 7126.35862772353, 1404.7331007108821, 1668.1659170414794, 1685.48592679234, 1721.4319274639338, 1726.7064599018197, 1045.5347370420243, 1095.1074873977682, 1103.3545375954996, 1121.2935850414187, 1128.7347473343757, 6280.152179455715, 6292.874142517216, 6301.495880329563, 6348.873022539523, 6381.872362552723, 7174.3184397482155, 7337.009503716929, 7385.8153546161775, 7399.334059934524, 7399.482036257518, 6575.079488871562, 6577.440917522064, 6577.539572229994, 6593.3736295051895, 6612.603243805291, 3828.559715632682, 3875.457435958968, 3886.1644746379666, 3887.630675085862, 3890.5331360236387, 7283.7450689225625, 7624.504407213513, 7627.199144089791, 7651.655675494616, 7662.003939487831, 7520.210377910241, 7537.32424989132, 7552.358049565739, 7557.470977031555, 7567.470277080662, 6097.359777223328, 7514.436417268617, 7592.430567707444, 7604.315611594983, 7617.390608751176, 6951.409130223988, 7320.304571065741, 7336.082989626297, 7340.302671246223, 7362.852742945218, 7161.641917904188, 7216.3505284524645, 7234.0, 7240.814041821582, 7259.0200322957835, 7588.582627955516, 7590.886136707843, 7596.316331530189, 7596.658150383136, 7597.0, 4830.2513110467935, 4856.611471082376, 4858.174110401311, 4861.270809378621, 4861.6596838220985, 5978.893904627658, 6529.9020514691365, 7063.611501367474, 7301.160366557914, 7321.926780732125, 5933.317668468008, 6903.309669033057, 6934.653267336512, 7036.929630703832, 7043.119610048744, 5951.345352011189, 6734.02356658272, 7018.263082376481, 7034.0, 7046.612436316052, 6445.452136568444, 6844.657767111724, 6858.251145957749, 6873.621950792554, 6907.171139463297, 6827.897581536182, 7034.753783617549, 7048.894266586108, 7053.647317633995, 7064.187618423947, 5743.4256574342235, 6623.774601698701, 6744.662766861736, 6828.522003459909, 6940.861182776417], "unit": "score", "name": "prototype.html", "value": 5182.238873184688}, {"lowerIsBetter": false, "replicates": [642.8489305013321, 691.3432239372596, 708.4155571653386, 721.4949535325273, 748.9288517590827, 15158.999999999985, 15210.695786084285, 15465.381384744596, 15492.302846371898, 15630.374785008615, 14291.14253144812, 14351.64120896978, 14417.990740648132, 14423.783643245333, 14429.927850360733, 770.6994272233833, 779.7582749347721, 941.2375975459865, 946.9195118414943, 964.4213471916864, 944.9669261575846, 947.8672985781996, 952.2334520710859, 952.2857856607544, 953.3993584042079, 1013.8732658417698, 1024.7591815923251, 1027.1474676018886, 1027.2090490322448, 1027.5170669785227, 789.498668345599, 801.3148757812103, 802.8956235689382, 804.1074407407773, 1012.5291739341205, 741.8516296740673, 753.6118898767139, 754.1854796819476, 754.35125791819, 755.3390783064818, 15330.233488325539, 15424.691506169815, 15433.614159645986, 15440.227988600525, 15447.304871280707, 12638.2417054977, 12684.492620295177, 12749.980001599943, 12918.935405322913, 13058.0, 10085.69742907714, 10155.79688406235, 10214.387136771817, 10238.33450825694, 10365.689029329133, 3473.7221022318154, 3506.333796578642, 3514.5079688843584, 3593.730470214718, 3754.0239537720117, 61422.692886535726, 83951.0, 84700.0, 87084.0, 87315.56342218249, 7939.761807145795, 9646.565904534313, 9722.319437639297, 9740.123388895034, 9831.36096153762, 22184.778152218594, 22429.102835886708, 22527.549449010927, 22552.887235564045, 22677.773222267897, 7662.73180438682, 7787.532748035136, 7922.722704705423, 8078.596070196584, 8166.020077590727, 4203.684723645678, 4262.339337402707, 4264.552222016644, 4277.508086570022, 4355.412019377407, 3741.551013878352, 3780.0738818989525, 3785.962140378616, 3792.4500947362476, 3804.0299723570865, 639.6098379988204, 644.9161608990801, 647.5014239035916, 661.6956200147889, 666.7266420767462, 588.8557303460594, 591.7869566955892, 601.7141857617596, 609.0650850943869, 614.9969250153721, 6829.692663830139, 7773.416993725493, 7993.080795708568, 8342.457740246864, 8368.456050356708, 495.88594623236133, 596.3380647481292, 596.6986671730748, 607.975680972765, 615.3846153846154, 3464.8960531184107, 3780.4707340972286, 3831.272058308912, 3879.991202287369, 3928.626780455852, 94267.0, 94870.0, 96943.0, 97851.51074244583, 98392.50803745935, 75171.87242191372, 75437.0, 78391.60804195942, 79262.0, 79351.0, 71529.6423517879, 71622.0, 73642.26357736462, 73934.0, 74407.62796186093, 7676.155622881367, 7815.452918295892, 7905.56519391439, 7922.247386498273, 7940.761777146695, 52309.0, 53881.73059134679, 53896.92206155934, 54247.91504169816, 57364.0, 50864.72838178927, 67158.0, 70686.64656676889, 72227.91658125237, 72893.635531822, 10272.33229840058, 10391.688249352532, 10532.157427405942, 10689.144868410664, 11005.724856878642, 8510.27662648657, 9454.716358509255, 10857.294275872044, 10879.728006799893, 11088.778224435628, 10217.0, 10247.333923294962, 10417.114545263737, 10429.791404172025, 10480.737981550523, 10143.847842282225, 10338.172946164139, 10385.169186464913, 10460.372377657364, 10847.67456976292, 9981.900180998096, 11537.769244615229, 11563.0, 11724.94137529341, 11964.64106076819, 9586.808263834824, 9730.36752611078, 9755.21958243353, 9813.313068085166, 10085.848712269468, 9655.179309758749, 9696.418214907128, 9710.465924374228, 9732.610695572097, 9743.171830394496], "unit": "score", "name": "yui.html", "value": 6921.767426540037}], "name": "dromaeo_css", "value": 7635.727887117278}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 109.0, 112.0, 111.0, 112.0, 111.0, 121.0, 109.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [158.0, 112.0, 156.0, 149.0, 121.0, 171.0, 168.0, 146.0, 161.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [185.0, 187.0, 185.0, 188.0, 187.0, 185.0, 184.0, 183.0, 185.0, 185.0], "unit": "score", "name": "audio-dft", "value": 185.4}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 89.0, 138.0, 83.0, 84.0, 57.0, 57.0, 118.0, 56.0], "unit": "score", "name": "audio-fft", "value": 83.4}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 128.0, 81.0, 77.0, 87.0, 121.0, 76.0, 81.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 95.3}, {"lowerIsBetter": true, "replicates": [90.0, 86.0, 90.0, 87.0, 90.0, 90.0, 133.0, 129.0, 89.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 96.9}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 95.0, 94.0, 93.0, 94.0, 95.0, 95.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 94.3}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 84.0, 85.0, 85.0, 85.0, 85.0, 87.0, 84.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 85.1}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 60.0, 60.0, 59.0, 61.0, 59.0, 71.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 59.3}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 78.0, 71.0, 68.0, 65.0, 64.0, 66.0, 66.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.0}, {"lowerIsBetter": true, "replicates": [94.0, 104.0, 108.0, 106.0, 70.0, 92.0, 104.0, 105.0, 104.0, 110.0], "unit": "score", "name": "stanford-crypto-aes", "value": 99.7}, {"lowerIsBetter": true, "replicates": [129.0, 128.0, 125.0, 115.0, 116.0, 115.0, 117.0, 120.0, 134.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.7}, {"lowerIsBetter": true, "replicates": [135.0, 124.0, 136.0, 135.0, 134.0, 124.0, 134.0, 135.0, 134.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 132.4}, {"lowerIsBetter": true, "replicates": [68.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1445.0}]} 08:06:12 INFO - Return code: 0 08:06:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:06:12 INFO - # TBPL SUCCESS # 08:06:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:06:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:06:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:06:12 INFO - 2016-04-15 08:06:12.869 screenresolution[2002:29764] starting screenresolution argv=screenresolution get 08:06:12 INFO - 2016-04-15 08:06:12.872 screenresolution[2002:29764] Display 0: 1600x1200x32@60 08:06:12 INFO - 2016-04-15 08:06:12.877 screenresolution[2003:29767] starting screenresolution argv=screenresolution list 08:06:12 INFO - Available Modes on Display 0 08:06:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:06:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:06:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:06:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:06:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:06:13 INFO - Intel Iris: 08:06:13 INFO - Chipset Model: Intel Iris 08:06:13 INFO - Type: GPU 08:06:13 INFO - Bus: Built-In 08:06:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:06:13 INFO - Vendor: Intel (0x8086) 08:06:13 INFO - Device ID: 0x0a2e 08:06:13 INFO - Revision ID: 0x0009 08:06:13 INFO - Displays: 08:06:13 INFO - Display: 08:06:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:06:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:06:13 INFO - Display Serial Number: 1600x1200 60 08:06:13 INFO - Main Display: Yes 08:06:13 INFO - Mirror: Off 08:06:13 INFO - Online: Yes 08:06:13 INFO - Rotation: Supported 08:06:13 INFO - Return code: 0 08:06:13 INFO - Running post-action listener: _resource_record_post_action 08:06:13 INFO - Running post-run listener: _resource_record_post_run 08:06:14 INFO - Total resource usage - Wall time: 1205s; CPU: 52.0%; Read bytes: 749568; Write bytes: 666398208; Read time: 7; Write time: 2091 08:06:14 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358199808; Write bytes: 355028480; Read time: 28003; Write time: 749 08:06:14 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 749568; Write bytes: 300195840; Read time: 7; Write time: 1311 08:06:14 INFO - Running post-run listener: _upload_blobber_files 08:06:14 INFO - Blob upload gear active. 08:06:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:06:14 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 08:06:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:06:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:06:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:15 INFO - (blobuploader) - INFO - Done attempting. 08:06:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:06:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:16 INFO - (blobuploader) - INFO - Done attempting. 08:06:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:06:16 INFO - Return code: 0 08:06:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:06:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:06:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44816afc15470bf684e213e60273a275614982ddae7133879a5f3963cd1377497a845aeb582052593a4aad7576637fab2694b4c8a7273940e00f19c93820fb75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6c6c3771134420f3cae73fe368ffaab7968a96a28ad395b67de443c465afce609091f5ab8860f573606a72130ee588711af4e29f8a5eb5fcc22c2316337679"} 08:06:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:06:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:06:16 INFO - Contents: 08:06:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44816afc15470bf684e213e60273a275614982ddae7133879a5f3963cd1377497a845aeb582052593a4aad7576637fab2694b4c8a7273940e00f19c93820fb75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6c6c3771134420f3cae73fe368ffaab7968a96a28ad395b67de443c465afce609091f5ab8860f573606a72130ee588711af4e29f8a5eb5fcc22c2316337679"} 08:06:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:06:16 INFO - Copying logs to upload dir... 08:06:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:06:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.704099 ========= master_lag: 77.67 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2016-04-15 08:07:34.409930) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-15 08:07:34.413092) ========= 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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44816afc15470bf684e213e60273a275614982ddae7133879a5f3963cd1377497a845aeb582052593a4aad7576637fab2694b4c8a7273940e00f19c93820fb75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6c6c3771134420f3cae73fe368ffaab7968a96a28ad395b67de443c465afce609091f5ab8860f573606a72130ee588711af4e29f8a5eb5fcc22c2316337679"} build_url:https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/belnWMPVSdK99lDSICljKw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44816afc15470bf684e213e60273a275614982ddae7133879a5f3963cd1377497a845aeb582052593a4aad7576637fab2694b4c8a7273940e00f19c93820fb75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6c6c3771134420f3cae73fe368ffaab7968a96a28ad395b67de443c465afce609091f5ab8860f573606a72130ee588711af4e29f8a5eb5fcc22c2316337679"}' ========= master_lag: 4.62 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-15 08:07:39.039544) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-04-15 08:07:39.039924) ========= 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.ff0MpcFDV5/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.CZ9TABa1Jh/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.005191 ========= master_lag: 6.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-04-15 08:07:45.081340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 08:07:45.081689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 08:07:45.517107) ========= ========= Total master_lag: 88.38 =========