builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0014 starttime: 1484016123.71 results: success (0) buildid: 20170109165508 builduid: 31c1edda307f41ee95db2a6b86426d0c revision: ce55e4d276031458f0730d481acff05d7c797038 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.709427) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.709960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.710236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.737157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.737484) ========= 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.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 --2017-01-09 18:42:03-- 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% 12.0M=0.001s 2017-01-09 18:42:03 (12.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095635 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.857059) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.857367) ========= 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.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.026044 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:03.905574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 18:42:03.905977) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ce55e4d276031458f0730d481acff05d7c797038 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ce55e4d276031458f0730d481acff05d7c797038 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 2017-01-09 18:42:03,997 truncating revision to first 12 chars 2017-01-09 18:42:03,997 Setting DEBUG logging. 2017-01-09 18:42:03,997 attempt 1/10 2017-01-09 18:42:03,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ce55e4d27603?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 18:42:05,685 unpacking tar archive at: mozilla-beta-ce55e4d27603/testing/mozharness/ program finished with exit code 0 elapsedTime=1.963981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 18:42:05.893113) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:05.893507) ========= script_repo_revision: ce55e4d276031458f0730d481acff05d7c797038 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:05.893951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:05.894273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 18:42:05.914101) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-09 18:42:05.914385) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:06 INFO - MultiFileLogger online at 20170109 18:42:06 in /builds/slave/test 18:42:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:42:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:42:06 INFO - {'append_to_log': False, 18:42:06 INFO - 'base_work_dir': '/builds/slave/test', 18:42:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:42:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:42:06 INFO - 'branch': 'Mozilla-Beta', 18:42:06 INFO - 'buildbot_json_path': 'buildprops.json', 18:42:06 INFO - 'config_files': ('talos/mac_config.py',), 18:42:06 INFO - 'default_actions': ('clobber', 18:42:06 INFO - 'read-buildbot-config', 18:42:06 INFO - 'download-and-extract', 18:42:06 INFO - 'populate-webroot', 18:42:06 INFO - 'create-virtualenv', 18:42:06 INFO - 'install', 18:42:06 INFO - 'run-tests'), 18:42:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:42:06 INFO - 'download_minidump_stackwalk': True, 18:42:06 INFO - 'download_symbols': 'ondemand', 18:42:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:42:06 INFO - 'tooltool.py': '/tools/tooltool.py', 18:42:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:42:06 INFO - '/tools/misc-python/virtualenv.py')}, 18:42:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:42:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:42:06 INFO - 'installer_path': 'installer.exe', 18:42:06 INFO - 'log_level': 'info', 18:42:06 INFO - 'log_name': 'talos', 18:42:06 INFO - 'log_to_console': True, 18:42:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:42:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:42:06 INFO - 'opt_config_files': (), 18:42:06 INFO - 'pip_index': False, 18:42:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:42:06 INFO - 'cmd': ('bash', 18:42:06 INFO - '-c', 18:42:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:42:06 INFO - 'enabled': True, 18:42:06 INFO - 'halt_on_failure': False, 18:42:06 INFO - 'name': 'check_screen_resolution'},), 18:42:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:42:06 INFO - 'cmd': ('bash', 18:42:06 INFO - '-c', 18:42:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:42:06 INFO - 'enabled': True, 18:42:06 INFO - 'halt_on_failure': False, 18:42:06 INFO - 'name': 'check_screen_resolution'},), 18:42:06 INFO - 'run_cmd_checks_enabled': True, 18:42:06 INFO - 'sps_profile': False, 18:42:06 INFO - 'sps_profile_interval': 0, 18:42:06 INFO - 'suite': 'dromaeojs', 18:42:06 INFO - 'system_bits': '32', 18:42:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:42:06 INFO - 'title': 't-yosemite-r7-0014', 18:42:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:42:06 INFO - 'use_talos_json': True, 18:42:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:42:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:42:06 INFO - 'work_dir': 'build'} 18:42:06 INFO - [mozharness: 2017-01-10 02:42:06.114328Z] Running clobber step. 18:42:06 INFO - Running pre-action listener: _resource_record_pre_action 18:42:06 INFO - Running main action method: clobber 18:42:06 INFO - rmtree: /builds/slave/test/build 18:42:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:42:08 INFO - Running post-action listener: _resource_record_post_action 18:42:08 INFO - [mozharness: 2017-01-10 02:42:08.482068Z] Finished clobber step (success) 18:42:08 INFO - [mozharness: 2017-01-10 02:42:08.482199Z] Running read-buildbot-config step. 18:42:08 INFO - Running pre-action listener: _resource_record_pre_action 18:42:08 INFO - Running main action method: read_buildbot_config 18:42:08 INFO - Using buildbot properties: 18:42:08 INFO - { 18:42:08 INFO - "project": "", 18:42:08 INFO - "product": "firefox", 18:42:08 INFO - "script_repo_revision": "production", 18:42:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 18:42:08 INFO - "repository": "", 18:42:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 18:42:08 INFO - "buildid": "20170109165508", 18:42:08 INFO - "pgo_build": "False", 18:42:08 INFO - "basedir": "/builds/slave/test", 18:42:08 INFO - "buildnumber": 1, 18:42:08 INFO - "slavename": "t-yosemite-r7-0014", 18:42:08 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:42:08 INFO - "platform": "yosemite_r7", 18:42:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:42:08 INFO - "branch": "mozilla-beta", 18:42:08 INFO - "repo_path": "releases/mozilla-beta", 18:42:08 INFO - "revision": "ce55e4d276031458f0730d481acff05d7c797038", 18:42:08 INFO - "stage_platform": "macosx64", 18:42:08 INFO - "builduid": "31c1edda307f41ee95db2a6b86426d0c", 18:42:08 INFO - "slavebuilddir": "test" 18:42:08 INFO - } 18:42:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 18:42:08 INFO - Running post-action listener: _resource_record_post_action 18:42:08 INFO - [mozharness: 2017-01-10 02:42:08.485406Z] Finished read-buildbot-config step (success) 18:42:08 INFO - [mozharness: 2017-01-10 02:42:08.485495Z] Running download-and-extract step. 18:42:08 INFO - Running pre-action listener: _resource_record_pre_action 18:42:08 INFO - Running main action method: download_and_extract 18:42:08 INFO - mkdir: /builds/slave/test/build/tests 18:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:08 INFO - trying https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 18:42:08 INFO - Downloading https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 18:42:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 18:42:09 INFO - Downloaded 1165 bytes. 18:42:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 18:42:09 INFO - Using the following test package requirements: 18:42:09 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 18:42:09 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 18:42:09 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 18:42:09 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:42:09 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 18:42:09 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 18:42:09 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 18:42:09 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 18:42:09 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 18:42:09 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 18:42:09 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 18:42:09 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 18:42:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 18:42:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 18:42:09 INFO - Fetch https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 18:42:10 INFO - Expected file size: 16234494 18:42:17 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 18:42:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 18:42:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 18:42:17 INFO - Fetch https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 18:42:18 INFO - Expected file size: 16234494 18:42:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 18:42:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 18:42:24 INFO - Fetch https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 18:42:25 INFO - Expected file size: 14414089 18:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:26 INFO - trying https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 18:42:26 INFO - Downloading https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 18:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 18:42:28 INFO - Downloaded 86139440 bytes. 18:42:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 18:42:28 INFO - mkdir: /builds/slave/test/properties 18:42:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:42:28 INFO - Writing to file /builds/slave/test/properties/build_url 18:42:28 INFO - Contents: 18:42:28 INFO - build_url:https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 18:42:29 INFO - Running post-action listener: _resource_record_post_action 18:42:29 INFO - Running post-action listener: set_extra_try_arguments 18:42:29 INFO - [mozharness: 2017-01-10 02:42:29.427635Z] Finished download-and-extract step (success) 18:42:29 INFO - [mozharness: 2017-01-10 02:42:29.427757Z] Running populate-webroot step. 18:42:29 INFO - Running pre-action listener: _resource_record_pre_action 18:42:29 INFO - Running main action method: populate_webroot 18:42:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:29 INFO - '--tppagecycles', 18:42:29 INFO - '7'], 18:42:29 INFO - 'tests': ['tsvgm']}}, 18:42:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:42:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['damp', 'tps']}, 18:42:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'tests': ['damp', 'tps']}, 18:42:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['dromaeo_dom']}, 18:42:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['basic_compositor_video']}, 18:42:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:42:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['a11yr', 18:42:29 INFO - 'ts_paint', 18:42:29 INFO - 'tpaint', 18:42:29 INFO - 'sessionrestore', 18:42:29 INFO - 'sessionrestore_no_auto_restore', 18:42:29 INFO - 'tabpaint']}, 18:42:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['tsvgx', 18:42:29 INFO - 'tsvgr_opacity', 18:42:29 INFO - 'tart', 18:42:29 INFO - 'tscrollx', 18:42:29 INFO - 'cart']}, 18:42:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:29 INFO - 'tsvgr_opacity', 18:42:29 INFO - 'tart', 18:42:29 INFO - 'tscrollx', 18:42:29 INFO - 'cart']}, 18:42:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'talos_options': ['--disable-e10s'], 18:42:29 INFO - 'tests': ['tp5o']}, 18:42:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'tests': ['tp5o']}, 18:42:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'talos_options': ['--disable-e10s', 18:42:29 INFO - '--xperf_path', 18:42:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:29 INFO - 'tests': ['tp5n']}, 18:42:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:29 INFO - 'talos_options': ['--xperf_path', 18:42:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:29 INFO - 'tests': ['tp5n']}}, 18:42:29 INFO - 'talos.zip': {'path': '', 18:42:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:29 INFO - Running post-action listener: _resource_record_post_action 18:42:29 INFO - [mozharness: 2017-01-10 02:42:29.447280Z] Finished populate-webroot step (success) 18:42:29 INFO - [mozharness: 2017-01-10 02:42:29.447395Z] Running create-virtualenv step. 18:42:29 INFO - Running pre-action listener: _resource_record_pre_action 18:42:29 INFO - Running main action method: create_virtualenv 18:42:29 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:29 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 18:42:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:29 INFO - Using real prefix '/tools/python27' 18:42:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:30 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:33 INFO - Installing pip.................done. 18:42:33 INFO - Return code: 0 18:42:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:33 INFO - 'HOME': '/Users/cltbld', 18:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:33 INFO - 'LOGNAME': 'cltbld', 18:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:33 INFO - 'MOZ_NO_REMOTE': '1', 18:42:33 INFO - 'NO_EM_RESTART': '1', 18:42:33 INFO - 'PAGER': '/bin/cat', 18:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:33 INFO - 'PWD': '/builds/slave/test', 18:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:33 INFO - 'SHELL': '/bin/bash', 18:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:33 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:33 INFO - 'USER': 'cltbld', 18:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:33 INFO - 'XPC_FLAGS': '0x0', 18:42:33 INFO - 'XPC_SERVICE_NAME': '0', 18:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:33 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 18:42:33 INFO - Cleaning up... 18:42:33 INFO - Return code: 0 18:42:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:33 INFO - 'HOME': '/Users/cltbld', 18:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:33 INFO - 'LOGNAME': 'cltbld', 18:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:33 INFO - 'MOZ_NO_REMOTE': '1', 18:42:33 INFO - 'NO_EM_RESTART': '1', 18:42:33 INFO - 'PAGER': '/bin/cat', 18:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:33 INFO - 'PWD': '/builds/slave/test', 18:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:33 INFO - 'SHELL': '/bin/bash', 18:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:33 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:33 INFO - 'USER': 'cltbld', 18:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:33 INFO - 'XPC_FLAGS': '0x0', 18:42:33 INFO - 'XPC_SERVICE_NAME': '0', 18:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:33 INFO - Downloading/unpacking psutil>=3.1.1 18:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:36 INFO - Installing collected packages: psutil 18:42:36 INFO - Running setup.py install for psutil 18:42:36 INFO - building 'psutil._psutil_osx' extension 18:42:36 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 18:42:37 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 18:42:37 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 18:42:37 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 18:42:37 INFO - building 'psutil._psutil_posix' extension 18:42:37 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 18:42:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:42:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:42:37 INFO - ^ 18:42:37 INFO - 1 warning generated. 18:42:37 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 18:42:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:38 INFO - Successfully installed psutil 18:42:38 INFO - Cleaning up... 18:42:38 INFO - Return code: 0 18:42:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:38 INFO - 'HOME': '/Users/cltbld', 18:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:38 INFO - 'LOGNAME': 'cltbld', 18:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:38 INFO - 'MOZ_NO_REMOTE': '1', 18:42:38 INFO - 'NO_EM_RESTART': '1', 18:42:38 INFO - 'PAGER': '/bin/cat', 18:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:38 INFO - 'PWD': '/builds/slave/test', 18:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:38 INFO - 'SHELL': '/bin/bash', 18:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:38 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:38 INFO - 'USER': 'cltbld', 18:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:38 INFO - 'XPC_FLAGS': '0x0', 18:42:38 INFO - 'XPC_SERVICE_NAME': '0', 18:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:42:41 INFO - Installing collected packages: mozsystemmonitor 18:42:41 INFO - Running setup.py install for mozsystemmonitor 18:42:41 INFO - Successfully installed mozsystemmonitor 18:42:41 INFO - Cleaning up... 18:42:41 INFO - Return code: 0 18:42:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:42:41 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')]} 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:41 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')]} 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:41 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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:41 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 18:42:41 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 18:42:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:41 INFO - 'HOME': '/Users/cltbld', 18:42:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:41 INFO - 'LOGNAME': 'cltbld', 18:42:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:41 INFO - 'MOZ_NO_REMOTE': '1', 18:42:41 INFO - 'NO_EM_RESTART': '1', 18:42:41 INFO - 'PAGER': '/bin/cat', 18:42:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:41 INFO - 'PWD': '/builds/slave/test', 18:42:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:41 INFO - 'SHELL': '/bin/bash', 18:42:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:41 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:41 INFO - 'USER': 'cltbld', 18:42:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:41 INFO - 'XPC_FLAGS': '0x0', 18:42:41 INFO - 'XPC_SERVICE_NAME': '0', 18:42:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:41 INFO - Downloading/unpacking jsonschema==2.5.1 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:44 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:44 INFO - warning: no files found matching '*.txt' 18:42:44 INFO - no previously-included directories found matching 'build' 18:42:44 INFO - no previously-included directories found matching 'dist' 18:42:44 INFO - no previously-included directories found matching '.git*' 18:42:44 INFO - Installing collected packages: jsonschema, functools32 18:42:44 INFO - Running setup.py install for functools32 18:42:44 INFO - warning: no files found matching '*.txt' 18:42:44 INFO - no previously-included directories found matching 'build' 18:42:44 INFO - no previously-included directories found matching 'dist' 18:42:44 INFO - no previously-included directories found matching '.git*' 18:42:44 INFO - Successfully installed jsonschema functools32 18:42:44 INFO - Cleaning up... 18:42:44 INFO - Return code: 0 18:42:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:44 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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:44 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 18:42:44 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 18:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:44 INFO - 'HOME': '/Users/cltbld', 18:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:44 INFO - 'LOGNAME': 'cltbld', 18:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:44 INFO - 'MOZ_NO_REMOTE': '1', 18:42:44 INFO - 'NO_EM_RESTART': '1', 18:42:44 INFO - 'PAGER': '/bin/cat', 18:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:44 INFO - 'PWD': '/builds/slave/test', 18:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:44 INFO - 'SHELL': '/bin/bash', 18:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:44 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:44 INFO - 'USER': 'cltbld', 18:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:44 INFO - 'XPC_FLAGS': '0x0', 18:42:44 INFO - 'XPC_SERVICE_NAME': '0', 18:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:42:45 INFO - Cleaning up... 18:42:45 INFO - Return code: 0 18:42:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:42:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:45 INFO - 'HOME': '/Users/cltbld', 18:42:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:45 INFO - 'LOGNAME': 'cltbld', 18:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:45 INFO - 'MOZ_NO_REMOTE': '1', 18:42:45 INFO - 'NO_EM_RESTART': '1', 18:42:45 INFO - 'PAGER': '/bin/cat', 18:42:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:45 INFO - 'PWD': '/builds/slave/test', 18:42:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:45 INFO - 'SHELL': '/bin/bash', 18:42:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:45 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:45 INFO - 'USER': 'cltbld', 18:42:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:45 INFO - 'XPC_FLAGS': '0x0', 18:42:45 INFO - 'XPC_SERVICE_NAME': '0', 18:42:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:45 INFO - Downloading/unpacking blobuploader==1.2.4 18:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:49 INFO - Downloading docopt-0.6.1.tar.gz 18:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:49 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:49 INFO - Running setup.py install for blobuploader 18:42:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:49 INFO - Running setup.py install for requests 18:42:50 INFO - Running setup.py install for docopt 18:42:50 INFO - Successfully installed blobuploader requests docopt 18:42:50 INFO - Cleaning up... 18:42:50 INFO - Return code: 0 18:42:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:50 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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:50 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 18:42:50 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 18:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:50 INFO - 'HOME': '/Users/cltbld', 18:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:50 INFO - 'LOGNAME': 'cltbld', 18:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:50 INFO - 'MOZ_NO_REMOTE': '1', 18:42:50 INFO - 'NO_EM_RESTART': '1', 18:42:50 INFO - 'PAGER': '/bin/cat', 18:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:50 INFO - 'PWD': '/builds/slave/test', 18:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:50 INFO - 'SHELL': '/bin/bash', 18:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:50 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:50 INFO - 'USER': 'cltbld', 18:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:50 INFO - 'XPC_FLAGS': '0x0', 18:42:50 INFO - 'XPC_SERVICE_NAME': '0', 18:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:50 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-P4bUEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:50 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-1EoIYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:50 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-lAy5zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:50 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-i1LjAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:50 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-uIGbd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-9_ucvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-mdSd8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-n9bDY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-cy5pT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-qOY6ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-qDihqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-xJu7U4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-QwxJET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-0L4DLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-owW61v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-WjqX4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-PDtEbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:52 INFO - Running setup.py install for manifestparser 18:42:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:52 INFO - Running setup.py install for mozcrash 18:42:52 INFO - Running setup.py install for mozdebug 18:42:52 INFO - Running setup.py install for mozdevice 18:42:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:42:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:52 INFO - Running setup.py install for mozfile 18:42:53 INFO - Running setup.py install for mozhttpd 18:42:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Running setup.py install for mozinfo 18:42:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Running setup.py install for mozInstall 18:42:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Running setup.py install for mozleak 18:42:53 INFO - Running setup.py install for mozlog 18:42:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Running setup.py install for moznetwork 18:42:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:53 INFO - Running setup.py install for mozprocess 18:42:54 INFO - Running setup.py install for mozprofile 18:42:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:54 INFO - Running setup.py install for mozrunner 18:42:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:54 INFO - Running setup.py install for mozscreenshot 18:42:54 INFO - Running setup.py install for moztest 18:42:54 INFO - Running setup.py install for mozversion 18:42:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:54 INFO - Cleaning up... 18:42:54 INFO - Return code: 0 18:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:42:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:54 INFO - 'HOME': '/Users/cltbld', 18:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:54 INFO - 'LOGNAME': 'cltbld', 18:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:54 INFO - 'MOZ_NO_REMOTE': '1', 18:42:54 INFO - 'NO_EM_RESTART': '1', 18:42:54 INFO - 'PAGER': '/bin/cat', 18:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:54 INFO - 'PWD': '/builds/slave/test', 18:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:54 INFO - 'SHELL': '/bin/bash', 18:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:42:54 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:54 INFO - 'USER': 'cltbld', 18:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:54 INFO - 'XPC_FLAGS': '0x0', 18:42:54 INFO - 'XPC_SERVICE_NAME': '0', 18:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-I__ezc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:55 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)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-8EIkV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:55 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)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-usBYuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-tpngEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-lnJVfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-BBIeE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ui2DgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:55 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Q177N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-uuyLHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-M9eAzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-t4wWCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-i2ICc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GDssed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-v6qumu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:56 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)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-rsYQA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-DFT2X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:56 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Tk_YU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:42:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:59 INFO - Downloading blessings-1.6.tar.gz 18:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:59 INFO - Installing collected packages: blessings 18:42:59 INFO - Running setup.py install for blessings 18:42:59 INFO - Successfully installed blessings 18:42:59 INFO - Cleaning up... 18:42:59 INFO - Return code: 0 18:42:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:43:00 INFO - Reading from file tmpfile_stdout 18:43:00 INFO - Current package versions: 18:43:00 INFO - blessings == 1.6 18:43:00 INFO - blobuploader == 1.2.4 18:43:00 INFO - docopt == 0.6.1 18:43:00 INFO - functools32 == 3.2.3-2 18:43:00 INFO - jsonschema == 2.5.1 18:43:00 INFO - manifestparser == 1.1 18:43:00 INFO - mozInstall == 1.12 18:43:00 INFO - mozcrash == 0.17 18:43:00 INFO - mozdebug == 0.1 18:43:00 INFO - mozdevice == 0.48 18:43:00 INFO - mozfile == 1.2 18:43:00 INFO - mozhttpd == 0.7 18:43:00 INFO - mozinfo == 0.9 18:43:00 INFO - mozleak == 0.1 18:43:00 INFO - mozlog == 3.4 18:43:00 INFO - moznetwork == 0.27 18:43:00 INFO - mozprocess == 0.23 18:43:00 INFO - mozprofile == 0.28 18:43:00 INFO - mozrunner == 6.12 18:43:00 INFO - mozscreenshot == 0.1 18:43:00 INFO - mozsystemmonitor == 0.3 18:43:00 INFO - moztest == 0.7 18:43:00 INFO - mozversion == 1.4 18:43:00 INFO - psutil == 3.1.1 18:43:00 INFO - requests == 1.2.3 18:43:00 INFO - wsgiref == 0.1.2 18:43:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:43:00 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')]} 18:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:43:00 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')]} 18:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:43:00 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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:43:00 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 18:43:00 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 18:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:43:00 INFO - 'HOME': '/Users/cltbld', 18:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:43:00 INFO - 'LOGNAME': 'cltbld', 18:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:00 INFO - 'MOZ_NO_REMOTE': '1', 18:43:00 INFO - 'NO_EM_RESTART': '1', 18:43:00 INFO - 'PAGER': '/bin/cat', 18:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:00 INFO - 'PWD': '/builds/slave/test', 18:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:43:00 INFO - 'SHELL': '/bin/bash', 18:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:43:00 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:43:00 INFO - 'USER': 'cltbld', 18:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:00 INFO - 'XPC_FLAGS': '0x0', 18:43:00 INFO - 'XPC_SERVICE_NAME': '0', 18:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 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)) 18:43:01 INFO - Cleaning up... 18:43:01 INFO - Return code: 0 18:43:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:43:01 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')]} 18:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:43:01 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')]} 18:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:43:01 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 0x10c19e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c114570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c68cce430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, '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.85lDicNsLR/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.pG7SQvja4R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 18:43:01 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 18:43:01 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 18:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:43:01 INFO - 'HOME': '/Users/cltbld', 18:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:43:01 INFO - 'LOGNAME': 'cltbld', 18:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:01 INFO - 'MOZ_NO_REMOTE': '1', 18:43:01 INFO - 'NO_EM_RESTART': '1', 18:43:01 INFO - 'PAGER': '/bin/cat', 18:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:01 INFO - 'PWD': '/builds/slave/test', 18:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:43:01 INFO - 'SHELL': '/bin/bash', 18:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:43:01 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:43:01 INFO - 'USER': 'cltbld', 18:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:01 INFO - 'XPC_FLAGS': '0x0', 18:43:01 INFO - 'XPC_SERVICE_NAME': '0', 18:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:43:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:43:01 INFO - Cleaning up... 18:43:01 INFO - Return code: 0 18:43:01 INFO - Running post-action listener: _resource_record_post_action 18:43:01 INFO - Running post-action listener: _start_resource_monitoring 18:43:01 INFO - Starting resource monitoring. 18:43:01 INFO - [mozharness: 2017-01-10 02:43:01.427978Z] Finished create-virtualenv step (success) 18:43:01 INFO - [mozharness: 2017-01-10 02:43:01.428908Z] Running install step. 18:43:01 INFO - Running pre-action listener: _resource_record_pre_action 18:43:01 INFO - Running main action method: install 18:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:43:01 INFO - Reading from file tmpfile_stdout 18:43:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:43:01 INFO - Reading from file tmpfile_stdout 18:43:01 INFO - Output received: 18:43:01 INFO - Usage: mozinstall [options] installer 18:43:01 INFO - Options: 18:43:01 INFO - -h, --help show this help message and exit 18:43:01 INFO - -d DEST, --destination=DEST 18:43:01 INFO - Directory to install application into. [default: 18:43:01 INFO - "/builds/slave/test"] 18:43:01 INFO - --app=APP Application being installed. [default: firefox] 18:43:01 INFO - mkdir: /builds/slave/test/build/application 18:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 18:43:23 INFO - Reading from file tmpfile_stdout 18:43:23 INFO - Output received: 18:43:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:43:23 INFO - Running post-action listener: _resource_record_post_action 18:43:23 INFO - [mozharness: 2017-01-10 02:43:23.623460Z] Finished install step (success) 18:43:23 INFO - [mozharness: 2017-01-10 02:43:23.623593Z] Running run-tests step. 18:43:23 INFO - Running pre-action listener: _resource_record_pre_action 18:43:23 INFO - Running main action method: run_tests 18:43:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:43:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:43:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:43:23 INFO - 2017-01-09 18:43:23.638 screenresolution[1629:7353] starting screenresolution argv=screenresolution get 18:43:23 INFO - 2017-01-09 18:43:23.640 screenresolution[1629:7353] Display 0: 1600x1200x32@60 18:43:23 INFO - 2017-01-09 18:43:23.646 screenresolution[1630:7356] starting screenresolution argv=screenresolution list 18:43:23 INFO - Available Modes on Display 0 18:43:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:43:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:43:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:43:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:43:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:43:23 INFO - Intel Iris: 18:43:23 INFO - Chipset Model: Intel Iris 18:43:23 INFO - Type: GPU 18:43:23 INFO - Bus: Built-In 18:43:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:43:23 INFO - Vendor: Intel (0x8086) 18:43:23 INFO - Device ID: 0x0a2e 18:43:23 INFO - Revision ID: 0x0009 18:43:23 INFO - Displays: 18:43:23 INFO - Display: 18:43:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:43:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:43:23 INFO - Display Serial Number: 1600x1200 60 18:43:23 INFO - Main Display: Yes 18:43:23 INFO - Mirror: Off 18:43:23 INFO - Online: Yes 18:43:23 INFO - Rotation: Supported 18:43:23 INFO - Return code: 0 18:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:43:23 INFO - Python 2.7.3 18:43:23 INFO - Return code: 0 18:43:23 INFO - grabbing minidump binary from tooltool 18:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:43:23 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 0x10bfb2d30>, '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 0x10be18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:43:23 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 18:43:23 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 18:43:23 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 18:43:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:43:24 INFO - Return code: 0 18:43:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:43:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:43:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:43:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:43:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 18:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 18:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.85lDicNsLR/Render', 18:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:43:24 INFO - 'HOME': '/Users/cltbld', 18:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:43:24 INFO - 'LOGNAME': 'cltbld', 18:43:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:24 INFO - 'MOZ_NO_REMOTE': '1', 18:43:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:24 INFO - 'NO_EM_RESTART': '1', 18:43:24 INFO - 'PAGER': '/bin/cat', 18:43:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:24 INFO - 'PWD': '/builds/slave/test', 18:43:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:43:24 INFO - 'SHELL': '/bin/bash', 18:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pG7SQvja4R/Listeners', 18:43:24 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 18:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:43:24 INFO - 'USER': 'cltbld', 18:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:24 INFO - 'XPC_FLAGS': '0x0', 18:43:24 INFO - 'XPC_SERVICE_NAME': '0', 18:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:43:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 18:43:24 INFO - mozversion application_buildid: 20170109165508 18:43:24 INFO - mozversion application_changeset: ce55e4d276031458f0730d481acff05d7c797038 18:43:24 INFO - mozversion application_display_name: Firefox 18:43:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:43:24 INFO - mozversion application_name: Firefox 18:43:24 INFO - mozversion application_remotingname: firefox 18:43:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:43:24 INFO - mozversion application_vendor: Mozilla 18:43:24 INFO - mozversion application_version: 51.0 18:43:24 INFO - mozversion platform_buildid: 20170109165508 18:43:24 INFO - mozversion platform_changeset: ce55e4d276031458f0730d481acff05d7c797038 18:43:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:43:24 INFO - mozversion platform_version: 51.0 18:43:24 INFO - using testdate: 1484016204 18:43:24 INFO - actual date: 1484016204 18:43:24 INFO - starting webserver on 'localhost:49240' 18:43:24 INFO - SUITE-START | Running 2 tests 18:43:24 INFO - TEST-START | dromaeo_css 18:43:24 INFO - operating with platform_type : mac_ 18:43:24 INFO - Initialising browser for dromaeo_css test... 18:43:24 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmprG4iUf/profile http://localhost:49240/getInfo.html) 18:43:26 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 18:43:26 INFO - PROCESS | 1640 | colorDepth:24 18:43:26 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 18:43:26 INFO - PROCESS | 1640 | __metrics 18:43:26 INFO - PROCESS | 1640 | 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" 18:43:26 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:27 INFO - TEST-INFO | 1640: exit 0 18:43:27 INFO - Browser initialized. 18:43:27 INFO - Running cycle 1/1 for dromaeo_css test... 18:43:27 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmprG4iUf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:39 INFO - PROCESS | 1643 | RSS: Main: 169046016 18:43:39 INFO - PROCESS | 1643 | 18:44:31 INFO - PROCESS | 1643 | 1484016271590 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] 18:46:42 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 18:46:43 INFO - PROCESS | 1643 | RSS: Main: 294518784 18:46:43 INFO - PROCESS | 1643 | 18:49:46 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 18:49:46 INFO - PROCESS | 1643 | RSS: Main: 302379008 18:49:46 INFO - PROCESS | 1643 | 18:52:49 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 18:52:49 INFO - PROCESS | 1643 | RSS: Main: 297312256 18:52:49 INFO - PROCESS | 1643 | 18:55:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 18:55:52 INFO - PROCESS | 1643 | RSS: Main: 280928256 18:55:52 INFO - PROCESS | 1643 | 18:58:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 18:58:56 INFO - PROCESS | 1643 | RSS: Main: 262959104 18:58:56 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 19:01:59 INFO - PROCESS | 1643 | RSS: Main: 313331712 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | __start_tp_report 19:01:59 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 19:01:59 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 19:01:59 INFO - PROCESS | 1643 | |i|pagename|runs| 19:01:59 INFO - PROCESS | 1643 | |0;dojo.html;3455.9308813823723;3456.498807672887;3466.388129519771;3504.5444092268035;3530.505729197913;5743.224664670268;5818.06910894257;5826.854328641786;5837.8832423351505;5843.444872737083;4681.344611754357;5696.572757043228;5758.510526605233;5761.020626493496;5854.560907931911;4475.440569928767;5292.391374991867;5307.65500242483;5352.090144675414;5354.330708661418;5250.9474905250845;5255.842324730264;5263.684178949256;5360.061989151903;5373.086575282192;5354.089804733205;5354.518093371616;5362.544183744367;5363.490468405495;5373.301470808789;5310.362756469229;5343.625946183749;5351.170568561879;5354.330708661418;5372.7045012524295;5179.922301165486;5213.426523082458;5258.13240815265;5316.4683531646915;5321.866953326198;4886.364772579577;5659.773609055633;5853.85365365865;5857.736401861884;5903.321118071433;6328.272248691414;6345.206849143857;6349.238091429012;6352.872942541123;6365.981442969102;5206.271122042917;6216.595921265103;6219.502439804804;6278.937210627881;6281.528885333618;5823.67969761663;5851.707414629252;5851.736671849777;5860.09168578871;5860.619059761145;6376.617402955838;6384.84037899047;6395.552311338161;6400.775972840928;6401.839954001094;6081.996470582304;6139.009643731611;6195.349488303754;6209.59637623553;6263.467605253604;6292;6337.714802833883;6338.366163383625;6341.746330146835;6346.809595712136;6303.716332765037;6306.716197771111;6315.55791094619;6326.430621244018;6328.525360597881;6147.815565533041;6150.138980542728;6223.284322302902;6285.371462853769;6315.400036996478;4993;5023.2214006829;5035.370578677665;5052.898942021139;5089.567386772166;4458.71018383804;4469.41897553316;4472.08322293929;4479.126570318756;4515.661325400608;5877.089051197071;5984.461398474158;6005.549583781147;6012.128241405059;6030.03519436888;6141.30190404341;6266;6270.216222972129;6314.147590075373;6341.143945567382;5996.970015149984;6007.459328660441;6008.158857759917;6014.819555413257;6045.214122164179;5489.615726899079;5498.367687715964;5503.559715222773;5506.697131657717;5508.586855985737;6250.499960003098;6302.086197501429;6364.140840986408;6383.9361606383345;6397.360263973565;6295.496360291258;6304.779332723333;6332.620042797447;6333.588316759396;6342.23893132827;6446.226452825691;6451.838704032437;6454;6458.160439142881;6459.6770161490795;6145.647957449354;6208.348123447154;6225.937740622717;6292.244930608356;6334.303226645157;6276.6547839869245;6309.747610095545;6319.24169099711;6354.809355719336;6356.2054743157105;6265.874682506415;6278.340774218824;6281.937180628135;6287.811365659038;6293.748250069946;4883.169861123546;6083.939160608337;6210.5652604317265;6239.0953311770445;6256.468200203034;6046.455818976313;6088.786892458743;6116.052011938156;6144.754209831735;6172.475339596005;6236.064590311489;6240.968795155995;6243.969744992026;6258.4680302173;6265.091561723433;5950.137230101697;5954.672493012753;5982.282126144891;6030.6080104793045;6121 19:01:59 INFO - PROCESS | 1643 | |1;ext.html;477.9713217206968;541.3990470577658;542.4521233554111;544.1456912647145;582.4030368871905;31844;32585.67414325856;42282.78858605707;43943.90140246495;45697.7715111424;44005.7799711001;44844.775776121074;45280;45308;45338.7733061335;9200.125988031126;10024.899751002506;10195.1843852492;10255.487225638726;10258.948705256464;9309.441433513977;12205;12472.314022728746;12482.063845211645;12708.491660333575;12435.378231088454;12510.061745369088;12526.373681315943;12527.812082818766;12540;10422.635207767691;10459.529321180566;12332.876671233262;12418.875811241862;12429.192102513398;10269.794604107876;12386.442610082478;12400.06999475034;12401.13192076555;12401.813972790414;42281.7315480533;42498.78750606227;42503.93740156521;42510.574894250974;42584.7224583263;3490.7381946354126;3556.1643013891717;3688.967089215024;3711.4989476420606;3779.508663873706;31756;32000;32115.51826722601;32295;32387;3341.097903566048;3389.762716609838;3398.167448974992;3427.8114703691285;3469.965300347015;522092;528260.358698204;529674;529755;530174;35109.12227194289;62270.688646556475;62774.686126569075;62775.744485110044;62890.68554657289;35720.46419303712;36890.8155459221;36968;37037.81481092631;37040.62959370426;15724.371025158865;15891.602709932344;15899.364025439085;15901.284442200129;15902.840971590367;12333.62999110028;12584.622461326175;13230.206187628773;13342.466301348031;13350.732985340239;7825.491343062683;8093.190680931977;8112.02655681322;8116.918830811616;8188.344932405394;3311.602607687093;3349.1627093226693;3361.7478689098416;3369.76411651187;3394.117529442363;963.1812958984807;986.9161121304769;1036.4196050211906;1087.8422628718793;1155.4627098399285;31143.68856311408;31171.53242701361;31194.68805311918;31446.685533144835;31809.52285715716;1085.9945700271448;1122.4163435013747;1128.8193888977723;1139.6239241050434;1178.752461982976;15959.122248276457;16237.025778453331;16379.262933168036;16554.17229138538;16575.72335991163;714561;722006;723303;724757;724972;61531.6923415389;62147.6892615534;62180;62226;62244;1309.8821106100497;1343.0934119469514;1425.1805212002932;1447.0666420159148;1473.911565306085;31145.75416983295;31920.52119218167;32486.35027299488;32733.836330818194;32913.83543082269;51126;51638.70903227299;51705.7414712924;51709.22436163385;51746.22380664218;581909;585073;585835;586074;586230;24342.878285608458;24958.12646557362;27105;27802;27864.86067569649;48810.7797305056;49137.508624913295;49430.505694944026;49993.50006499889;50194;39412.775823473574;40460.1907961845;40881.59118408897;42040.78979605083;42220;32164;40682;40786.59213407828;41828.95427614334;42075.57924420839;38871.46713242326;39694.62595679399;39941.80174594767;39963;40167.99580010523;34310.65689343074;34406;35138;35188.76839310612;35329.470057949664;22961.737104459415;25756.742432576182;26079;26399.34001649974;26520 19:01:59 INFO - PROCESS | 1643 | |2;jquery.html;386.90134015825976;388.52793855465615;391.45392177911833;399.63034193371135;419.64749610327317;15537.689246215068;20793.68809467857;21256;22275.220367287147;22311.772852493126;16733.414330498435;19079.809201907978;20750.89624551875;20965.742055476643;21771.782282177173;5325.946740532603;5950.880982380349;6244.968775156118;6245.843853903666;6247.968760156193;5989.341172471047;6198.132261483396;6232.657203853786;6243.906341404882;6263.874722505547;6237;6237.407446292595;6240.40716131969;6247.15663385444;6248.094026366174;5118.360204974379;6206.6586337751405;6222.471089957382;6273.592216505913;6348.17473728417;6008.909866352008;6082.459929070994;6160.599561028519;6169.475594574466;6171.691415429211;13751.656208594864;13758.724825503534;14109.082909610843;14112;14116;2208.0615738311153;2228.8885555722313;2305.262316058862;2399.8680072596176;2506.5738824399714;10136.341137826017;10204.693859184163;10399.012093851072;10652.864415822796;10710.839337409945;1751.8510926571257;1908.6564418404682;1913.6459754945379;1957.2660252405349;1975.1605567633699;96217;115412;134106.6589334104;134358;134788.32605837006;11468.082553395709;11713;11800.76398472043;11811.527538898348;11981.940090299493;13859.584212473643;14957.700845983238;15188.46840360582;15194.468193613391;15233.23833808305;7751.147373788879;7899.249571290718;8400.495970241805;8887.955560222157;9021.954890225506;8927.910720892838;9044.186023257807;9062.184403403593;9218;9218.53907304632;4581.5647513486165;4650.232711602548;4669.813207471731;4670.1827180244;4731.976340118277;1759.7976232733145;1800.4958611588513;1820.9468133430703;1925.5186203449139;1974.4767636576319;864.7103220421103;935.3125452792192;969.1616751509894;1038.7299302181336;1047.879493858201;20998.58002839965;22774.31677049658;22776.49458559557;22865.19971800979;22893.08427662875;978.7553111722069;993.076438911819;1003.3979612232602;1003.9548220330103;1011.8077565262577;10512.57949682004;11845.76308473843;11983.221090629082;12016.63950081499;12041.217320874115;625632;636714;637244;645967;649304;134481;138670.61329386578;138969.30515347357;139297;144018;1002.3785253143097;1047.1622701838498;1069.764651776608;1074.8548945892205;1077.0575746222055;19573.217071316987;19675.508112296735;20214;20631.58736825285;20691.06890189915;47056;56096.8780624377;57138;57742.13386799117;58772.706136469045;77738.83391749242;78906.6054669723;80143;80443.59778201072;82572;8201.507909525448;8382.413231073766;8436.440972739743;8468.322534197372;8587.270082043096;9490.620375184915;9855.408675479493;12600.747985040065;13128.409221585243;13431.932840335736;10107.545160467944;10841.295315804447;11377.601783937522;11601.76796464049;11776.293422394683;9877;10908.836367454653;11100.056495197998;11209.383483908463;11575.131865109983;10953.726156845823;11410.885891140982;11645.59240426581;11992.580259690869;12000.75998480043;7865.488743231672;8398.328133749408;8529.95735021321;8570.65717371323;8634.469499898414;9436.669716559887;9676.806463870542;11584.47869845842;11656.251562265728;11691 19:01:59 INFO - PROCESS | 1643 | |3;mootools.html;1403.4526534651482;1572.198178928746;1606.3012589523557;1808.6744386010512;1941.1555973151671;7075.433965282771;8108.837823243532;8655.523946182966;9094.863577046333;9101.635934562626;8804.163604457583;9097.63609455621;9110.635574577025;9114.635414583425;9119.088091190877;2367.029517897662;2419.4314336130997;2421.69728783902;2423.5516429460517;2423.563758523465;2160.4166874943758;2252.4594097416584;2432.501337225873;2437.122635851101;2437.171361737009;2392.2942731894045;2406.2781165650235;2419.588669926117;2435.2937648082034;2435.6468312094735;2373.4541055557147;2390.1156572068357;2401.1235898896866;2402.759724027592;2411.602085655865;2359.4101474631343;2382.0352757133387;2405.350555350046;2406.193925035098;2408.4701365699516;8311.293540049102;8559.529225892573;9019.864702029476;9139.405938613969;9167.495787731672;5354.732263386815;5572;5582.525485333752;5626.352969408529;5627;3559.8220088995445;3594.101474631342;3651.6165802590613;3666.3134957919387;3672.3573374659327;1091.939943303118;1092.464692300775;1099.3458891959272;1107.1585594947817;1118.9664310070712;12158.209716368408;12256.08079394049;12366.19619724715;12366.81449778245;12388.690282742913;4910.94414700841;4949.48030456804;4981.37732783402;5082.18685010404;5096.362954630671;10635.840462393071;10639.41483218415;10648.041676249175;10657.200709946785;10661.573537058432;5650.943490565124;5659.660420374791;5664.376918538957;5671.1493276008105;5671.829845104653;4599.816007359668;4714.703456549408;4926.3842019747535;5076.263941728503;5141;4132.912128377102;4134.64855487287;4157.91684166315;4206.894827629272;4232.470941132358;1295.0769200210557;1326.270551196838;1333.386642144605;1334.1394800353912;1337.725766217923;895.1898531828706;908.6910450446879;924.611663101499;929.3355250995571;948.1040416806052;5685.578605348663;5793.8551536211935;5980.491658209101;6051.304100028465;6212.875742485125;421.6942716530491;447.39601537923545;447.85220877110476;457.5378867344007;458.8875725447287;3989.860354887565;4010.1578668479365;4021.678265738734;4070.9619047143383;4117.629413352813;10753.731156721145;11150.721231969266;11243.381614011309;11253;11283.54865805375;11083.390413527171;11174.329540227613;11182.552697891993;11197.216194866443;11197.66407007791;8009.559474228973;8331.83336333282;8355.080941096474;8428.873566896502;8533.871991920127;5938.376470470538;5990.730417131327;6032.336442991355;6166.167567378347;6179.721912514037;10143.7464063399;10375.27373083877;10396.48017599132;10398;10407.895921040692;10068.647597334058;10645.467726613793;10661.733456663645;10663.200259980687;10678.73303167427;7160.033395491542;7378.409727221917;7398.593077380796;7821.100573434128;7949.165337639696;7843.960780196063;8026;8092.190780921861;8513.40406171562;8587.570621469027;7495.325420712162;7563.697452101855;7636.465447418627;7757.069151701832;7805.29752322271;7488.663010164447;7625.771226863203;7662.655180516778;7669.69321227167;7778.49439786412;7123.145222573325;7246.565206087652;7248.673809678472;7249.963750181215;7259.782206533812;4957.4794646561595;5004.949950500594;5065.620078494054;5113.130767769414;5176.948230517797;4782.760861956958;4865.756712164449;4877.244027175793;4957.206846904478;4974.253861920741 19:01:59 INFO - PROCESS | 1643 | |4;prototype.html;247.49264008781998;310.870988539756;314.776508678838;319.07150192938536;326.125982367256;4513.593776560109;5205.557527610153;5271.920921186175;5298.443663415339;5321.733913304329;4952.405711314638;5069.299411429659;5142.511461411169;5147.613928955325;5179.964007198557;3656.957767036395;3980.4029395590746;4027.7180597358124;4053.27041132596;4060.5939406059306;3362.957483180222;3878.864239751609;4029.7783621900785;4050.8379664813374;4068.2880495913096;3813.1420430403214;3964.8986037400223;3983.6215559521797;4007.2386246613196;4022.9827629278043;3021.9546706799306;3954.3673012318177;3967.8493236961535;3979.9204015919518;3997.660783637485;3022.848857557113;3519.5072689823446;3527.8412471438846;3529.347070791886;3536.45184996326;4676.789544470507;4826.010667813125;4834.395700537433;4877.51224877513;5110.540051395392;4535.886602834922;4548.295014272793;4557.498675145731;4564.566366195239;4604.953950460486;4255.5744425557505;5433.891322173535;5458.344998600153;5460.379018071309;5497.532709719679;3825.636564526393;3870.3033453978273;3871.728389510859;3872.1094148345755;3877.205172939569;5460.689434535722;5693.971530142323;5746.150079446781;5761.971190144064;5774.873899589582;3542.7423264740646;3627.7846921281666;3800.0669307266844;3870.5161854768153;3920.3727403615853;3272.8690852366117;3986.621270979252;3987.222491614107;4024.7987600619854;4045.7977101145416;3696.815159242027;3784.848606055727;4543.363929049936;4614.284785858197;4627.074585082997;4458.146786056926;5481.780728770885;5497.39528651848;5515.420880807537;5549.972250138723;1250.593557093948;1274.5979422635028;1325.9801102983463;1340.3097404836517;1365.487942021742;927.2072378116748;940.7977284883783;958.91849192818;961.7355227312405;961.8412961861214;5267.341582302212;5302.522772950376;5308.495692909168;5311.654742441729;5328.866778330573;5336.705216756754;5644.444000279879;5707.629004114802;5726.627769194989;5731.481157493267;4560.905382708126;4600.585947264695;5208.427073021964;5225.790968361299;5239.371275446971;2810.7632641637615;2811.479876222905;2887.13779612149;2958.94353424368;2973.8810447582287;5501.9174712379345;5785.508231800344;5800.767969281266;5855.443732845373;5862.530997520188;5697.829065128053;5703.971480142573;5714.342850572238;5718.256626638594;5720.628159169641;4820.614350851994;5798.217240672455;5799.681017543991;5816.534677225812;5826.213461182686;4152.730072545275;4828.923562868001;4844.370231869834;5274.89450210986;5588.86027849307;5019.996000799928;5498.752556134904;5499.670019798825;5515.696636685021;5575.721213939206;4794.760261986957;5559.9444005561045;5853.707314634336;5887.852803679771;5925;3819.3507103792977;3866.5940076291586;3875.9224815504094;3888.358420860526;3890.513685789276;5700.859828034327;5720.513756330592;5734.5125664319;5741.655500669973;5773.708985878009;4804.95195048045;4850.405033307164;5637.8590535236945;5645.181448689999;5663.810599774094;5226.797836497551;5374.596905232045;5528.585356098389;5557.333120025461;5563.499285064364;4191.2874811281545;5339.888286019665;5415.756290966837;5485.424030476743;5497.972510137423;4440.755758433188;4554.567316104964;5585.497305242548;5591.692456914909;5631.761012577226;4513.458384993822;5278.4678219899515;5486.341639003345;5507.03626865305;5509.614326997072 19:01:59 INFO - PROCESS | 1643 | |5;yui.html;744.3152299884109;781.9296263336298;796.8764841449574;805.7824387415394;868.8436081505329;15145.848541514582;16400.261988210543;16420.096894670787;16427.83572164278;16488.422905198306;12872.163309384883;14463.20452375119;14889.404423823034;15098.924505377458;15347.155906425141;881.2729498164024;897.771068377566;898.2275243290765;898.5148020069188;898.6585097662912;730.5288089182474;750.1410884537202;891.3893982621893;912.2154946745793;919.659725901414;919.8942121656029;949.4018768176039;949.7293271417648;950.5484894675009;956.7512446763822;939.8778158839341;940.7365937537501;940.8447606144978;941.0777438110622;943.5188054092393;700.6461736822923;700.8878579427318;701.1691145991965;701.5685353507556;703.6059806508372;15273.770893436607;15295.311710973034;15311;15327.61680957985;15335.386584536605;13547.254900980442;14758.409663613442;14866.702665946621;14934.775978360334;15064.246787660682;13369.933150334284;13474.191548507022;13610.727785444236;13638.863611363859;13722.313884305844;4598.839040633562;4607.9078418431445;4678.345031695565;4770.212914869043;4871.512848715136;99164.50417747938;121243;125931;130197.34901325527;134066;9409.764755881159;10845.566177352817;11086.223964322582;11211.215214935039;11252.15608829341;21460.78539214619;21608.027638756295;21627.567448650938;21703.891480542497;21731.782682173292;7812.68749250035;7949.006374203224;8016.464523348034;8155.225253600898;8165.2243036911395;4476.283794592915;4504.864854054384;4543.522930092358;4572.039871626996;4625.213713668684;3356.9090045735234;3450.879219227315;3453.9654603454146;3455.775374600643;3460.757746957739;639.3287048598952;646.4892734739503;648.4682560300603;650.5836264790538;650.7657243392375;582.9825105246764;621.6643012773143;634.6002018728171;659.3801826283278;672.6065251827638;8604.440711353742;9003.63985440575;9110.954445227731;9266.490343031199;9292.581833817494;582.6766144789598;638.125767698247;651.1827656291418;652.1619718661498;665.3246954341347;4415.205263052617;4564.7032942858605;4604.332371806069;4613.907721845611;4703.435587729495;150013;160991.19504402403;161453;169054.3094569063;173978;144609.66171014885;146025.14396628935;153255.46744532412;154058;154227;104356;107488.46255768827;120182;125692.37153814173;126348;9333.719988400358;9511.956174024246;9668.95690193629;9892.466667666524;10084.394936303845;45294.77352613216;59058;61707.9146042705;61780.69109654423;64571;91979.45594075815;93646;98257.50871245598;98920;99205.50397247968;12705.42825572833;13174.341282936006;13305.600831975056;13639.113457625223;14024;13883.722325553634;14894.478693245685;15840.44558440449;16502.004261850307;16705.916470418055;13526.729465410834;14842.925785371435;14930.626734331729;14945.327460264532;15655.921720391325;11984;14614.488492902698;14788.852111478747;14884.627884302547;15011.77482337744;13703.314834258048;14160.716785664023;14458.277086145441;14471.131732096108;14682.77975830342;11456.255343402652;11667.416629168678;11699.649010529698;11761.411929403324;12136;11932.28406295625;11970.700732481757;12190.390480476119;12382.5666101686;12401.441935112762 19:01:59 INFO - PROCESS | 1643 | __end_tp_report 19:01:59 INFO - PROCESS | 1643 | __start_cc_report 19:01:59 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1086 19:01:59 INFO - PROCESS | 1643 | __end_cc_report 19:01:59 INFO - PROCESS | 1643 | __startTimestamp1484017319334__endTimestamp 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | ------- Summary: start ------- 19:01:59 INFO - PROCESS | 1643 | Number of tests: 6 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5822.16 Median:6082.00 stddev:655.57 (10.8%) stddev-sans-first:630.76 19:01:59 INFO - PROCESS | 1643 | Values: 3455.9 3456.5 3466.4 3504.5 3530.5 5743.2 5818.1 5826.9 5837.9 5843.4 4681.3 5696.6 5758.5 5761.0 5854.6 4475.4 5292.4 5307.7 5352.1 5354.3 5250.9 5255.8 5263.7 5360.1 5373.1 5354.1 5354.5 5362.5 5363.5 5373.3 5310.4 5343.6 5351.2 5354.3 5372.7 5179.9 5213.4 5258.1 5316.5 5321.9 4886.4 5659.8 5853.9 5857.7 5903.3 6328.3 6345.2 6349.2 6352.9 6366.0 5206.3 6216.6 6219.5 6278.9 6281.5 5823.7 5851.7 5851.7 5860.1 5860.6 6376.6 6384.8 6395.6 6400.8 6401.8 6082.0 6139.0 6195.3 6209.6 6263.5 6292.0 6337.7 6338.4 6341.7 6346.8 6303.7 6306.7 6315.6 6326.4 6328.5 6147.8 6150.1 6223.3 6285.4 6315.4 4993.0 5023.2 5035.4 5052.9 5089.6 4458.7 4469.4 4472.1 4479.1 4515.7 5877.1 5984.5 6005.5 6012.1 6030.0 6141.3 6266.0 6270.2 6314.1 6341.1 5997.0 6007.5 6008.2 6014.8 6045.2 5489.6 5498.4 5503.6 5506.7 5508.6 6250.5 6302.1 6364.1 6383.9 6397.4 6295.5 6304.8 6332.6 6333.6 6342.2 6446.2 6451.8 6454.0 6458.2 6459.7 6145.6 6208.3 6225.9 6292.2 6334.3 6276.7 6309.7 6319.2 6354.8 6356.2 6265.9 6278.3 6281.9 6287.8 6293.7 4883.2 6083.9 6210.6 6239.1 6256.5 6046.5 6088.8 6116.1 6144.8 6172.5 6236.1 6241.0 6244.0 6258.5 6265.1 5950.1 5954.7 5982.3 6030.6 6121.0 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:73531.94 Median:3480.35 stddev:165334.38 (4750.5%) stddev-sans-first:165707.21 19:01:59 INFO - PROCESS | 1643 | Values: 478.0 541.4 542.5 544.1 582.4 31844.0 32585.7 42282.8 43943.9 45697.8 44005.8 44844.8 45280.0 45308.0 45338.8 9200.1 10024.9 10195.2 10255.5 10258.9 9309.4 12205.0 12472.3 12482.1 12708.5 12435.4 12510.1 12526.4 12527.8 12540.0 10422.6 10459.5 12332.9 12418.9 12429.2 10269.8 12386.4 12400.1 12401.1 12401.8 42281.7 42498.8 42503.9 42510.6 42584.7 3490.7 3556.2 3689.0 3711.5 3779.5 31756.0 32000.0 32115.5 32295.0 32387.0 3341.1 3389.8 3398.2 3427.8 3470.0 522092.0 528260.4 529674.0 529755.0 530174.0 35109.1 62270.7 62774.7 62775.7 62890.7 35720.5 36890.8 36968.0 37037.8 37040.6 15724.4 15891.6 15899.4 15901.3 15902.8 12333.6 12584.6 13230.2 13342.5 13350.7 7825.5 8093.2 8112.0 8116.9 8188.3 3311.6 3349.2 3361.7 3369.8 3394.1 963.2 986.9 1036.4 1087.8 1155.5 31143.7 31171.5 31194.7 31446.7 31809.5 1086.0 1122.4 1128.8 1139.6 1178.8 15959.1 16237.0 16379.3 16554.2 16575.7 714561.0 722006.0 723303.0 724757.0 724972.0 61531.7 62147.7 62180.0 62226.0 62244.0 1309.9 1343.1 1425.2 1447.1 1473.9 31145.8 31920.5 32486.4 32733.8 32913.8 51126.0 51638.7 51705.7 51709.2 51746.2 581909.0 585073.0 585835.0 586074.0 586230.0 24342.9 24958.1 27105.0 27802.0 27864.9 48810.8 49137.5 49430.5 49993.5 50194.0 39412.8 40460.2 40881.6 42040.8 42220.0 32164.0 40682.0 40786.6 41829.0 42075.6 38871.5 39694.6 39941.8 39963.0 40168.0 34310.7 34406.0 35138.0 35188.8 35329.5 22961.7 25756.7 26079.0 26399.3 26520.0 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:36589.53 Median:12270.33 stddev:106760.01 (870.1%) stddev-sans-first:107024.89 19:01:59 INFO - PROCESS | 1643 | Values: 386.9 388.5 391.5 399.6 419.6 15537.7 20793.7 21256.0 22275.2 22311.8 16733.4 19079.8 20750.9 20965.7 21771.8 5325.9 5950.9 6245.0 6245.8 6248.0 5989.3 6198.1 6232.7 6243.9 6263.9 6237.0 6237.4 6240.4 6247.2 6248.1 5118.4 6206.7 6222.5 6273.6 6348.2 6008.9 6082.5 6160.6 6169.5 6171.7 13751.7 13758.7 14109.1 14112.0 14116.0 2208.1 2228.9 2305.3 2399.9 2506.6 10136.3 10204.7 10399.0 10652.9 10710.8 1751.9 1908.7 1913.6 1957.3 1975.2 96217.0 115412.0 134106.7 134358.0 134788.3 11468.1 11713.0 11800.8 11811.5 11981.9 13859.6 14957.7 15188.5 15194.5 15233.2 7751.1 7899.2 8400.5 8888.0 9022.0 8927.9 9044.2 9062.2 9218.0 9218.5 4581.6 4650.2 4669.8 4670.2 4732.0 1759.8 1800.5 1820.9 1925.5 1974.5 864.7 935.3 969.2 1038.7 1047.9 20998.6 22774.3 22776.5 22865.2 22893.1 978.8 993.1 1003.4 1004.0 1011.8 10512.6 11845.8 11983.2 12016.6 12041.2 625632.0 636714.0 637244.0 645967.0 649304.0 134481.0 138670.6 138969.3 139297.0 144018.0 1002.4 1047.2 1069.8 1074.9 1077.1 19573.2 19675.5 20214.0 20631.6 20691.1 47056.0 56096.9 57138.0 57742.1 58772.7 77738.8 78906.6 80143.0 80443.6 82572.0 8201.5 8382.4 8436.4 8468.3 8587.3 9490.6 9855.4 12600.7 13128.4 13431.9 10107.5 10841.3 11377.6 11601.8 11776.3 9877.0 10908.8 11100.1 11209.4 11575.1 10953.7 11410.9 11645.6 11992.6 12000.8 7865.5 8398.3 8530.0 8570.7 8634.5 9436.7 9676.8 11584.5 11656.3 11691.0 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5871.97 Median:4657.26 stddev:3347.66 (71.9%) stddev-sans-first:3340.21 19:01:59 INFO - PROCESS | 1643 | Values: 1403.5 1572.2 1606.3 1808.7 1941.2 7075.4 8108.8 8655.5 9094.9 9101.6 8804.2 9097.6 9110.6 9114.6 9119.1 2367.0 2419.4 2421.7 2423.6 2423.6 2160.4 2252.5 2432.5 2437.1 2437.2 2392.3 2406.3 2419.6 2435.3 2435.6 2373.5 2390.1 2401.1 2402.8 2411.6 2359.4 2382.0 2405.4 2406.2 2408.5 8311.3 8559.5 9019.9 9139.4 9167.5 5354.7 5572.0 5582.5 5626.4 5627.0 3559.8 3594.1 3651.6 3666.3 3672.4 1091.9 1092.5 1099.3 1107.2 1119.0 12158.2 12256.1 12366.2 12366.8 12388.7 4910.9 4949.5 4981.4 5082.2 5096.4 10635.8 10639.4 10648.0 10657.2 10661.6 5650.9 5659.7 5664.4 5671.1 5671.8 4599.8 4714.7 4926.4 5076.3 5141.0 4132.9 4134.6 4157.9 4206.9 4232.5 1295.1 1326.3 1333.4 1334.1 1337.7 895.2 908.7 924.6 929.3 948.1 5685.6 5793.9 5980.5 6051.3 6212.9 421.7 447.4 447.9 457.5 458.9 3989.9 4010.2 4021.7 4071.0 4117.6 10753.7 11150.7 11243.4 11253.0 11283.5 11083.4 11174.3 11182.6 11197.2 11197.7 8009.6 8331.8 8355.1 8428.9 8533.9 5938.4 5990.7 6032.3 6166.2 6179.7 10143.7 10375.3 10396.5 10398.0 10407.9 10068.6 10645.5 10661.7 10663.2 10678.7 7160.0 7378.4 7398.6 7821.1 7949.2 7844.0 8026.0 8092.2 8513.4 8587.6 7495.3 7563.7 7636.5 7757.1 7805.3 7488.7 7625.8 7662.7 7669.7 7778.5 7123.1 7246.6 7248.7 7250.0 7259.8 4957.5 5004.9 5065.6 5113.1 5176.9 4782.8 4865.8 4877.2 4957.2 4974.3 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4471.52 Median:4986.20 stddev:1366.99 (27.4%) stddev-sans-first:1333.55 19:01:59 INFO - PROCESS | 1643 | Values: 247.5 310.9 314.8 319.1 326.1 4513.6 5205.6 5271.9 5298.4 5321.7 4952.4 5069.3 5142.5 5147.6 5180.0 3657.0 3980.4 4027.7 4053.3 4060.6 3363.0 3878.9 4029.8 4050.8 4068.3 3813.1 3964.9 3983.6 4007.2 4023.0 3022.0 3954.4 3967.8 3979.9 3997.7 3022.8 3519.5 3527.8 3529.3 3536.5 4676.8 4826.0 4834.4 4877.5 5110.5 4535.9 4548.3 4557.5 4564.6 4605.0 4255.6 5433.9 5458.3 5460.4 5497.5 3825.6 3870.3 3871.7 3872.1 3877.2 5460.7 5694.0 5746.2 5762.0 5774.9 3542.7 3627.8 3800.1 3870.5 3920.4 3272.9 3986.6 3987.2 4024.8 4045.8 3696.8 3784.8 4543.4 4614.3 4627.1 4458.1 5481.8 5497.4 5515.4 5550.0 1250.6 1274.6 1326.0 1340.3 1365.5 927.2 940.8 958.9 961.7 961.8 5267.3 5302.5 5308.5 5311.7 5328.9 5336.7 5644.4 5707.6 5726.6 5731.5 4560.9 4600.6 5208.4 5225.8 5239.4 2810.8 2811.5 2887.1 2958.9 2973.9 5501.9 5785.5 5800.8 5855.4 5862.5 5697.8 5704.0 5714.3 5718.3 5720.6 4820.6 5798.2 5799.7 5816.5 5826.2 4152.7 4828.9 4844.4 5274.9 5588.9 5020.0 5498.8 5499.7 5515.7 5575.7 4794.8 5559.9 5853.7 5887.9 5925.0 3819.4 3866.6 3875.9 3888.4 3890.5 5700.9 5720.5 5734.5 5741.7 5773.7 4805.0 4850.4 5637.9 5645.2 5663.8 5226.8 5374.6 5528.6 5557.3 5563.5 4191.3 5339.9 5415.8 5485.4 5498.0 4440.8 4554.6 5585.5 5591.7 5631.8 4513.5 5278.5 5486.3 5507.0 5509.6 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:26700.66 Median:3452.42 stddev:43762.31 (1267.6%) stddev-sans-first:43841.68 19:01:59 INFO - PROCESS | 1643 | Values: 744.3 781.9 796.9 805.8 868.8 15145.8 16400.3 16420.1 16427.8 16488.4 12872.2 14463.2 14889.4 15098.9 15347.2 881.3 897.8 898.2 898.5 898.7 730.5 750.1 891.4 912.2 919.7 919.9 949.4 949.7 950.5 956.8 939.9 940.7 940.8 941.1 943.5 700.6 700.9 701.2 701.6 703.6 15273.8 15295.3 15311.0 15327.6 15335.4 13547.3 14758.4 14866.7 14934.8 15064.2 13369.9 13474.2 13610.7 13638.9 13722.3 4598.8 4607.9 4678.3 4770.2 4871.5 99164.5 121243.0 125931.0 130197.3 134066.0 9409.8 10845.6 11086.2 11211.2 11252.2 21460.8 21608.0 21627.6 21703.9 21731.8 7812.7 7949.0 8016.5 8155.2 8165.2 4476.3 4504.9 4543.5 4572.0 4625.2 3356.9 3450.9 3454.0 3455.8 3460.8 639.3 646.5 648.5 650.6 650.8 583.0 621.7 634.6 659.4 672.6 8604.4 9003.6 9111.0 9266.5 9292.6 582.7 638.1 651.2 652.2 665.3 4415.2 4564.7 4604.3 4613.9 4703.4 150013.0 160991.2 161453.0 169054.3 173978.0 144609.7 146025.1 153255.5 154058.0 154227.0 104356.0 107488.5 120182.0 125692.4 126348.0 9333.7 9512.0 9669.0 9892.5 10084.4 45294.8 59058.0 61707.9 61780.7 64571.0 91979.5 93646.0 98257.5 98920.0 99205.5 12705.4 13174.3 13305.6 13639.1 14024.0 13883.7 14894.5 15840.4 16502.0 16705.9 13526.7 14842.9 14930.6 14945.3 15655.9 11984.0 14614.5 14788.9 14884.6 15011.8 13703.3 14160.7 14458.3 14471.1 14682.8 11456.3 11667.4 11699.6 11761.4 12136.0 11932.3 11970.7 12190.4 12382.6 12401.4 19:01:59 INFO - PROCESS | 1643 | -------- Summary: end -------- 19:01:59 INFO - PROCESS | 1643 | 19:01:59 INFO - TEST-INFO | 1643: exit 0 19:01:59 INFO - TEST-OK | dromaeo_css | took 1115696ms 19:01:59 INFO - TEST-START | kraken 19:01:59 INFO - operating with platform_type : mac_ 19:01:59 INFO - Initialising browser for kraken test... 19:01:59 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpFKCs0w/profile http://localhost:49240/getInfo.html) 19:02:01 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 19:02:01 INFO - PROCESS | 1652 | colorDepth:24 19:02:01 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 19:02:01 INFO - PROCESS | 1652 | __metrics 19:02:02 INFO - PROCESS | 1652 | 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" 19:02:02 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:02:02 INFO - TEST-INFO | 1652: exit 0 19:02:02 INFO - Browser initialized. 19:02:02 INFO - Running cycle 1/1 for kraken test... 19:02:02 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpFKCs0w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:14 INFO - PROCESS | 1654 | RSS: Main: 164896768 19:02:14 INFO - PROCESS | 1654 | 19:02:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 19:02:18 INFO - PROCESS | 1654 | RSS: Main: 215945216 19:02:18 INFO - PROCESS | 1654 | 19:02:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 19:02:21 INFO - PROCESS | 1654 | RSS: Main: 227676160 19:02:21 INFO - PROCESS | 1654 | 19:02:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 19:02:25 INFO - PROCESS | 1654 | RSS: Main: 324866048 19:02:25 INFO - PROCESS | 1654 | 19:02:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 19:02:27 INFO - PROCESS | 1654 | RSS: Main: 228175872 19:02:27 INFO - PROCESS | 1654 | 19:02:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:02:30 INFO - PROCESS | 1654 | RSS: Main: 229056512 19:02:30 INFO - PROCESS | 1654 | 19:02:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 19:02:33 INFO - PROCESS | 1654 | RSS: Main: 265854976 19:02:33 INFO - PROCESS | 1654 | 19:02:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 19:02:36 INFO - PROCESS | 1654 | RSS: Main: 266391552 19:02:36 INFO - PROCESS | 1654 | 19:02:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 19:02:40 INFO - PROCESS | 1654 | RSS: Main: 263553024 19:02:40 INFO - PROCESS | 1654 | 19:02:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:02:42 INFO - PROCESS | 1654 | RSS: Main: 239570944 19:02:42 INFO - PROCESS | 1654 | 19:02:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:02:45 INFO - PROCESS | 1654 | RSS: Main: 245215232 19:02:45 INFO - PROCESS | 1654 | 19:02:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:02:47 INFO - PROCESS | 1654 | RSS: Main: 254566400 19:02:47 INFO - PROCESS | 1654 | 19:02:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:02:51 INFO - PROCESS | 1654 | RSS: Main: 266260480 19:02:51 INFO - PROCESS | 1654 | 19:02:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:02:54 INFO - PROCESS | 1654 | RSS: Main: 259792896 19:02:54 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 19:02:56 INFO - PROCESS | 1654 | RSS: Main: 303812608 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | __start_tp_report 19:02:56 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 19:02:56 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 19:02:56 INFO - PROCESS | 1654 | |i|pagename|runs| 19:02:56 INFO - PROCESS | 1654 | |0;ai-astar;104;113;111;112;110;111;113;113;111;111 19:02:56 INFO - PROCESS | 1654 | |1;audio-beat-detection;140;108;111;140;107;107;108;106;138;106 19:02:56 INFO - PROCESS | 1654 | |2;audio-dft;181;180;184;182;185;185;196;259;206;183 19:02:56 INFO - PROCESS | 1654 | |3;audio-fft;81;59;57;90;59;60;75;82;82;58 19:02:56 INFO - PROCESS | 1654 | |4;audio-oscillator;84;72;94;69;68;69;91;67;69;89 19:02:56 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;85;88;86;88;83;88;86;87;84 19:02:56 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;92;93;94;94;92;95;93;92;92 19:02:56 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;86;86;97;144;130;85;86;106;140 19:02:56 INFO - PROCESS | 1654 | |8;json-parse-financial;54;53;53;54;57;58;58;58;58;58 19:02:56 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;65;75;74;71;68;71;67;68;69 19:02:56 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;84;83;81;81;83;83;95;101;101;75 19:02:56 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;136;134;135;130;132;135;178;149;196;135 19:02:56 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;161;160;160;160;159;157;159;158;159;160 19:02:56 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;65;68;67;66;65;65;64;65;64;66 19:02:56 INFO - PROCESS | 1654 | __end_tp_report 19:02:56 INFO - PROCESS | 1654 | __start_cc_report 19:02:56 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1861 19:02:56 INFO - PROCESS | 1654 | __end_cc_report 19:02:56 INFO - PROCESS | 1654 | __startTimestamp1484017376682__endTimestamp 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | ------- Summary: start ------- 19:02:56 INFO - PROCESS | 1654 | Number of tests: 14 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:2.64 (2.4%) stddev-sans-first:1.12 19:02:56 INFO - PROCESS | 1654 | Values: 104.0 113.0 111.0 112.0 110.0 111.0 113.0 113.0 111.0 111.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:109.50 stddev:15.42 (14.1%) stddev-sans-first:13.95 19:02:56 INFO - PROCESS | 1654 | Values: 140.0 108.0 111.0 140.0 107.0 107.0 108.0 106.0 138.0 106.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:194.10 Median:185.00 stddev:24.19 (13.1%) stddev-sans-first:25.18 19:02:56 INFO - PROCESS | 1654 | Values: 181.0 180.0 184.0 182.0 185.0 185.0 196.0 259.0 206.0 183.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:70.30 Median:78.00 stddev:12.86 (16.5%) stddev-sans-first:13.04 19:02:56 INFO - PROCESS | 1654 | Values: 81.0 59.0 57.0 90.0 59.0 60.0 75.0 82.0 82.0 58.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.00 stddev:10.93 (14.0%) stddev-sans-first:11.31 19:02:56 INFO - PROCESS | 1654 | Values: 84.0 72.0 94.0 69.0 68.0 69.0 91.0 67.0 69.0 89.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.83 (2.1%) stddev-sans-first:1.83 19:02:56 INFO - PROCESS | 1654 | Values: 88.0 85.0 88.0 86.0 88.0 83.0 88.0 86.0 87.0 84.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.50 stddev:1.42 (1.5%) stddev-sans-first:1.12 19:02:56 INFO - PROCESS | 1654 | Values: 96.0 92.0 93.0 94.0 94.0 92.0 95.0 93.0 92.0 92.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:104.60 Median:86.00 stddev:24.22 (28.2%) stddev-sans-first:24.73 19:02:56 INFO - PROCESS | 1654 | Values: 86.0 86.0 86.0 97.0 144.0 130.0 85.0 86.0 106.0 140.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:58.00 stddev:2.28 (3.9%) stddev-sans-first:2.29 19:02:56 INFO - PROCESS | 1654 | Values: 54.0 53.0 53.0 54.0 57.0 58.0 58.0 58.0 58.0 58.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:70.00 stddev:3.75 (5.4%) stddev-sans-first:3.27 19:02:56 INFO - PROCESS | 1654 | Values: 63.0 65.0 75.0 74.0 71.0 68.0 71.0 67.0 68.0 69.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:83.00 stddev:8.99 (10.8%) stddev-sans-first:9.49 19:02:56 INFO - PROCESS | 1654 | Values: 84.0 83.0 81.0 81.0 83.0 83.0 95.0 101.0 101.0 75.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:135.50 stddev:22.59 (16.7%) stddev-sans-first:23.67 19:02:56 INFO - PROCESS | 1654 | Values: 136.0 134.0 135.0 130.0 132.0 135.0 178.0 149.0 196.0 135.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.30 Median:160.00 stddev:1.16 (0.7%) stddev-sans-first:1.05 19:02:56 INFO - PROCESS | 1654 | Values: 161.0 160.0 160.0 160.0 159.0 157.0 159.0 158.0 159.0 160.0 19:02:56 INFO - PROCESS | 1654 | 19:02:56 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:1.27 (1.9%) stddev-sans-first:1.33 19:02:56 INFO - PROCESS | 1654 | Values: 65.0 68.0 67.0 66.0 65.0 65.0 64.0 65.0 64.0 66.0 19:02:56 INFO - PROCESS | 1654 | -------- Summary: end -------- 19:02:56 INFO - PROCESS | 1654 | 19:02:57 INFO - TEST-INFO | 1654: exit 0 19:02:57 INFO - TEST-OK | kraken | took 57599ms 19:02:57 INFO - SUITE-END | took 1173s 19:02:57 INFO - Completed test suite (00:19:33) 19:02:57 INFO - javascript benchmark 19:02:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.9308813823723, 3456.498807672887, 3466.388129519771, 3504.5444092268035, 3530.505729197913, 5743.224664670268, 5818.06910894257, 5826.854328641786, 5837.8832423351505, 5843.444872737083, 4681.344611754357, 5696.572757043228, 5758.510526605233, 5761.020626493496, 5854.560907931911, 4475.440569928767, 5292.391374991867, 5307.65500242483, 5352.090144675414, 5354.330708661418, 5250.9474905250845, 5255.842324730264, 5263.684178949256, 5360.061989151903, 5373.086575282192, 5354.089804733205, 5354.518093371616, 5362.544183744367, 5363.490468405495, 5373.301470808789, 5310.362756469229, 5343.625946183749, 5351.170568561879, 5354.330708661418, 5372.7045012524295, 5179.922301165486, 5213.426523082458, 5258.13240815265, 5316.4683531646915, 5321.866953326198, 4886.364772579577, 5659.773609055633, 5853.85365365865, 5857.736401861884, 5903.321118071433, 6328.272248691414, 6345.206849143857, 6349.238091429012, 6352.872942541123, 6365.981442969102, 5206.271122042917, 6216.595921265103, 6219.502439804804, 6278.937210627881, 6281.528885333618, 5823.67969761663, 5851.707414629252, 5851.736671849777, 5860.09168578871, 5860.619059761145, 6376.617402955838, 6384.84037899047, 6395.552311338161, 6400.775972840928, 6401.839954001094, 6081.996470582304, 6139.009643731611, 6195.349488303754, 6209.59637623553, 6263.467605253604, 6292.0, 6337.714802833883, 6338.366163383625, 6341.746330146835, 6346.809595712136, 6303.716332765037, 6306.716197771111, 6315.55791094619, 6326.430621244018, 6328.525360597881, 6147.815565533041, 6150.138980542728, 6223.284322302902, 6285.371462853769, 6315.400036996478, 4993.0, 5023.2214006829, 5035.370578677665, 5052.898942021139, 5089.567386772166, 4458.71018383804, 4469.41897553316, 4472.08322293929, 4479.126570318756, 4515.661325400608, 5877.089051197071, 5984.461398474158, 6005.549583781147, 6012.128241405059, 6030.03519436888, 6141.30190404341, 6266.0, 6270.216222972129, 6314.147590075373, 6341.143945567382, 5996.970015149984, 6007.459328660441, 6008.158857759917, 6014.819555413257, 6045.214122164179, 5489.615726899079, 5498.367687715964, 5503.559715222773, 5506.697131657717, 5508.586855985737, 6250.499960003098, 6302.086197501429, 6364.140840986408, 6383.9361606383345, 6397.360263973565, 6295.496360291258, 6304.779332723333, 6332.620042797447, 6333.588316759396, 6342.23893132827, 6446.226452825691, 6451.838704032437, 6454.0, 6458.160439142881, 6459.6770161490795, 6145.647957449354, 6208.348123447154, 6225.937740622717, 6292.244930608356, 6334.303226645157, 6276.6547839869245, 6309.747610095545, 6319.24169099711, 6354.809355719336, 6356.2054743157105, 6265.874682506415, 6278.340774218824, 6281.937180628135, 6287.811365659038, 6293.748250069946, 4883.169861123546, 6083.939160608337, 6210.5652604317265, 6239.0953311770445, 6256.468200203034, 6046.455818976313, 6088.786892458743, 6116.052011938156, 6144.754209831735, 6172.475339596005, 6236.064590311489, 6240.968795155995, 6243.969744992026, 6258.4680302173, 6265.091561723433, 5950.137230101697, 5954.672493012753, 5982.282126144891, 6030.6080104793045, 6121.0], "value": 5781.737531796402, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.9713217206968, 541.3990470577658, 542.4521233554111, 544.1456912647145, 582.4030368871905, 31844.0, 32585.67414325856, 42282.78858605707, 43943.90140246495, 45697.7715111424, 44005.7799711001, 44844.775776121074, 45280.0, 45308.0, 45338.7733061335, 9200.125988031126, 10024.899751002506, 10195.1843852492, 10255.487225638726, 10258.948705256464, 9309.441433513977, 12205.0, 12472.314022728746, 12482.063845211645, 12708.491660333575, 12435.378231088454, 12510.061745369088, 12526.373681315943, 12527.812082818766, 12540.0, 10422.635207767691, 10459.529321180566, 12332.876671233262, 12418.875811241862, 12429.192102513398, 10269.794604107876, 12386.442610082478, 12400.06999475034, 12401.13192076555, 12401.813972790414, 42281.7315480533, 42498.78750606227, 42503.93740156521, 42510.574894250974, 42584.7224583263, 3490.7381946354126, 3556.1643013891717, 3688.967089215024, 3711.4989476420606, 3779.508663873706, 31756.0, 32000.0, 32115.51826722601, 32295.0, 32387.0, 3341.097903566048, 3389.762716609838, 3398.167448974992, 3427.8114703691285, 3469.965300347015, 522092.0, 528260.358698204, 529674.0, 529755.0, 530174.0, 35109.12227194289, 62270.688646556475, 62774.686126569075, 62775.744485110044, 62890.68554657289, 35720.46419303712, 36890.8155459221, 36968.0, 37037.81481092631, 37040.62959370426, 15724.371025158865, 15891.602709932344, 15899.364025439085, 15901.284442200129, 15902.840971590367, 12333.62999110028, 12584.622461326175, 13230.206187628773, 13342.466301348031, 13350.732985340239, 7825.491343062683, 8093.190680931977, 8112.02655681322, 8116.918830811616, 8188.344932405394, 3311.602607687093, 3349.1627093226693, 3361.7478689098416, 3369.76411651187, 3394.117529442363, 963.1812958984807, 986.9161121304769, 1036.4196050211906, 1087.8422628718793, 1155.4627098399285, 31143.68856311408, 31171.53242701361, 31194.68805311918, 31446.685533144835, 31809.52285715716, 1085.9945700271448, 1122.4163435013747, 1128.8193888977723, 1139.6239241050434, 1178.752461982976, 15959.122248276457, 16237.025778453331, 16379.262933168036, 16554.17229138538, 16575.72335991163, 714561.0, 722006.0, 723303.0, 724757.0, 724972.0, 61531.6923415389, 62147.6892615534, 62180.0, 62226.0, 62244.0, 1309.8821106100497, 1343.0934119469514, 1425.1805212002932, 1447.0666420159148, 1473.911565306085, 31145.75416983295, 31920.52119218167, 32486.35027299488, 32733.836330818194, 32913.83543082269, 51126.0, 51638.70903227299, 51705.7414712924, 51709.22436163385, 51746.22380664218, 581909.0, 585073.0, 585835.0, 586074.0, 586230.0, 24342.878285608458, 24958.12646557362, 27105.0, 27802.0, 27864.86067569649, 48810.7797305056, 49137.508624913295, 49430.505694944026, 49993.50006499889, 50194.0, 39412.775823473574, 40460.1907961845, 40881.59118408897, 42040.78979605083, 42220.0, 32164.0, 40682.0, 40786.59213407828, 41828.95427614334, 42075.57924420839, 38871.46713242326, 39694.62595679399, 39941.80174594767, 39963.0, 40167.99580010523, 34310.65689343074, 34406.0, 35138.0, 35188.76839310612, 35329.470057949664, 22961.737104459415, 25756.742432576182, 26079.0, 26399.34001649974, 26520.0], "value": 19895.0617964843, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.90134015825976, 388.52793855465615, 391.45392177911833, 399.63034193371135, 419.64749610327317, 15537.689246215068, 20793.68809467857, 21256.0, 22275.220367287147, 22311.772852493126, 16733.414330498435, 19079.809201907978, 20750.89624551875, 20965.742055476643, 21771.782282177173, 5325.946740532603, 5950.880982380349, 6244.968775156118, 6245.843853903666, 6247.968760156193, 5989.341172471047, 6198.132261483396, 6232.657203853786, 6243.906341404882, 6263.874722505547, 6237.0, 6237.407446292595, 6240.40716131969, 6247.15663385444, 6248.094026366174, 5118.360204974379, 6206.6586337751405, 6222.471089957382, 6273.592216505913, 6348.17473728417, 6008.909866352008, 6082.459929070994, 6160.599561028519, 6169.475594574466, 6171.691415429211, 13751.656208594864, 13758.724825503534, 14109.082909610843, 14112.0, 14116.0, 2208.0615738311153, 2228.8885555722313, 2305.262316058862, 2399.8680072596176, 2506.5738824399714, 10136.341137826017, 10204.693859184163, 10399.012093851072, 10652.864415822796, 10710.839337409945, 1751.8510926571257, 1908.6564418404682, 1913.6459754945379, 1957.2660252405349, 1975.1605567633699, 96217.0, 115412.0, 134106.6589334104, 134358.0, 134788.32605837006, 11468.082553395709, 11713.0, 11800.76398472043, 11811.527538898348, 11981.940090299493, 13859.584212473643, 14957.700845983238, 15188.46840360582, 15194.468193613391, 15233.23833808305, 7751.147373788879, 7899.249571290718, 8400.495970241805, 8887.955560222157, 9021.954890225506, 8927.910720892838, 9044.186023257807, 9062.184403403593, 9218.0, 9218.53907304632, 4581.5647513486165, 4650.232711602548, 4669.813207471731, 4670.1827180244, 4731.976340118277, 1759.7976232733145, 1800.4958611588513, 1820.9468133430703, 1925.5186203449139, 1974.4767636576319, 864.7103220421103, 935.3125452792192, 969.1616751509894, 1038.7299302181336, 1047.879493858201, 20998.58002839965, 22774.31677049658, 22776.49458559557, 22865.19971800979, 22893.08427662875, 978.7553111722069, 993.076438911819, 1003.3979612232602, 1003.9548220330103, 1011.8077565262577, 10512.57949682004, 11845.76308473843, 11983.221090629082, 12016.63950081499, 12041.217320874115, 625632.0, 636714.0, 637244.0, 645967.0, 649304.0, 134481.0, 138670.61329386578, 138969.30515347357, 139297.0, 144018.0, 1002.3785253143097, 1047.1622701838498, 1069.764651776608, 1074.8548945892205, 1077.0575746222055, 19573.217071316987, 19675.508112296735, 20214.0, 20631.58736825285, 20691.06890189915, 47056.0, 56096.8780624377, 57138.0, 57742.13386799117, 58772.706136469045, 77738.83391749242, 78906.6054669723, 80143.0, 80443.59778201072, 82572.0, 8201.507909525448, 8382.413231073766, 8436.440972739743, 8468.322534197372, 8587.270082043096, 9490.620375184915, 9855.408675479493, 12600.747985040065, 13128.409221585243, 13431.932840335736, 10107.545160467944, 10841.295315804447, 11377.601783937522, 11601.76796464049, 11776.293422394683, 9877.0, 10908.836367454653, 11100.056495197998, 11209.383483908463, 11575.131865109983, 10953.726156845823, 11410.885891140982, 11645.59240426581, 11992.580259690869, 12000.75998480043, 7865.488743231672, 8398.328133749408, 8529.95735021321, 8570.65717371323, 8634.469499898414, 9436.669716559887, 9676.806463870542, 11584.47869845842, 11656.251562265728, 11691.0], "value": 9705.996941203675, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.4526534651482, 1572.198178928746, 1606.3012589523557, 1808.6744386010512, 1941.1555973151671, 7075.433965282771, 8108.837823243532, 8655.523946182966, 9094.863577046333, 9101.635934562626, 8804.163604457583, 9097.63609455621, 9110.635574577025, 9114.635414583425, 9119.088091190877, 2367.029517897662, 2419.4314336130997, 2421.69728783902, 2423.5516429460517, 2423.563758523465, 2160.4166874943758, 2252.4594097416584, 2432.501337225873, 2437.122635851101, 2437.171361737009, 2392.2942731894045, 2406.2781165650235, 2419.588669926117, 2435.2937648082034, 2435.6468312094735, 2373.4541055557147, 2390.1156572068357, 2401.1235898896866, 2402.759724027592, 2411.602085655865, 2359.4101474631343, 2382.0352757133387, 2405.350555350046, 2406.193925035098, 2408.4701365699516, 8311.293540049102, 8559.529225892573, 9019.864702029476, 9139.405938613969, 9167.495787731672, 5354.732263386815, 5572.0, 5582.525485333752, 5626.352969408529, 5627.0, 3559.8220088995445, 3594.101474631342, 3651.6165802590613, 3666.3134957919387, 3672.3573374659327, 1091.939943303118, 1092.464692300775, 1099.3458891959272, 1107.1585594947817, 1118.9664310070712, 12158.209716368408, 12256.08079394049, 12366.19619724715, 12366.81449778245, 12388.690282742913, 4910.94414700841, 4949.48030456804, 4981.37732783402, 5082.18685010404, 5096.362954630671, 10635.840462393071, 10639.41483218415, 10648.041676249175, 10657.200709946785, 10661.573537058432, 5650.943490565124, 5659.660420374791, 5664.376918538957, 5671.1493276008105, 5671.829845104653, 4599.816007359668, 4714.703456549408, 4926.3842019747535, 5076.263941728503, 5141.0, 4132.912128377102, 4134.64855487287, 4157.91684166315, 4206.894827629272, 4232.470941132358, 1295.0769200210557, 1326.270551196838, 1333.386642144605, 1334.1394800353912, 1337.725766217923, 895.1898531828706, 908.6910450446879, 924.611663101499, 929.3355250995571, 948.1040416806052, 5685.578605348663, 5793.8551536211935, 5980.491658209101, 6051.304100028465, 6212.875742485125, 421.6942716530491, 447.39601537923545, 447.85220877110476, 457.5378867344007, 458.8875725447287, 3989.860354887565, 4010.1578668479365, 4021.678265738734, 4070.9619047143383, 4117.629413352813, 10753.731156721145, 11150.721231969266, 11243.381614011309, 11253.0, 11283.54865805375, 11083.390413527171, 11174.329540227613, 11182.552697891993, 11197.216194866443, 11197.66407007791, 8009.559474228973, 8331.83336333282, 8355.080941096474, 8428.873566896502, 8533.871991920127, 5938.376470470538, 5990.730417131327, 6032.336442991355, 6166.167567378347, 6179.721912514037, 10143.7464063399, 10375.27373083877, 10396.48017599132, 10398.0, 10407.895921040692, 10068.647597334058, 10645.467726613793, 10661.733456663645, 10663.200259980687, 10678.73303167427, 7160.033395491542, 7378.409727221917, 7398.593077380796, 7821.100573434128, 7949.165337639696, 7843.960780196063, 8026.0, 8092.190780921861, 8513.40406171562, 8587.570621469027, 7495.325420712162, 7563.697452101855, 7636.465447418627, 7757.069151701832, 7805.29752322271, 7488.663010164447, 7625.771226863203, 7662.655180516778, 7669.69321227167, 7778.49439786412, 7123.145222573325, 7246.565206087652, 7248.673809678472, 7249.963750181215, 7259.782206533812, 4957.4794646561595, 5004.949950500594, 5065.620078494054, 5113.130767769414, 5176.948230517797, 4782.760861956958, 4865.756712164449, 4877.244027175793, 4957.206846904478, 4974.253861920741], "value": 4622.656328911976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [247.49264008781998, 310.870988539756, 314.776508678838, 319.07150192938536, 326.125982367256, 4513.593776560109, 5205.557527610153, 5271.920921186175, 5298.443663415339, 5321.733913304329, 4952.405711314638, 5069.299411429659, 5142.511461411169, 5147.613928955325, 5179.964007198557, 3656.957767036395, 3980.4029395590746, 4027.7180597358124, 4053.27041132596, 4060.5939406059306, 3362.957483180222, 3878.864239751609, 4029.7783621900785, 4050.8379664813374, 4068.2880495913096, 3813.1420430403214, 3964.8986037400223, 3983.6215559521797, 4007.2386246613196, 4022.9827629278043, 3021.9546706799306, 3954.3673012318177, 3967.8493236961535, 3979.9204015919518, 3997.660783637485, 3022.848857557113, 3519.5072689823446, 3527.8412471438846, 3529.347070791886, 3536.45184996326, 4676.789544470507, 4826.010667813125, 4834.395700537433, 4877.51224877513, 5110.540051395392, 4535.886602834922, 4548.295014272793, 4557.498675145731, 4564.566366195239, 4604.953950460486, 4255.5744425557505, 5433.891322173535, 5458.344998600153, 5460.379018071309, 5497.532709719679, 3825.636564526393, 3870.3033453978273, 3871.728389510859, 3872.1094148345755, 3877.205172939569, 5460.689434535722, 5693.971530142323, 5746.150079446781, 5761.971190144064, 5774.873899589582, 3542.7423264740646, 3627.7846921281666, 3800.0669307266844, 3870.5161854768153, 3920.3727403615853, 3272.8690852366117, 3986.621270979252, 3987.222491614107, 4024.7987600619854, 4045.7977101145416, 3696.815159242027, 3784.848606055727, 4543.363929049936, 4614.284785858197, 4627.074585082997, 4458.146786056926, 5481.780728770885, 5497.39528651848, 5515.420880807537, 5549.972250138723, 1250.593557093948, 1274.5979422635028, 1325.9801102983463, 1340.3097404836517, 1365.487942021742, 927.2072378116748, 940.7977284883783, 958.91849192818, 961.7355227312405, 961.8412961861214, 5267.341582302212, 5302.522772950376, 5308.495692909168, 5311.654742441729, 5328.866778330573, 5336.705216756754, 5644.444000279879, 5707.629004114802, 5726.627769194989, 5731.481157493267, 4560.905382708126, 4600.585947264695, 5208.427073021964, 5225.790968361299, 5239.371275446971, 2810.7632641637615, 2811.479876222905, 2887.13779612149, 2958.94353424368, 2973.8810447582287, 5501.9174712379345, 5785.508231800344, 5800.767969281266, 5855.443732845373, 5862.530997520188, 5697.829065128053, 5703.971480142573, 5714.342850572238, 5718.256626638594, 5720.628159169641, 4820.614350851994, 5798.217240672455, 5799.681017543991, 5816.534677225812, 5826.213461182686, 4152.730072545275, 4828.923562868001, 4844.370231869834, 5274.89450210986, 5588.86027849307, 5019.996000799928, 5498.752556134904, 5499.670019798825, 5515.696636685021, 5575.721213939206, 4794.760261986957, 5559.9444005561045, 5853.707314634336, 5887.852803679771, 5925.0, 3819.3507103792977, 3866.5940076291586, 3875.9224815504094, 3888.358420860526, 3890.513685789276, 5700.859828034327, 5720.513756330592, 5734.5125664319, 5741.655500669973, 5773.708985878009, 4804.95195048045, 4850.405033307164, 5637.8590535236945, 5645.181448689999, 5663.810599774094, 5226.797836497551, 5374.596905232045, 5528.585356098389, 5557.333120025461, 5563.499285064364, 4191.2874811281545, 5339.888286019665, 5415.756290966837, 5485.424030476743, 5497.972510137423, 4440.755758433188, 4554.567316104964, 5585.497305242548, 5591.692456914909, 5631.761012577226, 4513.458384993822, 5278.4678219899515, 5486.341639003345, 5507.03626865305, 5509.614326997072], "value": 4043.67921724174, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.3152299884109, 781.9296263336298, 796.8764841449574, 805.7824387415394, 868.8436081505329, 15145.848541514582, 16400.261988210543, 16420.096894670787, 16427.83572164278, 16488.422905198306, 12872.163309384883, 14463.20452375119, 14889.404423823034, 15098.924505377458, 15347.155906425141, 881.2729498164024, 897.771068377566, 898.2275243290765, 898.5148020069188, 898.6585097662912, 730.5288089182474, 750.1410884537202, 891.3893982621893, 912.2154946745793, 919.659725901414, 919.8942121656029, 949.4018768176039, 949.7293271417648, 950.5484894675009, 956.7512446763822, 939.8778158839341, 940.7365937537501, 940.8447606144978, 941.0777438110622, 943.5188054092393, 700.6461736822923, 700.8878579427318, 701.1691145991965, 701.5685353507556, 703.6059806508372, 15273.770893436607, 15295.311710973034, 15311.0, 15327.61680957985, 15335.386584536605, 13547.254900980442, 14758.409663613442, 14866.702665946621, 14934.775978360334, 15064.246787660682, 13369.933150334284, 13474.191548507022, 13610.727785444236, 13638.863611363859, 13722.313884305844, 4598.839040633562, 4607.9078418431445, 4678.345031695565, 4770.212914869043, 4871.512848715136, 99164.50417747938, 121243.0, 125931.0, 130197.34901325527, 134066.0, 9409.764755881159, 10845.566177352817, 11086.223964322582, 11211.215214935039, 11252.15608829341, 21460.78539214619, 21608.027638756295, 21627.567448650938, 21703.891480542497, 21731.782682173292, 7812.68749250035, 7949.006374203224, 8016.464523348034, 8155.225253600898, 8165.2243036911395, 4476.283794592915, 4504.864854054384, 4543.522930092358, 4572.039871626996, 4625.213713668684, 3356.9090045735234, 3450.879219227315, 3453.9654603454146, 3455.775374600643, 3460.757746957739, 639.3287048598952, 646.4892734739503, 648.4682560300603, 650.5836264790538, 650.7657243392375, 582.9825105246764, 621.6643012773143, 634.6002018728171, 659.3801826283278, 672.6065251827638, 8604.440711353742, 9003.63985440575, 9110.954445227731, 9266.490343031199, 9292.581833817494, 582.6766144789598, 638.125767698247, 651.1827656291418, 652.1619718661498, 665.3246954341347, 4415.205263052617, 4564.7032942858605, 4604.332371806069, 4613.907721845611, 4703.435587729495, 150013.0, 160991.19504402403, 161453.0, 169054.3094569063, 173978.0, 144609.66171014885, 146025.14396628935, 153255.46744532412, 154058.0, 154227.0, 104356.0, 107488.46255768827, 120182.0, 125692.37153814173, 126348.0, 9333.719988400358, 9511.956174024246, 9668.95690193629, 9892.466667666524, 10084.394936303845, 45294.77352613216, 59058.0, 61707.9146042705, 61780.69109654423, 64571.0, 91979.45594075815, 93646.0, 98257.50871245598, 98920.0, 99205.50397247968, 12705.42825572833, 13174.341282936006, 13305.600831975056, 13639.113457625223, 14024.0, 13883.722325553634, 14894.478693245685, 15840.44558440449, 16502.004261850307, 16705.916470418055, 13526.729465410834, 14842.925785371435, 14930.626734331729, 14945.327460264532, 15655.921720391325, 11984.0, 14614.488492902698, 14788.852111478747, 14884.627884302547, 15011.77482337744, 13703.314834258048, 14160.716785664023, 14458.277086145441, 14471.131732096108, 14682.77975830342, 11456.255343402652, 11667.416629168678, 11699.649010529698, 11761.411929403324, 12136.0, 11932.28406295625, 11970.700732481757, 12190.390480476119, 12382.5666101686, 12401.441935112762], "value": 8170.016850838191, "unit": "score"}], "name": "dromaeo_css", "value": 7446.694807087358, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 111.0, 112.0, 110.0, 111.0, 113.0, 113.0, 111.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 111.0, 140.0, 107.0, 107.0, 108.0, 106.0, 138.0, 106.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 184.0, 182.0, 185.0, 185.0, 196.0, 259.0, 206.0, 183.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 57.0, 90.0, 59.0, 60.0, 75.0, 82.0, 82.0, 58.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 94.0, 69.0, 68.0, 69.0, 91.0, 67.0, 69.0, 89.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 83.0, 88.0, 86.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 93.0, 94.0, 94.0, 92.0, 95.0, 93.0, 92.0, 92.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 97.0, 144.0, 130.0, 85.0, 86.0, 106.0, 140.0], "value": 104.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 75.0, 74.0, 71.0, 68.0, 71.0, 67.0, 68.0, 69.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 81.0, 81.0, 83.0, 83.0, 95.0, 101.0, 101.0, 75.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 135.0, 130.0, 132.0, 135.0, 178.0, 149.0, 196.0, 135.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 159.0, 157.0, 159.0, 158.0, 159.0, 160.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 66.0, 65.0, 65.0, 64.0, 65.0, 64.0, 66.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1436.5, "alertThreshold": 2.0}]} 19:02:57 INFO - Return code: 0 19:02:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:02:58 INFO - # TBPL SUCCESS # 19:02:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:58 INFO - 2017-01-09 19:02:58.028 screenresolution[1658:9698] starting screenresolution argv=screenresolution get 19:02:58 INFO - 2017-01-09 19:02:58.031 screenresolution[1658:9698] Display 0: 1600x1200x32@60 19:02:58 INFO - 2017-01-09 19:02:58.037 screenresolution[1659:9701] starting screenresolution argv=screenresolution list 19:02:58 INFO - Available Modes on Display 0 19:02:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:58 INFO - Intel Iris: 19:02:58 INFO - Chipset Model: Intel Iris 19:02:58 INFO - Type: GPU 19:02:58 INFO - Bus: Built-In 19:02:58 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:58 INFO - Vendor: Intel (0x8086) 19:02:58 INFO - Device ID: 0x0a2e 19:02:58 INFO - Revision ID: 0x0009 19:02:58 INFO - Displays: 19:02:58 INFO - Display: 19:02:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:58 INFO - Display Serial Number: 1600x1200 60 19:02:58 INFO - Main Display: Yes 19:02:58 INFO - Mirror: Off 19:02:58 INFO - Online: Yes 19:02:58 INFO - Rotation: Supported 19:02:58 INFO - Return code: 0 19:02:58 INFO - Running post-action listener: _resource_record_post_action 19:02:58 INFO - [mozharness: 2017-01-10 03:02:58.271993Z] Finished run-tests step (success) 19:02:58 INFO - Running post-run listener: _resource_record_post_run 19:02:58 INFO - Total resource usage - Wall time: 1196s; CPU: 53.0%; Read bytes: 39298048; Write bytes: 493922304; Read time: 384; Write time: 1607 19:02:58 INFO - TinderboxPrint: CPU usage
53.1% 19:02:58 INFO - TinderboxPrint: I/O read bytes / time
39,298,048 / 384 19:02:58 INFO - TinderboxPrint: I/O write bytes / time
493,922,304 / 1,607 19:02:58 INFO - TinderboxPrint: CPU idle
2,242.4 (46.9%) 19:02:58 INFO - TinderboxPrint: CPU system
954.0 (19.9%) 19:02:58 INFO - TinderboxPrint: CPU user
1,589.2 (33.2%) 19:02:58 INFO - TinderboxPrint: Swap in / out
234,594,304 / 0 19:02:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194818560; Write bytes: 187170816; Read time: 17835; Write time: 352 19:02:58 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 35128320; Write bytes: 296429568; Read time: 352; Write time: 1216 19:02:58 INFO - Running post-run listener: _upload_blobber_files 19:02:58 INFO - Blob upload gear active. 19:02:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:02:58 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 19:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:02:59 INFO - (blobuploader) - INFO - Open directory for files ... 19:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:00 INFO - (blobuploader) - INFO - Done attempting. 19:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:01 INFO - (blobuploader) - INFO - Done attempting. 19:03:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:03:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:03 INFO - (blobuploader) - INFO - Done attempting. 19:03:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:03:03 INFO - Return code: 0 19:03:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:03:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:03:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b39201c1360924b8bddfc9f63ea100844085ea3617ad2ca4d7057b05f0a7e4dcfa5c4c464eaf3fd91f0e94eeb7278560abd90c7385dd845f155127d49ef6e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80662a2eb8197e998ae039e0a5712780c81658dbf8a7592c627524df60507aa869b18b4c1372b5c415111d864da7ab9d6e80fd2252e468cc66804e95f85fd7ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35f353ef1166a66da9edf4a77d0b1a6b437280f09a5315b443007f03ff3cdbcd15e3815f7f16ab58e4c7643bc77b4302324cbd1ba92f9be21da527e171d85ad1"} 19:03:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:03:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:03:03 INFO - Contents: 19:03:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b39201c1360924b8bddfc9f63ea100844085ea3617ad2ca4d7057b05f0a7e4dcfa5c4c464eaf3fd91f0e94eeb7278560abd90c7385dd845f155127d49ef6e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80662a2eb8197e998ae039e0a5712780c81658dbf8a7592c627524df60507aa869b18b4c1372b5c415111d864da7ab9d6e80fd2252e468cc66804e95f85fd7ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35f353ef1166a66da9edf4a77d0b1a6b437280f09a5315b443007f03ff3cdbcd15e3815f7f16ab58e4c7643bc77b4302324cbd1ba92f9be21da527e171d85ad1"} 19:03:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:03:03 INFO - Copying logs to upload dir... 19:03:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:03:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.869377 ========= master_lag: 3.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-09 19:03:06.972033) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 19:03:06.979926) ========= 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.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b39201c1360924b8bddfc9f63ea100844085ea3617ad2ca4d7057b05f0a7e4dcfa5c4c464eaf3fd91f0e94eeb7278560abd90c7385dd845f155127d49ef6e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80662a2eb8197e998ae039e0a5712780c81658dbf8a7592c627524df60507aa869b18b4c1372b5c415111d864da7ab9d6e80fd2252e468cc66804e95f85fd7ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35f353ef1166a66da9edf4a77d0b1a6b437280f09a5315b443007f03ff3cdbcd15e3815f7f16ab58e4c7643bc77b4302324cbd1ba92f9be21da527e171d85ad1"} build_url:https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/YqUc5OF2SNuCc_83tZVC_w/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b39201c1360924b8bddfc9f63ea100844085ea3617ad2ca4d7057b05f0a7e4dcfa5c4c464eaf3fd91f0e94eeb7278560abd90c7385dd845f155127d49ef6e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80662a2eb8197e998ae039e0a5712780c81658dbf8a7592c627524df60507aa869b18b4c1372b5c415111d864da7ab9d6e80fd2252e468cc66804e95f85fd7ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35f353ef1166a66da9edf4a77d0b1a6b437280f09a5315b443007f03ff3cdbcd15e3815f7f16ab58e4c7643bc77b4302324cbd1ba92f9be21da527e171d85ad1"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 19:03:07.089802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 19:03:07.090269) ========= 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.85lDicNsLR/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.pG7SQvja4R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005071 ========= master_lag: 0.84 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 19:03:07.930760) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 19:03:07.931057) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 19:03:07.931938) ========= ========= Total master_lag: 4.19 =========