builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1476850099.59 results: success (0) buildid: 20161018192623 builduid: d7aea97785604869af38474dfacb8196 revision: 54de8af4ed217813fa68bf8971966f9c6160850e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:19.589083) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:19.589527) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:19.589799) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:19.614239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:19.614623) ========= 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.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-10-18 21:08:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.3M=0.001s 2016-10-18 21:08:20 (15.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.435422 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:20.072859) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:20.073266) ========= 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.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.026427 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:20.122072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 21:08:20.122475) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 54de8af4ed217813fa68bf8971966f9c6160850e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 54de8af4ed217813fa68bf8971966f9c6160850e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-10-18 21:08:20,199 truncating revision to first 12 chars 2016-10-18 21:08:20,199 Setting DEBUG logging. 2016-10-18 21:08:20,199 attempt 1/10 2016-10-18 21:08:20,199 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/54de8af4ed21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 21:08:22,093 unpacking tar archive at: mozilla-central-54de8af4ed21/testing/mozharness/ program finished with exit code 0 elapsedTime=2.152534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 21:08:22.296863) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:22.297232) ========= script_repo_revision: 54de8af4ed217813fa68bf8971966f9c6160850e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:22.297689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:22.297995) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 21:08:22.316546) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-18 21:08:22.316978) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:08:22 INFO - MultiFileLogger online at 20161018 21:08:22 in /builds/slave/test 21:08:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:08:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:22 INFO - {'append_to_log': False, 21:08:22 INFO - 'base_work_dir': '/builds/slave/test', 21:08:22 INFO - 'blob_upload_branch': 'Firefox', 21:08:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:22 INFO - 'branch': 'Firefox', 21:08:22 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:22 INFO - 'config_files': ('talos/mac_config.py',), 21:08:22 INFO - 'default_actions': ('clobber', 21:08:22 INFO - 'read-buildbot-config', 21:08:22 INFO - 'download-and-extract', 21:08:22 INFO - 'populate-webroot', 21:08:22 INFO - 'create-virtualenv', 21:08:22 INFO - 'install', 21:08:22 INFO - 'run-tests'), 21:08:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:22 INFO - 'download_minidump_stackwalk': True, 21:08:22 INFO - 'download_symbols': 'ondemand', 21:08:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:22 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:22 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:22 INFO - 'installer_path': 'installer.exe', 21:08:22 INFO - 'log_level': 'info', 21:08:22 INFO - 'log_name': 'talos', 21:08:22 INFO - 'log_to_console': True, 21:08:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:08:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:08:22 INFO - 'opt_config_files': (), 21:08:22 INFO - 'pip_index': False, 21:08:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:22 INFO - 'cmd': ('bash', 21:08:22 INFO - '-c', 21:08:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:22 INFO - 'enabled': True, 21:08:22 INFO - 'halt_on_failure': False, 21:08:22 INFO - 'name': 'check_screen_resolution'},), 21:08:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:22 INFO - 'cmd': ('bash', 21:08:22 INFO - '-c', 21:08:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:22 INFO - 'enabled': True, 21:08:22 INFO - 'halt_on_failure': False, 21:08:22 INFO - 'name': 'check_screen_resolution'},), 21:08:22 INFO - 'run_cmd_checks_enabled': True, 21:08:22 INFO - 'sps_profile': False, 21:08:22 INFO - 'sps_profile_interval': 0, 21:08:22 INFO - 'suite': 'dromaeojs', 21:08:22 INFO - 'system_bits': '32', 21:08:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:22 INFO - 'title': 't-yosemite-r7-0033', 21:08:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:22 INFO - 'use_talos_json': True, 21:08:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:22 INFO - 'work_dir': 'build'} 21:08:22 INFO - [mozharness: 2016-10-19 04:08:22.525567Z] Running clobber step. 21:08:22 INFO - Running pre-action listener: _resource_record_pre_action 21:08:22 INFO - Running main action method: clobber 21:08:22 INFO - rmtree: /builds/slave/test/build 21:08:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:24 INFO - Running post-action listener: _resource_record_post_action 21:08:24 INFO - [mozharness: 2016-10-19 04:08:24.738810Z] Finished clobber step (success) 21:08:24 INFO - [mozharness: 2016-10-19 04:08:24.738957Z] Running read-buildbot-config step. 21:08:24 INFO - Running pre-action listener: _resource_record_pre_action 21:08:24 INFO - Running main action method: read_buildbot_config 21:08:24 INFO - Using buildbot properties: 21:08:24 INFO - { 21:08:24 INFO - "project": "", 21:08:24 INFO - "product": "firefox", 21:08:24 INFO - "script_repo_revision": "production", 21:08:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:08:24 INFO - "repository": "", 21:08:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:08:24 INFO - "buildid": "20161018192623", 21:08:24 INFO - "pgo_build": "False", 21:08:24 INFO - "basedir": "/builds/slave/test", 21:08:24 INFO - "buildnumber": 8, 21:08:24 INFO - "slavename": "t-yosemite-r7-0033", 21:08:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 21:08:24 INFO - "platform": "yosemite_r7", 21:08:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:08:24 INFO - "branch": "mozilla-central", 21:08:24 INFO - "repo_path": "mozilla-central", 21:08:24 INFO - "revision": "54de8af4ed217813fa68bf8971966f9c6160850e", 21:08:24 INFO - "stage_platform": "macosx64", 21:08:24 INFO - "builduid": "d7aea97785604869af38474dfacb8196", 21:08:24 INFO - "slavebuilddir": "test" 21:08:24 INFO - } 21:08:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 21:08:24 INFO - Running post-action listener: _resource_record_post_action 21:08:24 INFO - [mozharness: 2016-10-19 04:08:24.742312Z] Finished read-buildbot-config step (success) 21:08:24 INFO - [mozharness: 2016-10-19 04:08:24.742416Z] Running download-and-extract step. 21:08:24 INFO - Running pre-action listener: _resource_record_pre_action 21:08:24 INFO - Running main action method: download_and_extract 21:08:24 INFO - mkdir: /builds/slave/test/build/tests 21:08:24 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')]} 21:08:24 INFO - trying https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 21:08:24 INFO - Downloading https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 21:08:25 INFO - Downloaded 1199 bytes. 21:08:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:08:25 INFO - Using the following test package requirements: 21:08:25 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:08:25 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 21:08:25 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 21:08:25 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'jsshell-mac.zip'], 21:08:25 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 21:08:25 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:08:25 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 21:08:25 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 21:08:25 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 21:08:25 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:08:25 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 21:08:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:08:26 INFO - Http code: 200 21:08:26 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 21:08:26 INFO - x-amz-cf-id: K2LG-TdYpPC6r_cH-UmP9h8gDiPCzC8KXDl8MdR-2dfmaSejBw6gqA== 21:08:26 INFO - x-amz-version-id: lku19qTm8sTsMgURmatLgMX9wkjwxrCR 21:08:26 INFO - Expected file size: 16483652 21:08:26 INFO - Obtained file size: 16483652 21:08:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:08:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:08:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:08:33 INFO - Fetch https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:08:33 INFO - Http code: 200 21:08:33 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 21:08:33 INFO - x-amz-cf-id: nmjTP1njVk5KVjqxG_lzOpqWt7t9D5pYLsFuqIX7NV7s9ghlOYTcxg== 21:08:33 INFO - x-amz-version-id: lku19qTm8sTsMgURmatLgMX9wkjwxrCR 21:08:34 INFO - Expected file size: 16483652 21:08:34 INFO - Obtained file size: 16483652 21:08:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 21:08:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:08:40 INFO - Fetch https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 21:08:41 INFO - Http code: 200 21:08:41 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 21:08:41 INFO - x-amz-cf-id: 4l244Qd1A050aOt6F6n77-loPVRNJBr9GeeFlrDNsrZ2eueRuz1g1Q== 21:08:41 INFO - x-amz-version-id: 1tLhsKv3JQ2xukwrYNDnhB9H6Mvzvl1k 21:08:41 INFO - Expected file size: 14409028 21:08:41 INFO - Obtained file size: 14409028 21:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:42 INFO - trying https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:08:42 INFO - Downloading https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 21:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 21:08:45 INFO - Downloaded 111624786 bytes. 21:08:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:08:45 INFO - mkdir: /builds/slave/test/properties 21:08:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:45 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:45 INFO - Contents: 21:08:45 INFO - build_url:https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:08:46 INFO - Running post-action listener: _resource_record_post_action 21:08:46 INFO - Running post-action listener: set_extra_try_arguments 21:08:46 INFO - [mozharness: 2016-10-19 04:08:46.107352Z] Finished download-and-extract step (success) 21:08:46 INFO - [mozharness: 2016-10-19 04:08:46.107476Z] Running populate-webroot step. 21:08:46 INFO - Running pre-action listener: _resource_record_pre_action 21:08:46 INFO - Running main action method: populate_webroot 21:08:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:46 INFO - '--tppagecycles', 21:08:46 INFO - '7'], 21:08:46 INFO - 'tests': ['tsvgm']}}, 21:08:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['damp', 'tps']}, 21:08:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'tests': ['damp', 'tps']}, 21:08:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['dromaeo_dom']}, 21:08:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['basic_compositor_video']}, 21:08:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:08:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['a11yr', 21:08:46 INFO - 'ts_paint', 21:08:46 INFO - 'tpaint', 21:08:46 INFO - 'sessionrestore', 21:08:46 INFO - 'sessionrestore_no_auto_restore', 21:08:46 INFO - 'tabpaint']}, 21:08:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['tsvgx', 21:08:46 INFO - 'tsvgr_opacity', 21:08:46 INFO - 'tart', 21:08:46 INFO - 'tscrollx', 21:08:46 INFO - 'cart']}, 21:08:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:46 INFO - 'tsvgr_opacity', 21:08:46 INFO - 'tart', 21:08:46 INFO - 'tscrollx', 21:08:46 INFO - 'cart']}, 21:08:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'talos_options': ['--disable-e10s'], 21:08:46 INFO - 'tests': ['tp5o']}, 21:08:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'tests': ['tp5o']}, 21:08:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'talos_options': ['--disable-e10s', 21:08:46 INFO - '--xperf_path', 21:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:46 INFO - 'tests': ['tp5n']}, 21:08:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:08:46 INFO - 'talos_options': ['--xperf_path', 21:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:46 INFO - 'tests': ['tp5n']}}, 21:08:46 INFO - 'talos.zip': {'path': '', 21:08:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:46 INFO - Running post-action listener: _resource_record_post_action 21:08:46 INFO - [mozharness: 2016-10-19 04:08:46.126591Z] Finished populate-webroot step (success) 21:08:46 INFO - [mozharness: 2016-10-19 04:08:46.126722Z] Running create-virtualenv step. 21:08:46 INFO - Running pre-action listener: _resource_record_pre_action 21:08:46 INFO - Running main action method: create_virtualenv 21:08:46 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:46 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 21:08:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:46 INFO - Using real prefix '/tools/python27' 21:08:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:46 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:49 INFO - Installing pip.................done. 21:08:49 INFO - Return code: 0 21:08:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:08:50 INFO - Reading from file tmpfile_stdout 21:08:50 INFO - Output received: 21:08:50 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) 21:08:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08:50 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')]} 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 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')]} 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:08:50 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 21:08:50 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 21:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:50 INFO - 'HOME': '/Users/cltbld', 21:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:50 INFO - 'LOGNAME': 'cltbld', 21:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:50 INFO - 'MOZ_NO_REMOTE': '1', 21:08:50 INFO - 'NO_EM_RESTART': '1', 21:08:50 INFO - 'PAGER': '/bin/cat', 21:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:50 INFO - 'PWD': '/builds/slave/test', 21:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:50 INFO - 'SHELL': '/bin/bash', 21:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:08:50 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:50 INFO - 'USER': 'cltbld', 21:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:50 INFO - 'XPC_FLAGS': '0x0', 21:08:50 INFO - 'XPC_SERVICE_NAME': '0', 21:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:50 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 21:08:50 INFO - Cleaning up... 21:08:50 INFO - Return code: 0 21:08:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:08:50 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')]} 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 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')]} 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:50 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:08:50 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 21:08:50 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 21:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:50 INFO - 'HOME': '/Users/cltbld', 21:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:50 INFO - 'LOGNAME': 'cltbld', 21:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:50 INFO - 'MOZ_NO_REMOTE': '1', 21:08:50 INFO - 'NO_EM_RESTART': '1', 21:08:50 INFO - 'PAGER': '/bin/cat', 21:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:50 INFO - 'PWD': '/builds/slave/test', 21:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:50 INFO - 'SHELL': '/bin/bash', 21:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:08:50 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:50 INFO - 'USER': 'cltbld', 21:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:50 INFO - 'XPC_FLAGS': '0x0', 21:08:50 INFO - 'XPC_SERVICE_NAME': '0', 21:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:50 INFO - Downloading/unpacking psutil>=3.1.1 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:54 INFO - Installing collected packages: psutil 21:08:54 INFO - Running setup.py install for psutil 21:08:54 INFO - building 'psutil._psutil_osx' extension 21:08:54 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 21:08:54 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 21:08:54 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 21:08:54 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 21:08:54 INFO - building 'psutil._psutil_posix' extension 21:08:54 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 21:08:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:08:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:08:55 INFO - ^ 21:08:55 INFO - 1 warning generated. 21:08:55 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 21:08:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:55 INFO - Successfully installed psutil 21:08:55 INFO - Cleaning up... 21:08:55 INFO - Return code: 0 21:08:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:08:55 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')]} 21:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:55 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')]} 21:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:55 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:08:55 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 21:08:55 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 21:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:55 INFO - 'HOME': '/Users/cltbld', 21:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:55 INFO - 'LOGNAME': 'cltbld', 21:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:55 INFO - 'MOZ_NO_REMOTE': '1', 21:08:55 INFO - 'NO_EM_RESTART': '1', 21:08:55 INFO - 'PAGER': '/bin/cat', 21:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:55 INFO - 'PWD': '/builds/slave/test', 21:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:55 INFO - 'SHELL': '/bin/bash', 21:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:08:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:55 INFO - 'USER': 'cltbld', 21:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:55 INFO - 'XPC_FLAGS': '0x0', 21:08:55 INFO - 'XPC_SERVICE_NAME': '0', 21:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:08:58 INFO - Installing collected packages: mozsystemmonitor 21:08:58 INFO - Running setup.py install for mozsystemmonitor 21:08:58 INFO - Successfully installed mozsystemmonitor 21:08:58 INFO - Cleaning up... 21:08:58 INFO - Return code: 0 21:08:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:58 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:08:58 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 21:08:58 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 21:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:58 INFO - 'HOME': '/Users/cltbld', 21:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:58 INFO - 'LOGNAME': 'cltbld', 21:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:58 INFO - 'MOZ_NO_REMOTE': '1', 21:08:58 INFO - 'NO_EM_RESTART': '1', 21:08:58 INFO - 'PAGER': '/bin/cat', 21:08:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:58 INFO - 'PWD': '/builds/slave/test', 21:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:58 INFO - 'SHELL': '/bin/bash', 21:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:08:58 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:58 INFO - 'USER': 'cltbld', 21:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:58 INFO - 'XPC_FLAGS': '0x0', 21:08:58 INFO - 'XPC_SERVICE_NAME': '0', 21:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:58 INFO - Downloading/unpacking jsonschema==2.5.1 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 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 21:09:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:09:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:09:01 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 21:09:01 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 21:09:01 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 21:09:01 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 21:09:01 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 21:09:01 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 21:09:01 INFO - Downloading functools32-3.2.3-2.tar.gz 21:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:09:01 INFO - warning: no files found matching '*.txt' 21:09:01 INFO - no previously-included directories found matching 'build' 21:09:01 INFO - no previously-included directories found matching 'dist' 21:09:01 INFO - no previously-included directories found matching '.git*' 21:09:01 INFO - Installing collected packages: jsonschema, functools32 21:09:01 INFO - Running setup.py install for functools32 21:09:01 INFO - warning: no files found matching '*.txt' 21:09:01 INFO - no previously-included directories found matching 'build' 21:09:01 INFO - no previously-included directories found matching 'dist' 21:09:01 INFO - no previously-included directories found matching '.git*' 21:09:01 INFO - Successfully installed jsonschema functools32 21:09:01 INFO - Cleaning up... 21:09:02 INFO - Return code: 0 21:09:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:09: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')]} 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09: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')]} 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:02 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 21:09:02 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 21:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:02 INFO - 'HOME': '/Users/cltbld', 21:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:02 INFO - 'LOGNAME': 'cltbld', 21:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:02 INFO - 'MOZ_NO_REMOTE': '1', 21:09:02 INFO - 'NO_EM_RESTART': '1', 21:09:02 INFO - 'PAGER': '/bin/cat', 21:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:02 INFO - 'PWD': '/builds/slave/test', 21:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:02 INFO - 'SHELL': '/bin/bash', 21:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:02 INFO - 'USER': 'cltbld', 21:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:02 INFO - 'XPC_FLAGS': '0x0', 21:09:02 INFO - 'XPC_SERVICE_NAME': '0', 21:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:09:02 INFO - Cleaning up... 21:09:02 INFO - Return code: 0 21:09:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:09: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')]} 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09: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')]} 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:02 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:02 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 21:09:02 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 21:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:02 INFO - 'HOME': '/Users/cltbld', 21:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:02 INFO - 'LOGNAME': 'cltbld', 21:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:02 INFO - 'MOZ_NO_REMOTE': '1', 21:09:02 INFO - 'NO_EM_RESTART': '1', 21:09:02 INFO - 'PAGER': '/bin/cat', 21:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:02 INFO - 'PWD': '/builds/slave/test', 21:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:02 INFO - 'SHELL': '/bin/bash', 21:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:02 INFO - 'USER': 'cltbld', 21:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:02 INFO - 'XPC_FLAGS': '0x0', 21:09:02 INFO - 'XPC_SERVICE_NAME': '0', 21:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:02 INFO - Downloading/unpacking blobuploader==1.2.4 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:05 INFO - Downloading blobuploader-1.2.4.tar.gz 21:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:09:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:09:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:09:06 INFO - Downloading docopt-0.6.1.tar.gz 21:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:09:06 INFO - Installing collected packages: blobuploader, requests, docopt 21:09:06 INFO - Running setup.py install for blobuploader 21:09:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:09:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:09:06 INFO - Running setup.py install for requests 21:09:07 INFO - Running setup.py install for docopt 21:09:07 INFO - Successfully installed blobuploader requests docopt 21:09:07 INFO - Cleaning up... 21:09:07 INFO - Return code: 0 21:09:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:07 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')]} 21:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:07 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')]} 21:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:07 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:07 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 21:09:07 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 21:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:07 INFO - 'HOME': '/Users/cltbld', 21:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:07 INFO - 'LOGNAME': 'cltbld', 21:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:07 INFO - 'MOZ_NO_REMOTE': '1', 21:09:07 INFO - 'NO_EM_RESTART': '1', 21:09:07 INFO - 'PAGER': '/bin/cat', 21:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:07 INFO - 'PWD': '/builds/slave/test', 21:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:07 INFO - 'SHELL': '/bin/bash', 21:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:07 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:07 INFO - 'USER': 'cltbld', 21:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:07 INFO - 'XPC_FLAGS': '0x0', 21:09:07 INFO - 'XPC_SERVICE_NAME': '0', 21:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:09:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-E00wzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:09:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-urYC0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:09:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3nE51G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:09:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-baLU85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-FOQDut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-omhSzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nJqyK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-9DBsnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qCsgvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-FbTqTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-8ANc6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-l4cdG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1k1sq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-D6vYTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-x7WRlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OWIAsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-s2Hl7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:09:09 INFO - Running setup.py install for manifestparser 21:09:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:09:09 INFO - Running setup.py install for mozcrash 21:09:09 INFO - Running setup.py install for mozdebug 21:09:09 INFO - Running setup.py install for mozdevice 21:09:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:09:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Running setup.py install for mozfile 21:09:10 INFO - Running setup.py install for mozhttpd 21:09:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Running setup.py install for mozinfo 21:09:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Running setup.py install for mozInstall 21:09:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Running setup.py install for mozleak 21:09:10 INFO - Running setup.py install for mozlog 21:09:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:09:10 INFO - Running setup.py install for moznetwork 21:09:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Running setup.py install for mozprocess 21:09:11 INFO - Running setup.py install for mozprofile 21:09:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Running setup.py install for mozrunner 21:09:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Running setup.py install for mozscreenshot 21:09:11 INFO - Running setup.py install for moztest 21:09:11 INFO - Running setup.py install for mozversion 21:09:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:09:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:09:11 INFO - Cleaning up... 21:09:11 INFO - Return code: 0 21:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:11 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')]} 21:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:11 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')]} 21:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:11 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:11 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 21:09:11 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 21:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:11 INFO - 'HOME': '/Users/cltbld', 21:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:11 INFO - 'LOGNAME': 'cltbld', 21:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:11 INFO - 'MOZ_NO_REMOTE': '1', 21:09:11 INFO - 'NO_EM_RESTART': '1', 21:09:11 INFO - 'PAGER': '/bin/cat', 21:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:11 INFO - 'PWD': '/builds/slave/test', 21:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:11 INFO - 'SHELL': '/bin/bash', 21:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:11 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:11 INFO - 'USER': 'cltbld', 21:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:11 INFO - 'XPC_FLAGS': '0x0', 21:09:11 INFO - 'XPC_SERVICE_NAME': '0', 21:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-79CkDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-pdiksR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dGOPPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7Cgpxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-C8TIB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ngwjZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kedcdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:09:12 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)) 21:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:09:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-CJtKdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-eGaq29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iOrZze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MHaoGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-w1bqFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-M8uZuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-mYHDv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-NGGq9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hqZU8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:09:13 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)) 21:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:09:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lN8kQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:09:14 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)) 21:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:09:14 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)) 21:09:14 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)) 21:09:14 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)) 21:09:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:09:14 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 21:09:14 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 21:09:14 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 21:09:14 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 21:09:14 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 21:09:14 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 21:09:16 INFO - Downloading blessings-1.6.tar.gz 21:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:09:16 INFO - Installing collected packages: blessings 21:09:16 INFO - Running setup.py install for blessings 21:09:16 INFO - Successfully installed blessings 21:09:16 INFO - Cleaning up... 21:09:16 INFO - Return code: 0 21:09:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:17 INFO - Reading from file tmpfile_stdout 21:09:17 INFO - Current package versions: 21:09:17 INFO - blessings == 1.6 21:09:17 INFO - blobuploader == 1.2.4 21:09:17 INFO - docopt == 0.6.1 21:09:17 INFO - functools32 == 3.2.3-2 21:09:17 INFO - jsonschema == 2.5.1 21:09:17 INFO - manifestparser == 1.1 21:09:17 INFO - mozInstall == 1.12 21:09:17 INFO - mozcrash == 0.17 21:09:17 INFO - mozdebug == 0.1 21:09:17 INFO - mozdevice == 0.48 21:09:17 INFO - mozfile == 1.2 21:09:17 INFO - mozhttpd == 0.7 21:09:17 INFO - mozinfo == 0.9 21:09:17 INFO - mozleak == 0.1 21:09:17 INFO - mozlog == 3.4 21:09:17 INFO - moznetwork == 0.27 21:09:17 INFO - mozprocess == 0.23 21:09:17 INFO - mozprofile == 0.28 21:09:17 INFO - mozrunner == 6.12 21:09:17 INFO - mozscreenshot == 0.1 21:09:17 INFO - mozsystemmonitor == 0.3 21:09:17 INFO - moztest == 0.7 21:09:17 INFO - mozversion == 1.4 21:09:17 INFO - psutil == 3.1.1 21:09:17 INFO - requests == 1.2.3 21:09:17 INFO - wsgiref == 0.1.2 21:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:17 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:17 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 21:09:17 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 21:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:17 INFO - 'HOME': '/Users/cltbld', 21:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:17 INFO - 'LOGNAME': 'cltbld', 21:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:17 INFO - 'MOZ_NO_REMOTE': '1', 21:09:17 INFO - 'NO_EM_RESTART': '1', 21:09:17 INFO - 'PAGER': '/bin/cat', 21:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:17 INFO - 'PWD': '/builds/slave/test', 21:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:17 INFO - 'SHELL': '/bin/bash', 21:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:17 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:17 INFO - 'USER': 'cltbld', 21:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:17 INFO - 'XPC_FLAGS': '0x0', 21:09:17 INFO - 'XPC_SERVICE_NAME': '0', 21:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 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)) 21:09:18 INFO - Cleaning up... 21:09:18 INFO - Return code: 0 21:09:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:18 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 0x10fdfb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd71570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7e257db90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, '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.rBvzkEV2s9/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.goifM67jYu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 21:09:18 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 21:09:18 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 21:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:18 INFO - 'HOME': '/Users/cltbld', 21:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:18 INFO - 'LOGNAME': 'cltbld', 21:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:18 INFO - 'MOZ_NO_REMOTE': '1', 21:09:18 INFO - 'NO_EM_RESTART': '1', 21:09:18 INFO - 'PAGER': '/bin/cat', 21:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:18 INFO - 'PWD': '/builds/slave/test', 21:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:18 INFO - 'SHELL': '/bin/bash', 21:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:18 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:18 INFO - 'USER': 'cltbld', 21:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:18 INFO - 'XPC_FLAGS': '0x0', 21:09:18 INFO - 'XPC_SERVICE_NAME': '0', 21:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:09:18 INFO - Cleaning up... 21:09:18 INFO - Return code: 0 21:09:18 INFO - Running post-action listener: _resource_record_post_action 21:09:18 INFO - Running post-action listener: _start_resource_monitoring 21:09:18 INFO - Starting resource monitoring. 21:09:18 INFO - [mozharness: 2016-10-19 04:09:18.891668Z] Finished create-virtualenv step (success) 21:09:18 INFO - [mozharness: 2016-10-19 04:09:18.892666Z] Running install step. 21:09:18 INFO - Running pre-action listener: _resource_record_pre_action 21:09:18 INFO - Running main action method: install 21:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:09:19 INFO - Reading from file tmpfile_stdout 21:09:19 INFO - Detecting whether we're running mozinstall >=1.0... 21:09:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:09:19 INFO - Reading from file tmpfile_stdout 21:09:19 INFO - Output received: 21:09:19 INFO - Usage: mozinstall [options] installer 21:09:19 INFO - Options: 21:09:19 INFO - -h, --help show this help message and exit 21:09:19 INFO - -d DEST, --destination=DEST 21:09:19 INFO - Directory to install application into. [default: 21:09:19 INFO - "/builds/slave/test"] 21:09:19 INFO - --app=APP Application being installed. [default: firefox] 21:09:19 INFO - mkdir: /builds/slave/test/build/application 21:09:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:09:53 INFO - Reading from file tmpfile_stdout 21:09:53 INFO - Output received: 21:09:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:09:53 INFO - Running post-action listener: _resource_record_post_action 21:09:53 INFO - [mozharness: 2016-10-19 04:09:53.401247Z] Finished install step (success) 21:09:53 INFO - [mozharness: 2016-10-19 04:09:53.401338Z] Running run-tests step. 21:09:53 INFO - Running pre-action listener: _resource_record_pre_action 21:09:53 INFO - Running main action method: run_tests 21:09:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:09:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:09:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:09:53 INFO - 2016-10-18 21:09:53.413 screenresolution[1638:7870] starting screenresolution argv=screenresolution get 21:09:53 INFO - 2016-10-18 21:09:53.416 screenresolution[1638:7870] Display 0: 1600x1200x32@60 21:09:53 INFO - 2016-10-18 21:09:53.421 screenresolution[1639:7872] starting screenresolution argv=screenresolution list 21:09:53 INFO - Available Modes on Display 0 21:09:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:09:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:09:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:09:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:09:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:09:53 INFO - Intel Iris: 21:09:53 INFO - Chipset Model: Intel Iris 21:09:53 INFO - Type: GPU 21:09:53 INFO - Bus: Built-In 21:09:53 INFO - VRAM (Dynamic, Max): 1536 MB 21:09:53 INFO - Vendor: Intel (0x8086) 21:09:53 INFO - Device ID: 0x0a2e 21:09:53 INFO - Revision ID: 0x0009 21:09:53 INFO - Displays: 21:09:53 INFO - Display: 21:09:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:09:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:09:53 INFO - Display Serial Number: 1600x1200 60 21:09:53 INFO - Main Display: Yes 21:09:53 INFO - Mirror: Off 21:09:53 INFO - Online: Yes 21:09:53 INFO - Rotation: Supported 21:09:53 INFO - Return code: 0 21:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:09:53 INFO - Python 2.7.3 21:09:53 INFO - Return code: 0 21:09:53 INFO - grabbing minidump binary from tooltool 21:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:53 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 0x10fc0fd30>, '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 0x10fa35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc07b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:09:53 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 21:09:53 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 21:09:53 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 21:09:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:09:53 INFO - Return code: 0 21:09:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:09:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:09:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 21:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 21:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rBvzkEV2s9/Render', 21:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:53 INFO - 'HOME': '/Users/cltbld', 21:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:53 INFO - 'LOGNAME': 'cltbld', 21:09:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:53 INFO - 'MOZ_NO_REMOTE': '1', 21:09:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:53 INFO - 'NO_EM_RESTART': '1', 21:09:53 INFO - 'PAGER': '/bin/cat', 21:09:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:53 INFO - 'PWD': '/builds/slave/test', 21:09:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:53 INFO - 'SHELL': '/bin/bash', 21:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.goifM67jYu/Listeners', 21:09:53 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 21:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:53 INFO - 'USER': 'cltbld', 21:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:53 INFO - 'XPC_FLAGS': '0x0', 21:09:53 INFO - 'XPC_SERVICE_NAME': '0', 21:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 21:09:53 INFO - mozversion application_buildid: 20161018192623 21:09:53 INFO - mozversion application_changeset: 54de8af4ed217813fa68bf8971966f9c6160850e 21:09:53 INFO - mozversion application_display_name: Nightly 21:09:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:09:53 INFO - mozversion application_name: Firefox 21:09:53 INFO - mozversion application_remotingname: firefox 21:09:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:09:53 INFO - mozversion application_vendor: Mozilla 21:09:53 INFO - mozversion application_version: 52.0a1 21:09:53 INFO - mozversion platform_buildid: 20161018192623 21:09:53 INFO - mozversion platform_changeset: 54de8af4ed217813fa68bf8971966f9c6160850e 21:09:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:09:53 INFO - mozversion platform_version: 52.0a1 21:09:53 INFO - using testdate: 1476850193 21:09:53 INFO - actual date: 1476850193 21:09:53 INFO - starting webserver on 'localhost:49272' 21:09:53 INFO - SUITE-START | Running 2 tests 21:09:53 INFO - TEST-START | dromaeo_css 21:09:53 INFO - operating with platform_type : mac_ 21:09:53 INFO - Initialising browser for dromaeo_css test... 21:09:54 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpCL7S52/profile http://localhost:49272/getInfo.html) 21:09:56 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 21:09:56 INFO - PROCESS | 1649 | colorDepth:24 21:09:56 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 21:09:56 INFO - PROCESS | 1649 | __metrics 21:09:56 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:09:56 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:09:57 INFO - TEST-INFO | 1649: exit 0 21:09:57 INFO - Browser initialized. 21:09:57 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:57 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpCL7S52/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:10:09 INFO - PROCESS | 1654 | RSS: Main: 231235584 21:10:09 INFO - PROCESS | 1654 | 21:11:00 INFO - PROCESS | 1654 | 1476850260908 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] 21:13:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 21:13:15 INFO - PROCESS | 1654 | RSS: Main: 359010304 21:13:15 INFO - PROCESS | 1654 | 21:16:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 21:16:19 INFO - PROCESS | 1654 | RSS: Main: 366661632 21:16:19 INFO - PROCESS | 1654 | 21:19:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 21:19:22 INFO - PROCESS | 1654 | RSS: Main: 363507712 21:19:22 INFO - PROCESS | 1654 | 21:22:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 21:22:25 INFO - PROCESS | 1654 | RSS: Main: 364658688 21:22:25 INFO - PROCESS | 1654 | 21:25:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 21:25:29 INFO - PROCESS | 1654 | RSS: Main: 331440128 21:25:29 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 21:28:33 INFO - PROCESS | 1654 | RSS: Main: 459534336 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | __start_tp_report 21:28:33 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 21:28:33 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 21:28:33 INFO - PROCESS | 1654 | |i|pagename|runs| 21:28:33 INFO - PROCESS | 1654 | |0;dojo.html;3297.6042874855016;3319.087251005973;3331.5002749587566;3344.097093784677;3377.1725927147854;4425.77871106445;5598.216249725042;5670.206171136034;5746.9712651436785;5760;4644.5819876211135;5685.317761868571;5757.568182386327;5767.279090113736;5778.653280803144;5104.081265372231;5114.207297868837;5129.102407078746;5161.17421212606;5166.793328266865;5197.792088316482;5294.2852714883575;5295.576353891679;5309.044372013036;5328.6269961102735;5095.235714642796;5266.947330526684;5275.366955965271;5285;5361.249425080492;5147.382314122329;5190.4031036430915;5195.740212989335;5197.0385478686185;5197.246399272084;5118.872028199287;5143.794248230066;5182.144946083891;5188.351456067991;5204.68771873685;5625.268715067015;5631.211630371751;5636.830895073154;5649.265595472603;5666.914996275059;6134.405972711579;6152.169457123276;6152.230971128609;6162.859870923849;6164.506839452833;5426.538744206747;6183.505319574469;6188.84527886802;6216.067589861512;6224.688765561749;5672.432756724335;5740.339860915965;5740.454656807555;5743.195952566644;5745.712714364265;6198.101275315054;6263.937360626335;6267.122602835607;6310.779122730774;6328.145700330489;6156.8768624627255;6168.784092556739;6172.6913654318005;6204.844878878064;6205.565610407319;6139.631622102688;6140.539459540552;6143.204841796972;6154.815355539341;6256.343083976208;6173.81478555644;6216.098665693539;6218.906716399258;6223.719932602953;6242.937570624326;6204.317525072239;6209.348018458088;6225.439710426083;6233.657148856766;6242.219722534684;4867;4908.582770464551;4928;4930.704157750546;4963.454020057863;4272.594103560219;4367.082912588328;4376.277914144194;4376.321670141133;4388.71473354231;5745.569082318842;5760.971195143998;5761.3950535194035;5834.066549352084;5849.444302791228;6289.371062893674;6294.65379404128;6313;6315.431611155018;6332.430081292614;5563.888722225533;5690.430956904276;5700.743466544015;5729.312482502127;5733.140028995601;5402.91895621566;5411.242426060355;5412.864678382994;5423.566114710892;5424.349078110652;5302.28419163408;6340.619562826245;6345.777897773556;6351.079093531504;6370.267419246846;6128.014278093631;6177.2587289525545;6180.351063138485;6192.133101365723;6254.312025677263;6292.874142517216;6337.397947195009;6343.777967771106;6345.936540634534;6370.203724534434;6115.663638499747;6174.413430724074;6184.443400094013;6189.783357582463;6197.628142311476;6126.27339965311;6200.56596038291;6285.43431091204;6297.811065668037;6312.242530896138;6091.573589848846;6111.19673459643;6135.294441139326;6176;6190.690465476799;5940.61386009908;6112.694365281807;6117.755289788359;6138.723757431016;6146.139540464249;5782.2194003808945;5888.234529511136;5918.911216331845;5964.970175149097;5968.104784282393;6068.7269072892705;6080.4831589313635;6101.701646049799;6124.816255512342;6154.599951003171;5972.163897054329;5973.342932277359;5974.910376344272;5976.462118409364;6052.576319657582 21:28:33 INFO - PROCESS | 1654 | |1;ext.html;500.33205670429976;510.3059838619478;525.9526642602165;527.4013994116676;547.6522408270747;24524.50950980979;31955.84022079892;40415.78752637422;41673.58326416735;41692.37461438081;42371.36442953358;42643;42839.78580107095;42885.35671964923;42924.35613465793;9351.532423378821;9552.426854388725;9574.856377154349;9596.32825702201;9603.423794572313;9411.717648470556;9499.620015199383;9503.762405939873;9522.190613797835;9525.476098814563;9464.1482266596;9522.333436659437;9523.523823808817;9529.428234305964;9544.093311135466;9453.763655908588;9507.857382139273;9509.619615215452;9515.0960658738;9540.713778586618;9382.90617093834;9452.43285402878;9465.52672366379;9477.28920330978;9494.762630934281;40279;40482;40546.59453405458;40618.796906015574;40635.593644063774;3398.04854640701;3425.143714071482;3513.6486351364915;3606.8376923038527;3617.981910090433;30218.84890575555;30286;31379.84310078458;31502.054938351885;31841.203969900707;3210.213497693057;3233.4826427771445;3243.9781468837477;3364.4616861302075;3368.5957685077947;367308;371583;371610.1419492912;372933;373011;33081.66918330834;33830.094636278096;57683.71158144182;57832;57965;35359.4696079559;35462;35544.64455355465;35557.288854222774;35623.64376356255;13808.585742427744;14099.295035248197;14181.865450763915;14182.290885455686;14211.36048877803;12229;12370.319632420124;12401.00791936643;12430.689232769253;12431.06766992461;6505.772297969548;7512.248775122444;7747.883781743279;7803.063632364153;7808.726694565777;2944.4111177764535;3317.502374643823;3324.3517514085006;3331.7501187410558;3364.175776934667;872.3631748823394;960.2893858544627;1019.9949000254951;1027.2501074215863;1051.9474026298653;27086.729132708813;29697.881722418835;29765.553516697266;30605.540916886264;30835.537466938014;1011.5397494140149;1020.5458570936011;1039.7608550033535;1050.4380156616326;1079.3577821196375;14893.255337233095;15398.384064637288;15548.378064877279;15744.212789360487;15875.841241587666;168901;171185;654614;654964;655590;57093.42906570881;57612.423875761546;57732;57805.13292300619;57875.131873021935;1232.6979889927024;1288.3365066990507;1343.5969209237385;1388.9652758681113;1404.3961096728506;27068;29139.708602914547;30938.381232375676;31347.529787053667;31734.365312694077;41557.37663935104;48341.03317933692;48740.29408970669;48817.755911220214;49032.75483622559;510026;513071;513211;513408;514410;18725.90637046806;23705.40736481602;25598.872005639852;26170.345741356618;26351.34121646974;40514.79742601268;44176.7791161055;44778.77610611926;45122;45507.31739023851;34691.9592412228;37031;37484.81257593694;37917.67288144902;38350.808245959706;35798.93014003907;37050.444243335834;37711;37830;38677.22645547017;35231.47152792761;36004.4599331005;36857.26285474222;37101.44347834839;37260.81369593135;32509.512357314186;33419.66580334165;33564.16089597682;33597;33861.49207761935;24170.758292417555;24308.87845560761;24539.754602453748;24852.378690532158;24985.87507062453 21:28:33 INFO - PROCESS | 1654 | |2;jquery.html;335.13535079494903;351.3482489981083;367.5295621604346;379.09207448161646;403.3586597310278;14949.775753363694;15292.694146117072;15837.762433563492;19986.20055197794;20333.898330508327;19469.31857384992;19702.507437314074;20216.19135234589;20474.283400081;21064.789352106476;5775.2203452534;6094.329623741385;6105.023196288595;6113.847153821145;6114.205153330061;5056.393232812049;6596.175478065242;6652.767153149641;6744;6746.763863264787;5669.971650141743;5954.374790646984;6541.378569035958;6656.068150458939;6691.431228345572;5981.58128930975;6021.157038014703;6030.8190754277;6033.306169790487;6038.818835434944;5096.51583099605;5370.516653501204;5419.214213938957;5437.13005919051;5818.359980402152;13043.282619455926;13087.934560327232;13167.341632918316;13305.800412993813;13330.333483325794;2082.5938941906334;2154.353693891826;2168;2231.0183519251477;2251.6960210371553;9744.269179811472;9798;9822.508874556315;10132.696019119438;10246.743831404274;1801.8198180182007;1833.6149408624206;1871.6631006418838;1882.2376937340398;1890.253349926785;99533.5023324886;110919.85527902587;120213;123509;123724.14413783801;11470.426478676032;11498.655040348804;11625.432694469057;11657.067434605211;11675.708107297216;14286.499972500913;14450.349734261983;14470.855291446951;14488.203148826915;14688.485902993558;6962.477814163856;7395.708909535313;8332.083470818205;8382.580870956428;8581.240845626631;7540.17058123606;7673.769786906401;7997.960010200029;8366.414350995372;8507.574621268912;4408.140412619508;4512.255477846119;4521.909561808811;4586.036932244199;4595.839145629887;1674.824143464943;1885.5191926059042;1940.262700173925;1941.223510595773;1957.8237958583795;882.7616543533338;927.7958849053198;972.8686627305224;976.2482888176206;985.7042887133831;19670.11484483173;21220;21300.656927737935;21361.144564766702;21588.568228635653;739.2681245566957;875.0680525240598;886.4636894678755;945.2957546627806;970.0445061614304;10645.520951557199;11107.333559986426;11110.388928608843;11130.443477826238;11166;580052;582256;592702;598219;598451;92347;99201.50399248103;106611;116318.4184079091;119641.40179299048;751.9097708275042;923.9214666753401;998.0468652436905;998.960041598328;1003.8193125237382;19030.42908712741;19230.750001249875;19270.807291927464;19325.710114348578;19352.70970935409;50975.49024509708;54019.45980540144;55242.72378638081;55495.06311711036;55720.6069848257;74038.62980685061;78040;78126.6093669528;78613.21386786288;78831;7834.569098699626;7935.373248483931;7945.682172713026;8202.097769245498;8229.382796290422;9059.95810481803;12525.874741252472;12568.87431125677;12765.744685106432;12856.807147892976;10637.734056648645;10685.465726713477;10949.83575246356;11009.889901101207;11045.668629941114;10520.631777887736;10550.366977981346;10674.35953842772;10961.287516311415;11070.335779853234;8813;9956.755405574304;10916.672499825017;11121.721956951142;11371;7783.844323113392;7901.367890568626;7941.166177551274;8136.918630813616;8161.877571836546;9491.762705932408;10506.15950723925;10875.933572621178;10877.021068103908;10974.670759877217 21:28:33 INFO - PROCESS | 1654 | |3;mootools.html;1522.1856306875823;1528.9235538223086;1758.3757765993073;1848.0759620189906;1927.4699457649144;8361.874571881419;8835.911640883589;8847.11528847115;8857.379983401164;8870.822583548324;7701.460897737161;8034.959825200865;8049.315808156299;8305.418620696553;8633.87049194261;2253.1212826997444;2300.229423143249;2301.7928386445215;2302.5625131225097;2302.9424264393356;2311.895964681585;2339.801116905065;2346.800521955627;2348.013834189636;2354.705661792276;2296.850704704197;2304.8040916522114;2320.14154762738;2339.6724458575814;2346.6480027995854;2320.8143348532244;2326.6393708974965;2328.1036800831716;2328.5459335429596;2328.6507023946374;2284.394635421598;2327.4065113396146;2327.9650805237934;2328.836981411302;2332.0788288626068;8746.56267186638;8811.162939520735;8814.427062240933;8817.691380801705;8832.249258813008;5149.0989076911765;5316.441773613754;5329.893402131975;5331.493508116761;5342.332208473941;3418.5897692276835;3504.5969713483023;3632.346177688015;3645.198056427582;3649.324874898126;1001.5360095531138;1058.7564860082148;1060.3584831177104;1069.786042791437;1087.5160553553671;11190.94404527972;11218.607348742837;11363.261388009752;11409.60066397672;11443.713907152305;4502.977485112553;4548.909021819545;4763.428388593391;4764.523547645208;4766.976165119152;9727.562259698188;9782.804343913082;9788.168005719594;9813.950930245303;9838.458884761263;5293.153095504701;5314.681119132864;5315.149576067811;5321.494458026481;5322.733863306819;4369.47566292047;4457.821687132543;4547.317902314613;4847.636427267969;4878.463369029404;3832.5400951885954;3874.302625527432;3950.881473555798;4001.799910004488;4003.53959294679;1261.426051146726;1263.9256631862843;1266.0439620785908;1273.6752128207247;1280.4494067550856;836.4646626159263;861.4529773593813;908.2824568590871;924.6347692661361;932.5233626336633;5608.355039170548;5750.913736293959;5957.434043765836;6043.848903777441;6076.605020673642;443.22878191945784;454.1530046463322;459.21703495540027;942.7218970403748;1010.6563768318807;3892.377219644843;4034.7175697700877;4039.8688367257214;4045.1505183911117;4058.776767277828;9989.900100999042;10418.479076046167;10633.574657013633;10750.193735469871;10753.085987690978;10400.167986561208;10483.108935740394;10696.78606427867;10700.250982431311;10719.410432426292;6323.399277068671;7947.165547617533;8070.959645201854;8202.38482113844;8257.752267432106;5911.935851546763;5955.315138758925;6051.939480605138;6149.692515374303;6164.445199932027;9949.154321882721;9985.101340879355;9989;10010.299279050396;10012.948640392946;8301.626426810688;9529.285303602397;10006.749831254278;10118.520962363973;10135.898641013791;6822.692978815866;7088.362047415757;7264.600446975467;7420.814479638052;7582.128055273493;8291.419600627898;8409.159084091541;8489.95755021221;8494.405391622528;8508.319334453352;7323.340899319087;7477.551346919202;7540.924590754023;7612.086549614081;7613.505122167041;6385.361463853763;7235.529690570096;7323.414126869944;7400.370968467741;7463.2163622818825;6967.442604591722;6995.755148569776;7068.540544864567;7070.010198572307;7082.681279342545;4985.551300382983;5003.749812509287;5037.319961805109;5044.167712327571;5159.432462429205;4847.781849816696;4861.562459378673;4961.503849615154;5088.59291256706;5121.308623335802 21:28:33 INFO - PROCESS | 1654 | |4;prototype.html;293.14986539036806;302.15999521330696;310.58381768430297;314.718327097248;317.3399329394859;4779.689320194185;5252.793826592831;5341.759620817063;5477.75350109245;5483.725813709313;4532.97733511332;5118.692878427297;5313.256144139824;5353.804939974601;5388.703621300837;3352.8826491072878;3495.860165593373;4099.098198396363;4138.4067134153265;4142.212979533894;3101.736352410048;3980.6616437602693;4132.628063474287;4158.9621085668105;4169.6455801256925;3445.846346033729;3729.4778730977687;3933.704972127088;4022.7184097113213;4149.46057012588;4093.079057212133;4098.7950602470055;4104.486939132608;4123.773192474398;4128;3542.494439863048;3547.6452354764315;3551.627079156703;3553.875614353485;3555.5555555555557;5037.345145131109;5224.660397074178;5270.710110943896;5330.974229792692;5337.332833395825;3743.6630703236838;4667.556582124693;4679.368285281512;4696.577308042259;4710.952890471086;5218.947810521884;5628.268325117709;5650.576206784466;5659.377468478464;5666.829995100154;3290.7367410607094;3562.9643703562892;3602.8018458984743;3930.744501607415;3953.853382519066;4845.176320025604;5788.652680839121;5824.388439213906;5939.910901336483;5968.641881487081;3762.3039737648623;3805.372113601225;3892.5328960524917;3914.5693973662874;3929.31237033518;3921.843126274974;3983.1237127831832;3998.4402183694306;4000.4199391088127;4000.6999475038906;4283.528811830696;4356.825726970949;4527.7736113194205;4538.478075021415;4667.439907211156;4673.789679464432;4723.976380118077;4920.548438210736;5644.407337229614;5673.716314184274;1017.4048181813574;1107.8781334053247;1147.6671765188169;1329.4217015598053;1342.375795255211;718.5042320798632;770.6185438208123;813.4427916876958;814.4421071565996;824.884516167737;4057.2553801865483;4371.5846994536105;4397.548808893058;4415.690901636919;4421.911561768811;4299.366240828465;4347.891287721674;4793.818812440331;4859.392575928009;4875.926861097087;3917.5494818095713;4001.659858912025;4351.586599273064;4399.274119770266;4411.316245981878;2171.6416791229585;2725.6184134221226;2997.595324631147;3038.5745995560637;3061.6938306169204;4980.850574482772;5238.659487133325;5251.92335571207;5273.709945953009;6001.899335232633;5032.949670503322;5660.660360378391;5798.26052184343;5940.376260492586;5951.5238780897425;4554.0891821635805;5936.940630593639;6021.006533921942;6043.788467403708;6096.908546371719;5027.321311622884;5120.820771272987;5772.509336706259;5977.462028417463;5995.520358371407;5556.583256255716;5560.192102988127;5628.718564071698;5742.397048309867;5812.651240925558;5066.6440105351185;5462.9726851365485;5583.637063590853;5781.421857814353;6048.213732214784;3800.315943130263;3824.196918647115;3911.941320880246;3929.8582272660897;3971.8808435746973;4840.2255639098985;5866.67733274674;5872.970635146797;5960.195373624679;5963.671998040149;4692.6480513960905;5123.846284611468;5683.801066962802;5750.338711048283;5824.796132135355;4828;5315.255864178941;5547.389787123332;5603.495685388335;5697.692434564008;5559.6664200148125;5587.469190426903;5608.270924779753;5622.381538030895;5665.178549110438;4493.618042466427;4571.085782843378;4935.950640493549;5611.971940140436;5683.71581420919;5518.592758846468;5540.722963851711;5553.805616803393;5568.832935011957;5618.915716264341 21:28:33 INFO - PROCESS | 1654 | |5;yui.html;634.6798658789714;701.715805098935;752.5559919647409;767.3209209849282;770.8997830282062;15317.463888763896;15996.920015399935;16040.759388609165;16040.919795401007;16077.276522556484;14499.92750036251;14611.196384198865;14614.634634134152;14656.853431465683;14680.045797023186;690.0649619765221;712.2343480758179;715.6421789105448;716.8924661300821;856.8029353248756;709.4324540367711;712.1987763765763;849.8810166576684;875.4134729731095;881.775147337428;936.3585943628602;946.1957336264189;949.9905000950006;950.1448696173464;952.861330709802;943.9339246252765;944.7307517357589;946.5314669238702;947.6777895515557;947.9194268487153;533.5624787674109;550.3203543623622;701.7578935267325;703.0473708125519;703.8908969109796;11545.365004924726;11833.053355731521;11839;14139.717205655828;14707.044042137228;13296.26870522121;13851.376688049062;14296.499622513162;14794.704105917928;15037.398504059824;11379.829302560469;11751.47118379675;11988.120509064136;12084.6374608761;12379.566715165016;3730.123420996064;3744.7165263562383;3792.5069740933777;3814.618538146191;3839.6928245740555;105595;106194;107292;107718;111083.88916110896;10012.098911098035;10303.484825758682;10769.192310576738;10856.34861908288;11179.329240245612;21794.673079903812;21872.343829685135;21886.23398181056;21889.781102188776;21900.561988760135;6405.231372235362;7778.105517865405;7815.882761758578;7965.761027169194;8097.919020809716;4287.399764033038;4371.344298355272;4384.517703052662;4537.75042372666;4554.043650833362;3476.5654293213347;3623.528941237675;3637;3639.1084184375;3658.1951970566433;635.6122765113278;636.4049613611288;642.3415998601378;657.8158115727604;662.0565693886193;602.4728362682653;616.2204810914197;617.4103730936945;639.7536948274948;657.9934200658028;8347.95826020866;9059.275257979345;9618.355383031008;9655.08276713711;9701.514924253901;635.6218050260088;664.9251959154599;675.9087523184307;679.7281087565013;691.7025678958096;4140.896477588084;4530;4698.8355407560575;4706.790887399389;4713.670043097019;126466.36766816291;131491;135271;148425;171778;108794.45602771935;112972;119072.87880530252;141920;147612.26193868963;96013.03986960041;99053;99476;103028.2123198295;104558.54414558483;9137.177654011171;9374.859377109486;9996.550155243178;10021.39871607706;10047.246456515644;60053;60437.395626044934;60934.695326523084;61431.6928415373;61714.69142654258;55173.44826551844;55252.447475526336;73117;74672;74804.62597686976;12783.48866045385;12853.293068881301;12857;12962.944580657284;13316.33418329099;14649.631060268071;14738.926305368404;14845.33196006204;14853.628659283604;15192.961407718722;13459.125156864773;13522.565938130838;13558.296509003872;13877.444902203799;14007.579772606838;13086.345682716017;13462.39496422109;13501.864981350061;13659.112157709716;13934.651633709238;12856.421461034462;13487.662808429475;13531.391087400894;13811.723765524432;13841.377138029015;10861.891381086089;10874.510647021061;11304.773904521699;11340.432978350884;11680.357580332824;9344;9408.717738467856;11207.551697931991;11477.540898364306;11553.13351498624 21:28:33 INFO - PROCESS | 1654 | __end_tp_report 21:28:33 INFO - PROCESS | 1654 | __start_cc_report 21:28:33 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1676 21:28:33 INFO - PROCESS | 1654 | __end_cc_report 21:28:33 INFO - PROCESS | 1654 | __startTimestamp1476851313279__endTimestamp 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | ------- Summary: start ------- 21:28:33 INFO - PROCESS | 1654 | Number of tests: 6 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5719.76 Median:5968.10 stddev:658.41 (11.0%) stddev-sans-first:632.41 21:28:33 INFO - PROCESS | 1654 | Values: 3297.6 3319.1 3331.5 3344.1 3377.2 4425.8 5598.2 5670.2 5747.0 5760.0 4644.6 5685.3 5757.6 5767.3 5778.7 5104.1 5114.2 5129.1 5161.2 5166.8 5197.8 5294.3 5295.6 5309.0 5328.6 5095.2 5266.9 5275.4 5285.0 5361.2 5147.4 5190.4 5195.7 5197.0 5197.2 5118.9 5143.8 5182.1 5188.4 5204.7 5625.3 5631.2 5636.8 5649.3 5666.9 6134.4 6152.2 6152.2 6162.9 6164.5 5426.5 6183.5 6188.8 6216.1 6224.7 5672.4 5740.3 5740.5 5743.2 5745.7 6198.1 6263.9 6267.1 6310.8 6328.1 6156.9 6168.8 6172.7 6204.8 6205.6 6139.6 6140.5 6143.2 6154.8 6256.3 6173.8 6216.1 6218.9 6223.7 6242.9 6204.3 6209.3 6225.4 6233.7 6242.2 4867.0 4908.6 4928.0 4930.7 4963.5 4272.6 4367.1 4376.3 4376.3 4388.7 5745.6 5761.0 5761.4 5834.1 5849.4 6289.4 6294.7 6313.0 6315.4 6332.4 5563.9 5690.4 5700.7 5729.3 5733.1 5402.9 5411.2 5412.9 5423.6 5424.3 5302.3 6340.6 6345.8 6351.1 6370.3 6128.0 6177.3 6180.4 6192.1 6254.3 6292.9 6337.4 6343.8 6345.9 6370.2 6115.7 6174.4 6184.4 6189.8 6197.6 6126.3 6200.6 6285.4 6297.8 6312.2 6091.6 6111.2 6135.3 6176.0 6190.7 5940.6 6112.7 6117.8 6138.7 6146.1 5782.2 5888.2 5918.9 5965.0 5968.1 6068.7 6080.5 6101.7 6124.8 6154.6 5972.2 5973.3 5974.9 5976.5 6052.6 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:58100.80 Median:37040.72 stddev:126699.81 (342.1%) stddev-sans-first:126981.43 21:28:33 INFO - PROCESS | 1654 | Values: 500.3 510.3 526.0 527.4 547.7 24524.5 31955.8 40415.8 41673.6 41692.4 42371.4 42643.0 42839.8 42885.4 42924.4 9351.5 9552.4 9574.9 9596.3 9603.4 9411.7 9499.6 9503.8 9522.2 9525.5 9464.1 9522.3 9523.5 9529.4 9544.1 9453.8 9507.9 9509.6 9515.1 9540.7 9382.9 9452.4 9465.5 9477.3 9494.8 40279.0 40482.0 40546.6 40618.8 40635.6 3398.0 3425.1 3513.6 3606.8 3618.0 30218.8 30286.0 31379.8 31502.1 31841.2 3210.2 3233.5 3244.0 3364.5 3368.6 367308.0 371583.0 371610.1 372933.0 373011.0 33081.7 33830.1 57683.7 57832.0 57965.0 35359.5 35462.0 35544.6 35557.3 35623.6 13808.6 14099.3 14181.9 14182.3 14211.4 12229.0 12370.3 12401.0 12430.7 12431.1 6505.8 7512.2 7747.9 7803.1 7808.7 2944.4 3317.5 3324.4 3331.8 3364.2 872.4 960.3 1020.0 1027.3 1051.9 27086.7 29697.9 29765.6 30605.5 30835.5 1011.5 1020.5 1039.8 1050.4 1079.4 14893.3 15398.4 15548.4 15744.2 15875.8 168901.0 171185.0 654614.0 654964.0 655590.0 57093.4 57612.4 57732.0 57805.1 57875.1 1232.7 1288.3 1343.6 1389.0 1404.4 27068.0 29139.7 30938.4 31347.5 31734.4 41557.4 48341.0 48740.3 48817.8 49032.8 510026.0 513071.0 513211.0 513408.0 514410.0 18725.9 23705.4 25598.9 26170.3 26351.3 40514.8 44176.8 44778.8 45122.0 45507.3 34692.0 37031.0 37484.8 37917.7 38350.8 35798.9 37050.4 37711.0 37830.0 38677.2 35231.5 36004.5 36857.3 37101.4 37260.8 32509.5 33419.7 33564.2 33597.0 33861.5 24170.8 24308.9 24539.8 24852.4 24985.9 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33688.08 Median:2405.75 stddev:98162.47 (4080.3%) stddev-sans-first:98405.90 21:28:33 INFO - PROCESS | 1654 | Values: 335.1 351.3 367.5 379.1 403.4 14949.8 15292.7 15837.8 19986.2 20333.9 19469.3 19702.5 20216.2 20474.3 21064.8 5775.2 6094.3 6105.0 6113.8 6114.2 5056.4 6596.2 6652.8 6744.0 6746.8 5670.0 5954.4 6541.4 6656.1 6691.4 5981.6 6021.2 6030.8 6033.3 6038.8 5096.5 5370.5 5419.2 5437.1 5818.4 13043.3 13087.9 13167.3 13305.8 13330.3 2082.6 2154.4 2168.0 2231.0 2251.7 9744.3 9798.0 9822.5 10132.7 10246.7 1801.8 1833.6 1871.7 1882.2 1890.3 99533.5 110919.9 120213.0 123509.0 123724.1 11470.4 11498.7 11625.4 11657.1 11675.7 14286.5 14450.3 14470.9 14488.2 14688.5 6962.5 7395.7 8332.1 8382.6 8581.2 7540.2 7673.8 7998.0 8366.4 8507.6 4408.1 4512.3 4521.9 4586.0 4595.8 1674.8 1885.5 1940.3 1941.2 1957.8 882.8 927.8 972.9 976.2 985.7 19670.1 21220.0 21300.7 21361.1 21588.6 739.3 875.1 886.5 945.3 970.0 10645.5 11107.3 11110.4 11130.4 11166.0 580052.0 582256.0 592702.0 598219.0 598451.0 92347.0 99201.5 106611.0 116318.4 119641.4 751.9 923.9 998.0 999.0 1003.8 19030.4 19230.8 19270.8 19325.7 19352.7 50975.5 54019.5 55242.7 55495.1 55720.6 74038.6 78040.0 78126.6 78613.2 78831.0 7834.6 7935.4 7945.7 8202.1 8229.4 9060.0 12525.9 12568.9 12765.7 12856.8 10637.7 10685.5 10949.8 11009.9 11045.7 10520.6 10550.4 10674.4 10961.3 11070.3 8813.0 9956.8 10916.7 11121.7 11371.0 7783.8 7901.4 7941.2 8136.9 8161.9 9491.8 10506.2 10875.9 10877.0 10974.7 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5645.83 Median:4973.53 stddev:3144.51 (63.2%) stddev-sans-first:3138.06 21:28:33 INFO - PROCESS | 1654 | Values: 1522.2 1528.9 1758.4 1848.1 1927.5 8361.9 8835.9 8847.1 8857.4 8870.8 7701.5 8035.0 8049.3 8305.4 8633.9 2253.1 2300.2 2301.8 2302.6 2302.9 2311.9 2339.8 2346.8 2348.0 2354.7 2296.9 2304.8 2320.1 2339.7 2346.6 2320.8 2326.6 2328.1 2328.5 2328.7 2284.4 2327.4 2328.0 2328.8 2332.1 8746.6 8811.2 8814.4 8817.7 8832.2 5149.1 5316.4 5329.9 5331.5 5342.3 3418.6 3504.6 3632.3 3645.2 3649.3 1001.5 1058.8 1060.4 1069.8 1087.5 11190.9 11218.6 11363.3 11409.6 11443.7 4503.0 4548.9 4763.4 4764.5 4767.0 9727.6 9782.8 9788.2 9814.0 9838.5 5293.2 5314.7 5315.1 5321.5 5322.7 4369.5 4457.8 4547.3 4847.6 4878.5 3832.5 3874.3 3950.9 4001.8 4003.5 1261.4 1263.9 1266.0 1273.7 1280.4 836.5 861.5 908.3 924.6 932.5 5608.4 5750.9 5957.4 6043.8 6076.6 443.2 454.2 459.2 942.7 1010.7 3892.4 4034.7 4039.9 4045.2 4058.8 9989.9 10418.5 10633.6 10750.2 10753.1 10400.2 10483.1 10696.8 10700.3 10719.4 6323.4 7947.2 8071.0 8202.4 8257.8 5911.9 5955.3 6051.9 6149.7 6164.4 9949.2 9985.1 9989.0 10010.3 10012.9 8301.6 9529.3 10006.7 10118.5 10135.9 6822.7 7088.4 7264.6 7420.8 7582.1 8291.4 8409.2 8490.0 8494.4 8508.3 7323.3 7477.6 7540.9 7612.1 7613.5 6385.4 7235.5 7323.4 7400.4 7463.2 6967.4 6995.8 7068.5 7070.0 7082.7 4985.6 5003.7 5037.3 5044.2 5159.4 4847.8 4861.6 4961.5 5088.6 5121.3 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4451.43 Median:4810.91 stddev:1396.27 (29.0%) stddev-sans-first:1364.86 21:28:33 INFO - PROCESS | 1654 | Values: 293.1 302.2 310.6 314.7 317.3 4779.7 5252.8 5341.8 5477.8 5483.7 4533.0 5118.7 5313.3 5353.8 5388.7 3352.9 3495.9 4099.1 4138.4 4142.2 3101.7 3980.7 4132.6 4159.0 4169.6 3445.8 3729.5 3933.7 4022.7 4149.5 4093.1 4098.8 4104.5 4123.8 4128.0 3542.5 3547.6 3551.6 3553.9 3555.6 5037.3 5224.7 5270.7 5331.0 5337.3 3743.7 4667.6 4679.4 4696.6 4711.0 5218.9 5628.3 5650.6 5659.4 5666.8 3290.7 3563.0 3602.8 3930.7 3953.9 4845.2 5788.7 5824.4 5939.9 5968.6 3762.3 3805.4 3892.5 3914.6 3929.3 3921.8 3983.1 3998.4 4000.4 4000.7 4283.5 4356.8 4527.8 4538.5 4667.4 4673.8 4724.0 4920.5 5644.4 5673.7 1017.4 1107.9 1147.7 1329.4 1342.4 718.5 770.6 813.4 814.4 824.9 4057.3 4371.6 4397.5 4415.7 4421.9 4299.4 4347.9 4793.8 4859.4 4875.9 3917.5 4001.7 4351.6 4399.3 4411.3 2171.6 2725.6 2997.6 3038.6 3061.7 4980.9 5238.7 5251.9 5273.7 6001.9 5032.9 5660.7 5798.3 5940.4 5951.5 4554.1 5936.9 6021.0 6043.8 6096.9 5027.3 5120.8 5772.5 5977.5 5995.5 5556.6 5560.2 5628.7 5742.4 5812.7 5066.6 5463.0 5583.6 5781.4 6048.2 3800.3 3824.2 3911.9 3929.9 3971.9 4840.2 5866.7 5873.0 5960.2 5963.7 4692.6 5123.8 5683.8 5750.3 5824.8 4828.0 5315.3 5547.4 5603.5 5697.7 5559.7 5587.5 5608.3 5622.4 5665.2 4493.6 4571.1 4936.0 5612.0 5683.7 5518.6 5540.7 5553.8 5568.8 5618.9 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23509.22 Median:3648.65 stddev:37310.32 (1022.6%) stddev-sans-first:37375.45 21:28:33 INFO - PROCESS | 1654 | Values: 634.7 701.7 752.6 767.3 770.9 15317.5 15996.9 16040.8 16040.9 16077.3 14499.9 14611.2 14614.6 14656.9 14680.0 690.1 712.2 715.6 716.9 856.8 709.4 712.2 849.9 875.4 881.8 936.4 946.2 950.0 950.1 952.9 943.9 944.7 946.5 947.7 947.9 533.6 550.3 701.8 703.0 703.9 11545.4 11833.1 11839.0 14139.7 14707.0 13296.3 13851.4 14296.5 14794.7 15037.4 11379.8 11751.5 11988.1 12084.6 12379.6 3730.1 3744.7 3792.5 3814.6 3839.7 105595.0 106194.0 107292.0 107718.0 111083.9 10012.1 10303.5 10769.2 10856.3 11179.3 21794.7 21872.3 21886.2 21889.8 21900.6 6405.2 7778.1 7815.9 7965.8 8097.9 4287.4 4371.3 4384.5 4537.8 4554.0 3476.6 3623.5 3637.0 3639.1 3658.2 635.6 636.4 642.3 657.8 662.1 602.5 616.2 617.4 639.8 658.0 8348.0 9059.3 9618.4 9655.1 9701.5 635.6 664.9 675.9 679.7 691.7 4140.9 4530.0 4698.8 4706.8 4713.7 126466.4 131491.0 135271.0 148425.0 171778.0 108794.5 112972.0 119072.9 141920.0 147612.3 96013.0 99053.0 99476.0 103028.2 104558.5 9137.2 9374.9 9996.6 10021.4 10047.2 60053.0 60437.4 60934.7 61431.7 61714.7 55173.4 55252.4 73117.0 74672.0 74804.6 12783.5 12853.3 12857.0 12962.9 13316.3 14649.6 14738.9 14845.3 14853.6 15193.0 13459.1 13522.6 13558.3 13877.4 14007.6 13086.3 13462.4 13501.9 13659.1 13934.7 12856.4 13487.7 13531.4 13811.7 13841.4 10861.9 10874.5 11304.8 11340.4 11680.4 9344.0 9408.7 11207.6 11477.5 11553.1 21:28:33 INFO - PROCESS | 1654 | -------- Summary: end -------- 21:28:33 INFO - PROCESS | 1654 | 21:28:33 INFO - TEST-INFO | 1654: exit 0 21:28:33 INFO - TEST-OK | dromaeo_css | took 1119917ms 21:28:33 INFO - TEST-START | kraken 21:28:33 INFO - operating with platform_type : mac_ 21:28:33 INFO - Initialising browser for kraken test... 21:28:33 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpOj9PfM/profile http://localhost:49272/getInfo.html) 21:28:36 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 21:28:36 INFO - PROCESS | 1660 | colorDepth:24 21:28:36 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 21:28:36 INFO - PROCESS | 1660 | __metrics 21:28:36 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:28:36 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:28:37 INFO - TEST-INFO | 1660: exit 0 21:28:37 INFO - Browser initialized. 21:28:37 INFO - Running cycle 1/1 for kraken test... 21:28:37 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpOj9PfM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:49 INFO - PROCESS | 1661 | RSS: Main: 231448576 21:28:49 INFO - PROCESS | 1661 | 21:28:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 21:28:52 INFO - PROCESS | 1661 | RSS: Main: 290443264 21:28:52 INFO - PROCESS | 1661 | 21:28:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 21:28:56 INFO - PROCESS | 1661 | RSS: Main: 291028992 21:28:56 INFO - PROCESS | 1661 | 21:28:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 21:29:00 INFO - PROCESS | 1661 | RSS: Main: 384593920 21:29:00 INFO - PROCESS | 1661 | 21:29:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 21:29:02 INFO - PROCESS | 1661 | RSS: Main: 284856320 21:29:02 INFO - PROCESS | 1661 | 21:29:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:29:05 INFO - PROCESS | 1661 | RSS: Main: 289624064 21:29:05 INFO - PROCESS | 1661 | 21:29:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 21:29:08 INFO - PROCESS | 1661 | RSS: Main: 324411392 21:29:08 INFO - PROCESS | 1661 | 21:29:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 21:29:12 INFO - PROCESS | 1661 | RSS: Main: 327987200 21:29:12 INFO - PROCESS | 1661 | 21:29:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 21:29:15 INFO - PROCESS | 1661 | RSS: Main: 326881280 21:29:15 INFO - PROCESS | 1661 | 21:29:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:29:17 INFO - PROCESS | 1661 | RSS: Main: 302514176 21:29:17 INFO - PROCESS | 1661 | 21:29:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:29:20 INFO - PROCESS | 1661 | RSS: Main: 303849472 21:29:20 INFO - PROCESS | 1661 | 21:29:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:29:23 INFO - PROCESS | 1661 | RSS: Main: 308932608 21:29:23 INFO - PROCESS | 1661 | 21:29:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:29:27 INFO - PROCESS | 1661 | RSS: Main: 316428288 21:29:27 INFO - PROCESS | 1661 | 21:29:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:29:30 INFO - PROCESS | 1661 | RSS: Main: 316653568 21:29:30 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 21:29:32 INFO - PROCESS | 1661 | RSS: Main: 360239104 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | __start_tp_report 21:29:32 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 21:29:32 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 21:29:32 INFO - PROCESS | 1661 | |i|pagename|runs| 21:29:32 INFO - PROCESS | 1661 | |0;ai-astar;104;111;115;112;114;114;112;120;164;153 21:29:32 INFO - PROCESS | 1661 | |1;audio-beat-detection;148;129;141;179;114;112;108;112;143;113 21:29:32 INFO - PROCESS | 1661 | |2;audio-dft;183;186;181;185;186;180;179;183;183;186 21:29:32 INFO - PROCESS | 1661 | |3;audio-fft;76;60;59;87;60;59;59;64;110;73 21:29:32 INFO - PROCESS | 1661 | |4;audio-oscillator;88;70;94;70;68;69;91;67;73;119 21:29:32 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;87;85;89;85;88;84;88;85;87;84 21:29:32 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;126;147;100;94;94;94;94;94;94 21:29:32 INFO - PROCESS | 1661 | |7;imaging-desaturate;78;84;78;82;81;83;82;85;78;85 21:29:32 INFO - PROCESS | 1661 | |8;json-parse-financial;59;52;53;51;54;56;54;56;55;56 21:29:32 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;64;79;65;70;73;69;70;68;69;68 21:29:32 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;104;85;86;90;89;86;89;88;86;92 21:29:32 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;151;157;164;148;145;147;151;157;214;149 21:29:32 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;171;231;181;172;171;171;172;177;172;174 21:29:32 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;63;86;86;69;69;70;76;85;91;86 21:29:32 INFO - PROCESS | 1661 | __end_tp_report 21:29:32 INFO - PROCESS | 1661 | __start_cc_report 21:29:32 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1998 21:29:32 INFO - PROCESS | 1661 | __end_cc_report 21:29:32 INFO - PROCESS | 1661 | __startTimestamp1476851372862__endTimestamp 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | ------- Summary: start ------- 21:29:32 INFO - PROCESS | 1661 | Number of tests: 14 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:121.90 Median:114.50 stddev:19.86 (17.3%) stddev-sans-first:19.98 21:29:32 INFO - PROCESS | 1661 | Values: 104.0 111.0 115.0 112.0 114.0 114.0 112.0 120.0 164.0 153.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:135.00 stddev:22.83 (16.9%) stddev-sans-first:23.26 21:29:32 INFO - PROCESS | 1661 | Values: 148.0 129.0 141.0 179.0 114.0 112.0 108.0 112.0 143.0 113.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:2.57 (1.4%) stddev-sans-first:2.73 21:29:32 INFO - PROCESS | 1661 | Values: 183.0 186.0 181.0 185.0 186.0 180.0 179.0 183.0 183.0 186.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:70.70 Median:62.00 stddev:16.76 (27.0%) stddev-sans-first:17.67 21:29:32 INFO - PROCESS | 1661 | Values: 76.0 60.0 59.0 87.0 60.0 59.0 59.0 64.0 110.0 73.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:71.50 stddev:16.92 (23.7%) stddev-sans-first:17.75 21:29:32 INFO - PROCESS | 1661 | Values: 88.0 70.0 94.0 70.0 68.0 69.0 91.0 67.0 73.0 119.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.90 21:29:32 INFO - PROCESS | 1661 | Values: 87.0 85.0 89.0 85.0 88.0 84.0 88.0 85.0 87.0 84.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:94.00 stddev:18.44 (19.6%) stddev-sans-first:19.20 21:29:32 INFO - PROCESS | 1661 | Values: 93.0 126.0 147.0 100.0 94.0 94.0 94.0 94.0 94.0 94.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.50 stddev:2.80 (3.4%) stddev-sans-first:2.65 21:29:32 INFO - PROCESS | 1661 | Values: 78.0 84.0 78.0 82.0 81.0 83.0 82.0 85.0 78.0 85.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.50 stddev:2.32 (4.2%) stddev-sans-first:1.83 21:29:32 INFO - PROCESS | 1661 | Values: 59.0 52.0 53.0 51.0 54.0 56.0 54.0 56.0 55.0 56.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:4.20 (6.0%) stddev-sans-first:3.95 21:29:32 INFO - PROCESS | 1661 | Values: 64.0 79.0 65.0 70.0 73.0 69.0 70.0 68.0 69.0 68.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.50 stddev:5.54 (6.3%) stddev-sans-first:2.32 21:29:32 INFO - PROCESS | 1661 | Values: 104.0 85.0 86.0 90.0 89.0 86.0 89.0 88.0 86.0 92.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:154.00 stddev:20.39 (13.2%) stddev-sans-first:21.46 21:29:32 INFO - PROCESS | 1661 | Values: 151.0 157.0 164.0 148.0 145.0 147.0 151.0 157.0 214.0 149.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:173.00 stddev:18.49 (10.7%) stddev-sans-first:19.37 21:29:32 INFO - PROCESS | 1661 | Values: 171.0 231.0 181.0 172.0 171.0 171.0 172.0 177.0 172.0 174.0 21:29:32 INFO - PROCESS | 1661 | 21:29:32 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:85.50 stddev:9.80 (11.5%) stddev-sans-first:8.74 21:29:32 INFO - PROCESS | 1661 | Values: 63.0 86.0 86.0 69.0 69.0 70.0 76.0 85.0 91.0 86.0 21:29:32 INFO - PROCESS | 1661 | -------- Summary: end -------- 21:29:32 INFO - PROCESS | 1661 | 21:29:33 INFO - TEST-INFO | 1661: exit 0 21:29:33 INFO - TEST-OK | kraken | took 59867ms 21:29:33 INFO - SUITE-END | took 1179s 21:29:33 INFO - Completed test suite (00:19:39) 21:29:33 INFO - javascript benchmark 21:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.6042874855016, 3319.087251005973, 3331.5002749587566, 3344.097093784677, 3377.1725927147854, 4425.77871106445, 5598.216249725042, 5670.206171136034, 5746.9712651436785, 5760.0, 4644.5819876211135, 5685.317761868571, 5757.568182386327, 5767.279090113736, 5778.653280803144, 5104.081265372231, 5114.207297868837, 5129.102407078746, 5161.17421212606, 5166.793328266865, 5197.792088316482, 5294.2852714883575, 5295.576353891679, 5309.044372013036, 5328.6269961102735, 5095.235714642796, 5266.947330526684, 5275.366955965271, 5285.0, 5361.249425080492, 5147.382314122329, 5190.4031036430915, 5195.740212989335, 5197.0385478686185, 5197.246399272084, 5118.872028199287, 5143.794248230066, 5182.144946083891, 5188.351456067991, 5204.68771873685, 5625.268715067015, 5631.211630371751, 5636.830895073154, 5649.265595472603, 5666.914996275059, 6134.405972711579, 6152.169457123276, 6152.230971128609, 6162.859870923849, 6164.506839452833, 5426.538744206747, 6183.505319574469, 6188.84527886802, 6216.067589861512, 6224.688765561749, 5672.432756724335, 5740.339860915965, 5740.454656807555, 5743.195952566644, 5745.712714364265, 6198.101275315054, 6263.937360626335, 6267.122602835607, 6310.779122730774, 6328.145700330489, 6156.8768624627255, 6168.784092556739, 6172.6913654318005, 6204.844878878064, 6205.565610407319, 6139.631622102688, 6140.539459540552, 6143.204841796972, 6154.815355539341, 6256.343083976208, 6173.81478555644, 6216.098665693539, 6218.906716399258, 6223.719932602953, 6242.937570624326, 6204.317525072239, 6209.348018458088, 6225.439710426083, 6233.657148856766, 6242.219722534684, 4867.0, 4908.582770464551, 4928.0, 4930.704157750546, 4963.454020057863, 4272.594103560219, 4367.082912588328, 4376.277914144194, 4376.321670141133, 4388.71473354231, 5745.569082318842, 5760.971195143998, 5761.3950535194035, 5834.066549352084, 5849.444302791228, 6289.371062893674, 6294.65379404128, 6313.0, 6315.431611155018, 6332.430081292614, 5563.888722225533, 5690.430956904276, 5700.743466544015, 5729.312482502127, 5733.140028995601, 5402.91895621566, 5411.242426060355, 5412.864678382994, 5423.566114710892, 5424.349078110652, 5302.28419163408, 6340.619562826245, 6345.777897773556, 6351.079093531504, 6370.267419246846, 6128.014278093631, 6177.2587289525545, 6180.351063138485, 6192.133101365723, 6254.312025677263, 6292.874142517216, 6337.397947195009, 6343.777967771106, 6345.936540634534, 6370.203724534434, 6115.663638499747, 6174.413430724074, 6184.443400094013, 6189.783357582463, 6197.628142311476, 6126.27339965311, 6200.56596038291, 6285.43431091204, 6297.811065668037, 6312.242530896138, 6091.573589848846, 6111.19673459643, 6135.294441139326, 6176.0, 6190.690465476799, 5940.61386009908, 6112.694365281807, 6117.755289788359, 6138.723757431016, 6146.139540464249, 5782.2194003808945, 5888.234529511136, 5918.911216331845, 5964.970175149097, 5968.104784282393, 6068.7269072892705, 6080.4831589313635, 6101.701646049799, 6124.816255512342, 6154.599951003171, 5972.163897054329, 5973.342932277359, 5974.910376344272, 5976.462118409364, 6052.576319657582], "value": 5677.278772752935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.33205670429976, 510.3059838619478, 525.9526642602165, 527.4013994116676, 547.6522408270747, 24524.50950980979, 31955.84022079892, 40415.78752637422, 41673.58326416735, 41692.37461438081, 42371.36442953358, 42643.0, 42839.78580107095, 42885.35671964923, 42924.35613465793, 9351.532423378821, 9552.426854388725, 9574.856377154349, 9596.32825702201, 9603.423794572313, 9411.717648470556, 9499.620015199383, 9503.762405939873, 9522.190613797835, 9525.476098814563, 9464.1482266596, 9522.333436659437, 9523.523823808817, 9529.428234305964, 9544.093311135466, 9453.763655908588, 9507.857382139273, 9509.619615215452, 9515.0960658738, 9540.713778586618, 9382.90617093834, 9452.43285402878, 9465.52672366379, 9477.28920330978, 9494.762630934281, 40279.0, 40482.0, 40546.59453405458, 40618.796906015574, 40635.593644063774, 3398.04854640701, 3425.143714071482, 3513.6486351364915, 3606.8376923038527, 3617.981910090433, 30218.84890575555, 30286.0, 31379.84310078458, 31502.054938351885, 31841.203969900707, 3210.213497693057, 3233.4826427771445, 3243.9781468837477, 3364.4616861302075, 3368.5957685077947, 367308.0, 371583.0, 371610.1419492912, 372933.0, 373011.0, 33081.66918330834, 33830.094636278096, 57683.71158144182, 57832.0, 57965.0, 35359.4696079559, 35462.0, 35544.64455355465, 35557.288854222774, 35623.64376356255, 13808.585742427744, 14099.295035248197, 14181.865450763915, 14182.290885455686, 14211.36048877803, 12229.0, 12370.319632420124, 12401.00791936643, 12430.689232769253, 12431.06766992461, 6505.772297969548, 7512.248775122444, 7747.883781743279, 7803.063632364153, 7808.726694565777, 2944.4111177764535, 3317.502374643823, 3324.3517514085006, 3331.7501187410558, 3364.175776934667, 872.3631748823394, 960.2893858544627, 1019.9949000254951, 1027.2501074215863, 1051.9474026298653, 27086.729132708813, 29697.881722418835, 29765.553516697266, 30605.540916886264, 30835.537466938014, 1011.5397494140149, 1020.5458570936011, 1039.7608550033535, 1050.4380156616326, 1079.3577821196375, 14893.255337233095, 15398.384064637288, 15548.378064877279, 15744.212789360487, 15875.841241587666, 168901.0, 171185.0, 654614.0, 654964.0, 655590.0, 57093.42906570881, 57612.423875761546, 57732.0, 57805.13292300619, 57875.131873021935, 1232.6979889927024, 1288.3365066990507, 1343.5969209237385, 1388.9652758681113, 1404.3961096728506, 27068.0, 29139.708602914547, 30938.381232375676, 31347.529787053667, 31734.365312694077, 41557.37663935104, 48341.03317933692, 48740.29408970669, 48817.755911220214, 49032.75483622559, 510026.0, 513071.0, 513211.0, 513408.0, 514410.0, 18725.90637046806, 23705.40736481602, 25598.872005639852, 26170.345741356618, 26351.34121646974, 40514.79742601268, 44176.7791161055, 44778.77610611926, 45122.0, 45507.31739023851, 34691.9592412228, 37031.0, 37484.81257593694, 37917.67288144902, 38350.808245959706, 35798.93014003907, 37050.444243335834, 37711.0, 37830.0, 38677.22645547017, 35231.47152792761, 36004.4599331005, 36857.26285474222, 37101.44347834839, 37260.81369593135, 32509.512357314186, 33419.66580334165, 33564.16089597682, 33597.0, 33861.49207761935, 24170.758292417555, 24308.87845560761, 24539.754602453748, 24852.378690532158, 24985.87507062453], "value": 17915.801885207147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.13535079494903, 351.3482489981083, 367.5295621604346, 379.09207448161646, 403.3586597310278, 14949.775753363694, 15292.694146117072, 15837.762433563492, 19986.20055197794, 20333.898330508327, 19469.31857384992, 19702.507437314074, 20216.19135234589, 20474.283400081, 21064.789352106476, 5775.2203452534, 6094.329623741385, 6105.023196288595, 6113.847153821145, 6114.205153330061, 5056.393232812049, 6596.175478065242, 6652.767153149641, 6744.0, 6746.763863264787, 5669.971650141743, 5954.374790646984, 6541.378569035958, 6656.068150458939, 6691.431228345572, 5981.58128930975, 6021.157038014703, 6030.8190754277, 6033.306169790487, 6038.818835434944, 5096.51583099605, 5370.516653501204, 5419.214213938957, 5437.13005919051, 5818.359980402152, 13043.282619455926, 13087.934560327232, 13167.341632918316, 13305.800412993813, 13330.333483325794, 2082.5938941906334, 2154.353693891826, 2168.0, 2231.0183519251477, 2251.6960210371553, 9744.269179811472, 9798.0, 9822.508874556315, 10132.696019119438, 10246.743831404274, 1801.8198180182007, 1833.6149408624206, 1871.6631006418838, 1882.2376937340398, 1890.253349926785, 99533.5023324886, 110919.85527902587, 120213.0, 123509.0, 123724.14413783801, 11470.426478676032, 11498.655040348804, 11625.432694469057, 11657.067434605211, 11675.708107297216, 14286.499972500913, 14450.349734261983, 14470.855291446951, 14488.203148826915, 14688.485902993558, 6962.477814163856, 7395.708909535313, 8332.083470818205, 8382.580870956428, 8581.240845626631, 7540.17058123606, 7673.769786906401, 7997.960010200029, 8366.414350995372, 8507.574621268912, 4408.140412619508, 4512.255477846119, 4521.909561808811, 4586.036932244199, 4595.839145629887, 1674.824143464943, 1885.5191926059042, 1940.262700173925, 1941.223510595773, 1957.8237958583795, 882.7616543533338, 927.7958849053198, 972.8686627305224, 976.2482888176206, 985.7042887133831, 19670.11484483173, 21220.0, 21300.656927737935, 21361.144564766702, 21588.568228635653, 739.2681245566957, 875.0680525240598, 886.4636894678755, 945.2957546627806, 970.0445061614304, 10645.520951557199, 11107.333559986426, 11110.388928608843, 11130.443477826238, 11166.0, 580052.0, 582256.0, 592702.0, 598219.0, 598451.0, 92347.0, 99201.50399248103, 106611.0, 116318.4184079091, 119641.40179299048, 751.9097708275042, 923.9214666753401, 998.0468652436905, 998.960041598328, 1003.8193125237382, 19030.42908712741, 19230.750001249875, 19270.807291927464, 19325.710114348578, 19352.70970935409, 50975.49024509708, 54019.45980540144, 55242.72378638081, 55495.06311711036, 55720.6069848257, 74038.62980685061, 78040.0, 78126.6093669528, 78613.21386786288, 78831.0, 7834.569098699626, 7935.373248483931, 7945.682172713026, 8202.097769245498, 8229.382796290422, 9059.95810481803, 12525.874741252472, 12568.87431125677, 12765.744685106432, 12856.807147892976, 10637.734056648645, 10685.465726713477, 10949.83575246356, 11009.889901101207, 11045.668629941114, 10520.631777887736, 10550.366977981346, 10674.35953842772, 10961.287516311415, 11070.335779853234, 8813.0, 9956.755405574304, 10916.672499825017, 11121.721956951142, 11371.0, 7783.844323113392, 7901.367890568626, 7941.166177551274, 8136.918630813616, 8161.877571836546, 9491.762705932408, 10506.15950723925, 10875.933572621178, 10877.021068103908, 10974.670759877217], "value": 9211.246682999963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.1856306875823, 1528.9235538223086, 1758.3757765993073, 1848.0759620189906, 1927.4699457649144, 8361.874571881419, 8835.911640883589, 8847.11528847115, 8857.379983401164, 8870.822583548324, 7701.460897737161, 8034.959825200865, 8049.315808156299, 8305.418620696553, 8633.87049194261, 2253.1212826997444, 2300.229423143249, 2301.7928386445215, 2302.5625131225097, 2302.9424264393356, 2311.895964681585, 2339.801116905065, 2346.800521955627, 2348.013834189636, 2354.705661792276, 2296.850704704197, 2304.8040916522114, 2320.14154762738, 2339.6724458575814, 2346.6480027995854, 2320.8143348532244, 2326.6393708974965, 2328.1036800831716, 2328.5459335429596, 2328.6507023946374, 2284.394635421598, 2327.4065113396146, 2327.9650805237934, 2328.836981411302, 2332.0788288626068, 8746.56267186638, 8811.162939520735, 8814.427062240933, 8817.691380801705, 8832.249258813008, 5149.0989076911765, 5316.441773613754, 5329.893402131975, 5331.493508116761, 5342.332208473941, 3418.5897692276835, 3504.5969713483023, 3632.346177688015, 3645.198056427582, 3649.324874898126, 1001.5360095531138, 1058.7564860082148, 1060.3584831177104, 1069.786042791437, 1087.5160553553671, 11190.94404527972, 11218.607348742837, 11363.261388009752, 11409.60066397672, 11443.713907152305, 4502.977485112553, 4548.909021819545, 4763.428388593391, 4764.523547645208, 4766.976165119152, 9727.562259698188, 9782.804343913082, 9788.168005719594, 9813.950930245303, 9838.458884761263, 5293.153095504701, 5314.681119132864, 5315.149576067811, 5321.494458026481, 5322.733863306819, 4369.47566292047, 4457.821687132543, 4547.317902314613, 4847.636427267969, 4878.463369029404, 3832.5400951885954, 3874.302625527432, 3950.881473555798, 4001.799910004488, 4003.53959294679, 1261.426051146726, 1263.9256631862843, 1266.0439620785908, 1273.6752128207247, 1280.4494067550856, 836.4646626159263, 861.4529773593813, 908.2824568590871, 924.6347692661361, 932.5233626336633, 5608.355039170548, 5750.913736293959, 5957.434043765836, 6043.848903777441, 6076.605020673642, 443.22878191945784, 454.1530046463322, 459.21703495540027, 942.7218970403748, 1010.6563768318807, 3892.377219644843, 4034.7175697700877, 4039.8688367257214, 4045.1505183911117, 4058.776767277828, 9989.900100999042, 10418.479076046167, 10633.574657013633, 10750.193735469871, 10753.085987690978, 10400.167986561208, 10483.108935740394, 10696.78606427867, 10700.250982431311, 10719.410432426292, 6323.399277068671, 7947.165547617533, 8070.959645201854, 8202.38482113844, 8257.752267432106, 5911.935851546763, 5955.315138758925, 6051.939480605138, 6149.692515374303, 6164.445199932027, 9949.154321882721, 9985.101340879355, 9989.0, 10010.299279050396, 10012.948640392946, 8301.626426810688, 9529.285303602397, 10006.749831254278, 10118.520962363973, 10135.898641013791, 6822.692978815866, 7088.362047415757, 7264.600446975467, 7420.814479638052, 7582.128055273493, 8291.419600627898, 8409.159084091541, 8489.95755021221, 8494.405391622528, 8508.319334453352, 7323.340899319087, 7477.551346919202, 7540.924590754023, 7612.086549614081, 7613.505122167041, 6385.361463853763, 7235.529690570096, 7323.414126869944, 7400.370968467741, 7463.2163622818825, 6967.442604591722, 6995.755148569776, 7068.540544864567, 7070.010198572307, 7082.681279342545, 4985.551300382983, 5003.749812509287, 5037.319961805109, 5044.167712327571, 5159.432462429205, 4847.781849816696, 4861.562459378673, 4961.503849615154, 5088.59291256706, 5121.308623335802], "value": 4516.695243533528, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.14986539036806, 302.15999521330696, 310.58381768430297, 314.718327097248, 317.3399329394859, 4779.689320194185, 5252.793826592831, 5341.759620817063, 5477.75350109245, 5483.725813709313, 4532.97733511332, 5118.692878427297, 5313.256144139824, 5353.804939974601, 5388.703621300837, 3352.8826491072878, 3495.860165593373, 4099.098198396363, 4138.4067134153265, 4142.212979533894, 3101.736352410048, 3980.6616437602693, 4132.628063474287, 4158.9621085668105, 4169.6455801256925, 3445.846346033729, 3729.4778730977687, 3933.704972127088, 4022.7184097113213, 4149.46057012588, 4093.079057212133, 4098.7950602470055, 4104.486939132608, 4123.773192474398, 4128.0, 3542.494439863048, 3547.6452354764315, 3551.627079156703, 3553.875614353485, 3555.5555555555557, 5037.345145131109, 5224.660397074178, 5270.710110943896, 5330.974229792692, 5337.332833395825, 3743.6630703236838, 4667.556582124693, 4679.368285281512, 4696.577308042259, 4710.952890471086, 5218.947810521884, 5628.268325117709, 5650.576206784466, 5659.377468478464, 5666.829995100154, 3290.7367410607094, 3562.9643703562892, 3602.8018458984743, 3930.744501607415, 3953.853382519066, 4845.176320025604, 5788.652680839121, 5824.388439213906, 5939.910901336483, 5968.641881487081, 3762.3039737648623, 3805.372113601225, 3892.5328960524917, 3914.5693973662874, 3929.31237033518, 3921.843126274974, 3983.1237127831832, 3998.4402183694306, 4000.4199391088127, 4000.6999475038906, 4283.528811830696, 4356.825726970949, 4527.7736113194205, 4538.478075021415, 4667.439907211156, 4673.789679464432, 4723.976380118077, 4920.548438210736, 5644.407337229614, 5673.716314184274, 1017.4048181813574, 1107.8781334053247, 1147.6671765188169, 1329.4217015598053, 1342.375795255211, 718.5042320798632, 770.6185438208123, 813.4427916876958, 814.4421071565996, 824.884516167737, 4057.2553801865483, 4371.5846994536105, 4397.548808893058, 4415.690901636919, 4421.911561768811, 4299.366240828465, 4347.891287721674, 4793.818812440331, 4859.392575928009, 4875.926861097087, 3917.5494818095713, 4001.659858912025, 4351.586599273064, 4399.274119770266, 4411.316245981878, 2171.6416791229585, 2725.6184134221226, 2997.595324631147, 3038.5745995560637, 3061.6938306169204, 4980.850574482772, 5238.659487133325, 5251.92335571207, 5273.709945953009, 6001.899335232633, 5032.949670503322, 5660.660360378391, 5798.26052184343, 5940.376260492586, 5951.5238780897425, 4554.0891821635805, 5936.940630593639, 6021.006533921942, 6043.788467403708, 6096.908546371719, 5027.321311622884, 5120.820771272987, 5772.509336706259, 5977.462028417463, 5995.520358371407, 5556.583256255716, 5560.192102988127, 5628.718564071698, 5742.397048309867, 5812.651240925558, 5066.6440105351185, 5462.9726851365485, 5583.637063590853, 5781.421857814353, 6048.213732214784, 3800.315943130263, 3824.196918647115, 3911.941320880246, 3929.8582272660897, 3971.8808435746973, 4840.2255639098985, 5866.67733274674, 5872.970635146797, 5960.195373624679, 5963.671998040149, 4692.6480513960905, 5123.846284611468, 5683.801066962802, 5750.338711048283, 5824.796132135355, 4828.0, 5315.255864178941, 5547.389787123332, 5603.495685388335, 5697.692434564008, 5559.6664200148125, 5587.469190426903, 5608.270924779753, 5622.381538030895, 5665.178549110438, 4493.618042466427, 4571.085782843378, 4935.950640493549, 5611.971940140436, 5683.71581420919, 5518.592758846468, 5540.722963851711, 5553.805616803393, 5568.832935011957, 5618.915716264341], "value": 4000.5816470095356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.6798658789714, 701.715805098935, 752.5559919647409, 767.3209209849282, 770.8997830282062, 15317.463888763896, 15996.920015399935, 16040.759388609165, 16040.919795401007, 16077.276522556484, 14499.92750036251, 14611.196384198865, 14614.634634134152, 14656.853431465683, 14680.045797023186, 690.0649619765221, 712.2343480758179, 715.6421789105448, 716.8924661300821, 856.8029353248756, 709.4324540367711, 712.1987763765763, 849.8810166576684, 875.4134729731095, 881.775147337428, 936.3585943628602, 946.1957336264189, 949.9905000950006, 950.1448696173464, 952.861330709802, 943.9339246252765, 944.7307517357589, 946.5314669238702, 947.6777895515557, 947.9194268487153, 533.5624787674109, 550.3203543623622, 701.7578935267325, 703.0473708125519, 703.8908969109796, 11545.365004924726, 11833.053355731521, 11839.0, 14139.717205655828, 14707.044042137228, 13296.26870522121, 13851.376688049062, 14296.499622513162, 14794.704105917928, 15037.398504059824, 11379.829302560469, 11751.47118379675, 11988.120509064136, 12084.6374608761, 12379.566715165016, 3730.123420996064, 3744.7165263562383, 3792.5069740933777, 3814.618538146191, 3839.6928245740555, 105595.0, 106194.0, 107292.0, 107718.0, 111083.88916110896, 10012.098911098035, 10303.484825758682, 10769.192310576738, 10856.34861908288, 11179.329240245612, 21794.673079903812, 21872.343829685135, 21886.23398181056, 21889.781102188776, 21900.561988760135, 6405.231372235362, 7778.105517865405, 7815.882761758578, 7965.761027169194, 8097.919020809716, 4287.399764033038, 4371.344298355272, 4384.517703052662, 4537.75042372666, 4554.043650833362, 3476.5654293213347, 3623.528941237675, 3637.0, 3639.1084184375, 3658.1951970566433, 635.6122765113278, 636.4049613611288, 642.3415998601378, 657.8158115727604, 662.0565693886193, 602.4728362682653, 616.2204810914197, 617.4103730936945, 639.7536948274948, 657.9934200658028, 8347.95826020866, 9059.275257979345, 9618.355383031008, 9655.08276713711, 9701.514924253901, 635.6218050260088, 664.9251959154599, 675.9087523184307, 679.7281087565013, 691.7025678958096, 4140.896477588084, 4530.0, 4698.8355407560575, 4706.790887399389, 4713.670043097019, 126466.36766816291, 131491.0, 135271.0, 148425.0, 171778.0, 108794.45602771935, 112972.0, 119072.87880530252, 141920.0, 147612.26193868963, 96013.03986960041, 99053.0, 99476.0, 103028.2123198295, 104558.54414558483, 9137.177654011171, 9374.859377109486, 9996.550155243178, 10021.39871607706, 10047.246456515644, 60053.0, 60437.395626044934, 60934.695326523084, 61431.6928415373, 61714.69142654258, 55173.44826551844, 55252.447475526336, 73117.0, 74672.0, 74804.62597686976, 12783.48866045385, 12853.293068881301, 12857.0, 12962.944580657284, 13316.33418329099, 14649.631060268071, 14738.926305368404, 14845.33196006204, 14853.628659283604, 15192.961407718722, 13459.125156864773, 13522.565938130838, 13558.296509003872, 13877.444902203799, 14007.579772606838, 13086.345682716017, 13462.39496422109, 13501.864981350061, 13659.112157709716, 13934.651633709238, 12856.421461034462, 13487.662808429475, 13531.391087400894, 13811.723765524432, 13841.377138029015, 10861.891381086089, 10874.510647021061, 11304.773904521699, 11340.432978350884, 11680.357580332824, 9344.0, 9408.717738467856, 11207.551697931991, 11477.540898364306, 11553.13351498624], "value": 7659.380306372434, "unit": "score"}], "name": "dromaeo_css", "value": 7114.525032305941, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 115.0, 112.0, 114.0, 114.0, 112.0, 120.0, 164.0, 153.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 129.0, 141.0, 179.0, 114.0, 112.0, 108.0, 112.0, 143.0, 113.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 181.0, 185.0, 186.0, 180.0, 179.0, 183.0, 183.0, 186.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 60.0, 59.0, 87.0, 60.0, 59.0, 59.0, 64.0, 110.0, 73.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 94.0, 70.0, 68.0, 69.0, 91.0, 67.0, 73.0, 119.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 85.0, 88.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 126.0, 147.0, 100.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 78.0, 82.0, 81.0, 83.0, 82.0, 85.0, 78.0, 85.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 52.0, 53.0, 51.0, 54.0, 56.0, 54.0, 56.0, 55.0, 56.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 79.0, 65.0, 70.0, 73.0, 69.0, 70.0, 68.0, 69.0, 68.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 85.0, 86.0, 90.0, 89.0, 86.0, 89.0, 88.0, 86.0, 92.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 164.0, 148.0, 145.0, 147.0, 151.0, 157.0, 214.0, 149.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 231.0, 181.0, 172.0, 171.0, 171.0, 172.0, 177.0, 172.0, 174.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 86.0, 86.0, 69.0, 69.0, 70.0, 76.0, 85.0, 91.0, 86.0], "value": 78.1, "unit": "score"}], "name": "kraken", "value": 1486.6, "alertThreshold": 2.0}]} 21:29:34 INFO - Return code: 0 21:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:29:34 INFO - # TBPL SUCCESS # 21:29:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:29:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:29:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:29:34 INFO - 2016-10-18 21:29:34.151 screenresolution[1665:10162] starting screenresolution argv=screenresolution get 21:29:34 INFO - 2016-10-18 21:29:34.155 screenresolution[1665:10162] Display 0: 1600x1200x32@60 21:29:34 INFO - 2016-10-18 21:29:34.162 screenresolution[1666:10165] starting screenresolution argv=screenresolution list 21:29:34 INFO - Available Modes on Display 0 21:29:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:29:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:29:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:29:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:29:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:29:34 INFO - Intel Iris: 21:29:34 INFO - Chipset Model: Intel Iris 21:29:34 INFO - Type: GPU 21:29:34 INFO - Bus: Built-In 21:29:34 INFO - VRAM (Dynamic, Max): 1536 MB 21:29:34 INFO - Vendor: Intel (0x8086) 21:29:34 INFO - Device ID: 0x0a2e 21:29:34 INFO - Revision ID: 0x0009 21:29:34 INFO - Displays: 21:29:34 INFO - Display: 21:29:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:29:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:29:34 INFO - Display Serial Number: 1600x1200 60 21:29:34 INFO - Main Display: Yes 21:29:34 INFO - Mirror: Off 21:29:34 INFO - Online: Yes 21:29:34 INFO - Rotation: Supported 21:29:34 INFO - Return code: 0 21:29:34 INFO - Running post-action listener: _resource_record_post_action 21:29:34 INFO - [mozharness: 2016-10-19 04:29:34.404582Z] Finished run-tests step (success) 21:29:34 INFO - Running post-run listener: _resource_record_post_run 21:29:34 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 38556672; Write bytes: 708925440; Read time: 385; Write time: 1916 21:29:34 INFO - TinderboxPrint: CPU usage
53.5% 21:29:34 INFO - TinderboxPrint: I/O read bytes / time
38,556,672 / 385 21:29:34 INFO - TinderboxPrint: I/O write bytes / time
708,925,440 / 1,916 21:29:34 INFO - TinderboxPrint: CPU idle
2,261.3 (46.5%) 21:29:34 INFO - TinderboxPrint: CPU system
973.2 (20.0%) 21:29:34 INFO - TinderboxPrint: CPU user
1,627.3 (33.5%) 21:29:34 INFO - TinderboxPrint: Swap in / out
444,661,760 / 0 21:29:34 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397560320; Write bytes: 392239104; Read time: 29515; Write time: 733 21:29:34 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 36541440; Write bytes: 298311680; Read time: 363; Write time: 1143 21:29:34 INFO - Running post-run listener: _upload_blobber_files 21:29:34 INFO - Blob upload gear active. 21:29:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:29:34 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 21:29:34 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:29:34 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 21:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:36 INFO - (blobuploader) - INFO - Done attempting. 21:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:37 INFO - (blobuploader) - INFO - Done attempting. 21:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:29:40 INFO - (blobuploader) - INFO - Done attempting. 21:29:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:29:40 INFO - Return code: 0 21:29:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:29:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:29:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276e3003b008d88b7aa4c14d81adb04dc8ada908a9bde71bfd805a4651bffa0c228ec326b1eac8ee434c1edfbadcb3ff406e3b11e3b34fefb37a5c25b79dcb66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60147fc0865822a96dd807257cce8f232f52f76bcaf6203454dfbdb7de4ff04e7dbf38918b78d6b61ca350209003425f61164f438b0f39f6fe6ca94c1dc7262a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c5cae862e807b8668c1a2f35b8598d00f9aba783bda3bbb9d5ca4877ca633f9552e5e1905f5c20346525562630d31b59142cb6f196d8509766bc0d167317fb"} 21:29:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:29:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:29:40 INFO - Contents: 21:29:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276e3003b008d88b7aa4c14d81adb04dc8ada908a9bde71bfd805a4651bffa0c228ec326b1eac8ee434c1edfbadcb3ff406e3b11e3b34fefb37a5c25b79dcb66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60147fc0865822a96dd807257cce8f232f52f76bcaf6203454dfbdb7de4ff04e7dbf38918b78d6b61ca350209003425f61164f438b0f39f6fe6ca94c1dc7262a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c5cae862e807b8668c1a2f35b8598d00f9aba783bda3bbb9d5ca4877ca633f9552e5e1905f5c20346525562630d31b59142cb6f196d8509766bc0d167317fb"} 21:29:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:29:40 INFO - Copying logs to upload dir... 21:29:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:29:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.944949 ========= master_lag: 2.74 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-10-18 21:29:42.998919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 21:29:43.004573) ========= 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.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/Firefox/sha512/276e3003b008d88b7aa4c14d81adb04dc8ada908a9bde71bfd805a4651bffa0c228ec326b1eac8ee434c1edfbadcb3ff406e3b11e3b34fefb37a5c25b79dcb66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60147fc0865822a96dd807257cce8f232f52f76bcaf6203454dfbdb7de4ff04e7dbf38918b78d6b61ca350209003425f61164f438b0f39f6fe6ca94c1dc7262a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c5cae862e807b8668c1a2f35b8598d00f9aba783bda3bbb9d5ca4877ca633f9552e5e1905f5c20346525562630d31b59142cb6f196d8509766bc0d167317fb"} build_url:https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011024 build_url: 'https://queue.taskcluster.net/v1/task/PqTH_L8GR7-UvyawFBzw0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276e3003b008d88b7aa4c14d81adb04dc8ada908a9bde71bfd805a4651bffa0c228ec326b1eac8ee434c1edfbadcb3ff406e3b11e3b34fefb37a5c25b79dcb66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60147fc0865822a96dd807257cce8f232f52f76bcaf6203454dfbdb7de4ff04e7dbf38918b78d6b61ca350209003425f61164f438b0f39f6fe6ca94c1dc7262a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c5cae862e807b8668c1a2f35b8598d00f9aba783bda3bbb9d5ca4877ca633f9552e5e1905f5c20346525562630d31b59142cb6f196d8509766bc0d167317fb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 21:29:43.041590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:29:43.041918) ========= 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.rBvzkEV2s9/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.goifM67jYu/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005202 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 21:29:43.091540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 21:29:43.091845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 21:29:43.092137) ========= ========= Total master_lag: 2.87 =========