builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0357 starttime: 1492730293.57 results: success (0) buildid: 20170420144628 builduid: 52045ffb1f4f4f0c9d0943d0e6e88a39 revision: 5e3dc7e1288af835220871fefdb18d520efb06d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.571686) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.572178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.572484) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.596415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.596795) ========= 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.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-04-20 16:18:13-- 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% 155M=0s 2017-04-20 16:18:13 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.778806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.779261) ========= 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.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.027424 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:13.828959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 16:18:13.829339) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5e3dc7e1288af835220871fefdb18d520efb06d4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5e3dc7e1288af835220871fefdb18d520efb06d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-04-20 16:18:13,937 truncating revision to first 12 chars 2017-04-20 16:18:13,937 Setting DEBUG logging. 2017-04-20 16:18:13,937 attempt 1/10 2017-04-20 16:18:13,937 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5e3dc7e1288a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 16:18:15,640 unpacking tar archive at: mozilla-central-5e3dc7e1288a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.989988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 16:18:15.843109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:15.843461) ========= script_repo_revision: 5e3dc7e1288af835220871fefdb18d520efb06d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:15.843874) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:15.844153) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 16:18:15.862908) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 16:18:15.863260) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:18:16 INFO - MultiFileLogger online at 20170420 16:18:16 in /builds/slave/test 16:18:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:18:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:16 INFO - {'append_to_log': False, 16:18:16 INFO - 'base_work_dir': '/builds/slave/test', 16:18:16 INFO - 'blob_upload_branch': 'Firefox', 16:18:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:16 INFO - 'branch': 'Firefox', 16:18:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:16 INFO - 'config_files': ('talos/mac_config.py',), 16:18:16 INFO - 'default_actions': ('clobber', 16:18:16 INFO - 'read-buildbot-config', 16:18:16 INFO - 'download-and-extract', 16:18:16 INFO - 'populate-webroot', 16:18:16 INFO - 'create-virtualenv', 16:18:16 INFO - 'install', 16:18:16 INFO - 'run-tests'), 16:18:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:16 INFO - 'download_minidump_stackwalk': True, 16:18:16 INFO - 'download_symbols': 'ondemand', 16:18:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:16 INFO - 'gecko_profile': False, 16:18:16 INFO - 'gecko_profile_interval': 0, 16:18:16 INFO - 'installer_path': 'installer.exe', 16:18:16 INFO - 'log_level': 'info', 16:18:16 INFO - 'log_name': 'talos', 16:18:16 INFO - 'log_to_console': True, 16:18:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:18:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:18:16 INFO - 'opt_config_files': (), 16:18:16 INFO - 'pip_index': False, 16:18:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:16 INFO - 'cmd': ('bash', 16:18:16 INFO - '-c', 16:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:16 INFO - 'enabled': True, 16:18:16 INFO - 'halt_on_failure': False, 16:18:16 INFO - 'name': 'check_screen_resolution'},), 16:18:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:16 INFO - 'cmd': ('bash', 16:18:16 INFO - '-c', 16:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:16 INFO - 'enabled': True, 16:18:16 INFO - 'halt_on_failure': False, 16:18:16 INFO - 'name': 'check_screen_resolution'},), 16:18:16 INFO - 'run_cmd_checks_enabled': True, 16:18:16 INFO - 'suite': 'dromaeojs-e10s', 16:18:16 INFO - 'system_bits': '32', 16:18:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:16 INFO - 'title': 't-yosemite-r7-0357', 16:18:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:16 INFO - 'use_talos_json': True, 16:18:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:16 INFO - 'work_dir': 'build'} 16:18:16 INFO - [mozharness: 2017-04-20 23:18:16.080228Z] Running clobber step. 16:18:16 INFO - Running pre-action listener: _resource_record_pre_action 16:18:16 INFO - Running main action method: clobber 16:18:16 INFO - rmtree: /builds/slave/test/build 16:18:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:18 INFO - Running post-action listener: _resource_record_post_action 16:18:18 INFO - [mozharness: 2017-04-20 23:18:18.551813Z] Finished clobber step (success) 16:18:18 INFO - [mozharness: 2017-04-20 23:18:18.552062Z] Running read-buildbot-config step. 16:18:18 INFO - Running pre-action listener: _resource_record_pre_action 16:18:18 INFO - Running main action method: read_buildbot_config 16:18:18 INFO - Using buildbot properties: 16:18:18 INFO - { 16:18:18 INFO - "project": "", 16:18:18 INFO - "product": "firefox", 16:18:18 INFO - "script_repo_revision": "production", 16:18:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:18:18 INFO - "repository": "", 16:18:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:18:18 INFO - "buildid": "20170420144628", 16:18:18 INFO - "pgo_build": "False", 16:18:18 INFO - "basedir": "/builds/slave/test", 16:18:18 INFO - "buildnumber": 31, 16:18:18 INFO - "slavename": "t-yosemite-r7-0357", 16:18:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:18:18 INFO - "platform": "yosemite_r7", 16:18:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:18:18 INFO - "branch": "mozilla-central", 16:18:18 INFO - "repo_path": "mozilla-central", 16:18:18 INFO - "revision": "5e3dc7e1288af835220871fefdb18d520efb06d4", 16:18:18 INFO - "stage_platform": "macosx64", 16:18:18 INFO - "builduid": "52045ffb1f4f4f0c9d0943d0e6e88a39", 16:18:18 INFO - "slavebuilddir": "test" 16:18:18 INFO - } 16:18:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:18:18 INFO - Running post-action listener: _resource_record_post_action 16:18:18 INFO - [mozharness: 2017-04-20 23:18:18.557653Z] Finished read-buildbot-config step (success) 16:18:18 INFO - [mozharness: 2017-04-20 23:18:18.557888Z] Running download-and-extract step. 16:18:18 INFO - Running pre-action listener: _resource_record_pre_action 16:18:18 INFO - Running main action method: download_and_extract 16:18:18 INFO - mkdir: /builds/slave/test/build/tests 16:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:18 INFO - trying https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:18:18 INFO - Downloading https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:18:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:18:19 INFO - Downloaded 1325 bytes. 16:18:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:18:19 INFO - Using the following test package requirements: 16:18:19 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:18:19 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:18:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:18:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:18:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'jsshell-mac.zip'], 16:18:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:18:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:18:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:18:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:18:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:18:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:18:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:18:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:18:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:18:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:19 INFO - Fetch https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:18:21 INFO - Expected file size: 52521907 16:18:21 INFO - Obtained file size: 52521907 16:18:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:18:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:18:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:37 INFO - Fetch https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:18:39 INFO - Expected file size: 52521907 16:18:39 INFO - Obtained file size: 52521907 16:18:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:18:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:18:55 INFO - Fetch https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:18:56 INFO - Expected file size: 14408811 16:18:56 INFO - Obtained file size: 14408811 16:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:57 INFO - trying https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:18:57 INFO - Downloading https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:18:59 INFO - Downloaded 70969641 bytes. 16:18:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:18:59 INFO - mkdir: /builds/slave/test/properties 16:18:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:59 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:59 INFO - Contents: 16:18:59 INFO - build_url:https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:18:59 INFO - Running post-action listener: _resource_record_post_action 16:18:59 INFO - Running post-action listener: set_extra_try_arguments 16:18:59 INFO - [mozharness: 2017-04-20 23:18:59.982659Z] Finished download-and-extract step (success) 16:18:59 INFO - [mozharness: 2017-04-20 23:18:59.982780Z] Running populate-webroot step. 16:18:59 INFO - Running pre-action listener: _resource_record_pre_action 16:18:59 INFO - Running main action method: populate_webroot 16:18:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:59 INFO - '--tppagecycles', 16:18:59 INFO - '7'], 16:18:59 INFO - 'tests': ['tsvgm']}}, 16:18:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:18:59 INFO - 'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:18:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:18:59 INFO - 'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['damp', 'tps']}, 16:18:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:18:59 INFO - 'tests': ['damp', 'tps']}, 16:18:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['dromaeo_dom']}, 16:18:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:18:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:18:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['a11yr', 16:18:59 INFO - 'ts_paint', 16:18:59 INFO - 'tpaint', 16:18:59 INFO - 'sessionrestore', 16:18:59 INFO - 'sessionrestore_no_auto_restore', 16:18:59 INFO - 'tabpaint']}, 16:18:59 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:18:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:18:59 INFO - 'bloom_basic_ref']}, 16:18:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:59 INFO - 'tests': ['tsvgx', 16:18:59 INFO - 'tsvgr_opacity', 16:18:59 INFO - 'tart', 16:18:59 INFO - 'tscrollx', 16:18:59 INFO - 'cart', 16:18:59 INFO - 'tsvg_static']}, 16:18:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:59 INFO - 'tsvgr_opacity', 16:18:59 INFO - 'tart', 16:18:59 INFO - 'tscrollx', 16:18:59 INFO - 'cart', 16:18:59 INFO - 'tsvg_static']}, 16:18:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['tp5o']}, 16:19:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:19:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s', 16:19:00 INFO - '--xperf_path', 16:19:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:00 INFO - 'tests': ['tp5n']}, 16:19:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:19:00 INFO - 'talos_options': ['--xperf_path', 16:19:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:00 INFO - 'tests': ['tp5n']}}, 16:19:00 INFO - 'talos.zip': {'path': '', 16:19:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:00 INFO - Running post-action listener: _resource_record_post_action 16:19:00 INFO - [mozharness: 2017-04-20 23:19:00.001559Z] Finished populate-webroot step (success) 16:19:00 INFO - [mozharness: 2017-04-20 23:19:00.001669Z] Running create-virtualenv step. 16:19:00 INFO - Running pre-action listener: _resource_record_pre_action 16:19:00 INFO - Running main action method: create_virtualenv 16:19:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:19:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:00 INFO - Using real prefix '/tools/python27' 16:19:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:00 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:03 INFO - Installing pip.................done. 16:19:03 INFO - Return code: 0 16:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:19:04 INFO - Reading from file tmpfile_stdout 16:19:04 INFO - Output received: 16:19:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:19:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:04 INFO - 'HOME': '/Users/cltbld', 16:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:04 INFO - 'LOGNAME': 'cltbld', 16:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:04 INFO - 'MOZ_NO_REMOTE': '1', 16:19:04 INFO - 'NO_EM_RESTART': '1', 16:19:04 INFO - 'PAGER': '/bin/cat', 16:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:04 INFO - 'PWD': '/builds/slave/test', 16:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:04 INFO - 'SHELL': '/bin/bash', 16:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:04 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:04 INFO - 'USER': 'cltbld', 16:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:04 INFO - 'XPC_FLAGS': '0x0', 16:19:04 INFO - 'XPC_SERVICE_NAME': '0', 16:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:19:04 INFO - Cleaning up... 16:19:04 INFO - Return code: 0 16:19:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:04 INFO - 'HOME': '/Users/cltbld', 16:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:04 INFO - 'LOGNAME': 'cltbld', 16:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:04 INFO - 'MOZ_NO_REMOTE': '1', 16:19:04 INFO - 'NO_EM_RESTART': '1', 16:19:04 INFO - 'PAGER': '/bin/cat', 16:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:04 INFO - 'PWD': '/builds/slave/test', 16:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:04 INFO - 'SHELL': '/bin/bash', 16:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:04 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:04 INFO - 'USER': 'cltbld', 16:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:04 INFO - 'XPC_FLAGS': '0x0', 16:19:04 INFO - 'XPC_SERVICE_NAME': '0', 16:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:04 INFO - Downloading/unpacking psutil>=3.1.1 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:08 INFO - Installing collected packages: psutil 16:19:08 INFO - Running setup.py install for psutil 16:19:08 INFO - building 'psutil._psutil_osx' extension 16:19:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:19:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:19:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:19:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:19:09 INFO - building 'psutil._psutil_posix' extension 16:19:09 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 16:19:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:19:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:19:09 INFO - ^ 16:19:09 INFO - 1 warning generated. 16:19:09 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 16:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:09 INFO - Successfully installed psutil 16:19:09 INFO - Cleaning up... 16:19:09 INFO - Return code: 0 16:19:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:09 INFO - 'HOME': '/Users/cltbld', 16:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:09 INFO - 'LOGNAME': 'cltbld', 16:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:09 INFO - 'MOZ_NO_REMOTE': '1', 16:19:09 INFO - 'NO_EM_RESTART': '1', 16:19:09 INFO - 'PAGER': '/bin/cat', 16:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:09 INFO - 'PWD': '/builds/slave/test', 16:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:09 INFO - 'SHELL': '/bin/bash', 16:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:09 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:09 INFO - 'USER': 'cltbld', 16:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:09 INFO - 'XPC_FLAGS': '0x0', 16:19:09 INFO - 'XPC_SERVICE_NAME': '0', 16:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:19:12 INFO - Installing collected packages: mozsystemmonitor 16:19:12 INFO - Running setup.py install for mozsystemmonitor 16:19:12 INFO - Successfully installed mozsystemmonitor 16:19:12 INFO - Cleaning up... 16:19:12 INFO - Return code: 0 16:19:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:12 INFO - 'HOME': '/Users/cltbld', 16:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:12 INFO - 'LOGNAME': 'cltbld', 16:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:12 INFO - 'MOZ_NO_REMOTE': '1', 16:19:12 INFO - 'NO_EM_RESTART': '1', 16:19:12 INFO - 'PAGER': '/bin/cat', 16:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:12 INFO - 'PWD': '/builds/slave/test', 16:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:12 INFO - 'SHELL': '/bin/bash', 16:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:12 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:12 INFO - 'USER': 'cltbld', 16:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:12 INFO - 'XPC_FLAGS': '0x0', 16:19:12 INFO - 'XPC_SERVICE_NAME': '0', 16:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:16 INFO - warning: no files found matching '*.txt' 16:19:16 INFO - no previously-included directories found matching 'build' 16:19:16 INFO - no previously-included directories found matching 'dist' 16:19:16 INFO - no previously-included directories found matching '.git*' 16:19:16 INFO - Installing collected packages: jsonschema, functools32 16:19:16 INFO - Running setup.py install for functools32 16:19:16 INFO - warning: no files found matching '*.txt' 16:19:16 INFO - no previously-included directories found matching 'build' 16:19:16 INFO - no previously-included directories found matching 'dist' 16:19:16 INFO - no previously-included directories found matching '.git*' 16:19:16 INFO - Successfully installed jsonschema functools32 16:19:16 INFO - Cleaning up... 16:19:16 INFO - Return code: 0 16:19:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:16 INFO - 'HOME': '/Users/cltbld', 16:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:16 INFO - 'LOGNAME': 'cltbld', 16:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:16 INFO - 'MOZ_NO_REMOTE': '1', 16:19:16 INFO - 'NO_EM_RESTART': '1', 16:19:16 INFO - 'PAGER': '/bin/cat', 16:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:16 INFO - 'PWD': '/builds/slave/test', 16:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:16 INFO - 'SHELL': '/bin/bash', 16:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:16 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:16 INFO - 'USER': 'cltbld', 16:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:16 INFO - 'XPC_FLAGS': '0x0', 16:19:16 INFO - 'XPC_SERVICE_NAME': '0', 16:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:19:16 INFO - Cleaning up... 16:19:16 INFO - Return code: 0 16:19:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:16 INFO - 'HOME': '/Users/cltbld', 16:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:16 INFO - 'LOGNAME': 'cltbld', 16:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:16 INFO - 'MOZ_NO_REMOTE': '1', 16:19:16 INFO - 'NO_EM_RESTART': '1', 16:19:16 INFO - 'PAGER': '/bin/cat', 16:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:16 INFO - 'PWD': '/builds/slave/test', 16:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:16 INFO - 'SHELL': '/bin/bash', 16:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:16 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:16 INFO - 'USER': 'cltbld', 16:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:16 INFO - 'XPC_FLAGS': '0x0', 16:19:16 INFO - 'XPC_SERVICE_NAME': '0', 16:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:17 INFO - Downloading/unpacking blobuploader==1.2.4 16:19:17 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 16:19:17 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 16:19:17 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 16:19:17 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 16:19:17 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 16:19:17 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 16:19:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 INFO - Downloading docopt-0.6.1.tar.gz 16:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:21 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:21 INFO - Running setup.py install for blobuploader 16:19:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:21 INFO - Running setup.py install for requests 16:19:21 INFO - Running setup.py install for docopt 16:19:21 INFO - Successfully installed blobuploader requests docopt 16:19:21 INFO - Cleaning up... 16:19:21 INFO - Return code: 0 16:19:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:21 INFO - 'HOME': '/Users/cltbld', 16:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:21 INFO - 'LOGNAME': 'cltbld', 16:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:21 INFO - 'MOZ_NO_REMOTE': '1', 16:19:21 INFO - 'NO_EM_RESTART': '1', 16:19:21 INFO - 'PAGER': '/bin/cat', 16:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:21 INFO - 'PWD': '/builds/slave/test', 16:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:21 INFO - 'SHELL': '/bin/bash', 16:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:21 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:21 INFO - 'USER': 'cltbld', 16:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:21 INFO - 'XPC_FLAGS': '0x0', 16:19:21 INFO - 'XPC_SERVICE_NAME': '0', 16:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-x1J84v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-GAyziL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ie1d7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-TSri3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-QUUmgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-M06cUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Nk6Fe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:22 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Lssfh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-7AVmjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-T_HTCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-bNdSrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-dyuVab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-sc2RvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Oig_Na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-aRymVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-BampC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:23 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-v69p9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:23 INFO - Running setup.py install for manifestparser 16:19:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozcrash 16:19:24 INFO - Running setup.py install for mozdebug 16:19:24 INFO - Running setup.py install for mozdevice 16:19:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozfile 16:19:24 INFO - Running setup.py install for mozhttpd 16:19:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozinfo 16:19:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozInstall 16:19:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozleak 16:19:25 INFO - Running setup.py install for mozlog 16:19:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for moznetwork 16:19:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozprocess 16:19:25 INFO - Running setup.py install for mozprofile 16:19:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozrunner 16:19:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozscreenshot 16:19:26 INFO - Running setup.py install for moztest 16:19:26 INFO - Running setup.py install for mozversion 16:19:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:26 INFO - Cleaning up... 16:19:26 INFO - Return code: 0 16:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:26 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 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:26 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 16:19:26 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 16:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:26 INFO - 'HOME': '/Users/cltbld', 16:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:26 INFO - 'LOGNAME': 'cltbld', 16:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:26 INFO - 'MOZ_NO_REMOTE': '1', 16:19:26 INFO - 'NO_EM_RESTART': '1', 16:19:26 INFO - 'PAGER': '/bin/cat', 16:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:26 INFO - 'PWD': '/builds/slave/test', 16:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:26 INFO - 'SHELL': '/bin/bash', 16:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:26 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:26 INFO - 'USER': 'cltbld', 16:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:26 INFO - 'XPC_FLAGS': '0x0', 16:19:26 INFO - 'XPC_SERVICE_NAME': '0', 16:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:26 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-JaQxNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:26 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-7qmBg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-UAuhpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-I741QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-_WMdsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-jSGD6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Hg5uN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-YKCAxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-XdTn2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-EwaaPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:27 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-LHwvfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-hOtzi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-gRNup6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-mn5CWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-0VVrhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-olMFzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:28 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-akjqz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:31 INFO - Downloading blessings-1.6.tar.gz 16:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:31 INFO - Installing collected packages: blessings 16:19:31 INFO - Running setup.py install for blessings 16:19:31 INFO - Successfully installed blessings 16:19:31 INFO - Cleaning up... 16:19:31 INFO - Return code: 0 16:19:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Current package versions: 16:19:32 INFO - blessings == 1.6 16:19:32 INFO - blobuploader == 1.2.4 16:19:32 INFO - docopt == 0.6.1 16:19:32 INFO - functools32 == 3.2.3-2 16:19:32 INFO - jsonschema == 2.5.1 16:19:32 INFO - manifestparser == 1.1 16:19:32 INFO - mozInstall == 1.12 16:19:32 INFO - mozcrash == 1.0 16:19:32 INFO - mozdebug == 0.1 16:19:32 INFO - mozdevice == 0.50 16:19:32 INFO - mozfile == 1.2 16:19:32 INFO - mozhttpd == 0.7 16:19:32 INFO - mozinfo == 0.9 16:19:32 INFO - mozleak == 0.1 16:19:32 INFO - mozlog == 3.4 16:19:32 INFO - moznetwork == 0.27 16:19:32 INFO - mozprocess == 0.25 16:19:32 INFO - mozprofile == 0.28 16:19:32 INFO - mozrunner == 6.13 16:19:32 INFO - mozscreenshot == 0.1 16:19:32 INFO - mozsystemmonitor == 0.3 16:19:32 INFO - moztest == 0.8 16:19:32 INFO - mozversion == 1.4 16:19:32 INFO - psutil == 3.1.1 16:19:32 INFO - requests == 1.2.3 16:19:32 INFO - wsgiref == 0.1.2 16:19:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:32 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')]} 16:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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')]} 16:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:32 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 16:19:32 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 16:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:32 INFO - 'HOME': '/Users/cltbld', 16:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:32 INFO - 'LOGNAME': 'cltbld', 16:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:32 INFO - 'MOZ_NO_REMOTE': '1', 16:19:32 INFO - 'NO_EM_RESTART': '1', 16:19:32 INFO - 'PAGER': '/bin/cat', 16:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:32 INFO - 'PWD': '/builds/slave/test', 16:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:32 INFO - 'SHELL': '/bin/bash', 16:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:32 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:32 INFO - 'USER': 'cltbld', 16:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:32 INFO - 'XPC_FLAGS': '0x0', 16:19:32 INFO - 'XPC_SERVICE_NAME': '0', 16:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 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)) 16:19:32 INFO - Cleaning up... 16:19:32 INFO - Return code: 0 16:19:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19:32 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')]} 16:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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')]} 16:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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 0x1049a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e858dfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, '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.cudnHgk8FO/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.9efmddzbrN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 16:19:32 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 16:19:32 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 16:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:32 INFO - 'HOME': '/Users/cltbld', 16:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:32 INFO - 'LOGNAME': 'cltbld', 16:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:32 INFO - 'MOZ_NO_REMOTE': '1', 16:19:32 INFO - 'NO_EM_RESTART': '1', 16:19:32 INFO - 'PAGER': '/bin/cat', 16:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:32 INFO - 'PWD': '/builds/slave/test', 16:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:32 INFO - 'SHELL': '/bin/bash', 16:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:32 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:32 INFO - 'USER': 'cltbld', 16:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:32 INFO - 'XPC_FLAGS': '0x0', 16:19:32 INFO - 'XPC_SERVICE_NAME': '0', 16:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:19:32 INFO - Cleaning up... 16:19:32 INFO - Return code: 0 16:19:32 INFO - Running post-action listener: _resource_record_post_action 16:19:32 INFO - Running post-action listener: _start_resource_monitoring 16:19:32 INFO - Starting resource monitoring. 16:19:32 INFO - [mozharness: 2017-04-20 23:19:32.615124Z] Finished create-virtualenv step (success) 16:19:32 INFO - [mozharness: 2017-04-20 23:19:32.615953Z] Running install step. 16:19:32 INFO - Running pre-action listener: _resource_record_pre_action 16:19:32 INFO - Running main action method: install 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Output received: 16:19:32 INFO - Usage: mozinstall [options] installer 16:19:32 INFO - Options: 16:19:32 INFO - -h, --help show this help message and exit 16:19:32 INFO - -d DEST, --destination=DEST 16:19:32 INFO - Directory to install application into. [default: 16:19:32 INFO - "/builds/slave/test"] 16:19:32 INFO - --app=APP Application being installed. [default: firefox] 16:19:32 INFO - mkdir: /builds/slave/test/build/application 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:19:52 INFO - Reading from file tmpfile_stdout 16:19:52 INFO - Output received: 16:19:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:19:52 INFO - Running post-action listener: _resource_record_post_action 16:19:52 INFO - [mozharness: 2017-04-20 23:19:52.888880Z] Finished install step (success) 16:19:52 INFO - [mozharness: 2017-04-20 23:19:52.889035Z] Running run-tests step. 16:19:52 INFO - Running pre-action listener: _resource_record_pre_action 16:19:52 INFO - Running main action method: run_tests 16:19:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:19:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:19:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:19:52 INFO - 2017-04-20 16:19:52.902 screenresolution[1638:7548] starting screenresolution argv=screenresolution get 16:19:52 INFO - 2017-04-20 16:19:52.905 screenresolution[1638:7548] Display 0: 1600x1200x32@60 16:19:52 INFO - 2017-04-20 16:19:52.910 screenresolution[1639:7550] starting screenresolution argv=screenresolution list 16:19:52 INFO - Available Modes on Display 0 16:19:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:19:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:19:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:19:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:19:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:19:53 INFO - Intel Iris: 16:19:53 INFO - Chipset Model: Intel Iris 16:19:53 INFO - Type: GPU 16:19:53 INFO - Bus: Built-In 16:19:53 INFO - VRAM (Dynamic, Max): 1536 MB 16:19:53 INFO - Vendor: Intel (0x8086) 16:19:53 INFO - Device ID: 0x0a2e 16:19:53 INFO - Revision ID: 0x0009 16:19:53 INFO - Displays: 16:19:53 INFO - Display: 16:19:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:19:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:19:53 INFO - Display Serial Number: 1600x1200 60 16:19:53 INFO - Main Display: Yes 16:19:53 INFO - Mirror: Off 16:19:53 INFO - Online: Yes 16:19:53 INFO - Rotation: Supported 16:19:53 INFO - Return code: 0 16:19:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:53 INFO - Python 2.7.3 16:19:53 INFO - Return code: 0 16:19:53 INFO - grabbing minidump binary from tooltool 16:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104785d30>, '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 0x1046abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10477abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:19:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:19:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:19:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:53 INFO - Return code: 0 16:19:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:19:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:53 INFO - ENV: RUST_BACKTRACE is now 1 16:19:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:19:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0357 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cudnHgk8FO/Render', 16:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:53 INFO - 'HOME': '/Users/cltbld', 16:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:53 INFO - 'LOGNAME': 'cltbld', 16:19:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:53 INFO - 'MOZ_NO_REMOTE': '1', 16:19:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:53 INFO - 'NO_EM_RESTART': '1', 16:19:53 INFO - 'PAGER': '/bin/cat', 16:19:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:53 INFO - 'PWD': '/builds/slave/test', 16:19:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:53 INFO - 'RUST_BACKTRACE': '1', 16:19:53 INFO - 'SHELL': '/bin/bash', 16:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9efmddzbrN/Listeners', 16:19:53 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 16:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:53 INFO - 'USER': 'cltbld', 16:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:53 INFO - 'XPC_FLAGS': '0x0', 16:19:53 INFO - 'XPC_SERVICE_NAME': '0', 16:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:19:53 INFO - mozversion application_buildid: 20170420144628 16:19:53 INFO - mozversion application_changeset: 5e3dc7e1288af835220871fefdb18d520efb06d4 16:19:53 INFO - mozversion application_display_name: Nightly 16:19:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:53 INFO - mozversion application_name: Firefox 16:19:53 INFO - mozversion application_remotingname: firefox 16:19:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:19:53 INFO - mozversion application_vendor: Mozilla 16:19:53 INFO - mozversion application_version: 55.0a1 16:19:53 INFO - mozversion platform_buildid: 20170420144628 16:19:53 INFO - mozversion platform_changeset: 5e3dc7e1288af835220871fefdb18d520efb06d4 16:19:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:19:53 INFO - mozversion platform_version: 55.0a1 16:19:53 INFO - using testdate: 1492730393 16:19:53 INFO - actual date: 1492730393 16:19:53 INFO - starting webserver on 'localhost:49243' 16:19:53 INFO - SUITE-START | Running 2 tests 16:19:53 INFO - TEST-START | dromaeo_css 16:19:53 INFO - operating with platform_type : mac_ 16:19:53 INFO - Initialising browser for dromaeo_css test... 16:19:53 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpA3k6AS/profile http://localhost:49243/getInfo.html) 16:19:56 INFO - PID 1649 | 2017-04-20 16:19:56.512 plugin-container[1651:7740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 16:19:56 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:19:56 INFO - PID 1649 | 2017-04-20 16:19:56.515 plugin-container[1651:7740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 16:19:56 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:19:56 INFO - PID 1649 | 2017-04-20 16:19:56.516 plugin-container[1651:7740] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:19:56 INFO - PID 1649 | __metrics Screen width/height:1600/1200 16:19:56 INFO - PID 1649 | colorDepth:24 16:19:56 INFO - PID 1649 | Browser inner width/height: 1024/689 16:19:56 INFO - PID 1649 | __metrics 16:19:56 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 16:19:57 INFO - TEST-INFO | 1649: exit 0 16:19:57 INFO - Browser initialized. 16:19:57 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:57 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpA3k6AS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:00 INFO - PID 1652 | 2017-04-20 16:20:00.310 plugin-container[1653:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:20:00 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1652 | 2017-04-20 16:20:00.310 plugin-container[1653:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 16:20:00 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1652 | 2017-04-20 16:20:00.310 plugin-container[1653:7868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:10 INFO - PID 1652 | RSS: Main: 235855872 16:20:10 INFO - PID 1652 | 16:23:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 16:23:15 INFO - PID 1652 | RSS: Main: 251494400 16:23:15 INFO - PID 1652 | 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 16:24:58 INFO - PID 1652 | 16:24:58 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:24:58 INFO - PID 1652 | Full stack: 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 16:24:58 INFO - PID 1652 | 16:24:58 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:24:58 INFO - PID 1652 | Full stack: 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 16:24:58 INFO - PID 1652 | 16:24:58 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:24:58 INFO - PID 1652 | Full stack: 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | ************************* 16:24:58 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 16:24:58 INFO - PID 1652 | 16:24:58 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:24:58 INFO - PID 1652 | Full stack: 16:24:58 INFO - PID 1652 | ************************* 16:26:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 16:26:18 INFO - PID 1652 | RSS: Main: 258052096 16:26:18 INFO - PID 1652 | 16:29:19 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 16:29:20 INFO - PID 1652 | RSS: Main: 267726848 16:29:20 INFO - PID 1652 | 16:32:22 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 16:32:22 INFO - PID 1652 | RSS: Main: 262381568 16:32:22 INFO - PID 1652 | 16:35:25 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 16:35:26 INFO - PID 1652 | RSS: Main: 270503936 16:35:26 INFO - PID 1652 | 16:38:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 16:38:28 INFO - PID 1652 | RSS: Main: 266145792 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | __start_tp_report 16:38:28 INFO - PID 1652 | _x_x_mozilla_page_load 16:38:28 INFO - PID 1652 | _x_x_mozilla_page_load_details 16:38:28 INFO - PID 1652 | |i|pagename|runs| 16:38:28 INFO - PID 1652 | |0;dojo.html;3529.3647143514163;3560.928781424373;3583.569971603408;3606.0804494853815;3613.295407395559;5981.910271345922;6125.448709616134;6206.410391012852;6234.0648902664625;6241.937580624192;6074.453299203071;6118.984682626211;6120.510359171267;6203.751849925998;6229.688515574226;4477.932831007522;5369.114096174127;5379.65032272901;5382.273393091931;5499.340079190482;5350.036993341197;5426.8371948841595;5479.287692599956;5490.231367608538;5514.834554963338;5245.291885595472;5304.177852432879;5441.455854414567;5449.100898351767;5485.424030476803;5288.365396152486;5290.629655924087;5311.814086506954;5369.973150134263;5411.350637923474;5264.631475796696;5279.736013199325;5297.841064768063;5319;5357.116075847481;5857.502112320458;6110.175126357974;6121.693915304217;6146.200993870812;6152.923238433256;6316.399942005502;6375.2349718033665;6443.645599492026;6485.77299794505;6527.640979746112;6632.436242919358;6661.16735408074;6670.0661907333015;6690.966545167292;6708.228553716336;6155.630662160284;6272.341404152589;6289.8742025159245;6296.087067375251;6312.495000399957;6444.806655800334;6588.308227636029;6734.1918969722965;6821.93178068213;6850.520463567503;6251.031090181029;6313.526485513605;6335.429811317003;6338.968305158537;6397.552171347961;5535.6955367454375;5714.285714285715;6193.349698281706;6519.054737063099;6619.8084344817435;6441.426904649623;6452.999785033234;6476.141058135594;6507.772227971998;6525.11910892033;6302.023186406114;6337.239531256187;6358.809235722936;6414.61512309263;6551.737930482823;4934.988327392876;5278.97360513195;5294.788208471695;5330.200469929541;5367.033933891937;4347.6304514116655;4720.88197795051;4735.360726301974;4746.620270378361;4771.212749896297;5871;6188.4739797117745;6264.9373506265265;6360.586561873556;6386;6391.872162556723;6550.2794692583775;6579.638119903356;6584.144061272004;6645.235797883209;6332.240131184288;6343.175387199634;6350.8412289692205;6372.36276372359;6380.138681278061;5842.737076831553;5860.560457965669;5866.178734977107;5872;5879.118132280107;6395.552311338255;6482;6531.412172904462;6573.1783527059115;6593.703283352164;6644.60132392058;6660.134182556237;6666.966665166837;6670.39966403026;6684.4318232950745;5445.50990410865;5922.111683247547;6561.835954101186;6771.187457505131;6834.487413444111;4954.653174277766;6240.5319601031015;6315.368463153648;6343.111964324906;6399.008153736178;6622.602643841385;6639.535232533676;6643.269240383457;6672.366125218096;6673.799786006428;6230.034344676583;6329.620222786648;6402.871942561216;6437.5171862111465;6449.709763060768;5150.381954165524;6343.0485427186295;6409.134766806422;6413.8717225656155;6451.612903225821;5213.60897932649;6262.624242545279;6297.811065668037;6330.208723909512;6352.396522330371;6257.1552840367785;6396.584222025554;6438.323975982455;6474.676266186578;6477.190351206099;6222.875542489215;6240.344763799736;6262.906056409066;6308.621482711052;6332.715027823851 16:38:28 INFO - PID 1652 | |1;ext.html;725.5646612032781;789.0531362365161;813.1177672225637;1166.26525289068;1182.757534705386;36494;36676.44985325222;36709;36715.816420917894;36743.81628091859;36982.63017369826;37279.627203727956;37280.88157355277;37291.06772330693;37292.067698307554;7900.407469439786;8115.310198633094;8318.958405207966;8574.871376929352;8578.528180950045;8492.53291068994;8534.359923005768;8558.871616925751;8622;8622.827543449159;8555.700550480735;8572.442791218551;8641;8648.65405383787;8669.609867555944;7500.512466689703;8574.69988550401;8626.956865215696;8626.956865215696;8631.525266110362;8370.497770133812;8372.83254334916;8388.496690198608;8448.155184481564;8451.53516556589;30242.790288388438;30788.692113078807;36586.63413365885;36646;36913.63086369129;3556.342076715816;3590.7486475946694;3593.1915319053264;3593.7125029997537;3602.441621548664;30353.848230758926;30395.544066839015;30409.847950760104;30545.541816872767;30712;3087.783855130142;3114.68853114689;3127.8592463339205;3129.405412971528;3130.9217269568444;223045;225576;261060.3893961074;274805;274964;39301.41047884284;46369.76815115903;46468.30297545539;46592.7670361646;46808.76595617;39911.80264592067;48062.759686201345;48349.75825120922;48386.758066210146;48566;13412.0611557192;13455.192688438725;13494;13541.59375218745;13556.59330220095;11010.889891100986;11016.779664406668;11038.558457661604;11054.060404865515;11061.059809916247;13290.667733306746;13318.66703332405;13346.399412026482;13375.264360460074;13403.731925361439;5217.660852044605;5878.823635290948;5957.076653118774;5960.225170727865;5977.163197152402;1013.3565186106728;1026.630413051301;1036.461040259061;1060.8143574874366;1064.5422468338534;22805.657915131287;23977.880110599337;24014.639780403308;24214.63678044831;24226.63660045101;1045.7751583409452;1078.829470025016;1126.7325254435646;1132.7168207948014;1141.7431078007548;11152.60965866207;11247.268927519684;12165.756684866254;12300.876991229974;12380.690482738004;300799;301387;301438;302828.4858575693;302829;42263;50266;50614.746926265136;50691;50742;1478.1943840606896;1504.6984358530012;1535.646801235722;1538.3077615073262;1540.5763415060949;18403.63192736111;23274.883625581762;23683.64474532849;23891.402714932265;24090.75909240885;34355.65644343525;35911.28177436489;36011.45982810312;36066.639333607374;36250.27499450049;269348;270208;270358.64820675773;270745;271689;32051.83974080208;32400.675993239765;33257;33780.324393512485;33835;41797.53708620184;42806.57193428151;42867.785661071495;43654.12691746084;43921.78039109784;29994.700053000062;30374.84812575923;30435.695643043287;30667.693323066484;30775.846120769253;30695.693043070176;30882.227944301572;30979.845100774353;31001.8449907749;31132;36901.63098368982;37252.813735931144;37388.813055934545;37389.43915841319;37472.81263593664;29316;30250.69749302479;30408.695913040585;31125.844370778002;31149.688503114678;22329.553408931406;23638.52722945566;24477;24510.509789803746;24770.50458990774 16:38:28 INFO - PID 1652 | |2;jquery.html;423.39789086943927;424.16651280234333;432.200429205969;445.7392425431122;451.1788544848377;18792.90603546982;20469.590608187846;21084.8945755271;21229.044692988813;21272.14911403544;20479.48801279965;22281.777182228172;22307.44231394216;22391.328260152222;22400.21599244027;6024.879502409949;6314.116023756677;6314.557980941336;6317.305096439388;6318.14705014824;5257.86855328616;6535;6930;6983.266756990519;6999.160100787913;6704.430123439513;6950.895736563955;6961.269066747995;6972.163340399134;6994.965025174867;6941.34057264559;6980.534087841559;6992.965035174842;7000.29997000301;7045.4715896307725;5627.887442251173;6781.796546103576;6870.141232345957;6874;6906.102206713145;28944.42111157786;29374.706252937413;29507.55738663922;29546;29640.40719185626;2335.054303007285;2374.9406264843346;2393.365758074112;2406.9638955415685;2408.3497455687043;18207.45377638673;18674.439766807016;19385.224591016344;19396.321128760424;19644.50888727779;2093.3196711068963;2278.28234106256;2298.8275879309117;2366;2379.9524009519714;129142;131041;161706;163425;166039;22367.9934402949;23442.413939651306;24250.272491825275;25867.22398328053;25935.740642593333;29482.557761633594;36931.4460283096;39236;40821.79589102035;41151.29638332564;9435.339526233098;9793.902060979299;10193.286469947181;10578.312409693346;10596.417197054081;8486.024107227624;8491.575421228914;8513.063563008063;8608.612612432456;8654.394192406597;11334.563068940053;11635.185537012498;11701.707457313465;11790.469428875549;11809.763804723858;4597.586217240464;4712.387389639325;4718.103560323528;4751.576695885124;4755.690880092783;953.547065144051;959.7924773022161;971.3394891473865;984.3995162950595;984.9655262065936;17582.91208543949;20026.899865500578;20868.956552172633;21073.78926210749;21254.78745212559;909.5634095634133;970.8689326940772;1010.2170817616407;1011.630754774502;1012.0638409471356;9729.854052189223;10703.357798532114;10972;10975;10978.615748448767;567368;567747;574183.258167413;583017;583093;101369;119988.20017699742;127069.37583745005;129986;131704;1126.3636045634191;1144.6909334479644;1164.8485696859354;1165.0272022861006;1172.407933993328;14303.213323266904;15145.545633631009;16983.7452438216;17742.733858992382;18047.917124972544;56604.71697641486;56701.14948275697;57235.713821430625;58080.4191958075;58376.708116459144;61698.223579938116;71349.92975105274;73786.26213737794;75883;77326.61336693281;7976.8005799853145;8491.74524764258;8955.776105597412;8998.190162885372;9267.76830579241;14463.421463141356;14624;14856.480023199136;15108.697826043637;15161.545153645407;11336.716582085513;11929.46317415701;12138.514459421523;12190.817137743119;12297.508099675913;10947.83578246343;11664;11886;11887.702807429883;12158.148929574845;11182.608608698656;11742.354170520362;12347.13570050088;12443.191192572454;12601.243925364508;8897.421667591585;9153.542322883695;9223.031581683827;9411;9412;12036.398180091135;12128.393580321124;12422.937885310515;12451.066170037464;12549.49802007946 16:38:28 INFO - PID 1652 | |3;mootools.html;1288.207752232377;1440.5966329427752;1451.7314296855077;1475.7491226491495;1476.0627001853827;11289.717757056067;11747.001504872085;11781;11827.172097953147;12180.756384872297;9883.456409897453;11172.944135279333;11770.764584708322;11966.641000769969;11969.222000569956;2500.3124140861228;2543.211604402632;2548.0062775517563;2558.2069558436942;2558.2325302409295;2472.4807790364043;2505.962410563843;2545.300042488321;2549.3244290263096;2554.6040363743555;2453.9631905521433;2514.9748502515017;2526.027479420427;2534.4170840706647;2536.581464058428;2425.223928342931;2492.8753562321904;2503.436726736488;2522.697276326834;2527.886245118974;2438.8170887183533;2443.059422122478;2450.620153876152;2452.9877350613133;2559.552078386275;11878.58424955131;11905;12005.339706316148;12006.039516838719;12032.217905836093;6648;6853.554518956252;6888.827779305508;6907.102076730043;6920.134983127109;3990.06233535119;4278.443802305712;4380.3210502372185;4474.105178964221;4520.3219517072375;1204.1871736577775;1205.8010428279324;1243.4466662335265;1298.4546490474024;1301.1737546657846;16271.59321016984;16575.6684866302;16652.33390664372;16669.91665041667;16900.239489223015;5717.6277693353295;5785.103308987113;5785.27684039495;5966.970165149147;5982.49148822355;10658.62694805678;12442.875571244353;12445.315507647167;12446.190997585129;12485.93757031209;6584.76953306632;6664.300248473937;6668.233153187445;6670.599764014175;6673.332666733288;5983;6103.328633850273;6170.907436388457;6213.596116252429;6282.057691346335;4304.892377690583;4442.644588432918;4458.888527786831;4500.887477813081;4515.819367225275;1287.1697754948007;1320.7688654485619;1345.4819894340749;1360.5578187089236;1364.733876894016;684.2404930527111;798.7044793426469;801.5511313664366;805.4080251015499;807.1605530248593;6128.877422451526;6186.566940314135;6203.906941395882;6317.3366796486625;6317.557770956081;562.0473297760338;569.894569504643;591.9526437884958;596.4423264247943;598.5809933046885;3893.377059670496;4109.91780164401;4271.978640106841;4372.366006928977;4400.73595584266;14569;15026.098434093989;15182.696346073017;15396.846031539766;15451.304691288919;14913.3289001993;15330.310136043905;15424.691506169815;15429.30568124437;15430.9198356114;8245.587720613945;8544;8554.529500877512;8719.738407847775;8727.956360218286;6959.164900212007;7146.106736657918;7203.783886483414;7272.527285726411;7275.090613673291;13314.600561983156;13570;13570.728585428433;13609.727805444034;13864.653383665489;13999.650008749863;14046;14058.156510609397;14231;14341;9500.144986951207;9506.334556581249;9573.952130239304;9673.419594824332;9698.6120555177;10661.466926653791;10684.358938463716;10792.190585705946;10806.675799726021;10811;9097.954510227406;9139.131782480654;9227.35408521397;9370.531473426165;9417.152456278967;7540.622968851645;8456.450330728483;8878.822423551623;9079.273658107204;9088.227500662519;8075;8081.63632636521;8184.918150818416;8205.671773129008;8232.300254478198;5434.375046869661;5451;5765.423457654369;6057.394260573908;6063.878722425438;5571.746357069627;5692.48767610917;6011.6693581853415;6127.20346354971;6285.685715714288 16:38:28 INFO - PID 1652 | |4;prototype.html;384.10886742756816;405.89040958941087;406.86370066027877;410.67146282973613;414.1137964755423;5433.185022246665;5992.670403127826;6203.47270482009;6261.3425590313045;6265.436110750026;5495.780168793253;5902.409759024106;6053.485453736438;6072.605280656754;6147.47746441553;4096.959030409695;4517.525659805706;4643.326717625942;4677.251639737643;4710.105080034799;4411.779411029452;4511.4135162429;4588.24293991491;4628.884277893046;4663.207254766681;4113.629773320401;4144.254034273829;4188.539260681323;4294.484661840568;4550.976030393152;4655.580997710223;4699.318598803188;4709.011107667394;4711.552402521772;4721.480637129913;3334.883279085245;3470.993411910543;3594.5621751299427;3676.8601733462447;3988.222296652154;5117;5794.97102514489;5941;5961.821145365602;6000.75996960121;4833.806647734122;5372.731363431852;5389.83830485086;5393.19102134679;5414.886440296845;5042.042012017705;5906.645601263938;6298.622082675009;6475.158229430144;6503.577267477599;4384.495782984965;4395.703267536084;4400.339949007643;4401.515833258339;4419.872932402217;6766.932330676679;6900.378965893094;7010.581349434437;7030.894536581955;7033.487800122947;3651.799151046665;3697.8721489945287;3775.981120094382;4432.401625780542;4455.710378825366;4527;4532.0256144928335;4546.65900057497;4548.363229147922;4572.725636461823;4583;5272.604554658416;5341.572674186055;5345.81289654868;5400.271912987806;6238.812835614939;6324.209473815773;6458.5479016469335;6556.8034708373225;6566.606003639797;1524.695060987807;1540.63795008173;1547.8916475846584;1553.1923399832024;1562.7968364112592;869.9739007829776;907.6838584053149;944.5418971798672;1006.4263369879245;1024.4479613385727;6152.569320147636;6189.257289125244;6224.284207316126;6257.812265632038;6265.686715664198;6302;6732.427743641747;6737.461003119739;6744.62904540255;6798.320167983261;5941;6039;6131.4977830430935;6187.659678717714;6224.159738435344;3101.581286526335;3494.7378946579106;3542.6811586956783;3543.326767914088;3548.4854696068924;6100.847478813065;6667.566473208187;6880.518363714492;7012.614306213105;7025.8898544351105;6878.8624227515165;6933.237343892168;6945.978499352797;6947.4789390795895;6969.1985421676145;5362.8659283517445;5510.834674959758;5962.910556341745;5975.166460300589;6170.629762214281;6141.416565426277;6661.903643712467;6736.191657001191;6737.966310168418;6762.763303284361;5703.771849125988;6314;6559.672016399257;6618.2719900811835;6681.031250468558;6629.745886398648;6696.631685257163;6887.793366199022;6965.373116419547;6995.825104372431;3941.6452519273403;4440.868383176158;4452.398926144929;4455.487618923736;4475.3734477172575;5821.27234095738;6055.426443761472;6652.301508341555;6692;6776.254611992578;5347.438518955453;5399.83800485986;6239.563230573816;6444.709988050456;6489.902651460137;5249.6850188987255;5683.2895888014;6417.26201486835;6539.346065393423;6559.934400656123;6329.588576742497;6358.268799088164;6363.3636636336;6378.170837791057;6391.840203994937;6086.786962456293;6542.677349541034;6551.541392102507;6553.279139294769;6609.834754130993;5728.598998070095;5856.502197313106;6263.311035785969;6371.706185773025;6378.45783108441 16:38:28 INFO - PID 1652 | |5;yui.html;815.3843847894839;823.3577809308739;828.4035494444001;849.9150084991497;853.4282031039203;16539.503814885556;16971.32114715413;17067.658646827073;17089.316427342907;17887.926724396544;17974.82025179751;18098.276068957224;18106;18259.45221643353;18397.90801045993;944.6221511395445;965.7344230336644;971.053223107468;971.7959228562011;972.7470857577046;951.9190868776162;955.6607404371453;956.9617215311379;957.1003256938491;960.8222478841402;1003.8845532763717;1005.8189525885338;1006.3509036671343;1006.9395836249812;1013.7313611892854;996.8704068471052;997.4414327976357;997.9401235925795;1000.6997900629818;1010.0656892374561;705.3158436316764;742.2911119880501;743.985120297591;744.8249661329584;747.4095464582973;16599.41902033423;16646.75029874553;16977.151142442828;17015.659686806317;17068;16633;16652.417165399274;16952.660946781118;17165.570860728458;17170.141492925304;13909.58271251864;13927.791083133761;14314.785278220836;14315.212663303515;14316.284185790773;3467.5665541807275;3516.3143187078276;3533.293341331744;3540.5751309842726;3541.73436992224;102434.48782756038;110558;121764.39117804443;127408.72591274061;128316.07525887119;13405.530806421728;13496.32518374097;13653.453861845415;13664.111832730841;13669.863301366859;24135.396615084763;24957.126500572394;25190.370240744127;25514.193438722097;25527.872360638077;7690.46166768332;7743.845123097506;7793.805154871174;7821.960890195513;7826.452148349561;3730.5709843367817;3804.6195380462286;3806.657400833883;3807.6573108420375;3820.8089595520114;4228.386883901817;4290.442242508454;4336.392904993303;4379.824807007684;4423.756693381895;915.6566287642135;952.9571169297399;953.6280850468323;954.117441366733;956.1490273656451;573.8880918220907;636.7357546618194;652.3346186889441;666.3203532396928;685.7599840055941;10871.565137394573;12021.879781202251;12297.139200256075;12653.620391388273;13417.731645367037;641.2465353209959;653.183520599251;657.4805903336406;663.1147418196762;679.4326737174515;4955.82654607087;4994.3507344045765;5130.717810520383;5238.947610523846;5286.788528458896;100533;100964;101061;101193;105181.94818051874;82055;83103.5844820772;84237.15762842294;84739.57630211809;84795.30409391777;72223.27776722166;75264;75300;78166.6091669538;79618;12064.75870482603;12338.198017128858;12603.747925041265;12609.747805043664;12641.2415255085;40425.1914961705;40581;40807.59192408038;40968.79515602403;41226.79386603168;53843.73078134741;54690.72654636701;54825.725871370385;55255.72372138114;55330.72334638301;16045.679086417973;16106;16126.838731612534;16384.426545070866;16387.70572119928;17811;17962.10189490494;18022.459326220236;18062.638747225246;18358.81641183571;15921.840781592036;16235.269412876212;16576.00543967366;16621.335146594;16650.584700300125;16212.027278363335;16278.51164465068;16286.674266514367;16506.83493165053;16525.917370413554;15975;16140.596485087966;16143.031418114952;16286.02283862972;16326.020438773712;10405;11135.10919126484;11162.441877906234;11412.372319522507;11707.004904582865;12160.331181785086;12605.87394126047;12721.300328482022;12794.808077878653;13011.934940325238 16:38:28 INFO - PID 1652 | __end_tp_report 16:38:28 INFO - PID 1652 | __start_cc_report 16:38:28 INFO - PID 1652 | _x_x_mozilla_cycle_collect,412 16:38:28 INFO - PID 1652 | __end_cc_report 16:38:28 INFO - PID 1652 | __startTimestamp1492731508217__endTimestamp 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | ------- Summary: start ------- 16:38:28 INFO - PID 1652 | Number of tests: 6 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6012.40 Median:6296.09 stddev:695.43 (11.0%) stddev-sans-first:669.73 16:38:28 INFO - PID 1652 | Values: 3529.4 3560.9 3583.6 3606.1 3613.3 5981.9 6125.4 6206.4 6234.1 6241.9 6074.5 6119.0 6120.5 6203.8 6229.7 4477.9 5369.1 5379.7 5382.3 5499.3 5350.0 5426.8 5479.3 5490.2 5514.8 5245.3 5304.2 5441.5 5449.1 5485.4 5288.4 5290.6 5311.8 5370.0 5411.4 5264.6 5279.7 5297.8 5319.0 5357.1 5857.5 6110.2 6121.7 6146.2 6152.9 6316.4 6375.2 6443.6 6485.8 6527.6 6632.4 6661.2 6670.1 6691.0 6708.2 6155.6 6272.3 6289.9 6296.1 6312.5 6444.8 6588.3 6734.2 6821.9 6850.5 6251.0 6313.5 6335.4 6339.0 6397.6 5535.7 5714.3 6193.3 6519.1 6619.8 6441.4 6453.0 6476.1 6507.8 6525.1 6302.0 6337.2 6358.8 6414.6 6551.7 4935.0 5279.0 5294.8 5330.2 5367.0 4347.6 4720.9 4735.4 4746.6 4771.2 5871.0 6188.5 6264.9 6360.6 6386.0 6391.9 6550.3 6579.6 6584.1 6645.2 6332.2 6343.2 6350.8 6372.4 6380.1 5842.7 5860.6 5866.2 5872.0 5879.1 6395.6 6482.0 6531.4 6573.2 6593.7 6644.6 6660.1 6667.0 6670.4 6684.4 5445.5 5922.1 6561.8 6771.2 6834.5 4954.7 6240.5 6315.4 6343.1 6399.0 6622.6 6639.5 6643.3 6672.4 6673.8 6230.0 6329.6 6402.9 6437.5 6449.7 5150.4 6343.0 6409.1 6413.9 6451.6 5213.6 6262.6 6297.8 6330.2 6352.4 6257.2 6396.6 6438.3 6474.7 6477.2 6222.9 6240.3 6262.9 6308.6 6332.7 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:42333.06 Median:17138.77 stddev:72146.19 (421.0%) stddev-sans-first:72280.94 16:38:28 INFO - PID 1652 | Values: 725.6 789.1 813.1 1166.3 1182.8 36494.0 36676.4 36709.0 36715.8 36743.8 36982.6 37279.6 37280.9 37291.1 37292.1 7900.4 8115.3 8319.0 8574.9 8578.5 8492.5 8534.4 8558.9 8622.0 8622.8 8555.7 8572.4 8641.0 8648.7 8669.6 7500.5 8574.7 8627.0 8627.0 8631.5 8370.5 8372.8 8388.5 8448.2 8451.5 30242.8 30788.7 36586.6 36646.0 36913.6 3556.3 3590.7 3593.2 3593.7 3602.4 30353.8 30395.5 30409.8 30545.5 30712.0 3087.8 3114.7 3127.9 3129.4 3130.9 223045.0 225576.0 261060.4 274805.0 274964.0 39301.4 46369.8 46468.3 46592.8 46808.8 39911.8 48062.8 48349.8 48386.8 48566.0 13412.1 13455.2 13494.0 13541.6 13556.6 11010.9 11016.8 11038.6 11054.1 11061.1 13290.7 13318.7 13346.4 13375.3 13403.7 5217.7 5878.8 5957.1 5960.2 5977.2 1013.4 1026.6 1036.5 1060.8 1064.5 22805.7 23977.9 24014.6 24214.6 24226.6 1045.8 1078.8 1126.7 1132.7 1141.7 11152.6 11247.3 12165.8 12300.9 12380.7 300799.0 301387.0 301438.0 302828.5 302829.0 42263.0 50266.0 50614.7 50691.0 50742.0 1478.2 1504.7 1535.6 1538.3 1540.6 18403.6 23274.9 23683.6 23891.4 24090.8 34355.7 35911.3 36011.5 36066.6 36250.3 269348.0 270208.0 270358.6 270745.0 271689.0 32051.8 32400.7 33257.0 33780.3 33835.0 41797.5 42806.6 42867.8 43654.1 43921.8 29994.7 30374.8 30435.7 30667.7 30775.8 30695.7 30882.2 30979.8 31001.8 31132.0 36901.6 37252.8 37388.8 37389.4 37472.8 29316.0 30250.7 30408.7 31125.8 31149.7 22329.6 23638.5 24477.0 24510.5 24770.5 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:37066.55 Median:2407.66 stddev:96614.30 (4012.8%) stddev-sans-first:96846.16 16:38:28 INFO - PID 1652 | Values: 423.4 424.2 432.2 445.7 451.2 18792.9 20469.6 21084.9 21229.0 21272.1 20479.5 22281.8 22307.4 22391.3 22400.2 6024.9 6314.1 6314.6 6317.3 6318.1 5257.9 6535.0 6930.0 6983.3 6999.2 6704.4 6950.9 6961.3 6972.2 6995.0 6941.3 6980.5 6993.0 7000.3 7045.5 5627.9 6781.8 6870.1 6874.0 6906.1 28944.4 29374.7 29507.6 29546.0 29640.4 2335.1 2374.9 2393.4 2407.0 2408.3 18207.5 18674.4 19385.2 19396.3 19644.5 2093.3 2278.3 2298.8 2366.0 2380.0 129142.0 131041.0 161706.0 163425.0 166039.0 22368.0 23442.4 24250.3 25867.2 25935.7 29482.6 36931.4 39236.0 40821.8 41151.3 9435.3 9793.9 10193.3 10578.3 10596.4 8486.0 8491.6 8513.1 8608.6 8654.4 11334.6 11635.2 11701.7 11790.5 11809.8 4597.6 4712.4 4718.1 4751.6 4755.7 953.5 959.8 971.3 984.4 985.0 17582.9 20026.9 20869.0 21073.8 21254.8 909.6 970.9 1010.2 1011.6 1012.1 9729.9 10703.4 10972.0 10975.0 10978.6 567368.0 567747.0 574183.3 583017.0 583093.0 101369.0 119988.2 127069.4 129986.0 131704.0 1126.4 1144.7 1164.8 1165.0 1172.4 14303.2 15145.5 16983.7 17742.7 18047.9 56604.7 56701.1 57235.7 58080.4 58376.7 61698.2 71349.9 73786.3 75883.0 77326.6 7976.8 8491.7 8955.8 8998.2 9267.8 14463.4 14624.0 14856.5 15108.7 15161.5 11336.7 11929.5 12138.5 12190.8 12297.5 10947.8 11664.0 11886.0 11887.7 12158.1 11182.6 11742.4 12347.1 12443.2 12601.2 8897.4 9153.5 9223.0 9411.0 9412.0 12036.4 12128.4 12422.9 12451.1 12549.5 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7176.41 Median:4049.99 stddev:4580.41 (113.1%) stddev-sans-first:4571.89 16:38:28 INFO - PID 1652 | Values: 1288.2 1440.6 1451.7 1475.7 1476.1 11289.7 11747.0 11781.0 11827.2 12180.8 9883.5 11172.9 11770.8 11966.6 11969.2 2500.3 2543.2 2548.0 2558.2 2558.2 2472.5 2506.0 2545.3 2549.3 2554.6 2454.0 2515.0 2526.0 2534.4 2536.6 2425.2 2492.9 2503.4 2522.7 2527.9 2438.8 2443.1 2450.6 2453.0 2559.6 11878.6 11905.0 12005.3 12006.0 12032.2 6648.0 6853.6 6888.8 6907.1 6920.1 3990.1 4278.4 4380.3 4474.1 4520.3 1204.2 1205.8 1243.4 1298.5 1301.2 16271.6 16575.7 16652.3 16669.9 16900.2 5717.6 5785.1 5785.3 5967.0 5982.5 10658.6 12442.9 12445.3 12446.2 12485.9 6584.8 6664.3 6668.2 6670.6 6673.3 5983.0 6103.3 6170.9 6213.6 6282.1 4304.9 4442.6 4458.9 4500.9 4515.8 1287.2 1320.8 1345.5 1360.6 1364.7 684.2 798.7 801.6 805.4 807.2 6128.9 6186.6 6203.9 6317.3 6317.6 562.0 569.9 592.0 596.4 598.6 3893.4 4109.9 4272.0 4372.4 4400.7 14569.0 15026.1 15182.7 15396.8 15451.3 14913.3 15330.3 15424.7 15429.3 15430.9 8245.6 8544.0 8554.5 8719.7 8728.0 6959.2 7146.1 7203.8 7272.5 7275.1 13314.6 13570.0 13570.7 13609.7 13864.7 13999.7 14046.0 14058.2 14231.0 14341.0 9500.1 9506.3 9574.0 9673.4 9698.6 10661.5 10684.4 10792.2 10806.7 10811.0 9098.0 9139.1 9227.4 9370.5 9417.2 7540.6 8456.5 8878.8 9079.3 9088.2 8075.0 8081.6 8184.9 8205.7 8232.3 5434.4 5451.0 5765.4 6057.4 6063.9 5571.7 5692.5 6011.7 6127.2 6285.7 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5239.71 Median:5951.41 stddev:1643.92 (27.6%) stddev-sans-first:1607.62 16:38:28 INFO - PID 1652 | Values: 384.1 405.9 406.9 410.7 414.1 5433.2 5992.7 6203.5 6261.3 6265.4 5495.8 5902.4 6053.5 6072.6 6147.5 4097.0 4517.5 4643.3 4677.3 4710.1 4411.8 4511.4 4588.2 4628.9 4663.2 4113.6 4144.3 4188.5 4294.5 4551.0 4655.6 4699.3 4709.0 4711.6 4721.5 3334.9 3471.0 3594.6 3676.9 3988.2 5117.0 5795.0 5941.0 5961.8 6000.8 4833.8 5372.7 5389.8 5393.2 5414.9 5042.0 5906.6 6298.6 6475.2 6503.6 4384.5 4395.7 4400.3 4401.5 4419.9 6766.9 6900.4 7010.6 7030.9 7033.5 3651.8 3697.9 3776.0 4432.4 4455.7 4527.0 4532.0 4546.7 4548.4 4572.7 4583.0 5272.6 5341.6 5345.8 5400.3 6238.8 6324.2 6458.5 6556.8 6566.6 1524.7 1540.6 1547.9 1553.2 1562.8 870.0 907.7 944.5 1006.4 1024.4 6152.6 6189.3 6224.3 6257.8 6265.7 6302.0 6732.4 6737.5 6744.6 6798.3 5941.0 6039.0 6131.5 6187.7 6224.2 3101.6 3494.7 3542.7 3543.3 3548.5 6100.8 6667.6 6880.5 7012.6 7025.9 6878.9 6933.2 6946.0 6947.5 6969.2 5362.9 5510.8 5962.9 5975.2 6170.6 6141.4 6661.9 6736.2 6738.0 6762.8 5703.8 6314.0 6559.7 6618.3 6681.0 6629.7 6696.6 6887.8 6965.4 6995.8 3941.6 4440.9 4452.4 4455.5 4475.4 5821.3 6055.4 6652.3 6692.0 6776.3 5347.4 5399.8 6239.6 6444.7 6489.9 5249.7 5683.3 6417.3 6539.3 6559.9 6329.6 6358.3 6363.4 6378.2 6391.8 6086.8 6542.7 6551.5 6553.3 6609.8 5728.6 5856.5 6263.3 6371.7 6378.5 16:38:28 INFO - PID 1652 | 16:38:28 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:21145.50 Median:18378.36 stddev:29009.21 (157.8%) stddev-sans-first:29050.42 16:38:28 INFO - PID 1652 | Values: 815.4 823.4 828.4 849.9 853.4 16539.5 16971.3 17067.7 17089.3 17887.9 17974.8 18098.3 18106.0 18259.5 18397.9 944.6 965.7 971.1 971.8 972.7 951.9 955.7 957.0 957.1 960.8 1003.9 1005.8 1006.4 1006.9 1013.7 996.9 997.4 997.9 1000.7 1010.1 705.3 742.3 744.0 744.8 747.4 16599.4 16646.8 16977.2 17015.7 17068.0 16633.0 16652.4 16952.7 17165.6 17170.1 13909.6 13927.8 14314.8 14315.2 14316.3 3467.6 3516.3 3533.3 3540.6 3541.7 102434.5 110558.0 121764.4 127408.7 128316.1 13405.5 13496.3 13653.5 13664.1 13669.9 24135.4 24957.1 25190.4 25514.2 25527.9 7690.5 7743.8 7793.8 7822.0 7826.5 3730.6 3804.6 3806.7 3807.7 3820.8 4228.4 4290.4 4336.4 4379.8 4423.8 915.7 953.0 953.6 954.1 956.1 573.9 636.7 652.3 666.3 685.8 10871.6 12021.9 12297.1 12653.6 13417.7 641.2 653.2 657.5 663.1 679.4 4955.8 4994.4 5130.7 5238.9 5286.8 100533.0 100964.0 101061.0 101193.0 105181.9 82055.0 83103.6 84237.2 84739.6 84795.3 72223.3 75264.0 75300.0 78166.6 79618.0 12064.8 12338.2 12603.7 12609.7 12641.2 40425.2 40581.0 40807.6 40968.8 41226.8 53843.7 54690.7 54825.7 55255.7 55330.7 16045.7 16106.0 16126.8 16384.4 16387.7 17811.0 17962.1 18022.5 18062.6 18358.8 15921.8 16235.3 16576.0 16621.3 16650.6 16212.0 16278.5 16286.7 16506.8 16525.9 15975.0 16140.6 16143.0 16286.0 16326.0 10405.0 11135.1 11162.4 11412.4 11707.0 12160.3 12605.9 12721.3 12794.8 13011.9 16:38:28 INFO - PID 1652 | -------- Summary: end -------- 16:38:28 INFO - PID 1652 | 16:38:28 INFO - TEST-INFO | 1652: exit 0 16:38:28 INFO - TEST-OK | dromaeo_css | took 1115361ms 16:38:28 INFO - TEST-START | kraken 16:38:28 INFO - operating with platform_type : mac_ 16:38:28 INFO - Initialising browser for kraken test... 16:38:28 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpYxzRYv/profile http://localhost:49243/getInfo.html) 16:38:31 INFO - PID 1665 | 2017-04-20 16:38:31.790 plugin-container[1666:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 16:38:31 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:31 INFO - PID 1665 | 2017-04-20 16:38:31.794 plugin-container[1666:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 16:38:31 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:31 INFO - PID 1665 | 2017-04-20 16:38:31.794 plugin-container[1666:10319] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:38:31 INFO - PID 1665 | __metrics Screen width/height:1600/1200 16:38:31 INFO - PID 1665 | colorDepth:24 16:38:31 INFO - PID 1665 | Browser inner width/height: 1024/689 16:38:31 INFO - PID 1665 | __metrics 16:38:31 ERROR - PID 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 16:38:32 INFO - TEST-INFO | 1665: exit 0 16:38:32 INFO - Browser initialized. 16:38:32 INFO - Running cycle 1/1 for kraken test... 16:38:32 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpYxzRYv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:35 INFO - PID 1667 | 2017-04-20 16:38:35.152 plugin-container[1668:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 16:38:35 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:35 INFO - PID 1667 | 2017-04-20 16:38:35.153 plugin-container[1668:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:38:35 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:35 INFO - PID 1667 | 2017-04-20 16:38:35.153 plugin-container[1668:10444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:38:45 INFO - PID 1667 | RSS: Main: 236445696 16:38:45 INFO - PID 1667 | 16:38:47 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:48 INFO - PID 1667 | RSS: Main: 248348672 16:38:48 INFO - PID 1667 | 16:38:51 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 16:38:52 INFO - PID 1667 | RSS: Main: 256811008 16:38:52 INFO - PID 1667 | 16:38:55 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 16:38:55 INFO - PID 1667 | RSS: Main: 259276800 16:38:55 INFO - PID 1667 | 16:38:57 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 16:38:58 INFO - PID 1667 | RSS: Main: 260186112 16:38:58 INFO - PID 1667 | 16:39:00 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:39:01 INFO - PID 1667 | RSS: Main: 260624384 16:39:01 INFO - PID 1667 | 16:39:03 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:04 INFO - PID 1667 | RSS: Main: 267874304 16:39:04 INFO - PID 1667 | 16:39:07 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:08 INFO - PID 1667 | RSS: Main: 269926400 16:39:08 INFO - PID 1667 | 16:39:11 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 16:39:12 INFO - PID 1667 | RSS: Main: 269271040 16:39:12 INFO - PID 1667 | 16:39:13 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:14 INFO - PID 1667 | RSS: Main: 268759040 16:39:14 INFO - PID 1667 | 16:39:16 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:16 INFO - PID 1667 | RSS: Main: 268894208 16:39:16 INFO - PID 1667 | 16:39:18 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:19 INFO - PID 1667 | RSS: Main: 268402688 16:39:19 INFO - PID 1667 | 16:39:22 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:23 INFO - PID 1667 | RSS: Main: 268664832 16:39:23 INFO - PID 1667 | 16:39:26 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:26 INFO - PID 1667 | RSS: Main: 268779520 16:39:26 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 16:39:28 INFO - PID 1667 | RSS: Main: 270249984 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | __start_tp_report 16:39:28 INFO - PID 1667 | _x_x_mozilla_page_load 16:39:28 INFO - PID 1667 | _x_x_mozilla_page_load_details 16:39:28 INFO - PID 1667 | |i|pagename|runs| 16:39:28 INFO - PID 1667 | |0;ai-astar;103;113;116;115;115;115;114;115;117;116 16:39:28 INFO - PID 1667 | |1;audio-beat-detection;137;109;113;149;125;108;139;137;157;113 16:39:28 INFO - PID 1667 | |2;audio-dft;201;198;207;228;213;208;197;199;198;198 16:39:28 INFO - PID 1667 | |3;audio-fft;79;57;58;82;59;66;50;63;99;82 16:39:28 INFO - PID 1667 | |4;audio-oscillator;91;99;107;71;70;73;84;70;71;91 16:39:28 INFO - PID 1667 | |5;imaging-gaussian-blur;89;91;89;89;98;90;90;101;115;90 16:39:28 INFO - PID 1667 | |6;imaging-darkroom;97;107;131;150;120;96;97;97;98;105 16:39:28 INFO - PID 1667 | |7;imaging-desaturate;93;90;92;92;92;90;98;93;141;142 16:39:28 INFO - PID 1667 | |8;json-parse-financial;46;46;42;45;53;52;75;72;72;71 16:39:28 INFO - PID 1667 | |9;json-stringify-tinderbox;57;60;58;62;63;57;55;58;71;78 16:39:28 INFO - PID 1667 | |10;stanford-crypto-aes;86;80;92;83;83;85;82;84;83;79 16:39:28 INFO - PID 1667 | |11;stanford-crypto-ccm;139;141;141;157;154;140;142;143;187;149 16:39:28 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;186;191;189;189;187;189;188;189;186;227 16:39:28 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;70;68;67;69;70;69;65;67;73;70 16:39:28 INFO - PID 1667 | __end_tp_report 16:39:28 INFO - PID 1667 | __start_cc_report 16:39:28 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1005 16:39:28 INFO - PID 1667 | __end_cc_report 16:39:28 INFO - PID 1667 | __startTimestamp1492731568652__endTimestamp 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | ------- Summary: start ------- 16:39:28 INFO - PID 1667 | Number of tests: 14 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.98 (3.5%) stddev-sans-first:1.17 16:39:28 INFO - PID 1667 | Values: 103.0 113.0 116.0 115.0 115.0 115.0 114.0 115.0 117.0 116.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:137.00 stddev:17.58 (12.8%) stddev-sans-first:18.38 16:39:28 INFO - PID 1667 | Values: 137.0 109.0 113.0 149.0 125.0 108.0 139.0 137.0 157.0 113.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:204.70 Median:204.00 stddev:9.82 (4.8%) stddev-sans-first:10.33 16:39:28 INFO - PID 1667 | Values: 201.0 198.0 207.0 228.0 213.0 208.0 197.0 199.0 198.0 198.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:69.50 Median:72.50 stddev:15.30 (21.1%) stddev-sans-first:15.84 16:39:28 INFO - PID 1667 | Values: 79.0 57.0 58.0 82.0 59.0 66.0 50.0 63.0 99.0 82.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:78.50 stddev:13.69 (17.4%) stddev-sans-first:14.18 16:39:28 INFO - PID 1667 | Values: 91.0 99.0 107.0 71.0 70.0 73.0 84.0 70.0 71.0 91.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:90.00 stddev:8.42 (9.4%) stddev-sans-first:8.71 16:39:28 INFO - PID 1667 | Values: 89.0 91.0 89.0 89.0 98.0 90.0 90.0 101.0 115.0 90.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:109.80 Median:96.50 stddev:18.32 (19.0%) stddev-sans-first:18.84 16:39:28 INFO - PID 1667 | Values: 97.0 107.0 131.0 150.0 120.0 96.0 97.0 97.0 98.0 105.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:102.30 Median:92.00 stddev:20.78 (22.6%) stddev-sans-first:21.77 16:39:28 INFO - PID 1667 | Values: 93.0 90.0 92.0 92.0 92.0 90.0 98.0 93.0 141.0 142.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:62.00 stddev:13.42 (21.6%) stddev-sans-first:13.58 16:39:28 INFO - PID 1667 | Values: 46.0 46.0 42.0 45.0 53.0 52.0 75.0 72.0 72.0 71.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:61.00 stddev:7.25 (11.9%) stddev-sans-first:7.47 16:39:28 INFO - PID 1667 | Values: 57.0 60.0 58.0 62.0 63.0 57.0 55.0 58.0 71.0 78.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:3.59 (4.3%) stddev-sans-first:3.71 16:39:28 INFO - PID 1667 | Values: 86.0 80.0 92.0 83.0 83.0 85.0 82.0 84.0 83.0 79.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:146.00 stddev:14.63 (10.0%) stddev-sans-first:15.03 16:39:28 INFO - PID 1667 | Values: 139.0 141.0 141.0 157.0 154.0 140.0 142.0 143.0 187.0 149.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:189.00 stddev:12.36 (6.5%) stddev-sans-first:12.91 16:39:28 INFO - PID 1667 | Values: 186.0 191.0 189.0 189.0 187.0 189.0 188.0 189.0 186.0 227.0 16:39:28 INFO - PID 1667 | 16:39:28 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:2.20 (3.2%) stddev-sans-first:2.29 16:39:28 INFO - PID 1667 | Values: 70.0 68.0 67.0 69.0 70.0 69.0 65.0 67.0 73.0 70.0 16:39:28 INFO - PID 1667 | -------- Summary: end -------- 16:39:28 INFO - PID 1667 | 16:39:29 INFO - TEST-INFO | 1667: exit 0 16:39:29 INFO - TEST-OK | kraken | took 60857ms 16:39:29 INFO - SUITE-END | took 1176s 16:39:29 INFO - Completed test suite (00:19:36) 16:39:29 INFO - javascript benchmark 16:39:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.868738161748, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3529.3647143514163, 3560.928781424373, 3583.569971603408, 3606.0804494853815, 3613.295407395559, 5981.910271345922, 6125.448709616134, 6206.410391012852, 6234.0648902664625, 6241.937580624192, 6074.453299203071, 6118.984682626211, 6120.510359171267, 6203.751849925998, 6229.688515574226, 4477.932831007522, 5369.114096174127, 5379.65032272901, 5382.273393091931, 5499.340079190482, 5350.036993341197, 5426.8371948841595, 5479.287692599956, 5490.231367608538, 5514.834554963338, 5245.291885595472, 5304.177852432879, 5441.455854414567, 5449.100898351767, 5485.424030476803, 5288.365396152486, 5290.629655924087, 5311.814086506954, 5369.973150134263, 5411.350637923474, 5264.631475796696, 5279.736013199325, 5297.841064768063, 5319.0, 5357.116075847481, 5857.502112320458, 6110.175126357974, 6121.693915304217, 6146.200993870812, 6152.923238433256, 6316.399942005502, 6375.2349718033665, 6443.645599492026, 6485.77299794505, 6527.640979746112, 6632.436242919358, 6661.16735408074, 6670.0661907333015, 6690.966545167292, 6708.228553716336, 6155.630662160284, 6272.341404152589, 6289.8742025159245, 6296.087067375251, 6312.495000399957, 6444.806655800334, 6588.308227636029, 6734.1918969722965, 6821.93178068213, 6850.520463567503, 6251.031090181029, 6313.526485513605, 6335.429811317003, 6338.968305158537, 6397.552171347961, 5535.6955367454375, 5714.285714285715, 6193.349698281706, 6519.054737063099, 6619.8084344817435, 6441.426904649623, 6452.999785033234, 6476.141058135594, 6507.772227971998, 6525.11910892033, 6302.023186406114, 6337.239531256187, 6358.809235722936, 6414.61512309263, 6551.737930482823, 4934.988327392876, 5278.97360513195, 5294.788208471695, 5330.200469929541, 5367.033933891937, 4347.6304514116655, 4720.88197795051, 4735.360726301974, 4746.620270378361, 4771.212749896297, 5871.0, 6188.4739797117745, 6264.9373506265265, 6360.586561873556, 6386.0, 6391.872162556723, 6550.2794692583775, 6579.638119903356, 6584.144061272004, 6645.235797883209, 6332.240131184288, 6343.175387199634, 6350.8412289692205, 6372.36276372359, 6380.138681278061, 5842.737076831553, 5860.560457965669, 5866.178734977107, 5872.0, 5879.118132280107, 6395.552311338255, 6482.0, 6531.412172904462, 6573.1783527059115, 6593.703283352164, 6644.60132392058, 6660.134182556237, 6666.966665166837, 6670.39966403026, 6684.4318232950745, 5445.50990410865, 5922.111683247547, 6561.835954101186, 6771.187457505131, 6834.487413444111, 4954.653174277766, 6240.5319601031015, 6315.368463153648, 6343.111964324906, 6399.008153736178, 6622.602643841385, 6639.535232533676, 6643.269240383457, 6672.366125218096, 6673.799786006428, 6230.034344676583, 6329.620222786648, 6402.871942561216, 6437.5171862111465, 6449.709763060768, 5150.381954165524, 6343.0485427186295, 6409.134766806422, 6413.8717225656155, 6451.612903225821, 5213.60897932649, 6262.624242545279, 6297.811065668037, 6330.208723909512, 6352.396522330371, 6257.1552840367785, 6396.584222025554, 6438.323975982455, 6474.676266186578, 6477.190351206099, 6222.875542489215, 6240.344763799736, 6262.906056409066, 6308.621482711052, 6332.715027823851], "value": 5969.708449679454, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.5646612032781, 789.0531362365161, 813.1177672225637, 1166.26525289068, 1182.757534705386, 36494.0, 36676.44985325222, 36709.0, 36715.816420917894, 36743.81628091859, 36982.63017369826, 37279.627203727956, 37280.88157355277, 37291.06772330693, 37292.067698307554, 7900.407469439786, 8115.310198633094, 8318.958405207966, 8574.871376929352, 8578.528180950045, 8492.53291068994, 8534.359923005768, 8558.871616925751, 8622.0, 8622.827543449159, 8555.700550480735, 8572.442791218551, 8641.0, 8648.65405383787, 8669.609867555944, 7500.512466689703, 8574.69988550401, 8626.956865215696, 8626.956865215696, 8631.525266110362, 8370.497770133812, 8372.83254334916, 8388.496690198608, 8448.155184481564, 8451.53516556589, 30242.790288388438, 30788.692113078807, 36586.63413365885, 36646.0, 36913.63086369129, 3556.342076715816, 3590.7486475946694, 3593.1915319053264, 3593.7125029997537, 3602.441621548664, 30353.848230758926, 30395.544066839015, 30409.847950760104, 30545.541816872767, 30712.0, 3087.783855130142, 3114.68853114689, 3127.8592463339205, 3129.405412971528, 3130.9217269568444, 223045.0, 225576.0, 261060.3893961074, 274805.0, 274964.0, 39301.41047884284, 46369.76815115903, 46468.30297545539, 46592.7670361646, 46808.76595617, 39911.80264592067, 48062.759686201345, 48349.75825120922, 48386.758066210146, 48566.0, 13412.0611557192, 13455.192688438725, 13494.0, 13541.59375218745, 13556.59330220095, 11010.889891100986, 11016.779664406668, 11038.558457661604, 11054.060404865515, 11061.059809916247, 13290.667733306746, 13318.66703332405, 13346.399412026482, 13375.264360460074, 13403.731925361439, 5217.660852044605, 5878.823635290948, 5957.076653118774, 5960.225170727865, 5977.163197152402, 1013.3565186106728, 1026.630413051301, 1036.461040259061, 1060.8143574874366, 1064.5422468338534, 22805.657915131287, 23977.880110599337, 24014.639780403308, 24214.63678044831, 24226.63660045101, 1045.7751583409452, 1078.829470025016, 1126.7325254435646, 1132.7168207948014, 1141.7431078007548, 11152.60965866207, 11247.268927519684, 12165.756684866254, 12300.876991229974, 12380.690482738004, 300799.0, 301387.0, 301438.0, 302828.4858575693, 302829.0, 42263.0, 50266.0, 50614.746926265136, 50691.0, 50742.0, 1478.1943840606896, 1504.6984358530012, 1535.646801235722, 1538.3077615073262, 1540.5763415060949, 18403.63192736111, 23274.883625581762, 23683.64474532849, 23891.402714932265, 24090.75909240885, 34355.65644343525, 35911.28177436489, 36011.45982810312, 36066.639333607374, 36250.27499450049, 269348.0, 270208.0, 270358.64820675773, 270745.0, 271689.0, 32051.83974080208, 32400.675993239765, 33257.0, 33780.324393512485, 33835.0, 41797.53708620184, 42806.57193428151, 42867.785661071495, 43654.12691746084, 43921.78039109784, 29994.700053000062, 30374.84812575923, 30435.695643043287, 30667.693323066484, 30775.846120769253, 30695.693043070176, 30882.227944301572, 30979.845100774353, 31001.8449907749, 31132.0, 36901.63098368982, 37252.813735931144, 37388.813055934545, 37389.43915841319, 37472.81263593664, 29316.0, 30250.69749302479, 30408.695913040585, 31125.844370778002, 31149.688503114678, 22329.553408931406, 23638.52722945566, 24477.0, 24510.509789803746, 24770.50458990774], "value": 17070.53245034807, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.39789086943927, 424.16651280234333, 432.200429205969, 445.7392425431122, 451.1788544848377, 18792.90603546982, 20469.590608187846, 21084.8945755271, 21229.044692988813, 21272.14911403544, 20479.48801279965, 22281.777182228172, 22307.44231394216, 22391.328260152222, 22400.21599244027, 6024.879502409949, 6314.116023756677, 6314.557980941336, 6317.305096439388, 6318.14705014824, 5257.86855328616, 6535.0, 6930.0, 6983.266756990519, 6999.160100787913, 6704.430123439513, 6950.895736563955, 6961.269066747995, 6972.163340399134, 6994.965025174867, 6941.34057264559, 6980.534087841559, 6992.965035174842, 7000.29997000301, 7045.4715896307725, 5627.887442251173, 6781.796546103576, 6870.141232345957, 6874.0, 6906.102206713145, 28944.42111157786, 29374.706252937413, 29507.55738663922, 29546.0, 29640.40719185626, 2335.054303007285, 2374.9406264843346, 2393.365758074112, 2406.9638955415685, 2408.3497455687043, 18207.45377638673, 18674.439766807016, 19385.224591016344, 19396.321128760424, 19644.50888727779, 2093.3196711068963, 2278.28234106256, 2298.8275879309117, 2366.0, 2379.9524009519714, 129142.0, 131041.0, 161706.0, 163425.0, 166039.0, 22367.9934402949, 23442.413939651306, 24250.272491825275, 25867.22398328053, 25935.740642593333, 29482.557761633594, 36931.4460283096, 39236.0, 40821.79589102035, 41151.29638332564, 9435.339526233098, 9793.902060979299, 10193.286469947181, 10578.312409693346, 10596.417197054081, 8486.024107227624, 8491.575421228914, 8513.063563008063, 8608.612612432456, 8654.394192406597, 11334.563068940053, 11635.185537012498, 11701.707457313465, 11790.469428875549, 11809.763804723858, 4597.586217240464, 4712.387389639325, 4718.103560323528, 4751.576695885124, 4755.690880092783, 953.547065144051, 959.7924773022161, 971.3394891473865, 984.3995162950595, 984.9655262065936, 17582.91208543949, 20026.899865500578, 20868.956552172633, 21073.78926210749, 21254.78745212559, 909.5634095634133, 970.8689326940772, 1010.2170817616407, 1011.630754774502, 1012.0638409471356, 9729.854052189223, 10703.357798532114, 10972.0, 10975.0, 10978.615748448767, 567368.0, 567747.0, 574183.258167413, 583017.0, 583093.0, 101369.0, 119988.20017699742, 127069.37583745005, 129986.0, 131704.0, 1126.3636045634191, 1144.6909334479644, 1164.8485696859354, 1165.0272022861006, 1172.407933993328, 14303.213323266904, 15145.545633631009, 16983.7452438216, 17742.733858992382, 18047.917124972544, 56604.71697641486, 56701.14948275697, 57235.713821430625, 58080.4191958075, 58376.708116459144, 61698.223579938116, 71349.92975105274, 73786.26213737794, 75883.0, 77326.61336693281, 7976.8005799853145, 8491.74524764258, 8955.776105597412, 8998.190162885372, 9267.76830579241, 14463.421463141356, 14624.0, 14856.480023199136, 15108.697826043637, 15161.545153645407, 11336.716582085513, 11929.46317415701, 12138.514459421523, 12190.817137743119, 12297.508099675913, 10947.83578246343, 11664.0, 11886.0, 11887.702807429883, 12158.148929574845, 11182.608608698656, 11742.354170520362, 12347.13570050088, 12443.191192572454, 12601.243925364508, 8897.421667591585, 9153.542322883695, 9223.031581683827, 9411.0, 9412.0, 12036.398180091135, 12128.393580321124, 12422.937885310515, 12451.066170037464, 12549.49802007946], "value": 11482.918581339833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.207752232377, 1440.5966329427752, 1451.7314296855077, 1475.7491226491495, 1476.0627001853827, 11289.717757056067, 11747.001504872085, 11781.0, 11827.172097953147, 12180.756384872297, 9883.456409897453, 11172.944135279333, 11770.764584708322, 11966.641000769969, 11969.222000569956, 2500.3124140861228, 2543.211604402632, 2548.0062775517563, 2558.2069558436942, 2558.2325302409295, 2472.4807790364043, 2505.962410563843, 2545.300042488321, 2549.3244290263096, 2554.6040363743555, 2453.9631905521433, 2514.9748502515017, 2526.027479420427, 2534.4170840706647, 2536.581464058428, 2425.223928342931, 2492.8753562321904, 2503.436726736488, 2522.697276326834, 2527.886245118974, 2438.8170887183533, 2443.059422122478, 2450.620153876152, 2452.9877350613133, 2559.552078386275, 11878.58424955131, 11905.0, 12005.339706316148, 12006.039516838719, 12032.217905836093, 6648.0, 6853.554518956252, 6888.827779305508, 6907.102076730043, 6920.134983127109, 3990.06233535119, 4278.443802305712, 4380.3210502372185, 4474.105178964221, 4520.3219517072375, 1204.1871736577775, 1205.8010428279324, 1243.4466662335265, 1298.4546490474024, 1301.1737546657846, 16271.59321016984, 16575.6684866302, 16652.33390664372, 16669.91665041667, 16900.239489223015, 5717.6277693353295, 5785.103308987113, 5785.27684039495, 5966.970165149147, 5982.49148822355, 10658.62694805678, 12442.875571244353, 12445.315507647167, 12446.190997585129, 12485.93757031209, 6584.76953306632, 6664.300248473937, 6668.233153187445, 6670.599764014175, 6673.332666733288, 5983.0, 6103.328633850273, 6170.907436388457, 6213.596116252429, 6282.057691346335, 4304.892377690583, 4442.644588432918, 4458.888527786831, 4500.887477813081, 4515.819367225275, 1287.1697754948007, 1320.7688654485619, 1345.4819894340749, 1360.5578187089236, 1364.733876894016, 684.2404930527111, 798.7044793426469, 801.5511313664366, 805.4080251015499, 807.1605530248593, 6128.877422451526, 6186.566940314135, 6203.906941395882, 6317.3366796486625, 6317.557770956081, 562.0473297760338, 569.894569504643, 591.9526437884958, 596.4423264247943, 598.5809933046885, 3893.377059670496, 4109.91780164401, 4271.978640106841, 4372.366006928977, 4400.73595584266, 14569.0, 15026.098434093989, 15182.696346073017, 15396.846031539766, 15451.304691288919, 14913.3289001993, 15330.310136043905, 15424.691506169815, 15429.30568124437, 15430.9198356114, 8245.587720613945, 8544.0, 8554.529500877512, 8719.738407847775, 8727.956360218286, 6959.164900212007, 7146.106736657918, 7203.783886483414, 7272.527285726411, 7275.090613673291, 13314.600561983156, 13570.0, 13570.728585428433, 13609.727805444034, 13864.653383665489, 13999.650008749863, 14046.0, 14058.156510609397, 14231.0, 14341.0, 9500.144986951207, 9506.334556581249, 9573.952130239304, 9673.419594824332, 9698.6120555177, 10661.466926653791, 10684.358938463716, 10792.190585705946, 10806.675799726021, 10811.0, 9097.954510227406, 9139.131782480654, 9227.35408521397, 9370.531473426165, 9417.152456278967, 7540.622968851645, 8456.450330728483, 8878.822423551623, 9079.273658107204, 9088.227500662519, 8075.0, 8081.63632636521, 8184.918150818416, 8205.671773129008, 8232.300254478198, 5434.375046869661, 5451.0, 5765.423457654369, 6057.394260573908, 6063.878722425438, 5571.746357069627, 5692.48767610917, 6011.6693581853415, 6127.20346354971, 6285.685715714288], "value": 5361.1326251300425, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.10886742756816, 405.89040958941087, 406.86370066027877, 410.67146282973613, 414.1137964755423, 5433.185022246665, 5992.670403127826, 6203.47270482009, 6261.3425590313045, 6265.436110750026, 5495.780168793253, 5902.409759024106, 6053.485453736438, 6072.605280656754, 6147.47746441553, 4096.959030409695, 4517.525659805706, 4643.326717625942, 4677.251639737643, 4710.105080034799, 4411.779411029452, 4511.4135162429, 4588.24293991491, 4628.884277893046, 4663.207254766681, 4113.629773320401, 4144.254034273829, 4188.539260681323, 4294.484661840568, 4550.976030393152, 4655.580997710223, 4699.318598803188, 4709.011107667394, 4711.552402521772, 4721.480637129913, 3334.883279085245, 3470.993411910543, 3594.5621751299427, 3676.8601733462447, 3988.222296652154, 5117.0, 5794.97102514489, 5941.0, 5961.821145365602, 6000.75996960121, 4833.806647734122, 5372.731363431852, 5389.83830485086, 5393.19102134679, 5414.886440296845, 5042.042012017705, 5906.645601263938, 6298.622082675009, 6475.158229430144, 6503.577267477599, 4384.495782984965, 4395.703267536084, 4400.339949007643, 4401.515833258339, 4419.872932402217, 6766.932330676679, 6900.378965893094, 7010.581349434437, 7030.894536581955, 7033.487800122947, 3651.799151046665, 3697.8721489945287, 3775.981120094382, 4432.401625780542, 4455.710378825366, 4527.0, 4532.0256144928335, 4546.65900057497, 4548.363229147922, 4572.725636461823, 4583.0, 5272.604554658416, 5341.572674186055, 5345.81289654868, 5400.271912987806, 6238.812835614939, 6324.209473815773, 6458.5479016469335, 6556.8034708373225, 6566.606003639797, 1524.695060987807, 1540.63795008173, 1547.8916475846584, 1553.1923399832024, 1562.7968364112592, 869.9739007829776, 907.6838584053149, 944.5418971798672, 1006.4263369879245, 1024.4479613385727, 6152.569320147636, 6189.257289125244, 6224.284207316126, 6257.812265632038, 6265.686715664198, 6302.0, 6732.427743641747, 6737.461003119739, 6744.62904540255, 6798.320167983261, 5941.0, 6039.0, 6131.4977830430935, 6187.659678717714, 6224.159738435344, 3101.581286526335, 3494.7378946579106, 3542.6811586956783, 3543.326767914088, 3548.4854696068924, 6100.847478813065, 6667.566473208187, 6880.518363714492, 7012.614306213105, 7025.8898544351105, 6878.8624227515165, 6933.237343892168, 6945.978499352797, 6947.4789390795895, 6969.1985421676145, 5362.8659283517445, 5510.834674959758, 5962.910556341745, 5975.166460300589, 6170.629762214281, 6141.416565426277, 6661.903643712467, 6736.191657001191, 6737.966310168418, 6762.763303284361, 5703.771849125988, 6314.0, 6559.672016399257, 6618.2719900811835, 6681.031250468558, 6629.745886398648, 6696.631685257163, 6887.793366199022, 6965.373116419547, 6995.825104372431, 3941.6452519273403, 4440.868383176158, 4452.398926144929, 4455.487618923736, 4475.3734477172575, 5821.27234095738, 6055.426443761472, 6652.301508341555, 6692.0, 6776.254611992578, 5347.438518955453, 5399.83800485986, 6239.563230573816, 6444.709988050456, 6489.902651460137, 5249.6850188987255, 5683.2895888014, 6417.26201486835, 6539.346065393423, 6559.934400656123, 6329.588576742497, 6358.268799088164, 6363.3636636336, 6378.170837791057, 6391.840203994937, 6086.786962456293, 6542.677349541034, 6551.541392102507, 6553.279139294769, 6609.834754130993, 5728.598998070095, 5856.502197313106, 6263.311035785969, 6371.706185773025, 6378.45783108441], "value": 4724.413256251025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.3843847894839, 823.3577809308739, 828.4035494444001, 849.9150084991497, 853.4282031039203, 16539.503814885556, 16971.32114715413, 17067.658646827073, 17089.316427342907, 17887.926724396544, 17974.82025179751, 18098.276068957224, 18106.0, 18259.45221643353, 18397.90801045993, 944.6221511395445, 965.7344230336644, 971.053223107468, 971.7959228562011, 972.7470857577046, 951.9190868776162, 955.6607404371453, 956.9617215311379, 957.1003256938491, 960.8222478841402, 1003.8845532763717, 1005.8189525885338, 1006.3509036671343, 1006.9395836249812, 1013.7313611892854, 996.8704068471052, 997.4414327976357, 997.9401235925795, 1000.6997900629818, 1010.0656892374561, 705.3158436316764, 742.2911119880501, 743.985120297591, 744.8249661329584, 747.4095464582973, 16599.41902033423, 16646.75029874553, 16977.151142442828, 17015.659686806317, 17068.0, 16633.0, 16652.417165399274, 16952.660946781118, 17165.570860728458, 17170.141492925304, 13909.58271251864, 13927.791083133761, 14314.785278220836, 14315.212663303515, 14316.284185790773, 3467.5665541807275, 3516.3143187078276, 3533.293341331744, 3540.5751309842726, 3541.73436992224, 102434.48782756038, 110558.0, 121764.39117804443, 127408.72591274061, 128316.07525887119, 13405.530806421728, 13496.32518374097, 13653.453861845415, 13664.111832730841, 13669.863301366859, 24135.396615084763, 24957.126500572394, 25190.370240744127, 25514.193438722097, 25527.872360638077, 7690.46166768332, 7743.845123097506, 7793.805154871174, 7821.960890195513, 7826.452148349561, 3730.5709843367817, 3804.6195380462286, 3806.657400833883, 3807.6573108420375, 3820.8089595520114, 4228.386883901817, 4290.442242508454, 4336.392904993303, 4379.824807007684, 4423.756693381895, 915.6566287642135, 952.9571169297399, 953.6280850468323, 954.117441366733, 956.1490273656451, 573.8880918220907, 636.7357546618194, 652.3346186889441, 666.3203532396928, 685.7599840055941, 10871.565137394573, 12021.879781202251, 12297.139200256075, 12653.620391388273, 13417.731645367037, 641.2465353209959, 653.183520599251, 657.4805903336406, 663.1147418196762, 679.4326737174515, 4955.82654607087, 4994.3507344045765, 5130.717810520383, 5238.947610523846, 5286.788528458896, 100533.0, 100964.0, 101061.0, 101193.0, 105181.94818051874, 82055.0, 83103.5844820772, 84237.15762842294, 84739.57630211809, 84795.30409391777, 72223.27776722166, 75264.0, 75300.0, 78166.6091669538, 79618.0, 12064.75870482603, 12338.198017128858, 12603.747925041265, 12609.747805043664, 12641.2415255085, 40425.1914961705, 40581.0, 40807.59192408038, 40968.79515602403, 41226.79386603168, 53843.73078134741, 54690.72654636701, 54825.725871370385, 55255.72372138114, 55330.72334638301, 16045.679086417973, 16106.0, 16126.838731612534, 16384.426545070866, 16387.70572119928, 17811.0, 17962.10189490494, 18022.459326220236, 18062.638747225246, 18358.81641183571, 15921.840781592036, 16235.269412876212, 16576.00543967366, 16621.335146594, 16650.584700300125, 16212.027278363335, 16278.51164465068, 16286.674266514367, 16506.83493165053, 16525.917370413554, 15975.0, 16140.596485087966, 16143.031418114952, 16286.02283862972, 16326.020438773712, 10405.0, 11135.10919126484, 11162.441877906234, 11412.372319522507, 11707.004904582865, 12160.331181785086, 12605.87394126047, 12721.300328482022, 12794.808077878653, 13011.934940325238], "value": 8269.05916213388, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 116.0, 115.0, 115.0, 115.0, 114.0, 115.0, 117.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 113.0, 149.0, 125.0, 108.0, 139.0, 137.0, 157.0, 113.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 207.0, 228.0, 213.0, 208.0, 197.0, 199.0, 198.0, 198.0], "value": 204.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 58.0, 82.0, 59.0, 66.0, 50.0, 63.0, 99.0, 82.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 107.0, 71.0, 70.0, 73.0, 84.0, 70.0, 71.0, 91.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 89.0, 98.0, 90.0, 90.0, 101.0, 115.0, 90.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 131.0, 150.0, 120.0, 96.0, 97.0, 97.0, 98.0, 105.0], "value": 109.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 92.0, 92.0, 92.0, 90.0, 98.0, 93.0, 141.0, 142.0], "value": 102.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 46.0, 42.0, 45.0, 53.0, 52.0, 75.0, 72.0, 72.0, 71.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 58.0, 62.0, 63.0, 57.0, 55.0, 58.0, 71.0, 78.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 92.0, 83.0, 83.0, 85.0, 82.0, 84.0, 83.0, 79.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 141.0, 157.0, 154.0, 140.0, 142.0, 143.0, 187.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 191.0, 189.0, 189.0, 187.0, 189.0, 188.0, 189.0, 186.0, 227.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 69.0, 70.0, 69.0, 65.0, 67.0, 73.0, 70.0], "value": 68.8, "unit": "score"}]}]} 16:39:29 INFO - Return code: 0 16:39:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:39:29 ERROR - # TBPL SUCCESS # 16:39:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:39:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:39:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:39:29 INFO - 2017-04-20 16:39:29.972 screenresolution[1672:10830] starting screenresolution argv=screenresolution get 16:39:29 INFO - 2017-04-20 16:39:29.976 screenresolution[1672:10830] Display 0: 1600x1200x32@60 16:39:29 INFO - 2017-04-20 16:39:29.982 screenresolution[1673:10833] starting screenresolution argv=screenresolution list 16:39:29 INFO - Available Modes on Display 0 16:39:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:39:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:39:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:39:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:39:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:39:30 INFO - Intel Iris: 16:39:30 INFO - Chipset Model: Intel Iris 16:39:30 INFO - Type: GPU 16:39:30 INFO - Bus: Built-In 16:39:30 INFO - VRAM (Dynamic, Max): 1536 MB 16:39:30 INFO - Vendor: Intel (0x8086) 16:39:30 INFO - Device ID: 0x0a2e 16:39:30 INFO - Revision ID: 0x0009 16:39:30 INFO - Displays: 16:39:30 INFO - Display: 16:39:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:39:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:39:30 INFO - Display Serial Number: 1600x1200 60 16:39:30 INFO - Main Display: Yes 16:39:30 INFO - Mirror: Off 16:39:30 INFO - Online: Yes 16:39:30 INFO - Rotation: Supported 16:39:30 INFO - Return code: 0 16:39:30 INFO - Running post-action listener: _resource_record_post_action 16:39:30 INFO - [mozharness: 2017-04-20 23:39:30.238763Z] Finished run-tests step (success) 16:39:30 INFO - Running post-run listener: _resource_record_post_run 16:39:30 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39504384; Write bytes: 518575104; Read time: 253; Write time: 1638 16:39:30 INFO - TinderboxPrint: CPU usage
54.3% 16:39:30 INFO - TinderboxPrint: I/O read bytes / time
39,504,384 / 253 16:39:30 INFO - TinderboxPrint: I/O write bytes / time
518,575,104 / 1,638 16:39:30 INFO - TinderboxPrint: CPU idle
2,190.9 (45.7%) 16:39:30 INFO - TinderboxPrint: CPU system
962.9 (20.1%) 16:39:30 INFO - TinderboxPrint: CPU user
1,636.0 (34.2%) 16:39:30 INFO - TinderboxPrint: Swap in / out
246,886,400 / 0 16:39:30 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205380096; Write bytes: 204273152; Read time: 16084; Write time: 435 16:39:30 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36436480; Write bytes: 307121664; Read time: 230; Write time: 1174 16:39:30 INFO - Running post-run listener: _upload_blobber_files 16:39:30 INFO - Blob upload gear active. 16:39:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:30 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 16:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:39:31 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:31 INFO - (blobuploader) - INFO - Done attempting. 16:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:32 INFO - (blobuploader) - INFO - Done attempting. 16:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:33 INFO - (blobuploader) - INFO - Done attempting. 16:39:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:39:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:35 INFO - (blobuploader) - INFO - Done attempting. 16:39:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:35 INFO - Return code: 0 16:39:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796152c3ee226fc40c854731caf3929cb9aa4e1fcbb718023e0fffd5272f6b72f9af935fa4189e74b6d1602f6b7cda52413e78f0600933696b42b13017ae8d4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf3e633dc3c616659846fc32b6cc7699b297be3ce8a4cabe19cb52f8447efb40a0769322b11e5bffae331eb9a07c3154520338a8c8780482d6ea91cee3e74cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256460b998eec533241eaf106299f7bc1b441296ef2cd7ae04091a8e68c77779a74129e9f0799796ae828bb61d60e6497540508a81230395fd98c7189d965033", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38971c5d2d208e04c0002831c3b705a49969e5f4b33167dffa922f88e227bff10f04ffa7e1f473d1780de38665ed69b8d965469953d11bad450f3e28e0ccfbe9"} 16:39:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:35 INFO - Contents: 16:39:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796152c3ee226fc40c854731caf3929cb9aa4e1fcbb718023e0fffd5272f6b72f9af935fa4189e74b6d1602f6b7cda52413e78f0600933696b42b13017ae8d4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf3e633dc3c616659846fc32b6cc7699b297be3ce8a4cabe19cb52f8447efb40a0769322b11e5bffae331eb9a07c3154520338a8c8780482d6ea91cee3e74cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256460b998eec533241eaf106299f7bc1b441296ef2cd7ae04091a8e68c77779a74129e9f0799796ae828bb61d60e6497540508a81230395fd98c7189d965033", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38971c5d2d208e04c0002831c3b705a49969e5f4b33167dffa922f88e227bff10f04ffa7e1f473d1780de38665ed69b8d965469953d11bad450f3e28e0ccfbe9"} 16:39:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:35 INFO - Copying logs to upload dir... 16:39:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.103383 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 16:39:36.011088) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 16:39:36.014744) ========= 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.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796152c3ee226fc40c854731caf3929cb9aa4e1fcbb718023e0fffd5272f6b72f9af935fa4189e74b6d1602f6b7cda52413e78f0600933696b42b13017ae8d4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf3e633dc3c616659846fc32b6cc7699b297be3ce8a4cabe19cb52f8447efb40a0769322b11e5bffae331eb9a07c3154520338a8c8780482d6ea91cee3e74cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256460b998eec533241eaf106299f7bc1b441296ef2cd7ae04091a8e68c77779a74129e9f0799796ae828bb61d60e6497540508a81230395fd98c7189d965033", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38971c5d2d208e04c0002831c3b705a49969e5f4b33167dffa922f88e227bff10f04ffa7e1f473d1780de38665ed69b8d965469953d11bad450f3e28e0ccfbe9"} build_url:https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/MiZJfqHmTsW2SksXp-29Cw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/796152c3ee226fc40c854731caf3929cb9aa4e1fcbb718023e0fffd5272f6b72f9af935fa4189e74b6d1602f6b7cda52413e78f0600933696b42b13017ae8d4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf3e633dc3c616659846fc32b6cc7699b297be3ce8a4cabe19cb52f8447efb40a0769322b11e5bffae331eb9a07c3154520338a8c8780482d6ea91cee3e74cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/256460b998eec533241eaf106299f7bc1b441296ef2cd7ae04091a8e68c77779a74129e9f0799796ae828bb61d60e6497540508a81230395fd98c7189d965033", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38971c5d2d208e04c0002831c3b705a49969e5f4b33167dffa922f88e227bff10f04ffa7e1f473d1780de38665ed69b8d965469953d11bad450f3e28e0ccfbe9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 16:39:36.053198) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:39:36.053969) ========= 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.cudnHgk8FO/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.9efmddzbrN/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.005116 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:39:36.103227) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 16:39:36.103563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 16:39:36.104220) ========= ========= Total master_lag: 0.19 =========