builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0368 starttime: 1480351295.28 results: success (0) buildid: 20161128061357 builduid: f32ed2cbe46b4783a8150cbd1b9e37f4 revision: aed03debf4f236c5ccbd746f1a8fdb353a2b572b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.277987) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.278452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.278738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.303876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.304315) ========= 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.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-11-28 08:41:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 5.83M=0.002s 2016-11-28 08:41:35 (5.83 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087938 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.415633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.415958) ========= 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.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.019094 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:35.465120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 08:41:35.465472) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aed03debf4f236c5ccbd746f1a8fdb353a2b572b --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 aed03debf4f236c5ccbd746f1a8fdb353a2b572b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-11-28 08:41:35,548 truncating revision to first 12 chars 2016-11-28 08:41:35,548 Setting DEBUG logging. 2016-11-28 08:41:35,548 attempt 1/10 2016-11-28 08:41:35,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/aed03debf4f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 08:41:37,131 unpacking tar archive at: mozilla-aurora-aed03debf4f2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.856175 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 08:41:37.346233) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:37.346564) ========= script_repo_revision: aed03debf4f236c5ccbd746f1a8fdb353a2b572b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:37.346950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:37.347215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 08:41:37.367101) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 08:41:37.367488) ========= /tools/buildbot/bin/python -u 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', '-u', '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:41:37 INFO - MultiFileLogger online at 20161128 08:41:37 in /builds/slave/test 08:41:37 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 08:41:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:41:37 INFO - {'append_to_log': False, 08:41:37 INFO - 'base_work_dir': '/builds/slave/test', 08:41:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:41:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:41:37 INFO - 'branch': 'Mozilla-Aurora', 08:41:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:41:37 INFO - 'config_files': ('talos/mac_config.py',), 08:41:37 INFO - 'default_actions': ('clobber', 08:41:37 INFO - 'read-buildbot-config', 08:41:37 INFO - 'download-and-extract', 08:41:37 INFO - 'populate-webroot', 08:41:37 INFO - 'create-virtualenv', 08:41:37 INFO - 'install', 08:41:37 INFO - 'run-tests'), 08:41:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:41:37 INFO - 'download_minidump_stackwalk': True, 08:41:37 INFO - 'download_symbols': 'ondemand', 08:41:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:41:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:41:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:41:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:41:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:41:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:41:37 INFO - 'installer_path': 'installer.exe', 08:41:37 INFO - 'log_level': 'info', 08:41:37 INFO - 'log_name': 'talos', 08:41:37 INFO - 'log_to_console': True, 08:41:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:41:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:41:37 INFO - 'opt_config_files': (), 08:41:37 INFO - 'pip_index': False, 08:41:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:37 INFO - 'cmd': ('bash', 08:41:37 INFO - '-c', 08:41:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:37 INFO - 'enabled': True, 08:41:37 INFO - 'halt_on_failure': False, 08:41:37 INFO - 'name': 'check_screen_resolution'},), 08:41:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:37 INFO - 'cmd': ('bash', 08:41:37 INFO - '-c', 08:41:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:37 INFO - 'enabled': True, 08:41:37 INFO - 'halt_on_failure': False, 08:41:37 INFO - 'name': 'check_screen_resolution'},), 08:41:37 INFO - 'run_cmd_checks_enabled': True, 08:41:37 INFO - 'sps_profile': False, 08:41:37 INFO - 'sps_profile_interval': 0, 08:41:37 INFO - 'suite': 'dromaeojs', 08:41:37 INFO - 'system_bits': '32', 08:41:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:41:37 INFO - 'title': 't-yosemite-r7-0368', 08:41:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:41:37 INFO - 'use_talos_json': True, 08:41:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:41:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:41:37 INFO - 'work_dir': 'build'} 08:41:37 INFO - [mozharness: 2016-11-28 16:41:37.569532Z] Running clobber step. 08:41:37 INFO - Running pre-action listener: _resource_record_pre_action 08:41:37 INFO - Running main action method: clobber 08:41:37 INFO - rmtree: /builds/slave/test/build 08:41:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:41:39 INFO - Running post-action listener: _resource_record_post_action 08:41:39 INFO - [mozharness: 2016-11-28 16:41:39.048087Z] Finished clobber step (success) 08:41:39 INFO - [mozharness: 2016-11-28 16:41:39.048213Z] Running read-buildbot-config step. 08:41:39 INFO - Running pre-action listener: _resource_record_pre_action 08:41:39 INFO - Running main action method: read_buildbot_config 08:41:39 INFO - Using buildbot properties: 08:41:39 INFO - { 08:41:39 INFO - "project": "", 08:41:39 INFO - "product": "firefox", 08:41:39 INFO - "script_repo_revision": "production", 08:41:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:41:39 INFO - "repository": "", 08:41:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:41:39 INFO - "buildid": "20161128061357", 08:41:39 INFO - "pgo_build": "False", 08:41:39 INFO - "basedir": "/builds/slave/test", 08:41:39 INFO - "buildnumber": 51, 08:41:39 INFO - "slavename": "t-yosemite-r7-0368", 08:41:39 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:41:39 INFO - "platform": "yosemite_r7", 08:41:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:41:39 INFO - "branch": "mozilla-aurora", 08:41:39 INFO - "repo_path": "releases/mozilla-aurora", 08:41:39 INFO - "revision": "aed03debf4f236c5ccbd746f1a8fdb353a2b572b", 08:41:39 INFO - "stage_platform": "macosx64", 08:41:39 INFO - "builduid": "f32ed2cbe46b4783a8150cbd1b9e37f4", 08:41:39 INFO - "slavebuilddir": "test" 08:41:39 INFO - } 08:41:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:41:39 INFO - Running post-action listener: _resource_record_post_action 08:41:39 INFO - [mozharness: 2016-11-28 16:41:39.051501Z] Finished read-buildbot-config step (success) 08:41:39 INFO - [mozharness: 2016-11-28 16:41:39.051643Z] Running download-and-extract step. 08:41:39 INFO - Running pre-action listener: _resource_record_pre_action 08:41:39 INFO - Running main action method: download_and_extract 08:41:39 INFO - mkdir: /builds/slave/test/build/tests 08:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:39 INFO - trying https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:41:39 INFO - Downloading https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:41:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:41:40 INFO - Downloaded 1199 bytes. 08:41:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:41:40 INFO - Using the following test package requirements: 08:41:40 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:41:40 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:41:40 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:41:40 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'jsshell-mac.zip'], 08:41:40 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:41:40 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:41:40 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:41:40 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:41:40 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:41:40 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:41:40 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:41:40 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:41:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:41:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:41:40 INFO - Fetch https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:41:40 INFO - Http code: 200 08:41:40 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:41:40 INFO - x-amz-cf-id: TbHUKyRTGebTA1z66jXwM8iT7uoEIqR8QL76HGwsBisTg7qGV-exyQ== 08:41:40 INFO - x-amz-version-id: uE973YNYNg.ANvYXegv9KTbJzdATzKPe 08:41:41 INFO - Expected file size: 16279469 08:41:41 INFO - Obtained file size: 16279469 08:41:47 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:41:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:41:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:41:47 INFO - Fetch https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:41:48 INFO - Http code: 200 08:41:48 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 08:41:48 INFO - x-amz-cf-id: _Uqu0kxkCsNsgjgAGFFTVhCUiR5bB9wroIlgwdlrBVQiux1Fa4cKig== 08:41:48 INFO - x-amz-version-id: uE973YNYNg.ANvYXegv9KTbJzdATzKPe 08:41:48 INFO - Expected file size: 16279469 08:41:48 INFO - Obtained file size: 16279469 08:41:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:41:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:41:55 INFO - Fetch https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:41:56 INFO - Http code: 200 08:41:56 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:41:56 INFO - x-amz-cf-id: -S5wE1hTa4lixH0npOWqLkbfXd5qxoilBsveQJJR_Apvn0-6_DGmQw== 08:41:56 INFO - x-amz-version-id: 2O6ooB_GWzy_vzKpcMy8i7oBs0Gr5r5o 08:41:56 INFO - Expected file size: 14409038 08:41:56 INFO - Obtained file size: 14409038 08:41:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:56 INFO - trying https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:41:56 INFO - Downloading https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:41:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:41:59 INFO - Downloaded 109743881 bytes. 08:41:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:41:59 INFO - mkdir: /builds/slave/test/properties 08:41:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:59 INFO - Contents: 08:41:59 INFO - build_url:https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:42:00 INFO - Running post-action listener: _resource_record_post_action 08:42:00 INFO - Running post-action listener: set_extra_try_arguments 08:42:00 INFO - [mozharness: 2016-11-28 16:42:00.200301Z] Finished download-and-extract step (success) 08:42:00 INFO - [mozharness: 2016-11-28 16:42:00.200455Z] Running populate-webroot step. 08:42:00 INFO - Running pre-action listener: _resource_record_pre_action 08:42:00 INFO - Running main action method: populate_webroot 08:42:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:42:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:42:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:42:00 INFO - '--tppagecycles', 08:42:00 INFO - '7'], 08:42:00 INFO - 'tests': ['tsvgm']}}, 08:42:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:42:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:42:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['damp', 'tps']}, 08:42:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'tests': ['damp', 'tps']}, 08:42:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['dromaeo_dom']}, 08:42:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['basic_compositor_video']}, 08:42:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:42:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['a11yr', 08:42:00 INFO - 'ts_paint', 08:42:00 INFO - 'tpaint', 08:42:00 INFO - 'sessionrestore', 08:42:00 INFO - 'sessionrestore_no_auto_restore', 08:42:00 INFO - 'tabpaint']}, 08:42:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['tsvgx', 08:42:00 INFO - 'tsvgr_opacity', 08:42:00 INFO - 'tart', 08:42:00 INFO - 'tscrollx', 08:42:00 INFO - 'cart']}, 08:42:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:00 INFO - 'tsvgr_opacity', 08:42:00 INFO - 'tart', 08:42:00 INFO - 'tscrollx', 08:42:00 INFO - 'cart']}, 08:42:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'talos_options': ['--disable-e10s'], 08:42:00 INFO - 'tests': ['tp5o']}, 08:42:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'tests': ['tp5o']}, 08:42:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'talos_options': ['--disable-e10s', 08:42:00 INFO - '--xperf_path', 08:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:00 INFO - 'tests': ['tp5n']}, 08:42:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:00 INFO - 'talos_options': ['--xperf_path', 08:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:00 INFO - 'tests': ['tp5n']}}, 08:42:00 INFO - 'talos.zip': {'path': '', 08:42:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:42:00 INFO - Running post-action listener: _resource_record_post_action 08:42:00 INFO - [mozharness: 2016-11-28 16:42:00.218917Z] Finished populate-webroot step (success) 08:42:00 INFO - [mozharness: 2016-11-28 16:42:00.219045Z] Running create-virtualenv step. 08:42:00 INFO - Running pre-action listener: _resource_record_pre_action 08:42:00 INFO - Running main action method: create_virtualenv 08:42:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:00 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 08:42:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:42:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:00 INFO - Using real prefix '/tools/python27' 08:42:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:01 INFO - Installing distribute.............................................................................................................................................................................................done. 08:42:03 INFO - Installing pip.................done. 08:42:03 INFO - Return code: 0 08:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:42:04 INFO - Reading from file tmpfile_stdout 08:42:04 INFO - Output received: 08:42:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:42:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:04 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 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:04 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 08:42:04 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 08:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:04 INFO - 'HOME': '/Users/cltbld', 08:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:04 INFO - 'LOGNAME': 'cltbld', 08:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:04 INFO - 'MOZ_NO_REMOTE': '1', 08:42:04 INFO - 'NO_EM_RESTART': '1', 08:42:04 INFO - 'PAGER': '/bin/cat', 08:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:04 INFO - 'PWD': '/builds/slave/test', 08:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:04 INFO - 'SHELL': '/bin/bash', 08:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:04 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:04 INFO - 'USER': 'cltbld', 08:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:04 INFO - 'XPC_FLAGS': '0x0', 08:42:04 INFO - 'XPC_SERVICE_NAME': '0', 08:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:04 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 08:42:04 INFO - Cleaning up... 08:42:04 INFO - Return code: 0 08:42:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:04 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:04 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>=3.1.1'] in /builds/slave/test/build 08:42:04 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>=3.1.1 08:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:04 INFO - 'HOME': '/Users/cltbld', 08:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:04 INFO - 'LOGNAME': 'cltbld', 08:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:04 INFO - 'MOZ_NO_REMOTE': '1', 08:42:04 INFO - 'NO_EM_RESTART': '1', 08:42:04 INFO - 'PAGER': '/bin/cat', 08:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:04 INFO - 'PWD': '/builds/slave/test', 08:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:04 INFO - 'SHELL': '/bin/bash', 08:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:04 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:04 INFO - 'USER': 'cltbld', 08:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:04 INFO - 'XPC_FLAGS': '0x0', 08:42:04 INFO - 'XPC_SERVICE_NAME': '0', 08:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:04 INFO - Downloading/unpacking psutil>=3.1.1 08:42:04 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 08:42:04 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 08:42:04 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 08:42:04 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 08:42:04 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 08:42:04 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 08:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:42:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:08 INFO - Installing collected packages: psutil 08:42:08 INFO - Running setup.py install for psutil 08:42:08 INFO - building 'psutil._psutil_osx' extension 08:42:08 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 08:42:08 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 08:42:08 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 08:42:08 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 08:42:08 INFO - building 'psutil._psutil_posix' extension 08:42:08 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 08:42:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:42:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:42:08 INFO - ^ 08:42:08 INFO - 1 warning generated. 08:42:08 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 08:42:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:09 INFO - Successfully installed psutil 08:42:09 INFO - Cleaning up... 08:42:09 INFO - Return code: 0 08:42:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:42:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:09 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:09 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.3'] in /builds/slave/test/build 08:42:09 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.3 08:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:09 INFO - 'HOME': '/Users/cltbld', 08:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:09 INFO - 'LOGNAME': 'cltbld', 08:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:09 INFO - 'MOZ_NO_REMOTE': '1', 08:42:09 INFO - 'NO_EM_RESTART': '1', 08:42:09 INFO - 'PAGER': '/bin/cat', 08:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:09 INFO - 'PWD': '/builds/slave/test', 08:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:09 INFO - 'SHELL': '/bin/bash', 08:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:09 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:09 INFO - 'USER': 'cltbld', 08:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:09 INFO - 'XPC_FLAGS': '0x0', 08:42:09 INFO - 'XPC_SERVICE_NAME': '0', 08:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:42:09 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 08:42:09 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 08:42:09 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 08:42:09 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 08:42:09 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 08:42:09 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 08:42:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:42:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:42:12 INFO - Installing collected packages: mozsystemmonitor 08:42:12 INFO - Running setup.py install for mozsystemmonitor 08:42:12 INFO - Successfully installed mozsystemmonitor 08:42:12 INFO - Cleaning up... 08:42:12 INFO - Return code: 0 08:42:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:12 INFO - 'HOME': '/Users/cltbld', 08:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:12 INFO - 'LOGNAME': 'cltbld', 08:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:12 INFO - 'MOZ_NO_REMOTE': '1', 08:42:12 INFO - 'NO_EM_RESTART': '1', 08:42:12 INFO - 'PAGER': '/bin/cat', 08:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:12 INFO - 'PWD': '/builds/slave/test', 08:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:12 INFO - 'SHELL': '/bin/bash', 08:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:12 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:12 INFO - 'USER': 'cltbld', 08:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:12 INFO - 'XPC_FLAGS': '0x0', 08:42:12 INFO - 'XPC_SERVICE_NAME': '0', 08:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:12 INFO - Downloading/unpacking jsonschema==2.5.1 08:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:42:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:15 INFO - Downloading functools32-3.2.3-2.tar.gz 08:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:42:15 INFO - warning: no files found matching '*.txt' 08:42:15 INFO - no previously-included directories found matching 'build' 08:42:15 INFO - no previously-included directories found matching 'dist' 08:42:15 INFO - no previously-included directories found matching '.git*' 08:42:15 INFO - Installing collected packages: jsonschema, functools32 08:42:15 INFO - Running setup.py install for functools32 08:42:15 INFO - warning: no files found matching '*.txt' 08:42:15 INFO - no previously-included directories found matching 'build' 08:42:15 INFO - no previously-included directories found matching 'dist' 08:42:15 INFO - no previously-included directories found matching '.git*' 08:42:15 INFO - Successfully installed jsonschema functools32 08:42:15 INFO - Cleaning up... 08:42:16 INFO - Return code: 0 08:42:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:16 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:16 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:42:16 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 functools32==3.2.3-2 08:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:16 INFO - 'HOME': '/Users/cltbld', 08:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:16 INFO - 'LOGNAME': 'cltbld', 08:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:16 INFO - 'MOZ_NO_REMOTE': '1', 08:42:16 INFO - 'NO_EM_RESTART': '1', 08:42:16 INFO - 'PAGER': '/bin/cat', 08:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:16 INFO - 'PWD': '/builds/slave/test', 08:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:16 INFO - 'SHELL': '/bin/bash', 08:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:16 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:16 INFO - 'USER': 'cltbld', 08:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:16 INFO - 'XPC_FLAGS': '0x0', 08:42:16 INFO - 'XPC_SERVICE_NAME': '0', 08:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:42:16 INFO - Cleaning up... 08:42:16 INFO - Return code: 0 08:42:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:16 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 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:16 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 08:42:16 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 08:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:16 INFO - 'HOME': '/Users/cltbld', 08:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:16 INFO - 'LOGNAME': 'cltbld', 08:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:16 INFO - 'MOZ_NO_REMOTE': '1', 08:42:16 INFO - 'NO_EM_RESTART': '1', 08:42:16 INFO - 'PAGER': '/bin/cat', 08:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:16 INFO - 'PWD': '/builds/slave/test', 08:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:16 INFO - 'SHELL': '/bin/bash', 08:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:16 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:16 INFO - 'USER': 'cltbld', 08:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:16 INFO - 'XPC_FLAGS': '0x0', 08:42:16 INFO - 'XPC_SERVICE_NAME': '0', 08:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:16 INFO - Downloading/unpacking blobuploader==1.2.4 08:42:16 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 08:42:16 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 08:42:16 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 08:42:16 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 08:42:16 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 08:42:16 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 08:42:19 INFO - Downloading blobuploader-1.2.4.tar.gz 08:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:42:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:42:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:19 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 08:42:20 INFO - Downloading docopt-0.6.1.tar.gz 08:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:42:20 INFO - Installing collected packages: blobuploader, requests, docopt 08:42:20 INFO - Running setup.py install for blobuploader 08:42:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:42:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:42:20 INFO - Running setup.py install for requests 08:42:21 INFO - Running setup.py install for docopt 08:42:21 INFO - Successfully installed blobuploader requests docopt 08:42:21 INFO - Cleaning up... 08:42:21 INFO - Return code: 0 08:42:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:21 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 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:21 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 08:42:21 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 08:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:21 INFO - 'HOME': '/Users/cltbld', 08:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:21 INFO - 'LOGNAME': 'cltbld', 08:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:21 INFO - 'MOZ_NO_REMOTE': '1', 08:42:21 INFO - 'NO_EM_RESTART': '1', 08:42:21 INFO - 'PAGER': '/bin/cat', 08:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:21 INFO - 'PWD': '/builds/slave/test', 08:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:21 INFO - 'SHELL': '/bin/bash', 08:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:21 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:21 INFO - 'USER': 'cltbld', 08:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:21 INFO - 'XPC_FLAGS': '0x0', 08:42:21 INFO - 'XPC_SERVICE_NAME': '0', 08:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dQBu0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-fJB6No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2fOAvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2dVyIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-EPGuLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-kSbS1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-59_ico-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-kbAs0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-GHXf9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YL76YM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-_lsGEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ajBbQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-jpSscH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-7HRRhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:23 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-kxRDnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:23 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-h_OBQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:23 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-q_oHMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:42:23 INFO - Running setup.py install for manifestparser 08:42:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:42:23 INFO - Running setup.py install for mozcrash 08:42:23 INFO - Running setup.py install for mozdebug 08:42:23 INFO - Running setup.py install for mozdevice 08:42:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:42:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:42:23 INFO - Running setup.py install for mozfile 08:42:24 INFO - Running setup.py install for mozhttpd 08:42:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Running setup.py install for mozinfo 08:42:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Running setup.py install for mozInstall 08:42:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Running setup.py install for mozleak 08:42:24 INFO - Running setup.py install for mozlog 08:42:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:42:24 INFO - Running setup.py install for moznetwork 08:42:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Running setup.py install for mozprocess 08:42:25 INFO - Running setup.py install for mozprofile 08:42:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Running setup.py install for mozrunner 08:42:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Running setup.py install for mozscreenshot 08:42:25 INFO - Running setup.py install for moztest 08:42:25 INFO - Running setup.py install for mozversion 08:42:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:42:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:42:25 INFO - Cleaning up... 08:42:25 INFO - Return code: 0 08:42:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:25 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 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:25 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 08:42:25 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 08:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:25 INFO - 'HOME': '/Users/cltbld', 08:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:25 INFO - 'LOGNAME': 'cltbld', 08:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:25 INFO - 'MOZ_NO_REMOTE': '1', 08:42:25 INFO - 'NO_EM_RESTART': '1', 08:42:25 INFO - 'PAGER': '/bin/cat', 08:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:25 INFO - 'PWD': '/builds/slave/test', 08:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:25 INFO - 'SHELL': '/bin/bash', 08:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:25 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:25 INFO - 'USER': 'cltbld', 08:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:25 INFO - 'XPC_FLAGS': '0x0', 08:42:25 INFO - 'XPC_SERVICE_NAME': '0', 08:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-M9KCJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-QuyKtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-CjPf33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Nrwm3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-PrmRW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-JUeeXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-GUzQtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:26 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)) 08:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:26 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-EDnohd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-nZ7f7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-COhtiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dRA2wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-oUjBsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-X6nnn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-xu9NKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-8wHOQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-zqRclJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:27 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)) 08:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:27 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-gMn1Sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:28 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)) 08:42:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:42:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:42:28 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)) 08:42:28 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)) 08:42:28 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)) 08:42:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:42:28 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 08:42:28 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 08:42:28 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 08:42:28 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 08:42:28 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 08:42:28 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 08:42:29 INFO - Downloading blessings-1.6.tar.gz 08:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:42:30 INFO - Installing collected packages: blessings 08:42:30 INFO - Running setup.py install for blessings 08:42:30 INFO - Successfully installed blessings 08:42:30 INFO - Cleaning up... 08:42:30 INFO - Return code: 0 08:42:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:42:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:30 INFO - Reading from file tmpfile_stdout 08:42:30 INFO - Current package versions: 08:42:30 INFO - blessings == 1.6 08:42:30 INFO - blobuploader == 1.2.4 08:42:30 INFO - docopt == 0.6.1 08:42:30 INFO - functools32 == 3.2.3-2 08:42:30 INFO - jsonschema == 2.5.1 08:42:30 INFO - manifestparser == 1.1 08:42:30 INFO - mozInstall == 1.12 08:42:30 INFO - mozcrash == 1.0 08:42:30 INFO - mozdebug == 0.1 08:42:30 INFO - mozdevice == 0.48 08:42:30 INFO - mozfile == 1.2 08:42:30 INFO - mozhttpd == 0.7 08:42:30 INFO - mozinfo == 0.9 08:42:30 INFO - mozleak == 0.1 08:42:30 INFO - mozlog == 3.4 08:42:30 INFO - moznetwork == 0.27 08:42:30 INFO - mozprocess == 0.23 08:42:30 INFO - mozprofile == 0.28 08:42:30 INFO - mozrunner == 6.13 08:42:30 INFO - mozscreenshot == 0.1 08:42:30 INFO - mozsystemmonitor == 0.3 08:42:30 INFO - moztest == 0.7 08:42:30 INFO - mozversion == 1.4 08:42:30 INFO - psutil == 3.1.1 08:42:30 INFO - requests == 1.2.3 08:42:30 INFO - wsgiref == 0.1.2 08:42:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:30 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 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:30 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 08:42:30 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 08:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:30 INFO - 'HOME': '/Users/cltbld', 08:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:30 INFO - 'LOGNAME': 'cltbld', 08:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:30 INFO - 'MOZ_NO_REMOTE': '1', 08:42:30 INFO - 'NO_EM_RESTART': '1', 08:42:30 INFO - 'PAGER': '/bin/cat', 08:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:30 INFO - 'PWD': '/builds/slave/test', 08:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:30 INFO - 'SHELL': '/bin/bash', 08:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:30 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:30 INFO - 'USER': 'cltbld', 08:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:30 INFO - 'XPC_FLAGS': '0x0', 08:42:30 INFO - 'XPC_SERVICE_NAME': '0', 08:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 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)) 08:42:30 INFO - Cleaning up... 08:42:30 INFO - Return code: 0 08:42:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd156ea90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, '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.FRZtSGsCKa/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.7GKCPCL65W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 08:42:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:30 INFO - 'HOME': '/Users/cltbld', 08:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:30 INFO - 'LOGNAME': 'cltbld', 08:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:30 INFO - 'MOZ_NO_REMOTE': '1', 08:42:30 INFO - 'NO_EM_RESTART': '1', 08:42:30 INFO - 'PAGER': '/bin/cat', 08:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:30 INFO - 'PWD': '/builds/slave/test', 08:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:30 INFO - 'SHELL': '/bin/bash', 08:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:42:30 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:30 INFO - 'USER': 'cltbld', 08:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:30 INFO - 'XPC_FLAGS': '0x0', 08:42:30 INFO - 'XPC_SERVICE_NAME': '0', 08:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:42:31 INFO - Cleaning up... 08:42:31 INFO - Return code: 0 08:42:31 INFO - Running post-action listener: _resource_record_post_action 08:42:31 INFO - Running post-action listener: _start_resource_monitoring 08:42:31 INFO - Starting resource monitoring. 08:42:31 INFO - [mozharness: 2016-11-28 16:42:31.185187Z] Finished create-virtualenv step (success) 08:42:31 INFO - [mozharness: 2016-11-28 16:42:31.186176Z] Running install step. 08:42:31 INFO - Running pre-action listener: _resource_record_pre_action 08:42:31 INFO - Running main action method: install 08:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:31 INFO - Reading from file tmpfile_stdout 08:42:31 INFO - Detecting whether we're running mozinstall >=1.0... 08:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:42:31 INFO - Reading from file tmpfile_stdout 08:42:31 INFO - Output received: 08:42:31 INFO - Usage: mozinstall [options] installer 08:42:31 INFO - Options: 08:42:31 INFO - -h, --help show this help message and exit 08:42:31 INFO - -d DEST, --destination=DEST 08:42:31 INFO - Directory to install application into. [default: 08:42:31 INFO - "/builds/slave/test"] 08:42:31 INFO - --app=APP Application being installed. [default: firefox] 08:42:31 INFO - mkdir: /builds/slave/test/build/application 08:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:43:02 INFO - Reading from file tmpfile_stdout 08:43:02 INFO - Output received: 08:43:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:43:02 INFO - Running post-action listener: _resource_record_post_action 08:43:02 INFO - [mozharness: 2016-11-28 16:43:02.109639Z] Finished install step (success) 08:43:02 INFO - [mozharness: 2016-11-28 16:43:02.109818Z] Running run-tests step. 08:43:02 INFO - Running pre-action listener: _resource_record_pre_action 08:43:02 INFO - Running main action method: run_tests 08:43:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:02 INFO - 2016-11-28 08:43:02.124 screenresolution[1913:20201] starting screenresolution argv=screenresolution get 08:43:02 INFO - 2016-11-28 08:43:02.127 screenresolution[1913:20201] Display 0: 1600x1200x32@60 08:43:02 INFO - 2016-11-28 08:43:02.132 screenresolution[1914:20203] starting screenresolution argv=screenresolution list 08:43:02 INFO - Available Modes on Display 0 08:43:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:02 INFO - Intel Iris: 08:43:02 INFO - Chipset Model: Intel Iris 08:43:02 INFO - Type: GPU 08:43:02 INFO - Bus: Built-In 08:43:02 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:02 INFO - Vendor: Intel (0x8086) 08:43:02 INFO - Device ID: 0x0a2e 08:43:02 INFO - Revision ID: 0x0009 08:43:02 INFO - Displays: 08:43:02 INFO - Display: 08:43:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:02 INFO - Display Serial Number: 1600x1200 60 08:43:02 INFO - Main Display: Yes 08:43:02 INFO - Mirror: Off 08:43:02 INFO - Online: Yes 08:43:02 INFO - Rotation: Supported 08:43:02 INFO - Return code: 0 08:43:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:02 INFO - Python 2.7.3 08:43:02 INFO - Return code: 0 08:43:02 INFO - grabbing minidump binary from tooltool 08:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e81cd30>, '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 0x10e682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e814b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:02 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 08:43:02 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 08:43:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:43:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:02 INFO - Return code: 0 08:43:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:43:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:43:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:02 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/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 08:43:02 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/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0368 --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 08:43:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FRZtSGsCKa/Render', 08:43:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:02 INFO - 'HOME': '/Users/cltbld', 08:43:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:02 INFO - 'LOGNAME': 'cltbld', 08:43:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:02 INFO - 'MOZ_NO_REMOTE': '1', 08:43:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:02 INFO - 'NO_EM_RESTART': '1', 08:43:02 INFO - 'PAGER': '/bin/cat', 08:43:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:02 INFO - 'PWD': '/builds/slave/test', 08:43:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:02 INFO - 'SHELL': '/bin/bash', 08:43:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7GKCPCL65W/Listeners', 08:43:02 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 08:43:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:02 INFO - 'USER': 'cltbld', 08:43:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:02 INFO - 'XPC_FLAGS': '0x0', 08:43:02 INFO - 'XPC_SERVICE_NAME': '0', 08:43:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:02 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/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 08:43:02 INFO - mozversion application_buildid: 20161128061357 08:43:02 INFO - mozversion application_changeset: aed03debf4f236c5ccbd746f1a8fdb353a2b572b 08:43:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:43:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:02 INFO - mozversion application_name: Firefox 08:43:02 INFO - mozversion application_remotingname: firefox-dev 08:43:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:43:02 INFO - mozversion application_vendor: Mozilla 08:43:02 INFO - mozversion application_version: 52.0a2 08:43:02 INFO - mozversion platform_buildid: 20161128061357 08:43:02 INFO - mozversion platform_changeset: aed03debf4f236c5ccbd746f1a8fdb353a2b572b 08:43:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:43:02 INFO - mozversion platform_version: 52.0a2 08:43:02 INFO - using testdate: 1480351382 08:43:02 INFO - actual date: 1480351382 08:43:02 INFO - starting webserver on 'localhost:55488' 08:43:02 INFO - SUITE-START | Running 2 tests 08:43:02 INFO - TEST-START | dromaeo_css 08:43:02 INFO - operating with platform_type : mac_ 08:43:02 INFO - Initialising browser for dromaeo_css test... 08:43:02 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpjMF1NY/profile http://localhost:55488/getInfo.html) 08:43:05 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 08:43:05 INFO - PROCESS | 1924 | colorDepth:24 08:43:05 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 08:43:05 INFO - PROCESS | 1924 | __metrics 08:43:05 INFO - PROCESS | 1924 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:43:05 INFO - PROCESS | 1924 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:43:06 INFO - TEST-INFO | 1924: exit 0 08:43:06 INFO - Browser initialized. 08:43:06 INFO - Running cycle 1/1 for dromaeo_css test... 08:43:06 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpjMF1NY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:43:18 INFO - PROCESS | 1926 | RSS: Main: 222699520 08:43:18 INFO - PROCESS | 1926 | 08:44:09 INFO - PROCESS | 1926 | 1480351449352 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] 08:46:23 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55488/tests/dromaeo/cssquery-ext.html) 08:46:25 INFO - PROCESS | 1926 | RSS: Main: 367853568 08:46:25 INFO - PROCESS | 1926 | 08:49:28 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-ext.html (next: http://localhost:55488/tests/dromaeo/cssquery-jquery.html) 08:49:28 INFO - PROCESS | 1926 | RSS: Main: 344838144 08:49:28 INFO - PROCESS | 1926 | 08:52:31 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55488/tests/dromaeo/cssquery-mootools.html) 08:52:32 INFO - PROCESS | 1926 | RSS: Main: 337747968 08:52:32 INFO - PROCESS | 1926 | 08:55:34 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55488/tests/dromaeo/cssquery-prototype.html) 08:55:34 INFO - PROCESS | 1926 | RSS: Main: 316764160 08:55:34 INFO - PROCESS | 1926 | 08:58:38 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55488/tests/dromaeo/cssquery-yui.html) 08:58:39 INFO - PROCESS | 1926 | RSS: Main: 305889280 08:58:39 INFO - PROCESS | 1926 | 09:01:41 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:55488/tests/dromaeo/cssquery-yui.html (next: http://localhost:55488/tests/dromaeo/cssquery-dojo.html) 09:01:42 INFO - PROCESS | 1926 | RSS: Main: 358150144 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | __start_tp_report 09:01:42 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 09:01:42 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 09:01:42 INFO - PROCESS | 1926 | |i|pagename|runs| 09:01:42 INFO - PROCESS | 1926 | |0;dojo.html;3328.2012317043905;3339.382214290355;3342.8328583570824;3376.780509266896;3403.8468268927895;5698.0883058710615;5724.685142317181;5753.165790960308;5787.652740835542;5806.506446952004;5634.661920284786;5752;5757.942420575803;5778.219940308068;5791.507721843638;5202.855371818194;5233.450487698794;5258.211268309765;5265.104932161542;5312.442193569677;5121.12940800063;5195.532402083812;5200.453952335007;5214.191800270964;5328.5470734987375;5139.100657384962;5195.974020129913;5196.792128314863;5200.973995130019;5249.947500524984;5196.454372290912;5198.194279886624;5215.087359712035;5216.217567364925;5231.973840130813;4066.451029111062;5107.387113546361;5126.051680439131;5127;5131;5637.872425514873;5650.773969041234;5658.717064146817;5663.49028587425;5663.915041274384;6026.578139530235;6106.45041946227;6126.663033533154;6130.264368275791;6135.417135372177;6151.015837466029;6176.69116544171;6185;6185.721642526097;6194.876102477971;5699;5702.2301989231755;5705.201271821907;5709.600327977002;5710.200571919934;6190.628562286277;6252.280987686474;6283.3402492737605;6288;6289.559730818799;5212.452692467312;5881.617694849821;6055.515558755289;6132.520321146446;6170.012797952396;6213.596116252429;6222.968885155546;6233.812985610439;6238.906416403757;6242.906356404657;6058.27300723916;6137.294211165684;6172.845678857974;6184.536159788034;6206.472449841724;6119.563485741553;6125.295591007091;6166.568340216231;6172.321044685081;6186.783462578788;4910.631702622318;4927.457979622239;4934.8026078957155;4943.876403089879;4959.181735013683;4326.242907491176;4427.512973572904;4428.402165707653;4442.777861106932;4445.644348452116;5669.40471250521;5707.0583353747;5753.453421924911;5817.38917413674;5828.854278643068;6130.509559235251;6200.571013598903;6245.156903818017;6275.246970363494;6291.307956124914;5757.539396848242;5773.624714393635;5833.300003999547;5859.677717725566;5861.7655293787775;5259.027079990214;5299.364076310791;5315.6013299002725;5352.143657014859;5411.134218524939;6226.034964580497;6276.215473065867;6283.528735344776;6291.905621415591;6302.936970630235;6194.690265486798;6237.563370564017;6267.2792628848265;6267.529935254783;6273.905891411541;6158.261008678808;6177;6265.028920517327;6296.937030629635;6344.4290013897125;4847.297141914332;6155.2921414037955;6179.598326108789;6214.689265536615;6234.314225435108;5261.5264626183825;6169.96915015422;6266.436020757971;6275.717592708431;6286.057091436138;5871.735771890189;6044.818655440344;6048.4858787003595;6138.785142519991;6154.1999540059505;5331.200319952039;5911.083782013795;5998.700064996646;6030.668313242813;6075.665838378932;5944.4352786485215;5946;5950.702464876826;5950.732217050157;5958.731857066529;6020.127081573235;6062.787802426894;6072.05883088122;6077.027675571887;6084.452399284086;5714.485696287354;5751.5398768099285;5763.394843541368;5839.3868643793485;5882.088276317178 09:01:42 INFO - PROCESS | 1926 | |1;ext.html;450.3290097754346;470.0645715027095;505.93500681066377;518.3417060333377;538.8168022872225;30291.697083029165;32549.349013019753;33663.83168084156;39215.823525294254;40684.79657601715;41979.53071642485;42212.57787422125;42244.732658020235;42329.365059524134;42395.78802105985;9274.582643781028;9454.81090378192;9461.574229159703;9462.81074378512;9466.479343636098;9260.814783704322;9360.251179905592;9362.578683959204;9365.531723413837;9376.249900007982;9334.019927907573;9380.202682771938;9381.906180938206;9400.388974716656;9419.62321507139;9305.813883722321;9306.302027347976;9311.115444032806;9312.208462280714;9357.39176953496;9307.813843723156;9378.577963991569;9381.953090234505;9400.918894327104;9407.482588457631;39604;39950.60049399527;40015.59984400148;40085.79957100225;40089;3232.127325622069;3382.7970321780526;3497.387957107496;3677.595464498903;3872.186840763443;29500.557491637643;30139.69860301391;30468.390632187453;30498.085057448312;30885.691143088505;3117.267442151093;3159.573457583243;3159.8262095584732;3217.887373941901;3296;462690;466644;467219;467565;474946;59390;59825.70087149536;59864;60005.399946000856;60030.69984650049;34562.30875382529;35214.471782923276;35418.46872296918;62667;63022.36977630257;15771;16118.274677639534;16119.758203626954;16119.758203626954;16159.515214543582;12184;12260.32568208757;12268.570600029134;12277.754444911052;12278.38608069593;19001.954892481048;19070.52323691891;19072.427827165207;19118.61762764737;19130.617387652168;7453.85092298151;7644.120926093459;7653.617319134021;7722.729704460429;7737.26495982892;852.194676031157;944.5088553951906;966.1353088985319;979.9951000244953;1024.08856118055;28699.856500717782;29140.125796226148;29444.705552944197;29960.85019574888;30045.69954300429;1025.9384436933808;1044.8328267477168;1045.9581616735397;1130.5929865248504;1155.8035134027236;15351.769723454157;15384;15397.923010384877;15582.9220853895;15803.604909877346;155240;155367;155901;156595;157892;57261;58022.41977580255;58046;58050.709746451;58095;1165.636205639404;1235.2403271987662;1281.705207802192;1368.0970559430727;1384.7092110656674;29514.557281640362;30520.236994075327;30864;30953.535696964114;31879.043628691175;50376.74811625919;51365.48634513607;51436;51497.48502514927;51761;557681;570432;575535;575862;576081;35526.822365888;36467.63532364643;36926.44610330901;42862.570994321;43495.565044349154;42794.78602606967;45782.771086144356;46306.07387852291;46827.06345873132;47050.52949470461;34428.96713098611;38367.77954425067;38498.422523662724;38792.224155517295;38902;38382.6161738379;38487.422688659135;38570.228595428496;39811;39921.60078399179;32539.18652033718;32765;37713.811430943766;38089.42865856959;38380.42429363506;31844.044678659677;33854;34705.13237169091;34821;34879.65120348764;25095.121670741435;25470.853811578894;25491;25900.740992589832;26030.73969260283 09:01:42 INFO - PROCESS | 1926 | |2;jquery.html;358.94795254688086;375.64501546038997;379.25665695237353;386.9903252418689;409.9938500922487;14567.635809104764;15174.165420901845;16435.671286574277;19398.321058762947;20140.992950352498;18923.337683181093;19258.807411925878;19869.20523179071;19937.80062199381;20144.496387590316;5498.422665620113;5786.942130578683;5798.478136967672;5801.53587712982;5809.970950145264;4900.338454308676;6343.714532846033;6428.260750013762;6447.451966582846;6470.255920569124;6085.148079268906;6326.683665816714;6400.39196276353;6408.743650253984;6412;6286.151369565096;6347.714352854133;6349.904751428732;6358.205224346957;6365.4589359904;4972.651914365996;5228.764705588258;5287.841364759063;5317.8404647860625;5511.669299842022;12712.936435317764;12821.102522823407;12859.035572332112;13001.15492492985;13032.674183145402;2286.919957801469;2353.658719485665;2491.1440976472645;2506.674132362781;2616.9476610467873;9404.858927116098;10123.848142277871;10201.234907381902;12313.569025084169;14429.783553246709;1797.6764182447155;1806.1330561330635;1826.7807863056519;1828.3235202974984;1856.981430185708;104213;110077.44961275223;120360;125845.74154258524;129784;11314.830277545843;11494.367809770543;11560.595379161688;11562.248453850472;11767.411629418495;14562.927185364006;14596.708065838837;14617.634559135893;14622.341994610268;14628.926855365655;6502.902456463157;8228.83542329162;8244.258016778398;8372.916270837213;8381.413301068867;7972.800679983046;7973.800654983672;7982.521048737094;8136.959315203504;8142.552159631277;4475.060237350128;4483.865484035484;4488.551144885485;4514.25514790056;4585.266357382803;1839.4849442156217;1886.830185283331;1895.2134864031548;1936.4093951344976;1939.2049259803414;846.8137009857821;859.2996707683218;925.2089463508738;931;980.4803454169302;19259.422217333504;20399.63406038017;20454.59090818175;20497.59004819925;20663.380098597067;907.3240435875315;923.2106548900728;926.1294383279695;929.8372784762639;938.7418459923575;10220.897791022144;10583.364998100138;10744.301620394806;10757.193210509244;10759.462026898624;473395;473835;474260;567156;573736;89542.1797583155;90367.77057835489;95043;132475;140404;948.0898337595847;952.64752041745;999.3004896572428;1003.207466101786;1005.0452070533023;17159.142042898056;18522.444326670222;18998.240070397584;19038;19051.237950481827;52424.95150096901;52586;53302.66743331293;54578.72710636421;55496.44503554913;76029;76930.461390773;77364;78092.82860757207;78633.60683196547;7882.132965373919;7926.603669816602;7981.8802717958115;8166.877496837671;8317.126701696236;11040.392778397265;12152.513899444277;12389.070819688739;12541.247525148521;12773.872261277269;10433.373997560171;10518.842217366593;10537.15702743794;10572.682819515427;11020.614278500214;9610.18488032177;10222.386656800616;10471.790564188826;10651.573937042742;10652.73368165771;10684.504169416132;10758.924107589179;10834.349939003685;10915.83626245591;11234.887651123385;7038.331358520933;7766.650500727368;7912.84174316522;8291.875621865556;8339.583020848811;10154.543045563116;10735.677929662123;10950.780984380428;11038.834417483584;11106.61126860556 09:01:42 INFO - PROCESS | 1926 | |3;mootools.html;1470.264867566217;1484.858938400852;1635.754636804479;1759.2611103336612;1807.4487281379174;8081.1918808119235;8518.275946544536;8523.616437260323;8523.914760852389;8537.231649151576;7457.477976541644;7640.503367281123;7977.162397948219;8157.836843263131;8333.291670208024;2246.3934737620834;2281.0761641535205;2283.9885800570974;2285.1544928376525;2287.8055365293885;2233.542123864604;2322.0479603362623;2352.694149760529;2373.964390534143;2379.9762002380016;1776.6269083492482;2248.977510224893;2317.4901521665292;2335.5562443135836;2348.495073559184;2130.0627723801476;2145.184829764695;2149.881756503392;2151.978480215209;2157.9784202158094;2283.7716228377194;2352.694149760537;2353.870537120458;2360.3980984848927;2371.4427109629223;8280.254777070033;8344.62449189788;8350.415470917038;8357.331413486967;8359.623816928253;5031.84904452867;5191.870203244911;5193.428722840485;5193.9740301298625;5196.610254230948;3185.888493902702;3280.901572952815;3526.6297038811067;3528.259065596228;3528.558930133733;1051.7160366700948;1059.607945060325;1081.2809481694665;1087.7933192693442;1102.1843835561706;10959.83560246597;11001.22991390595;11136.777264454666;11144.721381965435;11183.496742646601;4461.866144015685;4620.376249206314;4724.527547245317;4737.123632128068;4751.09729151467;8595.538758411085;8767.561621918878;9444.386114902647;9454.14912657864;9460.668876589287;5206.843794686166;5209;5210.088234558937;5215.739213039333;5220.686758794484;3757.6993840492696;4234.703570750018;4303.203907277164;4696.295555666609;4793.760311984456;3759.8496060157286;3788.924221515554;3851.422286656968;3868.129670824042;3906.2968665640456;1087.6030248959073;1233.6977440527128;1242.5837344489516;1248.4843041271663;1259.537603984227;881.5405019994272;882.9514376709343;883.0992387764444;891.5765011619429;897.8653202019749;5345.7594408251725;5411.431799661058;5559.805406810742;5596.160575913564;5656.066748986454;433.9067100573392;446.9754163520972;455.22384334709244;456.42490462017435;456.7191177425926;3421.606515250728;3677.3013127505687;3724.497192878927;3743.363628183161;3877.728559000899;9934.105930466292;10145.94927025375;10295.279330446796;10311.896881031094;10325.32885362449;10022.74943126413;10115.544800483849;10266.794664106676;10279.229057820543;10294.330868493524;7748.147703752696;7929.44493885434;7935.285824275843;7936.761897143095;7942.404319676048;5278.81524146653;5472.179173124063;5475.4524547544925;5556.138798486241;5566.693831839288;9274.655323705761;9327.486988215442;9414.105659962293;9505.239580833655;9566.808663826545;9320.580573874056;9541.141297283279;9549.809003820024;9604.183644390025;9686.418814871131;5823.8835223294445;6524.9021264682015;6712.6979285931275;7155.356017958408;7261.092363454568;7496.475246732676;7759.72840950564;7780.416468764752;7813.492123012214;7841.490303130278;6867.690953907186;6929.341712537301;6956.652167391509;6972.302769722987;7122.252163522963;6810.31896810315;6825.590464572141;6893.172819261721;6923.273056329013;6939.271376505596;6369.426751592379;6464.385883341075;6539.771108011197;6551.639659818756;6646.302138275411;4612.423447069116;4634.0500197460115;4676.625869930465;4720.197566413786;4765.9046819064115;4702.482726900107;4732.290156476556;4764.142454358249;4802.447718512416;4820.927686084781 09:01:42 INFO - PROCESS | 1926 | |4;prototype.html;266.1655709351186;299.36385181489334;304.5021390027305;311.53425628685113;316.3514794670924;4127.752334859911;4599.310103484479;5241.2400201970695;5260.4213536511;5329.946700532993;5050.626205683323;5103.362079740033;5129.640365431906;5141.840427020651;5142.768575414106;4037.183267529623;4037.535683396403;4046.898827529306;4051.007503161722;4094.1780907496177;3599.46008098786;3709.2766910452474;4072.2669919414493;4082.265192265391;4090.6727461802984;3391.4573668213093;3483.9199848047188;3750.4561838533396;3869.9806500967456;3936.5276166860017;3469.4969229461713;3740.1397678533954;3942.446474322134;3963.6829053675924;3984.6613037891816;3017.3814368054495;3412.1640198151363;3430.2453460238708;3453.1539772755586;3458.204612939013;4406.515283318832;4862.829800956985;5057.2666963290485;5132.332796736438;5190.584753219733;3978.0054986253435;4476.597106260452;4501.527339629324;4503.954960450386;4523.977380113111;4595;5509.815389691195;5511.917321240185;5515.751791169367;5533.45858650464;3645.54430696163;3741.289155060557;3785.470034195215;3793.525809273841;3817.6945844332663;4401.757903315349;5547.8890422191735;5659.688717120557;5662.687327913134;5821.7380217890295;3211.454052810981;3246.9772021813055;3768.66212494566;3771.2086758789355;3790.886273411802;3833.2525157594;3856.5564960029956;3864.9033774155873;3879.9806000969816;3881.922361552753;4087.305158123126;4226.830926762957;4448.5551444855255;4493.910121797546;4563.475200352002;4703.143422393673;5005.474425185376;5359.410464848864;5474.753636086306;5478.315210598676;1090.6509916826699;1222.2971791220084;1240.0017985521743;1255.2092181925511;1304.5760127958451;795.7533164718956;889.4929889962789;907.6006557114848;912.6942474271061;915.5260031349655;4282.914341713148;4528.347153289092;4830.541098595628;4856.854294371175;4871.074495845778;4685.906281874343;4964.925526117111;5023.6985780853265;5343.332083489564;5497.890042199134;4969.602431805447;5183.429822719498;5226.425093239741;5228.346456692913;5230.843074707765;2624.8687565622026;2817.5914492398488;2847.6725176604955;2853.329467575118;2903.419316136781;4946.431160416598;5128.384593848762;5300.284461597712;5412.621116521806;5567.220589117527;4790.616750659939;5421.783128674888;5454;5500.50495455403;5556.97221513898;5031.3207716959205;5503.862403439866;5555.50000499957;5602.646888307002;5712.743196496764;4570.862874113782;4570.88572785683;4972.916791589238;5343.4389389113585;5378.569714422759;4361.302191649321;5133.204353325241;5302.840914772563;5314.760835762478;5347.598930080182;4759.358021482583;5502.58730595199;5505;5512.779488820403;5621.494065534122;3071.754259659177;3420.196253880386;3584.7849129052343;3632.964605087537;3658.60361463242;4394.176416786998;4796.544328288807;5352.625316227826;5353.4646535346155;5374.6506477078865;5187;5201.401838788588;5268.736563171749;5284.920726189033;5305;4939.308496810378;5046.621503387334;5116.334876466184;5202.427732949448;5204.7918083276245;4664.720116793003;5002.699838009731;5079.568236699922;5213.739313034409;5254.868628284324;4576.496585375539;4616.722996620214;4638.397008389023;5121.462246464068;5335.33308336458;4508.301213311942;5366.7584958677735;5380.542653874308;5382.273393091882;5390.622656414013 09:01:42 INFO - PROCESS | 1926 | |5;yui.html;597.808701215611;716.5700579652209;768.316198583261;781.3436713160944;785.1363500149836;14004.579862604112;14529.200893950829;14561;14595.635109122277;15830.525084247462;15097.62255943602;15167.089974601531;15292;15314.923425382858;15315.387384504607;689.9275576064504;852.9530875801828;854.1671869926834;857.3312816003496;864.01933805131;665.3479590001801;682.6313790553095;685.1059367525378;738.1953670499155;817.2113910076774;886.7650072730731;889.1108891108892;889.4529864133543;890.2833219258495;891.0198781340538;886.167003017165;886.1935638568935;886.330820230725;887.5029983209358;890.955452227386;523.6857885268809;534.7299613695099;629.3832044596276;637.9425851673325;662.9436497897684;14405.567832965027;14463.78304325436;14473.986820922539;14477.78283325751;14493.637659058502;12735.426905789262;12929.288889111094;12942;12960.157589756636;12980.028431401784;11759.294442333487;12177.025837932944;12185.756284874253;12431.440585173688;12537.498500059986;4392.626626736731;4465.59809617136;4542.000759832831;4552.521985191574;4553.317002449659;83116.75324870132;85542.57228713817;86990.565047175;88483;88774.55612721895;9983.700488985342;10030.047145521165;10199.337043092175;10250.231232657581;10359.533820978075;19125.043747812553;19214.231430742613;19275.51811204731;19275.807241927683;19286.939218342846;4905.681130726492;6770.984352347187;7223.313785190398;7271.89092163618;7334.96576384282;4038.2529232092156;4154.273002224599;4169.082801783603;4291.013066994608;4321.719088259316;3309.6028476582965;3326.085326535222;3392.219789448391;3392.796432214075;3402.9489557656657;545.2203349210666;620.7206756959387;621.6611946489174;644.1143427786793;647.2750519418281;596.686739461786;597.2176448852018;607.6020206764488;609.7165466692637;626.4236902050125;7850.214978502104;8054.556999364974;8342.16578342161;8415.284700800377;8676;608.3794529579803;609.7865746988518;611.248164757351;629.5561629051508;647.459371424856;3970.9602903971168;4143.212789569972;4145.108801607669;4204.180184863983;4300.591443812833;76196.61901690456;76598.85101723367;78159;79570.20429795743;114000.71998559982;53773;81659;86959;103178;105445.67325960466;90012;90474;94377;95500;96099;6838.623875686686;7996.600169991593;8143.51138931666;8148.796280093045;8340.708075217339;56774.7161264191;58772.41227587841;58889.70555147197;59289.40710593011;62335.75328493314;67532;80558;81842;84374;85709;10099.94950025245;10163.034511721375;10297;10413.531391087268;10844;11646.35945022998;11658.300501969909;11828;11854.94072529632;11971.10216733731;10541.104006159563;11093;11166.776664466828;11199.384033878216;11450.94274528622;10874.238803283693;11096.5561377544;11182.440877956233;11189.888101118884;11379.374134422686;10639.255252132276;10657.25399222047;10994.450277485934;11033.062189713964;11363.318200907972;7577.355924746238;8999.415038022507;9445.480498572644;9477.52612369365;9519.524023798644;8972.820543589221;9345.392549484262;9357.485338306458;9384.624615015324;9385 09:01:42 INFO - PROCESS | 1926 | __end_tp_report 09:01:42 INFO - PROCESS | 1926 | __start_cc_report 09:01:42 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1538 09:01:42 INFO - PROCESS | 1926 | __end_cc_report 09:01:42 INFO - PROCESS | 1926 | __startTimestamp1480352502200__endTimestamp 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | ------- Summary: start ------- 09:01:42 INFO - PROCESS | 1926 | Number of tests: 6 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#0] dojo.html Cycles:165 Average:5694.73 Median:5861.77 stddev:641.44 (10.9%) stddev-sans-first:615.96 09:01:42 INFO - PROCESS | 1926 | Values: 3328.2 3339.4 3342.8 3376.8 3403.8 5698.1 5724.7 5753.2 5787.7 5806.5 5634.7 5752.0 5757.9 5778.2 5791.5 5202.9 5233.5 5258.2 5265.1 5312.4 5121.1 5195.5 5200.5 5214.2 5328.5 5139.1 5196.0 5196.8 5201.0 5249.9 5196.5 5198.2 5215.1 5216.2 5232.0 4066.5 5107.4 5126.1 5127.0 5131.0 5637.9 5650.8 5658.7 5663.5 5663.9 6026.6 6106.5 6126.7 6130.3 6135.4 6151.0 6176.7 6185.0 6185.7 6194.9 5699.0 5702.2 5705.2 5709.6 5710.2 6190.6 6252.3 6283.3 6288.0 6289.6 5212.5 5881.6 6055.5 6132.5 6170.0 6213.6 6223.0 6233.8 6238.9 6242.9 6058.3 6137.3 6172.8 6184.5 6206.5 6119.6 6125.3 6166.6 6172.3 6186.8 4910.6 4927.5 4934.8 4943.9 4959.2 4326.2 4427.5 4428.4 4442.8 4445.6 5669.4 5707.1 5753.5 5817.4 5828.9 6130.5 6200.6 6245.2 6275.2 6291.3 5757.5 5773.6 5833.3 5859.7 5861.8 5259.0 5299.4 5315.6 5352.1 5411.1 6226.0 6276.2 6283.5 6291.9 6302.9 6194.7 6237.6 6267.3 6267.5 6273.9 6158.3 6177.0 6265.0 6296.9 6344.4 4847.3 6155.3 6179.6 6214.7 6234.3 5261.5 6170.0 6266.4 6275.7 6286.1 5871.7 6044.8 6048.5 6138.8 6154.2 5331.2 5911.1 5998.7 6030.7 6075.7 5944.4 5946.0 5950.7 5950.7 5958.7 6020.1 6062.8 6072.1 6077.0 6084.5 5714.5 5751.5 5763.4 5839.4 5882.1 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#1] ext.html Cycles:180 Average:55648.83 Median:21332.21 stddev:116811.78 (547.6%) stddev-sans-first:117065.95 09:01:42 INFO - PROCESS | 1926 | Values: 450.3 470.1 505.9 518.3 538.8 30291.7 32549.3 33663.8 39215.8 40684.8 41979.5 42212.6 42244.7 42329.4 42395.8 9274.6 9454.8 9461.6 9462.8 9466.5 9260.8 9360.3 9362.6 9365.5 9376.2 9334.0 9380.2 9381.9 9400.4 9419.6 9305.8 9306.3 9311.1 9312.2 9357.4 9307.8 9378.6 9382.0 9400.9 9407.5 39604.0 39950.6 40015.6 40085.8 40089.0 3232.1 3382.8 3497.4 3677.6 3872.2 29500.6 30139.7 30468.4 30498.1 30885.7 3117.3 3159.6 3159.8 3217.9 3296.0 462690.0 466644.0 467219.0 467565.0 474946.0 59390.0 59825.7 59864.0 60005.4 60030.7 34562.3 35214.5 35418.5 62667.0 63022.4 15771.0 16118.3 16119.8 16119.8 16159.5 12184.0 12260.3 12268.6 12277.8 12278.4 19002.0 19070.5 19072.4 19118.6 19130.6 7453.9 7644.1 7653.6 7722.7 7737.3 852.2 944.5 966.1 980.0 1024.1 28699.9 29140.1 29444.7 29960.9 30045.7 1025.9 1044.8 1046.0 1130.6 1155.8 15351.8 15384.0 15397.9 15582.9 15803.6 155240.0 155367.0 155901.0 156595.0 157892.0 57261.0 58022.4 58046.0 58050.7 58095.0 1165.6 1235.2 1281.7 1368.1 1384.7 29514.6 30520.2 30864.0 30953.5 31879.0 50376.7 51365.5 51436.0 51497.5 51761.0 557681.0 570432.0 575535.0 575862.0 576081.0 35526.8 36467.6 36926.4 42862.6 43495.6 42794.8 45782.8 46306.1 46827.1 47050.5 34429.0 38367.8 38498.4 38792.2 38902.0 38382.6 38487.4 38570.2 39811.0 39921.6 32539.2 32765.0 37713.8 38089.4 38380.4 31844.0 33854.0 34705.1 34821.0 34879.7 25095.1 25470.9 25491.0 25900.7 26030.7 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#2] jquery.html Cycles:180 Average:31613.52 Median:2561.81 stddev:86452.13 (3374.6%) stddev-sans-first:86662.80 09:01:42 INFO - PROCESS | 1926 | Values: 358.9 375.6 379.3 387.0 410.0 14567.6 15174.2 16435.7 19398.3 20141.0 18923.3 19258.8 19869.2 19937.8 20144.5 5498.4 5786.9 5798.5 5801.5 5810.0 4900.3 6343.7 6428.3 6447.5 6470.3 6085.1 6326.7 6400.4 6408.7 6412.0 6286.2 6347.7 6349.9 6358.2 6365.5 4972.7 5228.8 5287.8 5317.8 5511.7 12712.9 12821.1 12859.0 13001.2 13032.7 2286.9 2353.7 2491.1 2506.7 2616.9 9404.9 10123.8 10201.2 12313.6 14429.8 1797.7 1806.1 1826.8 1828.3 1857.0 104213.0 110077.4 120360.0 125845.7 129784.0 11314.8 11494.4 11560.6 11562.2 11767.4 14562.9 14596.7 14617.6 14622.3 14628.9 6502.9 8228.8 8244.3 8372.9 8381.4 7972.8 7973.8 7982.5 8137.0 8142.6 4475.1 4483.9 4488.6 4514.3 4585.3 1839.5 1886.8 1895.2 1936.4 1939.2 846.8 859.3 925.2 931.0 980.5 19259.4 20399.6 20454.6 20497.6 20663.4 907.3 923.2 926.1 929.8 938.7 10220.9 10583.4 10744.3 10757.2 10759.5 473395.0 473835.0 474260.0 567156.0 573736.0 89542.2 90367.8 95043.0 132475.0 140404.0 948.1 952.6 999.3 1003.2 1005.0 17159.1 18522.4 18998.2 19038.0 19051.2 52425.0 52586.0 53302.7 54578.7 55496.4 76029.0 76930.5 77364.0 78092.8 78633.6 7882.1 7926.6 7981.9 8166.9 8317.1 11040.4 12152.5 12389.1 12541.2 12773.9 10433.4 10518.8 10537.2 10572.7 11020.6 9610.2 10222.4 10471.8 10651.6 10652.7 10684.5 10758.9 10834.3 10915.8 11234.9 7038.3 7766.7 7912.8 8291.9 8339.6 10154.5 10735.7 10950.8 11038.8 11106.6 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#3] mootools.html Cycles:180 Average:5396.92 Median:4779.83 stddev:3020.75 (63.2%) stddev-sans-first:3014.81 09:01:42 INFO - PROCESS | 1926 | Values: 1470.3 1484.9 1635.8 1759.3 1807.4 8081.2 8518.3 8523.6 8523.9 8537.2 7457.5 7640.5 7977.2 8157.8 8333.3 2246.4 2281.1 2284.0 2285.2 2287.8 2233.5 2322.0 2352.7 2374.0 2380.0 1776.6 2249.0 2317.5 2335.6 2348.5 2130.1 2145.2 2149.9 2152.0 2158.0 2283.8 2352.7 2353.9 2360.4 2371.4 8280.3 8344.6 8350.4 8357.3 8359.6 5031.8 5191.9 5193.4 5194.0 5196.6 3185.9 3280.9 3526.6 3528.3 3528.6 1051.7 1059.6 1081.3 1087.8 1102.2 10959.8 11001.2 11136.8 11144.7 11183.5 4461.9 4620.4 4724.5 4737.1 4751.1 8595.5 8767.6 9444.4 9454.1 9460.7 5206.8 5209.0 5210.1 5215.7 5220.7 3757.7 4234.7 4303.2 4696.3 4793.8 3759.8 3788.9 3851.4 3868.1 3906.3 1087.6 1233.7 1242.6 1248.5 1259.5 881.5 883.0 883.1 891.6 897.9 5345.8 5411.4 5559.8 5596.2 5656.1 433.9 447.0 455.2 456.4 456.7 3421.6 3677.3 3724.5 3743.4 3877.7 9934.1 10145.9 10295.3 10311.9 10325.3 10022.7 10115.5 10266.8 10279.2 10294.3 7748.1 7929.4 7935.3 7936.8 7942.4 5278.8 5472.2 5475.5 5556.1 5566.7 9274.7 9327.5 9414.1 9505.2 9566.8 9320.6 9541.1 9549.8 9604.2 9686.4 5823.9 6524.9 6712.7 7155.4 7261.1 7496.5 7759.7 7780.4 7813.5 7841.5 6867.7 6929.3 6956.7 6972.3 7122.3 6810.3 6825.6 6893.2 6923.3 6939.3 6369.4 6464.4 6539.8 6551.6 6646.3 4612.4 4634.1 4676.6 4720.2 4765.9 4702.5 4732.3 4764.1 4802.4 4820.9 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#4] prototype.html Cycles:180 Average:4315.50 Median:4813.54 stddev:1301.82 (27.0%) stddev-sans-first:1269.49 09:01:42 INFO - PROCESS | 1926 | Values: 266.2 299.4 304.5 311.5 316.4 4127.8 4599.3 5241.2 5260.4 5329.9 5050.6 5103.4 5129.6 5141.8 5142.8 4037.2 4037.5 4046.9 4051.0 4094.2 3599.5 3709.3 4072.3 4082.3 4090.7 3391.5 3483.9 3750.5 3870.0 3936.5 3469.5 3740.1 3942.4 3963.7 3984.7 3017.4 3412.2 3430.2 3453.2 3458.2 4406.5 4862.8 5057.3 5132.3 5190.6 3978.0 4476.6 4501.5 4504.0 4524.0 4595.0 5509.8 5511.9 5515.8 5533.5 3645.5 3741.3 3785.5 3793.5 3817.7 4401.8 5547.9 5659.7 5662.7 5821.7 3211.5 3247.0 3768.7 3771.2 3790.9 3833.3 3856.6 3864.9 3880.0 3881.9 4087.3 4226.8 4448.6 4493.9 4563.5 4703.1 5005.5 5359.4 5474.8 5478.3 1090.7 1222.3 1240.0 1255.2 1304.6 795.8 889.5 907.6 912.7 915.5 4282.9 4528.3 4830.5 4856.9 4871.1 4685.9 4964.9 5023.7 5343.3 5497.9 4969.6 5183.4 5226.4 5228.3 5230.8 2624.9 2817.6 2847.7 2853.3 2903.4 4946.4 5128.4 5300.3 5412.6 5567.2 4790.6 5421.8 5454.0 5500.5 5557.0 5031.3 5503.9 5555.5 5602.6 5712.7 4570.9 4570.9 4972.9 5343.4 5378.6 4361.3 5133.2 5302.8 5314.8 5347.6 4759.4 5502.6 5505.0 5512.8 5621.5 3071.8 3420.2 3584.8 3633.0 3658.6 4394.2 4796.5 5352.6 5353.5 5374.7 5187.0 5201.4 5268.7 5284.9 5305.0 4939.3 5046.6 5116.3 5202.4 5204.8 4664.7 5002.7 5079.6 5213.7 5254.9 4576.5 4616.7 4638.4 5121.5 5335.3 4508.3 5366.8 5380.5 5382.3 5390.6 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - PROCESS | 1926 | [#5] yui.html Cycles:180 Average:19675.46 Median:29943.05 stddev:28900.81 (96.5%) stddev-sans-first:28946.38 09:01:42 INFO - PROCESS | 1926 | Values: 597.8 716.6 768.3 781.3 785.1 14004.6 14529.2 14561.0 14595.6 15830.5 15097.6 15167.1 15292.0 15314.9 15315.4 689.9 853.0 854.2 857.3 864.0 665.3 682.6 685.1 738.2 817.2 886.8 889.1 889.5 890.3 891.0 886.2 886.2 886.3 887.5 891.0 523.7 534.7 629.4 637.9 662.9 14405.6 14463.8 14474.0 14477.8 14493.6 12735.4 12929.3 12942.0 12960.2 12980.0 11759.3 12177.0 12185.8 12431.4 12537.5 4392.6 4465.6 4542.0 4552.5 4553.3 83116.8 85542.6 86990.6 88483.0 88774.6 9983.7 10030.0 10199.3 10250.2 10359.5 19125.0 19214.2 19275.5 19275.8 19286.9 4905.7 6771.0 7223.3 7271.9 7335.0 4038.3 4154.3 4169.1 4291.0 4321.7 3309.6 3326.1 3392.2 3392.8 3402.9 545.2 620.7 621.7 644.1 647.3 596.7 597.2 607.6 609.7 626.4 7850.2 8054.6 8342.2 8415.3 8676.0 608.4 609.8 611.2 629.6 647.5 3971.0 4143.2 4145.1 4204.2 4300.6 76196.6 76598.9 78159.0 79570.2 114000.7 53773.0 81659.0 86959.0 103178.0 105445.7 90012.0 90474.0 94377.0 95500.0 96099.0 6838.6 7996.6 8143.5 8148.8 8340.7 56774.7 58772.4 58889.7 59289.4 62335.8 67532.0 80558.0 81842.0 84374.0 85709.0 10099.9 10163.0 10297.0 10413.5 10844.0 11646.4 11658.3 11828.0 11854.9 11971.1 10541.1 11093.0 11166.8 11199.4 11450.9 10874.2 11096.6 11182.4 11189.9 11379.4 10639.3 10657.3 10994.5 11033.1 11363.3 7577.4 8999.4 9445.5 9477.5 9519.5 8972.8 9345.4 9357.5 9384.6 9385.0 09:01:42 INFO - PROCESS | 1926 | -------- Summary: end -------- 09:01:42 INFO - PROCESS | 1926 | 09:01:42 INFO - TEST-INFO | 1926: exit 0 09:01:42 INFO - TEST-OK | dromaeo_css | took 1120123ms 09:01:42 INFO - TEST-START | kraken 09:01:42 INFO - operating with platform_type : mac_ 09:01:42 INFO - Initialising browser for kraken test... 09:01:42 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpbs2k3s/profile http://localhost:55488/getInfo.html) 09:01:45 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 09:01:45 INFO - PROCESS | 1930 | colorDepth:24 09:01:45 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 09:01:45 INFO - PROCESS | 1930 | __metrics 09:01:45 INFO - PROCESS | 1930 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:01:45 INFO - PROCESS | 1930 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:46 INFO - TEST-INFO | 1930: exit 0 09:01:46 INFO - Browser initialized. 09:01:46 INFO - Running cycle 1/1 for kraken test... 09:01:46 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpbs2k3s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:58 INFO - PROCESS | 1931 | RSS: Main: 222543872 09:01:58 INFO - PROCESS | 1931 | 09:02:01 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:02 INFO - PROCESS | 1931 | RSS: Main: 272793600 09:02:02 INFO - PROCESS | 1931 | 09:02:04 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-dft) 09:02:05 INFO - PROCESS | 1931 | RSS: Main: 284348416 09:02:05 INFO - PROCESS | 1931 | 09:02:08 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-fft) 09:02:09 INFO - PROCESS | 1931 | RSS: Main: 380678144 09:02:09 INFO - PROCESS | 1931 | 09:02:11 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55488/tests/kraken/driver.html?testName=audio-oscillator) 09:02:11 INFO - PROCESS | 1931 | RSS: Main: 283402240 09:02:11 INFO - PROCESS | 1931 | 09:02:13 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:02:14 INFO - PROCESS | 1931 | RSS: Main: 284340224 09:02:14 INFO - PROCESS | 1931 | 09:02:17 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-darkroom) 09:02:17 INFO - PROCESS | 1931 | RSS: Main: 326651904 09:02:17 INFO - PROCESS | 1931 | 09:02:20 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55488/tests/kraken/driver.html?testName=imaging-desaturate) 09:02:21 INFO - PROCESS | 1931 | RSS: Main: 324866048 09:02:21 INFO - PROCESS | 1931 | 09:02:24 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55488/tests/kraken/driver.html?testName=json-parse-financial) 09:02:25 INFO - PROCESS | 1931 | RSS: Main: 322703360 09:02:25 INFO - PROCESS | 1931 | 09:02:26 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:02:27 INFO - PROCESS | 1931 | RSS: Main: 300507136 09:02:27 INFO - PROCESS | 1931 | 09:02:29 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:02:29 INFO - PROCESS | 1931 | RSS: Main: 303960064 09:02:29 INFO - PROCESS | 1931 | 09:02:31 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:02:32 INFO - PROCESS | 1931 | RSS: Main: 305672192 09:02:32 INFO - PROCESS | 1931 | 09:02:35 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:02:36 INFO - PROCESS | 1931 | RSS: Main: 314867712 09:02:36 INFO - PROCESS | 1931 | 09:02:39 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:02:40 INFO - PROCESS | 1931 | RSS: Main: 314642432 09:02:40 INFO - PROCESS | 1931 | 09:02:41 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55488/tests/kraken/driver.html?testName=ai-astar) 09:02:42 INFO - PROCESS | 1931 | RSS: Main: 358096896 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | __start_tp_report 09:02:42 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 09:02:42 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 09:02:42 INFO - PROCESS | 1931 | |i|pagename|runs| 09:02:42 INFO - PROCESS | 1931 | |0;ai-astar;114;113;114;114;113;114;113;113;116;113 09:02:42 INFO - PROCESS | 1931 | |1;audio-beat-detection;148;116;110;150;113;113;115;138;176;112 09:02:42 INFO - PROCESS | 1931 | |2;audio-dft;181;186;185;184;185;183;184;183;183;183 09:02:42 INFO - PROCESS | 1931 | |3;audio-fft;84;60;59;91;62;60;49;74;99;82 09:02:42 INFO - PROCESS | 1931 | |4;audio-oscillator;87;71;94;70;67;68;90;68;69;92 09:02:42 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;86;86;87;83;85;83;87;84;89;83 09:02:42 INFO - PROCESS | 1931 | |6;imaging-darkroom;95;96;95;95;94;115;160;162;93;94 09:02:42 INFO - PROCESS | 1931 | |7;imaging-desaturate;81;79;75;115;121;130;84;85;96;126 09:02:42 INFO - PROCESS | 1931 | |8;json-parse-financial;54;52;52;53;57;56;58;56;56;54 09:02:42 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;64;74;76;73;73;70;69;69;70;69 09:02:42 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;85;85;84;85;86;83;83;88;85;84 09:02:42 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;147;148;148;151;146;151;151;155;195;177 09:02:42 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;169;167;167;165;217;157;202;225;200;169 09:02:42 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;66;66;67;68;88;89;87;78;66;68 09:02:42 INFO - PROCESS | 1931 | __end_tp_report 09:02:42 INFO - PROCESS | 1931 | __start_cc_report 09:02:42 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,1973 09:02:42 INFO - PROCESS | 1931 | __end_cc_report 09:02:42 INFO - PROCESS | 1931 | __startTimestamp1480352562146__endTimestamp 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | ------- Summary: start ------- 09:02:42 INFO - PROCESS | 1931 | Number of tests: 14 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 09:02:42 INFO - PROCESS | 1931 | Values: 114.0 113.0 114.0 114.0 113.0 114.0 113.0 113.0 116.0 113.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:127.00 stddev:22.65 (17.8%) stddev-sans-first:22.97 09:02:42 INFO - PROCESS | 1931 | Values: 148.0 116.0 110.0 150.0 113.0 113.0 115.0 138.0 176.0 112.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.42 (0.8%) stddev-sans-first:1.12 09:02:42 INFO - PROCESS | 1931 | Values: 181.0 186.0 185.0 184.0 185.0 183.0 184.0 183.0 183.0 183.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:72.00 Median:78.00 stddev:16.41 (21.0%) stddev-sans-first:16.82 09:02:42 INFO - PROCESS | 1931 | Values: 84.0 60.0 59.0 91.0 62.0 60.0 49.0 74.0 99.0 82.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.50 (14.6%) stddev-sans-first:11.68 09:02:42 INFO - PROCESS | 1931 | Values: 87.0 71.0 94.0 70.0 67.0 68.0 90.0 68.0 69.0 92.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:2.06 (2.4%) stddev-sans-first:2.17 09:02:42 INFO - PROCESS | 1931 | Values: 86.0 86.0 87.0 83.0 85.0 83.0 87.0 84.0 89.0 83.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:109.90 Median:94.50 stddev:27.69 (29.3%) stddev-sans-first:28.84 09:02:42 INFO - PROCESS | 1931 | Values: 95.0 96.0 95.0 95.0 94.0 115.0 160.0 162.0 93.0 94.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:80.00 stddev:21.50 (26.9%) stddev-sans-first:21.77 09:02:42 INFO - PROCESS | 1931 | Values: 81.0 79.0 75.0 115.0 121.0 130.0 84.0 85.0 96.0 126.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:56.00 stddev:2.10 (3.7%) stddev-sans-first:2.20 09:02:42 INFO - PROCESS | 1931 | Values: 54.0 52.0 52.0 53.0 57.0 56.0 58.0 56.0 56.0 54.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:3.40 (4.8%) stddev-sans-first:2.60 09:02:42 INFO - PROCESS | 1931 | Values: 64.0 74.0 76.0 73.0 73.0 70.0 69.0 69.0 70.0 69.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:1.48 (1.7%) stddev-sans-first:1.56 09:02:42 INFO - PROCESS | 1931 | Values: 85.0 85.0 84.0 85.0 86.0 83.0 83.0 88.0 85.0 84.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:151.00 stddev:16.12 (10.7%) stddev-sans-first:16.70 09:02:42 INFO - PROCESS | 1931 | Values: 147.0 148.0 148.0 151.0 146.0 151.0 151.0 155.0 195.0 177.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.50 stddev:24.65 (13.4%) stddev-sans-first:25.55 09:02:42 INFO - PROCESS | 1931 | Values: 169.0 167.0 167.0 165.0 217.0 157.0 202.0 225.0 200.0 169.0 09:02:42 INFO - PROCESS | 1931 | 09:02:42 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:73.00 stddev:10.10 (13.8%) stddev-sans-first:10.26 09:02:42 INFO - PROCESS | 1931 | Values: 66.0 66.0 67.0 68.0 88.0 89.0 87.0 78.0 66.0 68.0 09:02:42 INFO - PROCESS | 1931 | -------- Summary: end -------- 09:02:42 INFO - PROCESS | 1931 | 09:02:43 INFO - TEST-INFO | 1931: exit 0 09:02:43 INFO - TEST-OK | kraken | took 60561ms 09:02:43 INFO - SUITE-END | took 1180s 09:02:43 INFO - Completed test suite (00:19:41) 09:02:43 INFO - javascript benchmark 09:02:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.2012317043905, 3339.382214290355, 3342.8328583570824, 3376.780509266896, 3403.8468268927895, 5698.0883058710615, 5724.685142317181, 5753.165790960308, 5787.652740835542, 5806.506446952004, 5634.661920284786, 5752.0, 5757.942420575803, 5778.219940308068, 5791.507721843638, 5202.855371818194, 5233.450487698794, 5258.211268309765, 5265.104932161542, 5312.442193569677, 5121.12940800063, 5195.532402083812, 5200.453952335007, 5214.191800270964, 5328.5470734987375, 5139.100657384962, 5195.974020129913, 5196.792128314863, 5200.973995130019, 5249.947500524984, 5196.454372290912, 5198.194279886624, 5215.087359712035, 5216.217567364925, 5231.973840130813, 4066.451029111062, 5107.387113546361, 5126.051680439131, 5127.0, 5131.0, 5637.872425514873, 5650.773969041234, 5658.717064146817, 5663.49028587425, 5663.915041274384, 6026.578139530235, 6106.45041946227, 6126.663033533154, 6130.264368275791, 6135.417135372177, 6151.015837466029, 6176.69116544171, 6185.0, 6185.721642526097, 6194.876102477971, 5699.0, 5702.2301989231755, 5705.201271821907, 5709.600327977002, 5710.200571919934, 6190.628562286277, 6252.280987686474, 6283.3402492737605, 6288.0, 6289.559730818799, 5212.452692467312, 5881.617694849821, 6055.515558755289, 6132.520321146446, 6170.012797952396, 6213.596116252429, 6222.968885155546, 6233.812985610439, 6238.906416403757, 6242.906356404657, 6058.27300723916, 6137.294211165684, 6172.845678857974, 6184.536159788034, 6206.472449841724, 6119.563485741553, 6125.295591007091, 6166.568340216231, 6172.321044685081, 6186.783462578788, 4910.631702622318, 4927.457979622239, 4934.8026078957155, 4943.876403089879, 4959.181735013683, 4326.242907491176, 4427.512973572904, 4428.402165707653, 4442.777861106932, 4445.644348452116, 5669.40471250521, 5707.0583353747, 5753.453421924911, 5817.38917413674, 5828.854278643068, 6130.509559235251, 6200.571013598903, 6245.156903818017, 6275.246970363494, 6291.307956124914, 5757.539396848242, 5773.624714393635, 5833.300003999547, 5859.677717725566, 5861.7655293787775, 5259.027079990214, 5299.364076310791, 5315.6013299002725, 5352.143657014859, 5411.134218524939, 6226.034964580497, 6276.215473065867, 6283.528735344776, 6291.905621415591, 6302.936970630235, 6194.690265486798, 6237.563370564017, 6267.2792628848265, 6267.529935254783, 6273.905891411541, 6158.261008678808, 6177.0, 6265.028920517327, 6296.937030629635, 6344.4290013897125, 4847.297141914332, 6155.2921414037955, 6179.598326108789, 6214.689265536615, 6234.314225435108, 5261.5264626183825, 6169.96915015422, 6266.436020757971, 6275.717592708431, 6286.057091436138, 5871.735771890189, 6044.818655440344, 6048.4858787003595, 6138.785142519991, 6154.1999540059505, 5331.200319952039, 5911.083782013795, 5998.700064996646, 6030.668313242813, 6075.665838378932, 5944.4352786485215, 5946.0, 5950.702464876826, 5950.732217050157, 5958.731857066529, 6020.127081573235, 6062.787802426894, 6072.05883088122, 6077.027675571887, 6084.452399284086, 5714.485696287354, 5751.5398768099285, 5763.394843541368, 5839.3868643793485, 5882.088276317178], "value": 5654.704099283104, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.3290097754346, 470.0645715027095, 505.93500681066377, 518.3417060333377, 538.8168022872225, 30291.697083029165, 32549.349013019753, 33663.83168084156, 39215.823525294254, 40684.79657601715, 41979.53071642485, 42212.57787422125, 42244.732658020235, 42329.365059524134, 42395.78802105985, 9274.582643781028, 9454.81090378192, 9461.574229159703, 9462.81074378512, 9466.479343636098, 9260.814783704322, 9360.251179905592, 9362.578683959204, 9365.531723413837, 9376.249900007982, 9334.019927907573, 9380.202682771938, 9381.906180938206, 9400.388974716656, 9419.62321507139, 9305.813883722321, 9306.302027347976, 9311.115444032806, 9312.208462280714, 9357.39176953496, 9307.813843723156, 9378.577963991569, 9381.953090234505, 9400.918894327104, 9407.482588457631, 39604.0, 39950.60049399527, 40015.59984400148, 40085.79957100225, 40089.0, 3232.127325622069, 3382.7970321780526, 3497.387957107496, 3677.595464498903, 3872.186840763443, 29500.557491637643, 30139.69860301391, 30468.390632187453, 30498.085057448312, 30885.691143088505, 3117.267442151093, 3159.573457583243, 3159.8262095584732, 3217.887373941901, 3296.0, 462690.0, 466644.0, 467219.0, 467565.0, 474946.0, 59390.0, 59825.70087149536, 59864.0, 60005.399946000856, 60030.69984650049, 34562.30875382529, 35214.471782923276, 35418.46872296918, 62667.0, 63022.36977630257, 15771.0, 16118.274677639534, 16119.758203626954, 16119.758203626954, 16159.515214543582, 12184.0, 12260.32568208757, 12268.570600029134, 12277.754444911052, 12278.38608069593, 19001.954892481048, 19070.52323691891, 19072.427827165207, 19118.61762764737, 19130.617387652168, 7453.85092298151, 7644.120926093459, 7653.617319134021, 7722.729704460429, 7737.26495982892, 852.194676031157, 944.5088553951906, 966.1353088985319, 979.9951000244953, 1024.08856118055, 28699.856500717782, 29140.125796226148, 29444.705552944197, 29960.85019574888, 30045.69954300429, 1025.9384436933808, 1044.8328267477168, 1045.9581616735397, 1130.5929865248504, 1155.8035134027236, 15351.769723454157, 15384.0, 15397.923010384877, 15582.9220853895, 15803.604909877346, 155240.0, 155367.0, 155901.0, 156595.0, 157892.0, 57261.0, 58022.41977580255, 58046.0, 58050.709746451, 58095.0, 1165.636205639404, 1235.2403271987662, 1281.705207802192, 1368.0970559430727, 1384.7092110656674, 29514.557281640362, 30520.236994075327, 30864.0, 30953.535696964114, 31879.043628691175, 50376.74811625919, 51365.48634513607, 51436.0, 51497.48502514927, 51761.0, 557681.0, 570432.0, 575535.0, 575862.0, 576081.0, 35526.822365888, 36467.63532364643, 36926.44610330901, 42862.570994321, 43495.565044349154, 42794.78602606967, 45782.771086144356, 46306.07387852291, 46827.06345873132, 47050.52949470461, 34428.96713098611, 38367.77954425067, 38498.422523662724, 38792.224155517295, 38902.0, 38382.6161738379, 38487.422688659135, 38570.228595428496, 39811.0, 39921.60078399179, 32539.18652033718, 32765.0, 37713.811430943766, 38089.42865856959, 38380.42429363506, 31844.044678659677, 33854.0, 34705.13237169091, 34821.0, 34879.65120348764, 25095.121670741435, 25470.853811578894, 25491.0, 25900.740992589832, 26030.73969260283], "value": 19009.407298639668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.94795254688086, 375.64501546038997, 379.25665695237353, 386.9903252418689, 409.9938500922487, 14567.635809104764, 15174.165420901845, 16435.671286574277, 19398.321058762947, 20140.992950352498, 18923.337683181093, 19258.807411925878, 19869.20523179071, 19937.80062199381, 20144.496387590316, 5498.422665620113, 5786.942130578683, 5798.478136967672, 5801.53587712982, 5809.970950145264, 4900.338454308676, 6343.714532846033, 6428.260750013762, 6447.451966582846, 6470.255920569124, 6085.148079268906, 6326.683665816714, 6400.39196276353, 6408.743650253984, 6412.0, 6286.151369565096, 6347.714352854133, 6349.904751428732, 6358.205224346957, 6365.4589359904, 4972.651914365996, 5228.764705588258, 5287.841364759063, 5317.8404647860625, 5511.669299842022, 12712.936435317764, 12821.102522823407, 12859.035572332112, 13001.15492492985, 13032.674183145402, 2286.919957801469, 2353.658719485665, 2491.1440976472645, 2506.674132362781, 2616.9476610467873, 9404.858927116098, 10123.848142277871, 10201.234907381902, 12313.569025084169, 14429.783553246709, 1797.6764182447155, 1806.1330561330635, 1826.7807863056519, 1828.3235202974984, 1856.981430185708, 104213.0, 110077.44961275223, 120360.0, 125845.74154258524, 129784.0, 11314.830277545843, 11494.367809770543, 11560.595379161688, 11562.248453850472, 11767.411629418495, 14562.927185364006, 14596.708065838837, 14617.634559135893, 14622.341994610268, 14628.926855365655, 6502.902456463157, 8228.83542329162, 8244.258016778398, 8372.916270837213, 8381.413301068867, 7972.800679983046, 7973.800654983672, 7982.521048737094, 8136.959315203504, 8142.552159631277, 4475.060237350128, 4483.865484035484, 4488.551144885485, 4514.25514790056, 4585.266357382803, 1839.4849442156217, 1886.830185283331, 1895.2134864031548, 1936.4093951344976, 1939.2049259803414, 846.8137009857821, 859.2996707683218, 925.2089463508738, 931.0, 980.4803454169302, 19259.422217333504, 20399.63406038017, 20454.59090818175, 20497.59004819925, 20663.380098597067, 907.3240435875315, 923.2106548900728, 926.1294383279695, 929.8372784762639, 938.7418459923575, 10220.897791022144, 10583.364998100138, 10744.301620394806, 10757.193210509244, 10759.462026898624, 473395.0, 473835.0, 474260.0, 567156.0, 573736.0, 89542.1797583155, 90367.77057835489, 95043.0, 132475.0, 140404.0, 948.0898337595847, 952.64752041745, 999.3004896572428, 1003.207466101786, 1005.0452070533023, 17159.142042898056, 18522.444326670222, 18998.240070397584, 19038.0, 19051.237950481827, 52424.95150096901, 52586.0, 53302.66743331293, 54578.72710636421, 55496.44503554913, 76029.0, 76930.461390773, 77364.0, 78092.82860757207, 78633.60683196547, 7882.132965373919, 7926.603669816602, 7981.8802717958115, 8166.877496837671, 8317.126701696236, 11040.392778397265, 12152.513899444277, 12389.070819688739, 12541.247525148521, 12773.872261277269, 10433.373997560171, 10518.842217366593, 10537.15702743794, 10572.682819515427, 11020.614278500214, 9610.18488032177, 10222.386656800616, 10471.790564188826, 10651.573937042742, 10652.73368165771, 10684.504169416132, 10758.924107589179, 10834.349939003685, 10915.83626245591, 11234.887651123385, 7038.331358520933, 7766.650500727368, 7912.84174316522, 8291.875621865556, 8339.583020848811, 10154.543045563116, 10735.677929662123, 10950.780984380428, 11038.834417483584, 11106.61126860556], "value": 9210.942451108585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.264867566217, 1484.858938400852, 1635.754636804479, 1759.2611103336612, 1807.4487281379174, 8081.1918808119235, 8518.275946544536, 8523.616437260323, 8523.914760852389, 8537.231649151576, 7457.477976541644, 7640.503367281123, 7977.162397948219, 8157.836843263131, 8333.291670208024, 2246.3934737620834, 2281.0761641535205, 2283.9885800570974, 2285.1544928376525, 2287.8055365293885, 2233.542123864604, 2322.0479603362623, 2352.694149760529, 2373.964390534143, 2379.9762002380016, 1776.6269083492482, 2248.977510224893, 2317.4901521665292, 2335.5562443135836, 2348.495073559184, 2130.0627723801476, 2145.184829764695, 2149.881756503392, 2151.978480215209, 2157.9784202158094, 2283.7716228377194, 2352.694149760537, 2353.870537120458, 2360.3980984848927, 2371.4427109629223, 8280.254777070033, 8344.62449189788, 8350.415470917038, 8357.331413486967, 8359.623816928253, 5031.84904452867, 5191.870203244911, 5193.428722840485, 5193.9740301298625, 5196.610254230948, 3185.888493902702, 3280.901572952815, 3526.6297038811067, 3528.259065596228, 3528.558930133733, 1051.7160366700948, 1059.607945060325, 1081.2809481694665, 1087.7933192693442, 1102.1843835561706, 10959.83560246597, 11001.22991390595, 11136.777264454666, 11144.721381965435, 11183.496742646601, 4461.866144015685, 4620.376249206314, 4724.527547245317, 4737.123632128068, 4751.09729151467, 8595.538758411085, 8767.561621918878, 9444.386114902647, 9454.14912657864, 9460.668876589287, 5206.843794686166, 5209.0, 5210.088234558937, 5215.739213039333, 5220.686758794484, 3757.6993840492696, 4234.703570750018, 4303.203907277164, 4696.295555666609, 4793.760311984456, 3759.8496060157286, 3788.924221515554, 3851.422286656968, 3868.129670824042, 3906.2968665640456, 1087.6030248959073, 1233.6977440527128, 1242.5837344489516, 1248.4843041271663, 1259.537603984227, 881.5405019994272, 882.9514376709343, 883.0992387764444, 891.5765011619429, 897.8653202019749, 5345.7594408251725, 5411.431799661058, 5559.805406810742, 5596.160575913564, 5656.066748986454, 433.9067100573392, 446.9754163520972, 455.22384334709244, 456.42490462017435, 456.7191177425926, 3421.606515250728, 3677.3013127505687, 3724.497192878927, 3743.363628183161, 3877.728559000899, 9934.105930466292, 10145.94927025375, 10295.279330446796, 10311.896881031094, 10325.32885362449, 10022.74943126413, 10115.544800483849, 10266.794664106676, 10279.229057820543, 10294.330868493524, 7748.147703752696, 7929.44493885434, 7935.285824275843, 7936.761897143095, 7942.404319676048, 5278.81524146653, 5472.179173124063, 5475.4524547544925, 5556.138798486241, 5566.693831839288, 9274.655323705761, 9327.486988215442, 9414.105659962293, 9505.239580833655, 9566.808663826545, 9320.580573874056, 9541.141297283279, 9549.809003820024, 9604.183644390025, 9686.418814871131, 5823.8835223294445, 6524.9021264682015, 6712.6979285931275, 7155.356017958408, 7261.092363454568, 7496.475246732676, 7759.72840950564, 7780.416468764752, 7813.492123012214, 7841.490303130278, 6867.690953907186, 6929.341712537301, 6956.652167391509, 6972.302769722987, 7122.252163522963, 6810.31896810315, 6825.590464572141, 6893.172819261721, 6923.273056329013, 6939.271376505596, 6369.426751592379, 6464.385883341075, 6539.771108011197, 6551.639659818756, 6646.302138275411, 4612.423447069116, 4634.0500197460115, 4676.625869930465, 4720.197566413786, 4765.9046819064115, 4702.482726900107, 4732.290156476556, 4764.142454358249, 4802.447718512416, 4820.927686084781], "value": 4293.061157284877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.1655709351186, 299.36385181489334, 304.5021390027305, 311.53425628685113, 316.3514794670924, 4127.752334859911, 4599.310103484479, 5241.2400201970695, 5260.4213536511, 5329.946700532993, 5050.626205683323, 5103.362079740033, 5129.640365431906, 5141.840427020651, 5142.768575414106, 4037.183267529623, 4037.535683396403, 4046.898827529306, 4051.007503161722, 4094.1780907496177, 3599.46008098786, 3709.2766910452474, 4072.2669919414493, 4082.265192265391, 4090.6727461802984, 3391.4573668213093, 3483.9199848047188, 3750.4561838533396, 3869.9806500967456, 3936.5276166860017, 3469.4969229461713, 3740.1397678533954, 3942.446474322134, 3963.6829053675924, 3984.6613037891816, 3017.3814368054495, 3412.1640198151363, 3430.2453460238708, 3453.1539772755586, 3458.204612939013, 4406.515283318832, 4862.829800956985, 5057.2666963290485, 5132.332796736438, 5190.584753219733, 3978.0054986253435, 4476.597106260452, 4501.527339629324, 4503.954960450386, 4523.977380113111, 4595.0, 5509.815389691195, 5511.917321240185, 5515.751791169367, 5533.45858650464, 3645.54430696163, 3741.289155060557, 3785.470034195215, 3793.525809273841, 3817.6945844332663, 4401.757903315349, 5547.8890422191735, 5659.688717120557, 5662.687327913134, 5821.7380217890295, 3211.454052810981, 3246.9772021813055, 3768.66212494566, 3771.2086758789355, 3790.886273411802, 3833.2525157594, 3856.5564960029956, 3864.9033774155873, 3879.9806000969816, 3881.922361552753, 4087.305158123126, 4226.830926762957, 4448.5551444855255, 4493.910121797546, 4563.475200352002, 4703.143422393673, 5005.474425185376, 5359.410464848864, 5474.753636086306, 5478.315210598676, 1090.6509916826699, 1222.2971791220084, 1240.0017985521743, 1255.2092181925511, 1304.5760127958451, 795.7533164718956, 889.4929889962789, 907.6006557114848, 912.6942474271061, 915.5260031349655, 4282.914341713148, 4528.347153289092, 4830.541098595628, 4856.854294371175, 4871.074495845778, 4685.906281874343, 4964.925526117111, 5023.6985780853265, 5343.332083489564, 5497.890042199134, 4969.602431805447, 5183.429822719498, 5226.425093239741, 5228.346456692913, 5230.843074707765, 2624.8687565622026, 2817.5914492398488, 2847.6725176604955, 2853.329467575118, 2903.419316136781, 4946.431160416598, 5128.384593848762, 5300.284461597712, 5412.621116521806, 5567.220589117527, 4790.616750659939, 5421.783128674888, 5454.0, 5500.50495455403, 5556.97221513898, 5031.3207716959205, 5503.862403439866, 5555.50000499957, 5602.646888307002, 5712.743196496764, 4570.862874113782, 4570.88572785683, 4972.916791589238, 5343.4389389113585, 5378.569714422759, 4361.302191649321, 5133.204353325241, 5302.840914772563, 5314.760835762478, 5347.598930080182, 4759.358021482583, 5502.58730595199, 5505.0, 5512.779488820403, 5621.494065534122, 3071.754259659177, 3420.196253880386, 3584.7849129052343, 3632.964605087537, 3658.60361463242, 4394.176416786998, 4796.544328288807, 5352.625316227826, 5353.4646535346155, 5374.6506477078865, 5187.0, 5201.401838788588, 5268.736563171749, 5284.920726189033, 5305.0, 4939.308496810378, 5046.621503387334, 5116.334876466184, 5202.427732949448, 5204.7918083276245, 4664.720116793003, 5002.699838009731, 5079.568236699922, 5213.739313034409, 5254.868628284324, 4576.496585375539, 4616.722996620214, 4638.397008389023, 5121.462246464068, 5335.33308336458, 4508.301213311942, 5366.7584958677735, 5380.542653874308, 5382.273393091882, 5390.622656414013], "value": 3903.420004709261, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.808701215611, 716.5700579652209, 768.316198583261, 781.3436713160944, 785.1363500149836, 14004.579862604112, 14529.200893950829, 14561.0, 14595.635109122277, 15830.525084247462, 15097.62255943602, 15167.089974601531, 15292.0, 15314.923425382858, 15315.387384504607, 689.9275576064504, 852.9530875801828, 854.1671869926834, 857.3312816003496, 864.01933805131, 665.3479590001801, 682.6313790553095, 685.1059367525378, 738.1953670499155, 817.2113910076774, 886.7650072730731, 889.1108891108892, 889.4529864133543, 890.2833219258495, 891.0198781340538, 886.167003017165, 886.1935638568935, 886.330820230725, 887.5029983209358, 890.955452227386, 523.6857885268809, 534.7299613695099, 629.3832044596276, 637.9425851673325, 662.9436497897684, 14405.567832965027, 14463.78304325436, 14473.986820922539, 14477.78283325751, 14493.637659058502, 12735.426905789262, 12929.288889111094, 12942.0, 12960.157589756636, 12980.028431401784, 11759.294442333487, 12177.025837932944, 12185.756284874253, 12431.440585173688, 12537.498500059986, 4392.626626736731, 4465.59809617136, 4542.000759832831, 4552.521985191574, 4553.317002449659, 83116.75324870132, 85542.57228713817, 86990.565047175, 88483.0, 88774.55612721895, 9983.700488985342, 10030.047145521165, 10199.337043092175, 10250.231232657581, 10359.533820978075, 19125.043747812553, 19214.231430742613, 19275.51811204731, 19275.807241927683, 19286.939218342846, 4905.681130726492, 6770.984352347187, 7223.313785190398, 7271.89092163618, 7334.96576384282, 4038.2529232092156, 4154.273002224599, 4169.082801783603, 4291.013066994608, 4321.719088259316, 3309.6028476582965, 3326.085326535222, 3392.219789448391, 3392.796432214075, 3402.9489557656657, 545.2203349210666, 620.7206756959387, 621.6611946489174, 644.1143427786793, 647.2750519418281, 596.686739461786, 597.2176448852018, 607.6020206764488, 609.7165466692637, 626.4236902050125, 7850.214978502104, 8054.556999364974, 8342.16578342161, 8415.284700800377, 8676.0, 608.3794529579803, 609.7865746988518, 611.248164757351, 629.5561629051508, 647.459371424856, 3970.9602903971168, 4143.212789569972, 4145.108801607669, 4204.180184863983, 4300.591443812833, 76196.61901690456, 76598.85101723367, 78159.0, 79570.20429795743, 114000.71998559982, 53773.0, 81659.0, 86959.0, 103178.0, 105445.67325960466, 90012.0, 90474.0, 94377.0, 95500.0, 96099.0, 6838.623875686686, 7996.600169991593, 8143.51138931666, 8148.796280093045, 8340.708075217339, 56774.7161264191, 58772.41227587841, 58889.70555147197, 59289.40710593011, 62335.75328493314, 67532.0, 80558.0, 81842.0, 84374.0, 85709.0, 10099.94950025245, 10163.034511721375, 10297.0, 10413.531391087268, 10844.0, 11646.35945022998, 11658.300501969909, 11828.0, 11854.94072529632, 11971.10216733731, 10541.104006159563, 11093.0, 11166.776664466828, 11199.384033878216, 11450.94274528622, 10874.238803283693, 11096.5561377544, 11182.440877956233, 11189.888101118884, 11379.374134422686, 10639.255252132276, 10657.25399222047, 10994.450277485934, 11033.062189713964, 11363.318200907972, 7577.355924746238, 8999.415038022507, 9445.480498572644, 9477.52612369365, 9519.524023798644, 8972.820543589221, 9345.392549484262, 9357.485338306458, 9384.624615015324, 9385.0], "value": 6957.763363720247, "unit": "score"}], "name": "dromaeo_css", "value": 6978.072181874362, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 116.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 110.0, 150.0, 113.0, 113.0, 115.0, 138.0, 176.0, 112.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 185.0, 184.0, 185.0, 183.0, 184.0, 183.0, 183.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 59.0, 91.0, 62.0, 60.0, 49.0, 74.0, 99.0, 82.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 94.0, 70.0, 67.0, 68.0, 90.0, 68.0, 69.0, 92.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 83.0, 85.0, 83.0, 87.0, 84.0, 89.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 94.0, 115.0, 160.0, 162.0, 93.0, 94.0], "value": 109.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 75.0, 115.0, 121.0, 130.0, 84.0, 85.0, 96.0, 126.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 53.0, 57.0, 56.0, 58.0, 56.0, 56.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 76.0, 73.0, 73.0, 70.0, 69.0, 69.0, 70.0, 69.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 86.0, 83.0, 83.0, 88.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 151.0, 146.0, 151.0, 151.0, 155.0, 195.0, 177.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 167.0, 165.0, 217.0, 157.0, 202.0, 225.0, 200.0, 169.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 68.0, 88.0, 89.0, 87.0, 78.0, 66.0, 68.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1495.8, "alertThreshold": 2.0}]} 09:02:43 INFO - Return code: 0 09:02:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:02:43 INFO - # TBPL SUCCESS # 09:02:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:43 INFO - 2016-11-28 09:02:43.979 screenresolution[1935:22507] starting screenresolution argv=screenresolution get 09:02:43 INFO - 2016-11-28 09:02:43.982 screenresolution[1935:22507] Display 0: 1600x1200x32@60 09:02:43 INFO - 2016-11-28 09:02:43.988 screenresolution[1936:22510] starting screenresolution argv=screenresolution list 09:02:43 INFO - Available Modes on Display 0 09:02:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:44 INFO - Intel Iris: 09:02:44 INFO - Chipset Model: Intel Iris 09:02:44 INFO - Type: GPU 09:02:44 INFO - Bus: Built-In 09:02:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:44 INFO - Vendor: Intel (0x8086) 09:02:44 INFO - Device ID: 0x0a2e 09:02:44 INFO - Revision ID: 0x0009 09:02:44 INFO - Displays: 09:02:44 INFO - Display: 09:02:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:44 INFO - Display Serial Number: 1600x1200 60 09:02:44 INFO - Main Display: Yes 09:02:44 INFO - Mirror: Off 09:02:44 INFO - Online: Yes 09:02:44 INFO - Rotation: Supported 09:02:44 INFO - Return code: 0 09:02:44 INFO - Running post-action listener: _resource_record_post_action 09:02:44 INFO - [mozharness: 2016-11-28 17:02:44.224759Z] Finished run-tests step (success) 09:02:44 INFO - Running post-run listener: _resource_record_post_run 09:02:44 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 315392; Write bytes: 657658368; Read time: 4; Write time: 1890 09:02:44 INFO - TinderboxPrint: CPU usage
53.6% 09:02:44 INFO - TinderboxPrint: I/O read bytes / time
315,392 / 4 09:02:44 INFO - TinderboxPrint: I/O write bytes / time
657,658,368 / 1,890 09:02:44 INFO - TinderboxPrint: CPU idle
2,248.7 (46.4%) 09:02:44 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 09:02:44 INFO - TinderboxPrint: CPU user
1,629.3 (33.6%) 09:02:44 INFO - TinderboxPrint: Swap in / out
348,540,928 / 0 09:02:44 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 335761920; Write bytes: 334126592; Read time: 25866; Write time: 694 09:02:44 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 315392; Write bytes: 305800192; Read time: 4; Write time: 1146 09:02:44 INFO - Running post-run listener: _upload_blobber_files 09:02:44 INFO - Blob upload gear active. 09:02:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:02:44 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 09:02:44 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'] 09:02:44 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 09:02:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:02:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:02:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:02:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:45 INFO - (blobuploader) - INFO - Done attempting. 09:02:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:02:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:02:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:47 INFO - (blobuploader) - INFO - Done attempting. 09:02:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:49 INFO - (blobuploader) - INFO - Done attempting. 09:02:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:49 INFO - Return code: 0 09:02:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e95f48d10e2530cba62bf3e4a4ef0a3134926ab1b8871729b5243d7a499c7a91a01a9742232eea761f08d53dda18a83fcd053aa960b5d5e30d02b9f842eac84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cecda6329682d25764d72606d635f7be571345f793fe8e4fbf74e047dfcee593187d6a611cccd74846def644c10ddd6416b771ef2e7af05750cbc6518646563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ee5fc5c78767cfaf624320b67a327cd447ac85d5fc1b96f25901c182dae881386ad0d22deebe591fe818b4ba795ff5e36dfc364fa61ac60763485558c507d5"} 09:02:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:49 INFO - Contents: 09:02:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e95f48d10e2530cba62bf3e4a4ef0a3134926ab1b8871729b5243d7a499c7a91a01a9742232eea761f08d53dda18a83fcd053aa960b5d5e30d02b9f842eac84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cecda6329682d25764d72606d635f7be571345f793fe8e4fbf74e047dfcee593187d6a611cccd74846def644c10ddd6416b771ef2e7af05750cbc6518646563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ee5fc5c78767cfaf624320b67a327cd447ac85d5fc1b96f25901c182dae881386ad0d22deebe591fe818b4ba795ff5e36dfc364fa61ac60763485558c507d5"} 09:02:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:49 INFO - Copying logs to upload dir... 09:02:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.134462 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 09:02:49.586249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:02:49.588387) ========= 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.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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/3e95f48d10e2530cba62bf3e4a4ef0a3134926ab1b8871729b5243d7a499c7a91a01a9742232eea761f08d53dda18a83fcd053aa960b5d5e30d02b9f842eac84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cecda6329682d25764d72606d635f7be571345f793fe8e4fbf74e047dfcee593187d6a611cccd74846def644c10ddd6416b771ef2e7af05750cbc6518646563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ee5fc5c78767cfaf624320b67a327cd447ac85d5fc1b96f25901c182dae881386ad0d22deebe591fe818b4ba795ff5e36dfc364fa61ac60763485558c507d5"} build_url:https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/FQvYy-e8SCWC2J9vu_keJA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e95f48d10e2530cba62bf3e4a4ef0a3134926ab1b8871729b5243d7a499c7a91a01a9742232eea761f08d53dda18a83fcd053aa960b5d5e30d02b9f842eac84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cecda6329682d25764d72606d635f7be571345f793fe8e4fbf74e047dfcee593187d6a611cccd74846def644c10ddd6416b771ef2e7af05750cbc6518646563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ee5fc5c78767cfaf624320b67a327cd447ac85d5fc1b96f25901c182dae881386ad0d22deebe591fe818b4ba795ff5e36dfc364fa61ac60763485558c507d5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:02:49.632786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:02:49.637028) ========= 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.FRZtSGsCKa/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.7GKCPCL65W/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.004958 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:02:49.685666) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:02:49.688479) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:02:49.688885) ========= ========= Total master_lag: 0.24 =========