builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0304 starttime: 1482250573.35 results: success (0) buildid: 20161220062753 builduid: 6818f0a1c15b4f08a2a9aaef7a351fd5 revision: 5206da513654c0e0b36293c9ce149ef9ed907a41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.353972) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.354432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.354721) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.384664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.385064) ========= 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.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-20 08:16: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% 18.7M=0.001s 2016-12-20 08:16:13 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.515509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.515826) ========= 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.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.027525 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.568403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:13.568757) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5206da513654c0e0b36293c9ce149ef9ed907a41 --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 5206da513654c0e0b36293c9ce149ef9ed907a41 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-20 08:16:13,667 truncating revision to first 12 chars 2016-12-20 08:16:13,667 Setting DEBUG logging. 2016-12-20 08:16:13,668 attempt 1/10 2016-12-20 08:16:13,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5206da513654?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 08:16:14,085 unpacking tar archive at: mozilla-central-5206da513654/testing/mozharness/ program finished with exit code 0 elapsedTime=0.690425 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:14.287170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:14.287968) ========= script_repo_revision: 5206da513654c0e0b36293c9ce149ef9ed907a41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:14.288347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:14.288637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 08:16:14.308080) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-20 08:16:14.308442) ========= /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.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:16:14 INFO - MultiFileLogger online at 20161220 08:16:14 in /builds/slave/test 08:16:14 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 08:16:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:16:14 INFO - {'append_to_log': False, 08:16:14 INFO - 'base_work_dir': '/builds/slave/test', 08:16:14 INFO - 'blob_upload_branch': 'Firefox', 08:16:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:16:14 INFO - 'branch': 'Firefox', 08:16:14 INFO - 'buildbot_json_path': 'buildprops.json', 08:16:14 INFO - 'config_files': ('talos/mac_config.py',), 08:16:14 INFO - 'default_actions': ('clobber', 08:16:14 INFO - 'read-buildbot-config', 08:16:14 INFO - 'download-and-extract', 08:16:14 INFO - 'populate-webroot', 08:16:14 INFO - 'create-virtualenv', 08:16:14 INFO - 'install', 08:16:14 INFO - 'run-tests'), 08:16:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:16:14 INFO - 'download_minidump_stackwalk': True, 08:16:14 INFO - 'download_symbols': 'ondemand', 08:16:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:16:14 INFO - 'tooltool.py': '/tools/tooltool.py', 08:16:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:16:14 INFO - '/tools/misc-python/virtualenv.py')}, 08:16:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:16:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:16:14 INFO - 'installer_path': 'installer.exe', 08:16:14 INFO - 'log_level': 'info', 08:16:14 INFO - 'log_name': 'talos', 08:16:14 INFO - 'log_to_console': True, 08:16:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:16:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:16:14 INFO - 'opt_config_files': (), 08:16:14 INFO - 'pip_index': False, 08:16:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:16:14 INFO - 'cmd': ('bash', 08:16:14 INFO - '-c', 08:16:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:16:14 INFO - 'enabled': True, 08:16:14 INFO - 'halt_on_failure': False, 08:16:14 INFO - 'name': 'check_screen_resolution'},), 08:16:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:16:14 INFO - 'cmd': ('bash', 08:16:14 INFO - '-c', 08:16:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:16:14 INFO - 'enabled': True, 08:16:14 INFO - 'halt_on_failure': False, 08:16:14 INFO - 'name': 'check_screen_resolution'},), 08:16:14 INFO - 'run_cmd_checks_enabled': True, 08:16:14 INFO - 'sps_profile': False, 08:16:14 INFO - 'sps_profile_interval': 0, 08:16:14 INFO - 'suite': 'dromaeojs-e10s', 08:16:14 INFO - 'system_bits': '32', 08:16:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:16:14 INFO - 'title': 't-yosemite-r7-0304', 08:16:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:16:14 INFO - 'use_talos_json': True, 08:16:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:16:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:16:14 INFO - 'work_dir': 'build'} 08:16:14 INFO - [mozharness: 2016-12-20 16:16:14.515393Z] Running clobber step. 08:16:14 INFO - Running pre-action listener: _resource_record_pre_action 08:16:14 INFO - Running main action method: clobber 08:16:14 INFO - rmtree: /builds/slave/test/build 08:16:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:16:16 INFO - Running post-action listener: _resource_record_post_action 08:16:16 INFO - [mozharness: 2016-12-20 16:16:16.579961Z] Finished clobber step (success) 08:16:16 INFO - [mozharness: 2016-12-20 16:16:16.580083Z] Running read-buildbot-config step. 08:16:16 INFO - Running pre-action listener: _resource_record_pre_action 08:16:16 INFO - Running main action method: read_buildbot_config 08:16:16 INFO - Using buildbot properties: 08:16:16 INFO - { 08:16:16 INFO - "project": "", 08:16:16 INFO - "product": "firefox", 08:16:16 INFO - "script_repo_revision": "production", 08:16:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:16:16 INFO - "repository": "", 08:16:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:16:16 INFO - "buildid": "20161220062753", 08:16:16 INFO - "pgo_build": "False", 08:16:16 INFO - "basedir": "/builds/slave/test", 08:16:16 INFO - "buildnumber": 15, 08:16:16 INFO - "slavename": "t-yosemite-r7-0304", 08:16:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:16:16 INFO - "platform": "yosemite_r7", 08:16:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:16:16 INFO - "branch": "mozilla-central", 08:16:16 INFO - "repo_path": "mozilla-central", 08:16:16 INFO - "revision": "5206da513654c0e0b36293c9ce149ef9ed907a41", 08:16:16 INFO - "stage_platform": "macosx64", 08:16:16 INFO - "builduid": "6818f0a1c15b4f08a2a9aaef7a351fd5", 08:16:16 INFO - "slavebuilddir": "test" 08:16:16 INFO - } 08:16:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:16:16 INFO - Running post-action listener: _resource_record_post_action 08:16:16 INFO - [mozharness: 2016-12-20 16:16:16.583536Z] Finished read-buildbot-config step (success) 08:16:16 INFO - [mozharness: 2016-12-20 16:16:16.583665Z] Running download-and-extract step. 08:16:16 INFO - Running pre-action listener: _resource_record_pre_action 08:16:16 INFO - Running main action method: download_and_extract 08:16:16 INFO - mkdir: /builds/slave/test/build/tests 08:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:16 INFO - trying https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:16:16 INFO - Downloading https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:16:17 INFO - Downloaded 1199 bytes. 08:16:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:16:17 INFO - Using the following test package requirements: 08:16:17 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:16:17 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:16:17 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:16:17 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'jsshell-mac.zip'], 08:16:17 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:16:17 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:16:17 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:16:17 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:16:17 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:16:17 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:17 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:16:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:16:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:16:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:17 INFO - Fetch https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:16:18 INFO - Expected file size: 16276070 08:16:18 INFO - Obtained file size: 16276070 08:16:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:16:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:16:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:25 INFO - Fetch https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:16:26 INFO - Expected file size: 16276070 08:16:26 INFO - Obtained file size: 16276070 08:16:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:16:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:16:32 INFO - Fetch https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:16:33 INFO - Expected file size: 14409734 08:16:33 INFO - Obtained file size: 14409734 08:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:34 INFO - trying https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:34 INFO - Downloading https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:16:36 INFO - Downloaded 69569492 bytes. 08:16:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:36 INFO - mkdir: /builds/slave/test/properties 08:16:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:36 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:36 INFO - Contents: 08:16:36 INFO - build_url:https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:37 INFO - Running post-action listener: _resource_record_post_action 08:16:37 INFO - Running post-action listener: set_extra_try_arguments 08:16:37 INFO - [mozharness: 2016-12-20 16:16:37.095017Z] Finished download-and-extract step (success) 08:16:37 INFO - [mozharness: 2016-12-20 16:16:37.095163Z] Running populate-webroot step. 08:16:37 INFO - Running pre-action listener: _resource_record_pre_action 08:16:37 INFO - Running main action method: populate_webroot 08:16:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:37 INFO - '--tppagecycles', 08:16:37 INFO - '7'], 08:16:37 INFO - 'tests': ['tsvgm']}}, 08:16:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['damp', 'tps']}, 08:16:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'tests': ['damp', 'tps']}, 08:16:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['dromaeo_dom']}, 08:16:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['basic_compositor_video']}, 08:16:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:16:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['a11yr', 08:16:37 INFO - 'ts_paint', 08:16:37 INFO - 'tpaint', 08:16:37 INFO - 'sessionrestore', 08:16:37 INFO - 'sessionrestore_no_auto_restore', 08:16:37 INFO - 'tabpaint']}, 08:16:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['tsvgx', 08:16:37 INFO - 'tsvgr_opacity', 08:16:37 INFO - 'tart', 08:16:37 INFO - 'tscrollx', 08:16:37 INFO - 'cart', 08:16:37 INFO - 'tsvg_static']}, 08:16:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:37 INFO - 'tsvgr_opacity', 08:16:37 INFO - 'tart', 08:16:37 INFO - 'tscrollx', 08:16:37 INFO - 'cart', 08:16:37 INFO - 'tsvg_static']}, 08:16:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'talos_options': ['--disable-e10s'], 08:16:37 INFO - 'tests': ['tp5o']}, 08:16:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:16:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'talos_options': ['--disable-e10s', 08:16:37 INFO - '--xperf_path', 08:16:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:37 INFO - 'tests': ['tp5n']}, 08:16:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:16:37 INFO - 'talos_options': ['--xperf_path', 08:16:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:37 INFO - 'tests': ['tp5n']}}, 08:16:37 INFO - 'talos.zip': {'path': '', 08:16:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:37 INFO - Running post-action listener: _resource_record_post_action 08:16:37 INFO - [mozharness: 2016-12-20 16:16:37.114076Z] Finished populate-webroot step (success) 08:16:37 INFO - [mozharness: 2016-12-20 16:16:37.114208Z] Running create-virtualenv step. 08:16:37 INFO - Running pre-action listener: _resource_record_pre_action 08:16:37 INFO - Running main action method: create_virtualenv 08:16:37 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:16:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:37 INFO - Using real prefix '/tools/python27' 08:16:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:40 INFO - Installing pip.................done. 08:16:40 INFO - Return code: 0 08:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:16:41 INFO - Reading from file tmpfile_stdout 08:16:41 INFO - Output received: 08:16:41 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:16:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:41 INFO - 'HOME': '/Users/cltbld', 08:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:41 INFO - 'LOGNAME': 'cltbld', 08:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:41 INFO - 'MOZ_NO_REMOTE': '1', 08:16:41 INFO - 'NO_EM_RESTART': '1', 08:16:41 INFO - 'PAGER': '/bin/cat', 08:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:41 INFO - 'PWD': '/builds/slave/test', 08:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:41 INFO - 'SHELL': '/bin/bash', 08:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:41 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:41 INFO - 'USER': 'cltbld', 08:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:41 INFO - 'XPC_FLAGS': '0x0', 08:16:41 INFO - 'XPC_SERVICE_NAME': '0', 08:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:16:42 INFO - Cleaning up... 08:16:42 INFO - Return code: 0 08:16:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:42 INFO - 'HOME': '/Users/cltbld', 08:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:42 INFO - 'LOGNAME': 'cltbld', 08:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:42 INFO - 'MOZ_NO_REMOTE': '1', 08:16:42 INFO - 'NO_EM_RESTART': '1', 08:16:42 INFO - 'PAGER': '/bin/cat', 08:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:42 INFO - 'PWD': '/builds/slave/test', 08:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:42 INFO - 'SHELL': '/bin/bash', 08:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:42 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:42 INFO - 'USER': 'cltbld', 08:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:42 INFO - 'XPC_FLAGS': '0x0', 08:16:42 INFO - 'XPC_SERVICE_NAME': '0', 08:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:42 INFO - Downloading/unpacking psutil>=3.1.1 08:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:46 INFO - Installing collected packages: psutil 08:16:46 INFO - Running setup.py install for psutil 08:16:46 INFO - building 'psutil._psutil_osx' extension 08:16:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:16:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:16:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:16:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:16:47 INFO - building 'psutil._psutil_posix' extension 08:16:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:16:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:47 INFO - ^ 08:16:47 INFO - 1 warning generated. 08:16:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:16:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:47 INFO - Successfully installed psutil 08:16:47 INFO - Cleaning up... 08:16:47 INFO - Return code: 0 08:16:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:47 INFO - 'HOME': '/Users/cltbld', 08:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:47 INFO - 'LOGNAME': 'cltbld', 08:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:47 INFO - 'MOZ_NO_REMOTE': '1', 08:16:47 INFO - 'NO_EM_RESTART': '1', 08:16:47 INFO - 'PAGER': '/bin/cat', 08:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:47 INFO - 'PWD': '/builds/slave/test', 08:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:47 INFO - 'SHELL': '/bin/bash', 08:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:47 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:47 INFO - 'USER': 'cltbld', 08:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:47 INFO - 'XPC_FLAGS': '0x0', 08:16:47 INFO - 'XPC_SERVICE_NAME': '0', 08:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:16:50 INFO - Installing collected packages: mozsystemmonitor 08:16:50 INFO - Running setup.py install for mozsystemmonitor 08:16:50 INFO - Successfully installed mozsystemmonitor 08:16:50 INFO - Cleaning up... 08:16:50 INFO - Return code: 0 08:16:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:50 INFO - 'HOME': '/Users/cltbld', 08:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:50 INFO - 'LOGNAME': 'cltbld', 08:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:50 INFO - 'MOZ_NO_REMOTE': '1', 08:16:50 INFO - 'NO_EM_RESTART': '1', 08:16:50 INFO - 'PAGER': '/bin/cat', 08:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:50 INFO - 'PWD': '/builds/slave/test', 08:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:50 INFO - 'SHELL': '/bin/bash', 08:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:50 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:50 INFO - 'USER': 'cltbld', 08:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:50 INFO - 'XPC_FLAGS': '0x0', 08:16:50 INFO - 'XPC_SERVICE_NAME': '0', 08:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:51 INFO - Downloading/unpacking jsonschema==2.5.1 08:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:16:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:54 INFO - Downloading functools32-3.2.3-2.tar.gz 08:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:16:54 INFO - warning: no files found matching '*.txt' 08:16:54 INFO - no previously-included directories found matching 'build' 08:16:54 INFO - no previously-included directories found matching 'dist' 08:16:54 INFO - no previously-included directories found matching '.git*' 08:16:54 INFO - Installing collected packages: jsonschema, functools32 08:16:54 INFO - Running setup.py install for functools32 08:16:54 INFO - warning: no files found matching '*.txt' 08:16:54 INFO - no previously-included directories found matching 'build' 08:16:54 INFO - no previously-included directories found matching 'dist' 08:16:54 INFO - no previously-included directories found matching '.git*' 08:16:54 INFO - Successfully installed jsonschema functools32 08:16:54 INFO - Cleaning up... 08:16:54 INFO - Return code: 0 08:16:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:54 INFO - 'HOME': '/Users/cltbld', 08:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:54 INFO - 'LOGNAME': 'cltbld', 08:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:54 INFO - 'MOZ_NO_REMOTE': '1', 08:16:54 INFO - 'NO_EM_RESTART': '1', 08:16:54 INFO - 'PAGER': '/bin/cat', 08:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:54 INFO - 'PWD': '/builds/slave/test', 08:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:54 INFO - 'SHELL': '/bin/bash', 08:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:54 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:54 INFO - 'USER': 'cltbld', 08:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:54 INFO - 'XPC_FLAGS': '0x0', 08:16:54 INFO - 'XPC_SERVICE_NAME': '0', 08:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:16:54 INFO - Cleaning up... 08:16:54 INFO - Return code: 0 08:16:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:54 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:54 INFO - 'HOME': '/Users/cltbld', 08:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:54 INFO - 'LOGNAME': 'cltbld', 08:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:54 INFO - 'MOZ_NO_REMOTE': '1', 08:16:54 INFO - 'NO_EM_RESTART': '1', 08:16:54 INFO - 'PAGER': '/bin/cat', 08:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:54 INFO - 'PWD': '/builds/slave/test', 08:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:54 INFO - 'SHELL': '/bin/bash', 08:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:54 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:54 INFO - 'USER': 'cltbld', 08:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:54 INFO - 'XPC_FLAGS': '0x0', 08:16:54 INFO - 'XPC_SERVICE_NAME': '0', 08:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:58 INFO - Downloading docopt-0.6.1.tar.gz 08:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:58 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:58 INFO - Running setup.py install for blobuploader 08:16:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:59 INFO - Running setup.py install for requests 08:16:59 INFO - Running setup.py install for docopt 08:16:59 INFO - Successfully installed blobuploader requests docopt 08:16:59 INFO - Cleaning up... 08:16:59 INFO - Return code: 0 08:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:59 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:16:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:59 INFO - 'HOME': '/Users/cltbld', 08:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:59 INFO - 'LOGNAME': 'cltbld', 08:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:59 INFO - 'MOZ_NO_REMOTE': '1', 08:16:59 INFO - 'NO_EM_RESTART': '1', 08:16:59 INFO - 'PAGER': '/bin/cat', 08:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:59 INFO - 'PWD': '/builds/slave/test', 08:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:59 INFO - 'SHELL': '/bin/bash', 08:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:16:59 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:59 INFO - 'USER': 'cltbld', 08:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:59 INFO - 'XPC_FLAGS': '0x0', 08:16:59 INFO - 'XPC_SERVICE_NAME': '0', 08:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-P2i37Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-a4e9is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-QgLuvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-5zS7JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-qwJzpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-zk9D7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-ZbuWsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-SD086N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:00 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Qh7VHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-bDMpBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-7fLQ6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-6NKkNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-hqV_7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-1KAivz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-2eQ65n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KGKPH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:01 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-PsYHqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:17:01 INFO - Running setup.py install for manifestparser 08:17:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:17:01 INFO - Running setup.py install for mozcrash 08:17:02 INFO - Running setup.py install for mozdebug 08:17:02 INFO - Running setup.py install for mozdevice 08:17:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Running setup.py install for mozfile 08:17:02 INFO - Running setup.py install for mozhttpd 08:17:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Running setup.py install for mozinfo 08:17:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Running setup.py install for mozInstall 08:17:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:17:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Running setup.py install for mozleak 08:17:03 INFO - Running setup.py install for mozlog 08:17:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Running setup.py install for moznetwork 08:17:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Running setup.py install for mozprocess 08:17:03 INFO - Running setup.py install for mozprofile 08:17:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:17:03 INFO - Running setup.py install for mozrunner 08:17:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:17:04 INFO - Running setup.py install for mozscreenshot 08:17:04 INFO - Running setup.py install for moztest 08:17:04 INFO - Running setup.py install for mozversion 08:17:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:17:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:17:04 INFO - Cleaning up... 08:17:04 INFO - Return code: 0 08:17:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:04 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:04 INFO - 'HOME': '/Users/cltbld', 08:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:04 INFO - 'LOGNAME': 'cltbld', 08:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:04 INFO - 'MOZ_NO_REMOTE': '1', 08:17:04 INFO - 'NO_EM_RESTART': '1', 08:17:04 INFO - 'PAGER': '/bin/cat', 08:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:04 INFO - 'PWD': '/builds/slave/test', 08:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:04 INFO - 'SHELL': '/bin/bash', 08:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:17:04 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:04 INFO - 'USER': 'cltbld', 08:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:04 INFO - 'XPC_FLAGS': '0x0', 08:17:04 INFO - 'XPC_SERVICE_NAME': '0', 08:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:04 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KhLJHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:04 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-_B4Zsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:04 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-6nflOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-olED48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BTUm81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-ZaDtua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-UxViE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-DnwyX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-AjAUw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-fqUm4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-0YOT3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:05 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-jTiHvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:06 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-wrXApY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:06 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-e_JUTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:06 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Vca4Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:06 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-PuZ0jR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:06 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-OBsDpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:17:06 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:17:06 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:17:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:17:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:17:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:17:09 INFO - Downloading blessings-1.6.tar.gz 08:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:17:09 INFO - Installing collected packages: blessings 08:17:09 INFO - Running setup.py install for blessings 08:17:09 INFO - Successfully installed blessings 08:17:09 INFO - Cleaning up... 08:17:09 INFO - Return code: 0 08:17:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:17:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:09 INFO - Reading from file tmpfile_stdout 08:17:09 INFO - Current package versions: 08:17:09 INFO - blessings == 1.6 08:17:09 INFO - blobuploader == 1.2.4 08:17:09 INFO - docopt == 0.6.1 08:17:09 INFO - functools32 == 3.2.3-2 08:17:09 INFO - jsonschema == 2.5.1 08:17:09 INFO - manifestparser == 1.1 08:17:09 INFO - mozInstall == 1.12 08:17:09 INFO - mozcrash == 1.0 08:17:09 INFO - mozdebug == 0.1 08:17:09 INFO - mozdevice == 0.49 08:17:09 INFO - mozfile == 1.2 08:17:09 INFO - mozhttpd == 0.7 08:17:09 INFO - mozinfo == 0.9 08:17:09 INFO - mozleak == 0.1 08:17:09 INFO - mozlog == 3.4 08:17:09 INFO - moznetwork == 0.27 08:17:09 INFO - mozprocess == 0.23 08:17:09 INFO - mozprofile == 0.28 08:17:09 INFO - mozrunner == 6.13 08:17:09 INFO - mozscreenshot == 0.1 08:17:09 INFO - mozsystemmonitor == 0.3 08:17:09 INFO - moztest == 0.7 08:17:09 INFO - mozversion == 1.4 08:17:09 INFO - psutil == 3.1.1 08:17:09 INFO - requests == 1.2.3 08:17:09 INFO - wsgiref == 0.1.2 08:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:09 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:09 INFO - 'HOME': '/Users/cltbld', 08:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:09 INFO - 'LOGNAME': 'cltbld', 08:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:09 INFO - 'MOZ_NO_REMOTE': '1', 08:17:09 INFO - 'NO_EM_RESTART': '1', 08:17:09 INFO - 'PAGER': '/bin/cat', 08:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:09 INFO - 'PWD': '/builds/slave/test', 08:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:09 INFO - 'SHELL': '/bin/bash', 08:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:17:09 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:09 INFO - 'USER': 'cltbld', 08:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:09 INFO - 'XPC_FLAGS': '0x0', 08:17:09 INFO - 'XPC_SERVICE_NAME': '0', 08:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:17:10 INFO - Cleaning up... 08:17:10 INFO - Return code: 0 08:17:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:10 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 0x106fbbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fbe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc34e10c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, '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.FWlCDfdFv0/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.H7y2OTumjg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:17:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:10 INFO - 'HOME': '/Users/cltbld', 08:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:10 INFO - 'LOGNAME': 'cltbld', 08:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:10 INFO - 'MOZ_NO_REMOTE': '1', 08:17:10 INFO - 'NO_EM_RESTART': '1', 08:17:10 INFO - 'PAGER': '/bin/cat', 08:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:10 INFO - 'PWD': '/builds/slave/test', 08:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:10 INFO - 'SHELL': '/bin/bash', 08:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:17:10 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:10 INFO - 'USER': 'cltbld', 08:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:10 INFO - 'XPC_FLAGS': '0x0', 08:17:10 INFO - 'XPC_SERVICE_NAME': '0', 08:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:17:10 INFO - Cleaning up... 08:17:10 INFO - Return code: 0 08:17:10 INFO - Running post-action listener: _resource_record_post_action 08:17:10 INFO - Running post-action listener: _start_resource_monitoring 08:17:10 INFO - Starting resource monitoring. 08:17:10 INFO - [mozharness: 2016-12-20 16:17:10.405146Z] Finished create-virtualenv step (success) 08:17:10 INFO - [mozharness: 2016-12-20 16:17:10.406092Z] Running install step. 08:17:10 INFO - Running pre-action listener: _resource_record_pre_action 08:17:10 INFO - Running main action method: install 08:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:10 INFO - Reading from file tmpfile_stdout 08:17:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:17:10 INFO - Reading from file tmpfile_stdout 08:17:10 INFO - Output received: 08:17:10 INFO - Usage: mozinstall [options] installer 08:17:10 INFO - Options: 08:17:10 INFO - -h, --help show this help message and exit 08:17:10 INFO - -d DEST, --destination=DEST 08:17:10 INFO - Directory to install application into. [default: 08:17:10 INFO - "/builds/slave/test"] 08:17:10 INFO - --app=APP Application being installed. [default: firefox] 08:17:10 INFO - mkdir: /builds/slave/test/build/application 08:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:17:32 INFO - Reading from file tmpfile_stdout 08:17:32 INFO - Output received: 08:17:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:17:32 INFO - Running post-action listener: _resource_record_post_action 08:17:32 INFO - [mozharness: 2016-12-20 16:17:32.351137Z] Finished install step (success) 08:17:32 INFO - [mozharness: 2016-12-20 16:17:32.351293Z] Running run-tests step. 08:17:32 INFO - Running pre-action listener: _resource_record_pre_action 08:17:32 INFO - Running main action method: run_tests 08:17:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:32 INFO - 2016-12-20 08:17:32.363 screenresolution[1631:7808] starting screenresolution argv=screenresolution get 08:17:32 INFO - 2016-12-20 08:17:32.365 screenresolution[1631:7808] Display 0: 1600x1200x32@60 08:17:32 INFO - 2016-12-20 08:17:32.370 screenresolution[1632:7811] starting screenresolution argv=screenresolution list 08:17:32 INFO - Available Modes on Display 0 08:17:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:32 INFO - Intel Iris: 08:17:32 INFO - Chipset Model: Intel Iris 08:17:32 INFO - Type: GPU 08:17:32 INFO - Bus: Built-In 08:17:32 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:32 INFO - Vendor: Intel (0x8086) 08:17:32 INFO - Device ID: 0x0a2e 08:17:32 INFO - Revision ID: 0x0009 08:17:32 INFO - Displays: 08:17:32 INFO - Display: 08:17:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:32 INFO - Display Serial Number: 1600x1200 60 08:17:32 INFO - Main Display: Yes 08:17:32 INFO - Mirror: Off 08:17:32 INFO - Online: Yes 08:17:32 INFO - Rotation: Supported 08:17:32 INFO - Return code: 0 08:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:17:32 INFO - Python 2.7.3 08:17:32 INFO - Return code: 0 08:17:32 INFO - grabbing minidump binary from tooltool 08:17: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')]} 08:17:32 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 0x106d99d30>, '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 0x106cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d8ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:17:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:17:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:17:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:17:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:17:32 INFO - Return code: 0 08:17:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:17:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:17:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:17:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:17:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:17:32 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/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 08:17:32 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/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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 08:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FWlCDfdFv0/Render', 08:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:32 INFO - 'HOME': '/Users/cltbld', 08:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:32 INFO - 'LOGNAME': 'cltbld', 08:17:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:17:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:32 INFO - 'MOZ_NO_REMOTE': '1', 08:17:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:17:32 INFO - 'NO_EM_RESTART': '1', 08:17:32 INFO - 'PAGER': '/bin/cat', 08:17:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:32 INFO - 'PWD': '/builds/slave/test', 08:17:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:32 INFO - 'SHELL': '/bin/bash', 08:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H7y2OTumjg/Listeners', 08:17:32 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 08:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:32 INFO - 'USER': 'cltbld', 08:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:32 INFO - 'XPC_FLAGS': '0x0', 08:17:32 INFO - 'XPC_SERVICE_NAME': '0', 08:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:32 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/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 08:17:32 INFO - mozversion application_buildid: 20161220062753 08:17:32 INFO - mozversion application_changeset: 5206da513654c0e0b36293c9ce149ef9ed907a41 08:17:32 INFO - mozversion application_display_name: Nightly 08:17:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:17:32 INFO - mozversion application_name: Firefox 08:17:32 INFO - mozversion application_remotingname: firefox 08:17:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:17:32 INFO - mozversion application_vendor: Mozilla 08:17:32 INFO - mozversion application_version: 53.0a1 08:17:32 INFO - mozversion platform_buildid: 20161220062753 08:17:32 INFO - mozversion platform_changeset: 5206da513654c0e0b36293c9ce149ef9ed907a41 08:17:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:17:32 INFO - mozversion platform_version: 53.0a1 08:17:32 INFO - using testdate: 1482250652 08:17:32 INFO - actual date: 1482250652 08:17:32 INFO - starting webserver on 'localhost:49242' 08:17:32 INFO - SUITE-START | Running 2 tests 08:17:32 INFO - TEST-START | dromaeo_css 08:17:32 INFO - operating with platform_type : mac_ 08:17:32 INFO - Initialising browser for dromaeo_css test... 08:17:32 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpYjyw8G/profile http://localhost:49242/getInfo.html) 08:17:35 INFO - PROCESS | 1642 | 2016-12-20 08:17:35.799 plugin-container[1644:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 08:17:35 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:35 INFO - PROCESS | 1642 | 2016-12-20 08:17:35.802 plugin-container[1644:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 08:17:35 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:35 INFO - PROCESS | 1642 | 2016-12-20 08:17:35.802 plugin-container[1644:7964] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:35 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 08:17:35 INFO - PROCESS | 1642 | colorDepth:24 08:17:35 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 08:17:35 INFO - PROCESS | 1642 | __metrics 08:17:35 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:17:36 INFO - TEST-INFO | 1642: exit 0 08:17:36 INFO - Browser initialized. 08:17:36 INFO - Running cycle 1/1 for dromaeo_css test... 08:17:36 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpYjyw8G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:39 INFO - PROCESS | 1645 | 2016-12-20 08:17:39.227 plugin-container[1646:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:17:39 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:39 INFO - PROCESS | 1645 | 2016-12-20 08:17:39.227 plugin-container[1646:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:17:39 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:39 INFO - PROCESS | 1645 | 2016-12-20 08:17:39.227 plugin-container[1646:8088] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:48 INFO - PROCESS | 1645 | RSS: Main: 236855296 08:17:48 INFO - PROCESS | 1645 | 08:18:38 INFO - PROCESS | 1645 | 1482250718480 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:20:51 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:20:53 INFO - PROCESS | 1645 | RSS: Main: 254062592 08:20:53 INFO - PROCESS | 1645 | 08:23:55 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 08:23:56 INFO - PROCESS | 1645 | RSS: Main: 266612736 08:23:56 INFO - PROCESS | 1645 | 08:26:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 08:26:58 INFO - PROCESS | 1645 | RSS: Main: 271060992 08:26:58 INFO - PROCESS | 1645 | 08:30:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 08:30:01 INFO - PROCESS | 1645 | RSS: Main: 270413824 08:30:01 INFO - PROCESS | 1645 | 08:33:04 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 08:33:04 INFO - PROCESS | 1645 | RSS: Main: 274874368 08:33:04 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 08:36:07 INFO - PROCESS | 1645 | RSS: Main: 277233664 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | __start_tp_report 08:36:07 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 08:36:07 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 08:36:07 INFO - PROCESS | 1645 | |i|pagename|runs| 08:36:07 INFO - PROCESS | 1645 | |0;dojo.html;2666.440047590006;2886.2928582497293;3144.827034513102;3403.9829800851007;3410.8465119069647;4557.77211139443;4781.497942716017;5656.377798442168;5684.687342196173;5788.27646544182;5705.714714264281;5710.7430165642545;5779.364269930315;5822.883542329151;5867.53311672082;5123.666961647499;5184.481551844804;5185.611079169059;5293.629445938766;5302.257683924254;5144.717040562768;5302.973485132569;5308.761105750251;5337.06601344765;5352.812651557197;5147.819826306061;5165;5268.604854635918;5270.420253772082;5302.257683924254;5166.250893620404;5182.4299327073995;5201.55786758126;5201.713905735183;5204.791808327663;5181.35233095863;5215.843524694227;5216.60875434344;5225.372955245357;5243.947560524384;5573.554115670737;5762.567807414419;5770.596058275923;5788.986927287708;5795.971020144872;5780.624259423124;6143;6182.659953702545;6211.68941552925;6221.564490485668;5453.291072160633;6129.23653806838;6154.969225153845;6165.260168779775;6232.813015609494;4920.5325494078725;5857.414258574151;5863.501602363847;5929.851753706149;5950.166976623276;4968.975155124202;6219.937800622027;6320.115183874169;6324.810255692337;6328.810135695937;6065.727042283108;6092.025275955826;6123.020316749299;6125;6154.1999540059505;6097.82716539334;6136.519078024463;6200;6205.286392064971;6214.658193799294;6100.206973093469;6138.848241798255;6193.102000209944;6213.192285003011;6235.127082208494;5111.156659151198;5353.848922481685;5838.328592211865;6099.8475038123515;6221.9377806221355;3812.542494900574;4074.1036971866733;4835.347228124158;4850.902981940341;4952.876178095505;4353.82584696615;4365.80353884076;4411.514733379326;4427.8228870844805;4458.331250312479;5979.252593425822;5980.192673989043;5983.311919129269;5987.940120598738;6008.098785182257;6195.31851496335;6205.937940620626;6209.19280493542;6212.47193988507;6236.314005459396;5751.971240143773;5802.65184088956;5830.300363956268;5856.472917437451;5884.999550076412;5407.053765591085;5412.296401467863;5414.864628384243;5416.7020813855615;5417.052015897202;6206.255249370104;6219.40915613016;6226.626402415779;6243.063540468966;6244.968775156095;5072.416672082758;6224.346443623535;6235.314115447388;6251.6561589113035;6256.937430625636;6417;6443;6446.355364463516;6450.967745161432;6476.31998640136;5019.924701129559;6060.818175454743;6210.813675589739;6236.656983865931;6256.468200203034;6038.486728628116;6129.114290130661;6130.061839217998;6138.263408391022;6224.9377506224355;4951.282064100757;5009.499050094962;6155.507559395327;6168.814935551941;6181.134641150153;6082.391760824059;6154.292256390393;6162.876742465215;6163.476104531165;6199.938000619936;5830.212921255746;5874.530037597067;5888.882222355614;5944.762209511571;6024.969875150597;6140.293866205444;6155.353687862889;6165.136880836776;6169.198004259597;6176.135341052166;5986.281646202484;5986.970065149646;6006.97881360161;6017.849553761191;6017.939820601738 08:36:07 INFO - PROCESS | 1645 | |1;ext.html;449.74814104101705;467.7848189832676;507.6547947395772;524.2869697211794;540.4325458268817;38061.61938380615;40019.59980400195;40156;41873.7906310468;41916.58083419165;40999.79500102503;41237;41316.38025429621;41367.58632413675;41415.378769318486;8059.435839491238;8079.353651707878;8187.853700481937;8324.334053275723;9183.173514383705;8401.579921003957;11024.614138505154;11263.26788758732;11280.210385273034;11439.771204575904;11253.54985800567;11271.211015228937;11294.378809165493;11299.152563557725;11353.091752659768;9858.260630452673;9882.505874706272;11037.172212084086;11244.49399777012;11267.549298028069;9505.667301644478;10584.947075264652;11057.336559806357;11121.05471034963;11151.330920144817;31801.681983180104;39829;39912.60087399118;39987.200255995005;40022.39966400506;3398.5921689397433;3400.4219282721997;3499.982500087509;3621.130928577149;3627.474016267626;29807.70192298071;30333;30564.083077507712;30602.693973060206;31255;3162.952555711643;3190.2502911815704;3220.7745457817964;3307.7684562080663;3307.8842240521467;483481;486319;488201.5589922063;488821.99999999645;489212;59373;59920.70039649774;59938.70030649819;59956;59982;62251.68874155691;62369.06446403308;62821.685891571164;63081.738365232435;63162;15564.377424903103;16062.67874642524;16075.598110047109;16084.517464476085;16121.919390402973;11102.61140860066;11410.201285910072;12186.964108050737;12391.504339826486;12416.937915310546;16391.262393192097;16655.750163747554;16748.99506029642;16862.57843553921;16870;7368.815779605446;7470.962645186739;7482.850342993219;7484.625768711543;7566.167721550625;841.5707988925569;862.5773371048141;954.3796532253951;964.4105576527047;1034.6016783538391;25162.874185628956;28123.468887799787;29787.74397459534;29854.850725746233;29898.701012990026;937.4937533731709;1023.3706270643501;1144.404909447083;1150.206357613244;1155.6590805712335;13690.794638080437;14706.63233419173;14966.401343946338;15073.472428465171;15657.982231154938;619801;624669;626708;627383;628239;51627;51761.74119129455;51953.220701689504;51960.48039519632;51969.22046169235;1317.2294207888492;1347.5889853594551;1367.118208755367;1368.4321006782272;1371.91082579632;24219.878900605385;28248.85875570609;28299.292517687223;28491;29082;44257.77871110752;45074.77462612666;45136.77431612821;45226.54773452223;45260.773696131306;565257;566953;567761;568492;568815;21555.892220538797;23954.88022559876;24100.794960252268;24370.147044853344;24414.389640258425;32685.836570816995;39749.40375894306;40400.595994039686;40884.38673419961;42118.36822447727;34381.79663711758;35090;35816.46275305925;36508.63491365052;37438.8128059358;27474.450510990067;34799.65200347964;35010;35724.821375892956;35785.284294314486;33899.49150762691;34061.97814065483;34116.14709632279;34233.48649770305;34602.48096278508;21589.784102158777;30455.238619033815;31057.22356941004;31636.525452118698;31656.683433165374;20458.987548050824;20682.276120335715;21009.369718908456;22814.42963925915;24333.27000189997 08:36:07 INFO - PROCESS | 1645 | |2;jquery.html;295.5537138920231;363.0868366059361;380.5927657406575;394.31389382474504;398.39841838823367;18590.535236619075;19137;19762.70355944662;20268.797312026876;20462;19916.904570248666;19991.300304489345;20166.092525836335;20442.48893777656;20465.795342046575;5731.083026715726;5745.253117094782;5970.8805823883495;6000.909986350208;6021.217241758587;5623.662580245178;5716.828495145152;5919.23050003499;5921.496672782819;5924.170616113747;5209.86975325618;5239.292695486118;5616.241807356016;5648.616089058187;5858.73635686393;4963.329950456714;5901.314901719728;6006.00900851359;6006.468350570598;6030.638161710312;5605.803796867131;5775.306963164405;5788.536917046668;5842.415758424166;5851.122331650244;10710;12729.172603780817;12949.676258093528;12968.546100886519;21739.891300543553;1680.8990111477003;2229.4649284171846;2256.751757306695;2324.1052194904914;2355.281639100074;6922.757703480354;8949.731508054769;9808.362456440309;10154.238432117621;10273.640422585173;1757;1909.4367161687335;1939;1942.8639995200342;1951.1317463728647;93981.53009234979;120149;121518;123357.76642233643;123958;9881.814182298027;11004.559817607205;11631.825522617168;11695;11878.227915185485;14373.137611743328;14538.27308634564;14548.490802822062;14582.41670333196;14600.050996685182;8119.959400202961;8397.70608028716;8423.157684231528;8434.831303374021;8572.828543429096;8126.512409255463;8184.508929464251;8187.795305117301;8354.874676879852;8369.49783013021;4453.376527286183;4516;4563.178627847019;4567.200739870509;4600.907981840412;1543.729847276722;1556.7976163098724;1601.4154833485693;1648.7279598866292;1927.094265695121;898.613977010123;927.7170463008847;949.852772820214;976.3799987008159;1013.7414959185508;15375.538733838002;19422.611547769247;20681.482962926046;20810;21169.25907593227;947.6351604632266;953.6376177052675;958.8561715742555;980.4705459052032;1001.9549120289604;11009.174311926477;11099.223054386115;11116.38859862699;11142.220044596963;11176.441177941071;396388.01805991365;455884;488223;533602;533653;88956;99288.51067233997;100333.96660333908;120557;124157;992.947475675786;997.152420442618;1035.53400969564;1043.1029314789275;1044.3211912256943;17481.77627566058;18069.909650451664;18712.625747485246;18948.810511895255;19065.23739050478;47098;47925.76037119792;50228.24201152942;51536;55489.722551386985;62539;64375.35624643694;76414;79271.20728792869;79653.60173199097;8024.518528888286;8222.588870556569;8236.546989915612;8250.54621995772;8412.747617571482;11943.76112477763;12330.63008109758;13103;13405.798913016117;13445.865541344852;11143.888561114609;11367.943160284476;11419.257748246337;11525;11553.24903881245;9825.312228143961;10045.94517575644;11062.502187401426;11329.660110196708;11589.072874169877;11312.773744524899;11420.772949274811;11603.477843496894;11622.94188529052;11644.941775291069;7840.255175758294;7953.960230198812;8104.79738006531;8365.163483651586;8440;10365.067143956778;10415.843762343722;10450.163986881184;10516.316439431412;11291 08:36:07 INFO - PROCESS | 1645 | |3;mootools.html;1457.8979471436999;1506.1189204315474;1726.8100508944021;1842.309134074722;1872.8501719862413;7512.699492020312;7792.883106753403;8044.959775201123;8555.272801811845;8556.016058153307;8039.356851451885;8300.75097747067;8323.875141872863;8412.36907231957;8457.91542084579;2285.314405678298;2330.2892617751577;2333.428310064037;2334.1947028275217;2347.049444974788;2053.5482193917314;2290.6564015397657;2301.2520930697506;2329.9650505242435;2353.670486131943;1898.3830255167056;2304.847576211894;2323.256557901472;2325.465143017107;2326.7324257710325;1996.5008747813047;2210.2153735424013;2283.9885800571055;2313.8148948084113;2318.953620927589;1983.6032793441225;2294.7475777664445;2310.4916918277954;2344.941376465602;2350.365401341628;8302.8339433211;8408.663653453854;8471.534065626389;8487.915120848837;8500.489970601784;5062.797488100508;5197.674592978804;5240.764165612557;5244.400457860351;5254.710990895499;2859.127965970378;3416.145963509123;3501.2997400519744;3605.7560141751314;3619.819009049537;1036.7822757220993;1050.5220124843179;1061.241212533035;1078.2829418436809;1081.7511972246348;10596.258261921668;10755.83866242007;10887.346759194474;10889;10927.398993055378;4516.977415112903;4588.95411045892;4678.251479763289;4694.554017368345;4762.357081793983;9314;9412.717618471457;9428.528573571293;9437.858432123523;9457.574409151604;4978.651494395358;5086.135356989246;5235.633505654568;5237.240600112962;5262.42113367537;3108.6269647641975;3893.9875632335797;4054.9254447571416;4428.55714428561;4436.735530373828;3202.551642770014;3408.9488657670154;3803.353429916921;3851.768893866377;3881.2237552489614;1288.407332627002;1295.8315418995471;1296.9948290075267;1300.5643109558405;1303.4590644882458;750.9849803003909;885.4598694796169;903.7722015232353;921.7465197070859;932.5710173320213;5720.742566584514;5774.162746401748;5808.332041815245;5954.345022047572;6058.12157237207;792.3265224559078;899.5771987166022;914.1133100892124;929.5823868600438;978.6868202175235;3350.877456052201;3685.981570092186;3714.60996595359;3816.122291872885;3873.0704630888386;8232.382571307056;9843;9901.752456188653;9905.851412228822;9956;9280.72157835266;9841.360311579725;9876.60493580263;9877.456739879231;9896.208303335716;7354;7741.651625676858;7745.922540774633;7765.922340776633;7774.6890124394395;5385.515303622693;5506.697131657797;5607.158926161109;5624.437556244343;5706.343770466449;7645.426593005435;9217.585208665492;9338.579763910777;9362.29782766309;9404.482753448627;9156.816863662823;9433.009533998831;9449.43303401798;9533.570989305359;9543.618255269712;6549.574277672126;6734.057231987624;7016.684249208696;7177.282271772781;7203.459740519377;7573.280538349069;7703.499272547267;7803.336716379172;7889.921100789148;7891.289783919475;6351.824912391223;6673.165854268216;7022.297770222936;7029.683664235019;7087.007818905255;5983.311919129182;6682.064510968566;6842.93157068423;6962.512624116263;6964.860702785814;6565.967170164118;6600.636964966973;6636.900446493403;6641.734330626914;6694.564853284521;4669.112868554964;4770.022145460103;4771.785269662804;4829.710217386968;4841;4213.852515161954;4647.395838540968;4678.625709943129;4766.261229509293;4815.542523460265 08:36:07 INFO - PROCESS | 1645 | |4;prototype.html;323.2597352063774;360.38014614862436;360.6375592529507;360.7402670077545;366.734765060541;4432.445944256968;5330.7867685292595;5484.204790305408;5498.725063746807;5528.557715382764;4598.264277715566;4628.4677262114865;4704.905901881961;5253.763580638871;5269.525742683158;3253.967460325402;4048.271311163989;4099.89750256243;4224.471941007382;4265.552117027699;3695.6119607441233;3946.5461471930666;4217.40956266123;4251.744895306276;4252.872413827575;4175.185838761443;4202.928253295406;4208.97895510522;4210.410542524049;4220.831166753326;3855.61443855615;4224.535301116859;4238.321868501041;4241.045764702948;4251.574842515754;2913.8688759005895;2921.547160190174;3501.91245218869;3510.2628448025944;3513.402721537345;4633.814647414099;4960.801567937278;5268.262443257947;5334.51989320963;5365.382980957162;3637.3089113068695;3961.7028722845903;4148.419221309019;4314.80583373749;4609.631229501631;4897.363342765418;5386.811461598825;5578.665280083167;5664.54683625309;5700.7719691212305;3281.753868459851;3621.311950729353;3886.980565097185;3892.3188442022533;3900.03597266077;5459.372172200208;5512.401403592953;6059.939400606026;6083.543734219907;6091.177691011701;2967.540031295153;3818.4081467372603;3938.763674179501;3955.6044395560216;3966.464527288837;3992.3612222044335;3997.8200980955926;4004.439378487014;4010.5387880393546;4013.1371755072214;4682.274247491644;4685.461171965268;4687.95312046882;4712.976435117803;4719.59883409914;5279.736013199325;5686.43135686428;5791;5829.5044921181325;5845;1303.4590644882267;1311.1608570514877;1313.4220942785335;1314.3296918571407;1316.782730849399;755.3277582951177;804.6097642643314;849.8257857139272;884.6594061286451;888.2627419242014;4416.53626369233;4972.303877457159;5096.439391666913;5187.170052791611;5238.816641417532;4570.748608826547;4806.49531799163;5316.175992721134;5695.373508914016;5721.997300944636;4924.704517728947;4959.536936603638;5207.401148867929;5219.478052194826;5254.97372513135;2417.4077351048754;2458.483718419152;2584.405586715066;2867.483852906497;2965.8517074146207;5978.55160862937;5988.640681559121;5993.280806303184;5994.520438364921;6010.759569617254;5081.59347252219;5138;5458.5087342139395;5808.82573522795;5952.940470595238;5389.595780316492;5407.972960135174;5940.970295148497;5954.821355359346;6012.384612693492;4994.525520075587;5468.64453810517;5721.539737912737;5733.3406658234835;5735.197072409783;4897.877553061202;5069.442361340176;5535.367066715409;5658.9151162733415;5677.489025987516;5513.531349835149;5589.944100559105;5719.7702493964;5950.672713000827;5968.522518198447;3627.336390245818;3729.776213427203;3768.773873567595;3777.6222377762006;3780.5652349980105;5194.428612852659;5683.857903552444;5908.438698323653;5955.940440595712;5973.432523910222;5012.04771093491;5312.495312945264;5701.458361455655;5836.445537673914;5878.676672782867;5593.692346920959;5771.797987070432;5782.306123265235;5803.535717142534;5823;4689.835855745169;4712.986707857827;4928.359313289249;5032.874178145576;5411.83764487066;5276.630635855607;5414.810481633124;5786.884262314814;5824.621399609012;5846.801405711733;4649.930251046169;4756.929690819538;4877.5366340197625;5650.547956163579;5725.398833122462 08:36:07 INFO - PROCESS | 1645 | |5;yui.html;661.3584822721959;736.2527035059412;739.4528049243561;785.77605382466;818.2390376949438;14746.262686865653;14977.47578834741;15555.688886222282;15703.60740981476;15760.842391576081;13955.232462214573;14187.78718319226;14244.786328205058;14422.134671919692;14455.855441445583;852.9189726975943;870.447265986097;870.8737233101197;871.7559083456642;873.3842640938155;676.1885737115457;696.707382899181;837.8198687282234;858.3690987124459;860.8192279621255;912.9497877616728;916.4867674102491;918.2424499787669;918.274563095154;920.1948295241664;903.3992395057279;910.3263584947158;911.7447114807832;912.1881525442361;912.3020889019905;676.7462201674372;676.972921083156;677.4411110833532;679.3614002837317;680.6902859199053;14533.200673963034;14650.974431789778;14653.047551909092;14757.114573125647;15220.771688424682;13896.722065558733;13975.440982360693;14321.57035288943;14335.283235838271;14351.78472322916;9734.561944712434;11608;11650.475728592233;11722.94138529302;12085.879141208652;3696.5748938872107;3702.4631428442995;3742.700583953277;3773.415120656303;3826.655600995896;107864.4606776969;111176.4441177797;111678.32482512768;111998;112542.43728781385;9071.81856362869;10739.248252622398;10741.140708743282;10837.46293311041;10989.395583242998;21744.26099459573;22040.008199631055;22065.889670551544;22096.88951555264;22387.552248955257;7722.3049925506975;7726.961365193138;7825.334846537994;7939.523628582304;8054.03351597812;4310.094880075157;4357.932306584907;4362.934555981663;4387.3857659927635;4466.196084704719;3437.1407148212948;3517.1910460593695;3528.8764893228613;3538.8230588470988;3575.3564358415215;623.0965100604052;635.5773410681937;638.6870433487558;650.1905128115526;652.4780175859388;566.1620801214226;570.261511342812;595.7974288741849;656.9934300657028;658.8649326887977;7918.524888506593;8249.29880960125;8280.917190828135;9131.634734610674;9154.31342649304;625.3933684326196;639.1818472355393;640.9104522312089;657.7697805768039;671.8589096289736;4088.100617864065;4217.493900731932;4382.430284063115;4414.779261036935;4716.434027916672;100531.49202761965;102555;107422.46288768662;108813.36779948307;112432;66789.66605166942;68573.2856678587;84978.72531912026;89520.6571901422;92576;79800;79863;80480;81531;81797;8811.559422028744;8872.955635221782;8968.910310897068;9029.683961061332;9199.356045077047;50067;52939.73530132325;54306;54805.45194548003;55378.16932746093;73181;77091.2290877084;77286.6135669318;77787.61106194434;79553;11575.247608905394;11687.707807304885;11942.880571194524;11952.940235298767;11976.520939162336;12519.749605008032;12696.555620553236;12701.93649031749;13265.754080696028;13398.866011340151;11486.483108259981;11771.705707357385;11890.643280701594;12182.878171218174;12203.69490762738;9521.666741664285;11979.520819167135;12026.75946481083;12411.317377544321;12441.315727635067;11916.52333906634;11920.165588408729;11946.163768536466;11958.64124076279;11973.880261197277;10191.337563058378;10217.182625389802;10394.324368915995;10427.478626068514;10491.370517768959;10113.949430252802;10448.477576121011;10451.581936722447;10452.372857628567;10457.633982810565 08:36:07 INFO - PROCESS | 1645 | __end_tp_report 08:36:07 INFO - PROCESS | 1645 | __start_cc_report 08:36:07 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,379 08:36:07 INFO - PROCESS | 1645 | __end_cc_report 08:36:07 INFO - PROCESS | 1645 | __startTimestamp1482251767919__endTimestamp 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | ------- Summary: start ------- 08:36:07 INFO - PROCESS | 1645 | Number of tests: 6 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5671.38 Median:5944.76 stddev:707.77 (11.9%) stddev-sans-first:669.53 08:36:07 INFO - PROCESS | 1645 | Values: 2666.4 2886.3 3144.8 3404.0 3410.8 4557.8 4781.5 5656.4 5684.7 5788.3 5705.7 5710.7 5779.4 5822.9 5867.5 5123.7 5184.5 5185.6 5293.6 5302.3 5144.7 5303.0 5308.8 5337.1 5352.8 5147.8 5165.0 5268.6 5270.4 5302.3 5166.3 5182.4 5201.6 5201.7 5204.8 5181.4 5215.8 5216.6 5225.4 5243.9 5573.6 5762.6 5770.6 5789.0 5796.0 5780.6 6143.0 6182.7 6211.7 6221.6 5453.3 6129.2 6155.0 6165.3 6232.8 4920.5 5857.4 5863.5 5929.9 5950.2 4969.0 6219.9 6320.1 6324.8 6328.8 6065.7 6092.0 6123.0 6125.0 6154.2 6097.8 6136.5 6200.0 6205.3 6214.7 6100.2 6138.8 6193.1 6213.2 6235.1 5111.2 5353.8 5838.3 6099.8 6221.9 3812.5 4074.1 4835.3 4850.9 4952.9 4353.8 4365.8 4411.5 4427.8 4458.3 5979.3 5980.2 5983.3 5987.9 6008.1 6195.3 6205.9 6209.2 6212.5 6236.3 5752.0 5802.7 5830.3 5856.5 5885.0 5407.1 5412.3 5414.9 5416.7 5417.1 6206.3 6219.4 6226.6 6243.1 6245.0 5072.4 6224.3 6235.3 6251.7 6256.9 6417.0 6443.0 6446.4 6451.0 6476.3 5019.9 6060.8 6210.8 6236.7 6256.5 6038.5 6129.1 6130.1 6138.3 6224.9 4951.3 5009.5 6155.5 6168.8 6181.1 6082.4 6154.3 6162.9 6163.5 6199.9 5830.2 5874.5 5888.9 5944.8 6025.0 6140.3 6155.4 6165.1 6169.2 6176.1 5986.3 5987.0 6007.0 6017.8 6017.9 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:68142.37 Median:34492.14 stddev:150553.41 (436.5%) stddev-sans-first:150889.97 08:36:07 INFO - PROCESS | 1645 | Values: 449.7 467.8 507.7 524.3 540.4 38061.6 40019.6 40156.0 41873.8 41916.6 40999.8 41237.0 41316.4 41367.6 41415.4 8059.4 8079.4 8187.9 8324.3 9183.2 8401.6 11024.6 11263.3 11280.2 11439.8 11253.5 11271.2 11294.4 11299.2 11353.1 9858.3 9882.5 11037.2 11244.5 11267.5 9505.7 10584.9 11057.3 11121.1 11151.3 31801.7 39829.0 39912.6 39987.2 40022.4 3398.6 3400.4 3500.0 3621.1 3627.5 29807.7 30333.0 30564.1 30602.7 31255.0 3163.0 3190.3 3220.8 3307.8 3307.9 483481.0 486319.0 488201.6 488822.0 489212.0 59373.0 59920.7 59938.7 59956.0 59982.0 62251.7 62369.1 62821.7 63081.7 63162.0 15564.4 16062.7 16075.6 16084.5 16121.9 11102.6 11410.2 12187.0 12391.5 12416.9 16391.3 16655.8 16749.0 16862.6 16870.0 7368.8 7471.0 7482.9 7484.6 7566.2 841.6 862.6 954.4 964.4 1034.6 25162.9 28123.5 29787.7 29854.9 29898.7 937.5 1023.4 1144.4 1150.2 1155.7 13690.8 14706.6 14966.4 15073.5 15658.0 619801.0 624669.0 626708.0 627383.0 628239.0 51627.0 51761.7 51953.2 51960.5 51969.2 1317.2 1347.6 1367.1 1368.4 1371.9 24219.9 28248.9 28299.3 28491.0 29082.0 44257.8 45074.8 45136.8 45226.5 45260.8 565257.0 566953.0 567761.0 568492.0 568815.0 21555.9 23954.9 24100.8 24370.1 24414.4 32685.8 39749.4 40400.6 40884.4 42118.4 34381.8 35090.0 35816.5 36508.6 37438.8 27474.5 34799.7 35010.0 35724.8 35785.3 33899.5 34062.0 34116.1 34233.5 34602.5 21589.8 30455.2 31057.2 31636.5 31656.7 20459.0 20682.3 21009.4 22814.4 24333.3 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:30508.42 Median:2339.69 stddev:81469.40 (3482.1%) stddev-sans-first:81666.36 08:36:07 INFO - PROCESS | 1645 | Values: 295.6 363.1 380.6 394.3 398.4 18590.5 19137.0 19762.7 20268.8 20462.0 19916.9 19991.3 20166.1 20442.5 20465.8 5731.1 5745.3 5970.9 6000.9 6021.2 5623.7 5716.8 5919.2 5921.5 5924.2 5209.9 5239.3 5616.2 5648.6 5858.7 4963.3 5901.3 6006.0 6006.5 6030.6 5605.8 5775.3 5788.5 5842.4 5851.1 10710.0 12729.2 12949.7 12968.5 21739.9 1680.9 2229.5 2256.8 2324.1 2355.3 6922.8 8949.7 9808.4 10154.2 10273.6 1757.0 1909.4 1939.0 1942.9 1951.1 93981.5 120149.0 121518.0 123357.8 123958.0 9881.8 11004.6 11631.8 11695.0 11878.2 14373.1 14538.3 14548.5 14582.4 14600.1 8120.0 8397.7 8423.2 8434.8 8572.8 8126.5 8184.5 8187.8 8354.9 8369.5 4453.4 4516.0 4563.2 4567.2 4600.9 1543.7 1556.8 1601.4 1648.7 1927.1 898.6 927.7 949.9 976.4 1013.7 15375.5 19422.6 20681.5 20810.0 21169.3 947.6 953.6 958.9 980.5 1002.0 11009.2 11099.2 11116.4 11142.2 11176.4 396388.0 455884.0 488223.0 533602.0 533653.0 88956.0 99288.5 100334.0 120557.0 124157.0 992.9 997.2 1035.5 1043.1 1044.3 17481.8 18069.9 18712.6 18948.8 19065.2 47098.0 47925.8 50228.2 51536.0 55489.7 62539.0 64375.4 76414.0 79271.2 79653.6 8024.5 8222.6 8236.5 8250.5 8412.7 11943.8 12330.6 13103.0 13405.8 13445.9 11143.9 11367.9 11419.3 11525.0 11553.2 9825.3 10045.9 11062.5 11329.7 11589.1 11312.8 11420.8 11603.5 11622.9 11644.9 7840.3 7954.0 8104.8 8365.2 8440.0 10365.1 10415.8 10450.2 10516.3 11291.0 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5361.07 Median:5446.11 stddev:2940.50 (54.0%) stddev-sans-first:2934.12 08:36:07 INFO - PROCESS | 1645 | Values: 1457.9 1506.1 1726.8 1842.3 1872.9 7512.7 7792.9 8045.0 8555.3 8556.0 8039.4 8300.8 8323.9 8412.4 8457.9 2285.3 2330.3 2333.4 2334.2 2347.0 2053.5 2290.7 2301.3 2330.0 2353.7 1898.4 2304.8 2323.3 2325.5 2326.7 1996.5 2210.2 2284.0 2313.8 2319.0 1983.6 2294.7 2310.5 2344.9 2350.4 8302.8 8408.7 8471.5 8487.9 8500.5 5062.8 5197.7 5240.8 5244.4 5254.7 2859.1 3416.1 3501.3 3605.8 3619.8 1036.8 1050.5 1061.2 1078.3 1081.8 10596.3 10755.8 10887.3 10889.0 10927.4 4517.0 4589.0 4678.3 4694.6 4762.4 9314.0 9412.7 9428.5 9437.9 9457.6 4978.7 5086.1 5235.6 5237.2 5262.4 3108.6 3894.0 4054.9 4428.6 4436.7 3202.6 3408.9 3803.4 3851.8 3881.2 1288.4 1295.8 1297.0 1300.6 1303.5 751.0 885.5 903.8 921.7 932.6 5720.7 5774.2 5808.3 5954.3 6058.1 792.3 899.6 914.1 929.6 978.7 3350.9 3686.0 3714.6 3816.1 3873.1 8232.4 9843.0 9901.8 9905.9 9956.0 9280.7 9841.4 9876.6 9877.5 9896.2 7354.0 7741.7 7745.9 7765.9 7774.7 5385.5 5506.7 5607.2 5624.4 5706.3 7645.4 9217.6 9338.6 9362.3 9404.5 9156.8 9433.0 9449.4 9533.6 9543.6 6549.6 6734.1 7016.7 7177.3 7203.5 7573.3 7703.5 7803.3 7889.9 7891.3 6351.8 6673.2 7022.3 7029.7 7087.0 5983.3 6682.1 6842.9 6962.5 6964.9 6566.0 6600.6 6636.9 6641.7 6694.6 4669.1 4770.0 4771.8 4829.7 4841.0 4213.9 4647.4 4678.6 4766.3 4815.5 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4529.20 Median:5003.29 stddev:1413.86 (28.3%) stddev-sans-first:1382.13 08:36:07 INFO - PROCESS | 1645 | Values: 323.3 360.4 360.6 360.7 366.7 4432.4 5330.8 5484.2 5498.7 5528.6 4598.3 4628.5 4704.9 5253.8 5269.5 3254.0 4048.3 4099.9 4224.5 4265.6 3695.6 3946.5 4217.4 4251.7 4252.9 4175.2 4202.9 4209.0 4210.4 4220.8 3855.6 4224.5 4238.3 4241.0 4251.6 2913.9 2921.5 3501.9 3510.3 3513.4 4633.8 4960.8 5268.3 5334.5 5365.4 3637.3 3961.7 4148.4 4314.8 4609.6 4897.4 5386.8 5578.7 5664.5 5700.8 3281.8 3621.3 3887.0 3892.3 3900.0 5459.4 5512.4 6059.9 6083.5 6091.2 2967.5 3818.4 3938.8 3955.6 3966.5 3992.4 3997.8 4004.4 4010.5 4013.1 4682.3 4685.5 4688.0 4713.0 4719.6 5279.7 5686.4 5791.0 5829.5 5845.0 1303.5 1311.2 1313.4 1314.3 1316.8 755.3 804.6 849.8 884.7 888.3 4416.5 4972.3 5096.4 5187.2 5238.8 4570.7 4806.5 5316.2 5695.4 5722.0 4924.7 4959.5 5207.4 5219.5 5255.0 2417.4 2458.5 2584.4 2867.5 2965.9 5978.6 5988.6 5993.3 5994.5 6010.8 5081.6 5138.0 5458.5 5808.8 5952.9 5389.6 5408.0 5941.0 5954.8 6012.4 4994.5 5468.6 5721.5 5733.3 5735.2 4897.9 5069.4 5535.4 5658.9 5677.5 5513.5 5589.9 5719.8 5950.7 5968.5 3627.3 3729.8 3768.8 3777.6 3780.6 5194.4 5683.9 5908.4 5955.9 5973.4 5012.0 5312.5 5701.5 5836.4 5878.7 5593.7 5771.8 5782.3 5803.5 5823.0 4689.8 4713.0 4928.4 5032.9 5411.8 5276.6 5414.8 5786.9 5824.6 5846.8 4649.9 4756.9 4877.5 5650.5 5725.4 08:36:07 INFO - PROCESS | 1645 | 08:36:07 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20574.91 Median:4360.43 stddev:30425.65 (697.8%) stddev-sans-first:30474.26 08:36:07 INFO - PROCESS | 1645 | Values: 661.4 736.3 739.5 785.8 818.2 14746.3 14977.5 15555.7 15703.6 15760.8 13955.2 14187.8 14244.8 14422.1 14455.9 852.9 870.4 870.9 871.8 873.4 676.2 696.7 837.8 858.4 860.8 912.9 916.5 918.2 918.3 920.2 903.4 910.3 911.7 912.2 912.3 676.7 677.0 677.4 679.4 680.7 14533.2 14651.0 14653.0 14757.1 15220.8 13896.7 13975.4 14321.6 14335.3 14351.8 9734.6 11608.0 11650.5 11722.9 12085.9 3696.6 3702.5 3742.7 3773.4 3826.7 107864.5 111176.4 111678.3 111998.0 112542.4 9071.8 10739.2 10741.1 10837.5 10989.4 21744.3 22040.0 22065.9 22096.9 22387.6 7722.3 7727.0 7825.3 7939.5 8054.0 4310.1 4357.9 4362.9 4387.4 4466.2 3437.1 3517.2 3528.9 3538.8 3575.4 623.1 635.6 638.7 650.2 652.5 566.2 570.3 595.8 657.0 658.9 7918.5 8249.3 8280.9 9131.6 9154.3 625.4 639.2 640.9 657.8 671.9 4088.1 4217.5 4382.4 4414.8 4716.4 100531.5 102555.0 107422.5 108813.4 112432.0 66789.7 68573.3 84978.7 89520.7 92576.0 79800.0 79863.0 80480.0 81531.0 81797.0 8811.6 8873.0 8968.9 9029.7 9199.4 50067.0 52939.7 54306.0 54805.5 55378.2 73181.0 77091.2 77286.6 77787.6 79553.0 11575.2 11687.7 11942.9 11952.9 11976.5 12519.7 12696.6 12701.9 13265.8 13398.9 11486.5 11771.7 11890.6 12182.9 12203.7 9521.7 11979.5 12026.8 12411.3 12441.3 11916.5 11920.2 11946.2 11958.6 11973.9 10191.3 10217.2 10394.3 10427.5 10491.4 10113.9 10448.5 10451.6 10452.4 10457.6 08:36:07 INFO - PROCESS | 1645 | -------- Summary: end -------- 08:36:07 INFO - PROCESS | 1645 | 08:36:08 INFO - TEST-INFO | 1645: exit 0 08:36:08 INFO - TEST-OK | dromaeo_css | took 1115737ms 08:36:08 INFO - TEST-START | kraken 08:36:08 INFO - operating with platform_type : mac_ 08:36:08 INFO - Initialising browser for kraken test... 08:36:08 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpJTSPWV/profile http://localhost:49242/getInfo.html) 08:36:11 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:36:11 INFO - PROCESS | 1655 | colorDepth:24 08:36:11 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:36:11 INFO - PROCESS | 1655 | __metrics 08:36:11 INFO - PROCESS | 1655 | 2016-12-20 08:36:11.573 plugin-container[1656:10528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 08:36:11 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:11 INFO - PROCESS | 1655 | 2016-12-20 08:36:11.576 plugin-container[1656:10528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:36:11 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:11 INFO - PROCESS | 1655 | 2016-12-20 08:36:11.576 plugin-container[1656:10528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:11 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:36:12 INFO - TEST-INFO | 1655: exit 0 08:36:12 INFO - Browser initialized. 08:36:12 INFO - Running cycle 1/1 for kraken test... 08:36:12 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpJTSPWV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:14 INFO - PROCESS | 1657 | 2016-12-20 08:36:14.700 plugin-container[1658:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 08:36:14 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:14 INFO - PROCESS | 1657 | 2016-12-20 08:36:14.701 plugin-container[1658:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:36:14 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:14 INFO - PROCESS | 1657 | 2016-12-20 08:36:14.701 plugin-container[1658:10645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:24 INFO - PROCESS | 1657 | RSS: Main: 237010944 08:36:24 INFO - PROCESS | 1657 | 08:36:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 08:36:28 INFO - PROCESS | 1657 | RSS: Main: 249782272 08:36:28 INFO - PROCESS | 1657 | 08:36:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 08:36:31 INFO - PROCESS | 1657 | RSS: Main: 257310720 08:36:31 INFO - PROCESS | 1657 | 08:36:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 08:36:35 INFO - PROCESS | 1657 | RSS: Main: 259948544 08:36:35 INFO - PROCESS | 1657 | 08:36:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 08:36:38 INFO - PROCESS | 1657 | RSS: Main: 262209536 08:36:38 INFO - PROCESS | 1657 | 08:36:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:36:40 INFO - PROCESS | 1657 | RSS: Main: 262803456 08:36:40 INFO - PROCESS | 1657 | 08:36:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 08:36:44 INFO - PROCESS | 1657 | RSS: Main: 269086720 08:36:44 INFO - PROCESS | 1657 | 08:36:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:48 INFO - PROCESS | 1657 | RSS: Main: 267841536 08:36:48 INFO - PROCESS | 1657 | 08:36:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 08:36:52 INFO - PROCESS | 1657 | RSS: Main: 267911168 08:36:52 INFO - PROCESS | 1657 | 08:36:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:54 INFO - PROCESS | 1657 | RSS: Main: 267259904 08:36:54 INFO - PROCESS | 1657 | 08:36:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:57 INFO - PROCESS | 1657 | RSS: Main: 267251712 08:36:57 INFO - PROCESS | 1657 | 08:36:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:37:00 INFO - PROCESS | 1657 | RSS: Main: 267304960 08:37:00 INFO - PROCESS | 1657 | 08:37:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:37:03 INFO - PROCESS | 1657 | RSS: Main: 267595776 08:37:03 INFO - PROCESS | 1657 | 08:37:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:37:07 INFO - PROCESS | 1657 | RSS: Main: 267374592 08:37:07 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 08:37:09 INFO - PROCESS | 1657 | RSS: Main: 268496896 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | __start_tp_report 08:37:09 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:37:09 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:37:09 INFO - PROCESS | 1657 | |i|pagename|runs| 08:37:09 INFO - PROCESS | 1657 | |0;ai-astar;105;118;118;118;116;117;117;92;168;170 08:37:09 INFO - PROCESS | 1657 | |1;audio-beat-detection;146;142;117;146;115;116;112;115;133;112 08:37:09 INFO - PROCESS | 1657 | |2;audio-dft;199;211;277;188;273;285;199;197;191;196 08:37:09 INFO - PROCESS | 1657 | |3;audio-fft;101;78;83;88;60;59;62;59;74;60 08:37:09 INFO - PROCESS | 1657 | |4;audio-oscillator;87;71;83;73;70;86;108;69;70;84 08:37:09 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;136;115;126;109;110;139;103;171;123;119 08:37:09 INFO - PROCESS | 1657 | |6;imaging-darkroom;99;130;112;173;132;143;174;103;101;133 08:37:09 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;148;122;92;92;92;93;93;96;104 08:37:09 INFO - PROCESS | 1657 | |8;json-parse-financial;60;59;57;56;58;60;60;59;56;58 08:37:09 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;70;76;79;82;77;68;70;71;73;71 08:37:09 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;89;95;91;90;81;108;80;111;109 08:37:09 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;147;132;149;152;149;149;156;186;156 08:37:09 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;182;174;175;174;176;172;178;172;178;176 08:37:09 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;70;70;59;61;64;72;91;88;89;92 08:37:09 INFO - PROCESS | 1657 | __end_tp_report 08:37:09 INFO - PROCESS | 1657 | __start_cc_report 08:37:09 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,943 08:37:09 INFO - PROCESS | 1657 | __end_cc_report 08:37:09 INFO - PROCESS | 1657 | __startTimestamp1482251829527__endTimestamp 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:37:09 INFO - PROCESS | 1657 | Number of tests: 14 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:123.90 Median:118.00 stddev:25.18 (21.3%) stddev-sans-first:25.76 08:37:09 INFO - PROCESS | 1657 | Values: 105.0 118.0 118.0 118.0 116.0 117.0 117.0 92.0 168.0 170.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:125.00 stddev:14.59 (11.7%) stddev-sans-first:13.44 08:37:09 INFO - PROCESS | 1657 | Values: 146.0 142.0 117.0 146.0 115.0 116.0 112.0 115.0 133.0 112.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:221.60 Median:205.00 stddev:39.71 (19.4%) stddev-sans-first:41.27 08:37:09 INFO - PROCESS | 1657 | Values: 199.0 211.0 277.0 188.0 273.0 285.0 199.0 197.0 191.0 196.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:72.40 Median:68.00 stddev:14.84 (21.8%) stddev-sans-first:11.58 08:37:09 INFO - PROCESS | 1657 | Values: 101.0 78.0 83.0 88.0 60.0 59.0 62.0 59.0 74.0 60.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:78.00 stddev:12.22 (15.7%) stddev-sans-first:12.71 08:37:09 INFO - PROCESS | 1657 | Values: 87.0 71.0 83.0 73.0 70.0 86.0 108.0 69.0 70.0 84.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:125.10 Median:124.50 stddev:19.83 (15.9%) stddev-sans-first:20.64 08:37:09 INFO - PROCESS | 1657 | Values: 136.0 115.0 126.0 109.0 110.0 139.0 103.0 171.0 123.0 119.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:130.00 Median:138.00 stddev:27.53 (20.0%) stddev-sans-first:26.82 08:37:09 INFO - PROCESS | 1657 | Values: 99.0 130.0 112.0 173.0 132.0 143.0 174.0 103.0 101.0 133.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:92.00 stddev:19.27 (20.9%) stddev-sans-first:19.35 08:37:09 INFO - PROCESS | 1657 | Values: 84.0 148.0 122.0 92.0 92.0 92.0 93.0 93.0 96.0 104.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.00 stddev:1.57 (2.7%) stddev-sans-first:1.54 08:37:09 INFO - PROCESS | 1657 | Values: 60.0 59.0 57.0 56.0 58.0 60.0 60.0 59.0 56.0 58.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:74.50 stddev:4.57 (6.1%) stddev-sans-first:4.65 08:37:09 INFO - PROCESS | 1657 | Values: 70.0 76.0 79.0 82.0 77.0 68.0 70.0 71.0 73.0 71.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:89.00 stddev:11.30 (12.7%) stddev-sans-first:11.83 08:37:09 INFO - PROCESS | 1657 | Values: 89.0 89.0 95.0 91.0 90.0 81.0 108.0 80.0 111.0 109.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:150.50 stddev:14.38 (9.6%) stddev-sans-first:14.29 08:37:09 INFO - PROCESS | 1657 | Values: 137.0 147.0 132.0 149.0 152.0 149.0 149.0 156.0 186.0 156.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:176.00 stddev:3.06 (1.7%) stddev-sans-first:2.24 08:37:09 INFO - PROCESS | 1657 | Values: 182.0 174.0 175.0 174.0 176.0 172.0 178.0 172.0 178.0 176.0 08:37:09 INFO - PROCESS | 1657 | 08:37:09 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:80.00 stddev:13.07 (16.3%) stddev-sans-first:13.71 08:37:09 INFO - PROCESS | 1657 | Values: 70.0 70.0 59.0 61.0 64.0 72.0 91.0 88.0 89.0 92.0 08:37:09 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:37:09 INFO - PROCESS | 1657 | 08:37:10 INFO - TEST-INFO | 1657: exit 0 08:37:10 INFO - TEST-OK | kraken | took 61736ms 08:37:10 INFO - SUITE-END | took 1177s 08:37:10 INFO - Completed test suite (00:19:37) 08:37:10 INFO - javascript benchmark 08:37:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7109.651269060443, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.440047590006, 2886.2928582497293, 3144.827034513102, 3403.9829800851007, 3410.8465119069647, 4557.77211139443, 4781.497942716017, 5656.377798442168, 5684.687342196173, 5788.27646544182, 5705.714714264281, 5710.7430165642545, 5779.364269930315, 5822.883542329151, 5867.53311672082, 5123.666961647499, 5184.481551844804, 5185.611079169059, 5293.629445938766, 5302.257683924254, 5144.717040562768, 5302.973485132569, 5308.761105750251, 5337.06601344765, 5352.812651557197, 5147.819826306061, 5165.0, 5268.604854635918, 5270.420253772082, 5302.257683924254, 5166.250893620404, 5182.4299327073995, 5201.55786758126, 5201.713905735183, 5204.791808327663, 5181.35233095863, 5215.843524694227, 5216.60875434344, 5225.372955245357, 5243.947560524384, 5573.554115670737, 5762.567807414419, 5770.596058275923, 5788.986927287708, 5795.971020144872, 5780.624259423124, 6143.0, 6182.659953702545, 6211.68941552925, 6221.564490485668, 5453.291072160633, 6129.23653806838, 6154.969225153845, 6165.260168779775, 6232.813015609494, 4920.5325494078725, 5857.414258574151, 5863.501602363847, 5929.851753706149, 5950.166976623276, 4968.975155124202, 6219.937800622027, 6320.115183874169, 6324.810255692337, 6328.810135695937, 6065.727042283108, 6092.025275955826, 6123.020316749299, 6125.0, 6154.1999540059505, 6097.82716539334, 6136.519078024463, 6200.0, 6205.286392064971, 6214.658193799294, 6100.206973093469, 6138.848241798255, 6193.102000209944, 6213.192285003011, 6235.127082208494, 5111.156659151198, 5353.848922481685, 5838.328592211865, 6099.8475038123515, 6221.9377806221355, 3812.542494900574, 4074.1036971866733, 4835.347228124158, 4850.902981940341, 4952.876178095505, 4353.82584696615, 4365.80353884076, 4411.514733379326, 4427.8228870844805, 4458.331250312479, 5979.252593425822, 5980.192673989043, 5983.311919129269, 5987.940120598738, 6008.098785182257, 6195.31851496335, 6205.937940620626, 6209.19280493542, 6212.47193988507, 6236.314005459396, 5751.971240143773, 5802.65184088956, 5830.300363956268, 5856.472917437451, 5884.999550076412, 5407.053765591085, 5412.296401467863, 5414.864628384243, 5416.7020813855615, 5417.052015897202, 6206.255249370104, 6219.40915613016, 6226.626402415779, 6243.063540468966, 6244.968775156095, 5072.416672082758, 6224.346443623535, 6235.314115447388, 6251.6561589113035, 6256.937430625636, 6417.0, 6443.0, 6446.355364463516, 6450.967745161432, 6476.31998640136, 5019.924701129559, 6060.818175454743, 6210.813675589739, 6236.656983865931, 6256.468200203034, 6038.486728628116, 6129.114290130661, 6130.061839217998, 6138.263408391022, 6224.9377506224355, 4951.282064100757, 5009.499050094962, 6155.507559395327, 6168.814935551941, 6181.134641150153, 6082.391760824059, 6154.292256390393, 6162.876742465215, 6163.476104531165, 6199.938000619936, 5830.212921255746, 5874.530037597067, 5888.882222355614, 5944.762209511571, 6024.969875150597, 6140.293866205444, 6155.353687862889, 6165.136880836776, 6169.198004259597, 6176.135341052166, 5986.281646202484, 5986.970065149646, 6006.97881360161, 6017.849553761191, 6017.939820601738], "value": 5624.187553156866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.74814104101705, 467.7848189832676, 507.6547947395772, 524.2869697211794, 540.4325458268817, 38061.61938380615, 40019.59980400195, 40156.0, 41873.7906310468, 41916.58083419165, 40999.79500102503, 41237.0, 41316.38025429621, 41367.58632413675, 41415.378769318486, 8059.435839491238, 8079.353651707878, 8187.853700481937, 8324.334053275723, 9183.173514383705, 8401.579921003957, 11024.614138505154, 11263.26788758732, 11280.210385273034, 11439.771204575904, 11253.54985800567, 11271.211015228937, 11294.378809165493, 11299.152563557725, 11353.091752659768, 9858.260630452673, 9882.505874706272, 11037.172212084086, 11244.49399777012, 11267.549298028069, 9505.667301644478, 10584.947075264652, 11057.336559806357, 11121.05471034963, 11151.330920144817, 31801.681983180104, 39829.0, 39912.60087399118, 39987.200255995005, 40022.39966400506, 3398.5921689397433, 3400.4219282721997, 3499.982500087509, 3621.130928577149, 3627.474016267626, 29807.70192298071, 30333.0, 30564.083077507712, 30602.693973060206, 31255.0, 3162.952555711643, 3190.2502911815704, 3220.7745457817964, 3307.7684562080663, 3307.8842240521467, 483481.0, 486319.0, 488201.5589922063, 488821.99999999645, 489212.0, 59373.0, 59920.70039649774, 59938.70030649819, 59956.0, 59982.0, 62251.68874155691, 62369.06446403308, 62821.685891571164, 63081.738365232435, 63162.0, 15564.377424903103, 16062.67874642524, 16075.598110047109, 16084.517464476085, 16121.919390402973, 11102.61140860066, 11410.201285910072, 12186.964108050737, 12391.504339826486, 12416.937915310546, 16391.262393192097, 16655.750163747554, 16748.99506029642, 16862.57843553921, 16870.0, 7368.815779605446, 7470.962645186739, 7482.850342993219, 7484.625768711543, 7566.167721550625, 841.5707988925569, 862.5773371048141, 954.3796532253951, 964.4105576527047, 1034.6016783538391, 25162.874185628956, 28123.468887799787, 29787.74397459534, 29854.850725746233, 29898.701012990026, 937.4937533731709, 1023.3706270643501, 1144.404909447083, 1150.206357613244, 1155.6590805712335, 13690.794638080437, 14706.63233419173, 14966.401343946338, 15073.472428465171, 15657.982231154938, 619801.0, 624669.0, 626708.0, 627383.0, 628239.0, 51627.0, 51761.74119129455, 51953.220701689504, 51960.48039519632, 51969.22046169235, 1317.2294207888492, 1347.5889853594551, 1367.118208755367, 1368.4321006782272, 1371.91082579632, 24219.878900605385, 28248.85875570609, 28299.292517687223, 28491.0, 29082.0, 44257.77871110752, 45074.77462612666, 45136.77431612821, 45226.54773452223, 45260.773696131306, 565257.0, 566953.0, 567761.0, 568492.0, 568815.0, 21555.892220538797, 23954.88022559876, 24100.794960252268, 24370.147044853344, 24414.389640258425, 32685.836570816995, 39749.40375894306, 40400.595994039686, 40884.38673419961, 42118.36822447727, 34381.79663711758, 35090.0, 35816.46275305925, 36508.63491365052, 37438.8128059358, 27474.450510990067, 34799.65200347964, 35010.0, 35724.821375892956, 35785.284294314486, 33899.49150762691, 34061.97814065483, 34116.14709632279, 34233.48649770305, 34602.48096278508, 21589.784102158777, 30455.238619033815, 31057.22356941004, 31636.525452118698, 31656.683433165374, 20458.987548050824, 20682.276120335715, 21009.369718908456, 22814.42963925915, 24333.27000189997], "value": 19269.175096114162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.5537138920231, 363.0868366059361, 380.5927657406575, 394.31389382474504, 398.39841838823367, 18590.535236619075, 19137.0, 19762.70355944662, 20268.797312026876, 20462.0, 19916.904570248666, 19991.300304489345, 20166.092525836335, 20442.48893777656, 20465.795342046575, 5731.083026715726, 5745.253117094782, 5970.8805823883495, 6000.909986350208, 6021.217241758587, 5623.662580245178, 5716.828495145152, 5919.23050003499, 5921.496672782819, 5924.170616113747, 5209.86975325618, 5239.292695486118, 5616.241807356016, 5648.616089058187, 5858.73635686393, 4963.329950456714, 5901.314901719728, 6006.00900851359, 6006.468350570598, 6030.638161710312, 5605.803796867131, 5775.306963164405, 5788.536917046668, 5842.415758424166, 5851.122331650244, 10710.0, 12729.172603780817, 12949.676258093528, 12968.546100886519, 21739.891300543553, 1680.8990111477003, 2229.4649284171846, 2256.751757306695, 2324.1052194904914, 2355.281639100074, 6922.757703480354, 8949.731508054769, 9808.362456440309, 10154.238432117621, 10273.640422585173, 1757.0, 1909.4367161687335, 1939.0, 1942.8639995200342, 1951.1317463728647, 93981.53009234979, 120149.0, 121518.0, 123357.76642233643, 123958.0, 9881.814182298027, 11004.559817607205, 11631.825522617168, 11695.0, 11878.227915185485, 14373.137611743328, 14538.27308634564, 14548.490802822062, 14582.41670333196, 14600.050996685182, 8119.959400202961, 8397.70608028716, 8423.157684231528, 8434.831303374021, 8572.828543429096, 8126.512409255463, 8184.508929464251, 8187.795305117301, 8354.874676879852, 8369.49783013021, 4453.376527286183, 4516.0, 4563.178627847019, 4567.200739870509, 4600.907981840412, 1543.729847276722, 1556.7976163098724, 1601.4154833485693, 1648.7279598866292, 1927.094265695121, 898.613977010123, 927.7170463008847, 949.852772820214, 976.3799987008159, 1013.7414959185508, 15375.538733838002, 19422.611547769247, 20681.482962926046, 20810.0, 21169.25907593227, 947.6351604632266, 953.6376177052675, 958.8561715742555, 980.4705459052032, 1001.9549120289604, 11009.174311926477, 11099.223054386115, 11116.38859862699, 11142.220044596963, 11176.441177941071, 396388.01805991365, 455884.0, 488223.0, 533602.0, 533653.0, 88956.0, 99288.51067233997, 100333.96660333908, 120557.0, 124157.0, 992.947475675786, 997.152420442618, 1035.53400969564, 1043.1029314789275, 1044.3211912256943, 17481.77627566058, 18069.909650451664, 18712.625747485246, 18948.810511895255, 19065.23739050478, 47098.0, 47925.76037119792, 50228.24201152942, 51536.0, 55489.722551386985, 62539.0, 64375.35624643694, 76414.0, 79271.20728792869, 79653.60173199097, 8024.518528888286, 8222.588870556569, 8236.546989915612, 8250.54621995772, 8412.747617571482, 11943.76112477763, 12330.63008109758, 13103.0, 13405.798913016117, 13445.865541344852, 11143.888561114609, 11367.943160284476, 11419.257748246337, 11525.0, 11553.24903881245, 9825.312228143961, 10045.94517575644, 11062.502187401426, 11329.660110196708, 11589.072874169877, 11312.773744524899, 11420.772949274811, 11603.477843496894, 11622.94188529052, 11644.941775291069, 7840.255175758294, 7953.960230198812, 8104.79738006531, 8365.163483651586, 8440.0, 10365.067143956778, 10415.843762343722, 10450.163986881184, 10516.316439431412, 11291.0], "value": 9175.298880928336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.8979471436999, 1506.1189204315474, 1726.8100508944021, 1842.309134074722, 1872.8501719862413, 7512.699492020312, 7792.883106753403, 8044.959775201123, 8555.272801811845, 8556.016058153307, 8039.356851451885, 8300.75097747067, 8323.875141872863, 8412.36907231957, 8457.91542084579, 2285.314405678298, 2330.2892617751577, 2333.428310064037, 2334.1947028275217, 2347.049444974788, 2053.5482193917314, 2290.6564015397657, 2301.2520930697506, 2329.9650505242435, 2353.670486131943, 1898.3830255167056, 2304.847576211894, 2323.256557901472, 2325.465143017107, 2326.7324257710325, 1996.5008747813047, 2210.2153735424013, 2283.9885800571055, 2313.8148948084113, 2318.953620927589, 1983.6032793441225, 2294.7475777664445, 2310.4916918277954, 2344.941376465602, 2350.365401341628, 8302.8339433211, 8408.663653453854, 8471.534065626389, 8487.915120848837, 8500.489970601784, 5062.797488100508, 5197.674592978804, 5240.764165612557, 5244.400457860351, 5254.710990895499, 2859.127965970378, 3416.145963509123, 3501.2997400519744, 3605.7560141751314, 3619.819009049537, 1036.7822757220993, 1050.5220124843179, 1061.241212533035, 1078.2829418436809, 1081.7511972246348, 10596.258261921668, 10755.83866242007, 10887.346759194474, 10889.0, 10927.398993055378, 4516.977415112903, 4588.95411045892, 4678.251479763289, 4694.554017368345, 4762.357081793983, 9314.0, 9412.717618471457, 9428.528573571293, 9437.858432123523, 9457.574409151604, 4978.651494395358, 5086.135356989246, 5235.633505654568, 5237.240600112962, 5262.42113367537, 3108.6269647641975, 3893.9875632335797, 4054.9254447571416, 4428.55714428561, 4436.735530373828, 3202.551642770014, 3408.9488657670154, 3803.353429916921, 3851.768893866377, 3881.2237552489614, 1288.407332627002, 1295.8315418995471, 1296.9948290075267, 1300.5643109558405, 1303.4590644882458, 750.9849803003909, 885.4598694796169, 903.7722015232353, 921.7465197070859, 932.5710173320213, 5720.742566584514, 5774.162746401748, 5808.332041815245, 5954.345022047572, 6058.12157237207, 792.3265224559078, 899.5771987166022, 914.1133100892124, 929.5823868600438, 978.6868202175235, 3350.877456052201, 3685.981570092186, 3714.60996595359, 3816.122291872885, 3873.0704630888386, 8232.382571307056, 9843.0, 9901.752456188653, 9905.851412228822, 9956.0, 9280.72157835266, 9841.360311579725, 9876.60493580263, 9877.456739879231, 9896.208303335716, 7354.0, 7741.651625676858, 7745.922540774633, 7765.922340776633, 7774.6890124394395, 5385.515303622693, 5506.697131657797, 5607.158926161109, 5624.437556244343, 5706.343770466449, 7645.426593005435, 9217.585208665492, 9338.579763910777, 9362.29782766309, 9404.482753448627, 9156.816863662823, 9433.009533998831, 9449.43303401798, 9533.570989305359, 9543.618255269712, 6549.574277672126, 6734.057231987624, 7016.684249208696, 7177.282271772781, 7203.459740519377, 7573.280538349069, 7703.499272547267, 7803.336716379172, 7889.921100789148, 7891.289783919475, 6351.824912391223, 6673.165854268216, 7022.297770222936, 7029.683664235019, 7087.007818905255, 5983.311919129182, 6682.064510968566, 6842.93157068423, 6962.512624116263, 6964.860702785814, 6565.967170164118, 6600.636964966973, 6636.900446493403, 6641.734330626914, 6694.564853284521, 4669.112868554964, 4770.022145460103, 4771.785269662804, 4829.710217386968, 4841.0, 4213.852515161954, 4647.395838540968, 4678.625709943129, 4766.261229509293, 4815.542523460265], "value": 4355.946992230111, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2597352063774, 360.38014614862436, 360.6375592529507, 360.7402670077545, 366.734765060541, 4432.445944256968, 5330.7867685292595, 5484.204790305408, 5498.725063746807, 5528.557715382764, 4598.264277715566, 4628.4677262114865, 4704.905901881961, 5253.763580638871, 5269.525742683158, 3253.967460325402, 4048.271311163989, 4099.89750256243, 4224.471941007382, 4265.552117027699, 3695.6119607441233, 3946.5461471930666, 4217.40956266123, 4251.744895306276, 4252.872413827575, 4175.185838761443, 4202.928253295406, 4208.97895510522, 4210.410542524049, 4220.831166753326, 3855.61443855615, 4224.535301116859, 4238.321868501041, 4241.045764702948, 4251.574842515754, 2913.8688759005895, 2921.547160190174, 3501.91245218869, 3510.2628448025944, 3513.402721537345, 4633.814647414099, 4960.801567937278, 5268.262443257947, 5334.51989320963, 5365.382980957162, 3637.3089113068695, 3961.7028722845903, 4148.419221309019, 4314.80583373749, 4609.631229501631, 4897.363342765418, 5386.811461598825, 5578.665280083167, 5664.54683625309, 5700.7719691212305, 3281.753868459851, 3621.311950729353, 3886.980565097185, 3892.3188442022533, 3900.03597266077, 5459.372172200208, 5512.401403592953, 6059.939400606026, 6083.543734219907, 6091.177691011701, 2967.540031295153, 3818.4081467372603, 3938.763674179501, 3955.6044395560216, 3966.464527288837, 3992.3612222044335, 3997.8200980955926, 4004.439378487014, 4010.5387880393546, 4013.1371755072214, 4682.274247491644, 4685.461171965268, 4687.95312046882, 4712.976435117803, 4719.59883409914, 5279.736013199325, 5686.43135686428, 5791.0, 5829.5044921181325, 5845.0, 1303.4590644882267, 1311.1608570514877, 1313.4220942785335, 1314.3296918571407, 1316.782730849399, 755.3277582951177, 804.6097642643314, 849.8257857139272, 884.6594061286451, 888.2627419242014, 4416.53626369233, 4972.303877457159, 5096.439391666913, 5187.170052791611, 5238.816641417532, 4570.748608826547, 4806.49531799163, 5316.175992721134, 5695.373508914016, 5721.997300944636, 4924.704517728947, 4959.536936603638, 5207.401148867929, 5219.478052194826, 5254.97372513135, 2417.4077351048754, 2458.483718419152, 2584.405586715066, 2867.483852906497, 2965.8517074146207, 5978.55160862937, 5988.640681559121, 5993.280806303184, 5994.520438364921, 6010.759569617254, 5081.59347252219, 5138.0, 5458.5087342139395, 5808.82573522795, 5952.940470595238, 5389.595780316492, 5407.972960135174, 5940.970295148497, 5954.821355359346, 6012.384612693492, 4994.525520075587, 5468.64453810517, 5721.539737912737, 5733.3406658234835, 5735.197072409783, 4897.877553061202, 5069.442361340176, 5535.367066715409, 5658.9151162733415, 5677.489025987516, 5513.531349835149, 5589.944100559105, 5719.7702493964, 5950.672713000827, 5968.522518198447, 3627.336390245818, 3729.776213427203, 3768.773873567595, 3777.6222377762006, 3780.5652349980105, 5194.428612852659, 5683.857903552444, 5908.438698323653, 5955.940440595712, 5973.432523910222, 5012.04771093491, 5312.495312945264, 5701.458361455655, 5836.445537673914, 5878.676672782867, 5593.692346920959, 5771.797987070432, 5782.306123265235, 5803.535717142534, 5823.0, 4689.835855745169, 4712.986707857827, 4928.359313289249, 5032.874178145576, 5411.83764487066, 5276.630635855607, 5414.810481633124, 5786.884262314814, 5824.621399609012, 5846.801405711733, 4649.930251046169, 4756.929690819538, 4877.5366340197625, 5650.547956163579, 5725.398833122462], "value": 4088.303048421736, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.3584822721959, 736.2527035059412, 739.4528049243561, 785.77605382466, 818.2390376949438, 14746.262686865653, 14977.47578834741, 15555.688886222282, 15703.60740981476, 15760.842391576081, 13955.232462214573, 14187.78718319226, 14244.786328205058, 14422.134671919692, 14455.855441445583, 852.9189726975943, 870.447265986097, 870.8737233101197, 871.7559083456642, 873.3842640938155, 676.1885737115457, 696.707382899181, 837.8198687282234, 858.3690987124459, 860.8192279621255, 912.9497877616728, 916.4867674102491, 918.2424499787669, 918.274563095154, 920.1948295241664, 903.3992395057279, 910.3263584947158, 911.7447114807832, 912.1881525442361, 912.3020889019905, 676.7462201674372, 676.972921083156, 677.4411110833532, 679.3614002837317, 680.6902859199053, 14533.200673963034, 14650.974431789778, 14653.047551909092, 14757.114573125647, 15220.771688424682, 13896.722065558733, 13975.440982360693, 14321.57035288943, 14335.283235838271, 14351.78472322916, 9734.561944712434, 11608.0, 11650.475728592233, 11722.94138529302, 12085.879141208652, 3696.5748938872107, 3702.4631428442995, 3742.700583953277, 3773.415120656303, 3826.655600995896, 107864.4606776969, 111176.4441177797, 111678.32482512768, 111998.0, 112542.43728781385, 9071.81856362869, 10739.248252622398, 10741.140708743282, 10837.46293311041, 10989.395583242998, 21744.26099459573, 22040.008199631055, 22065.889670551544, 22096.88951555264, 22387.552248955257, 7722.3049925506975, 7726.961365193138, 7825.334846537994, 7939.523628582304, 8054.03351597812, 4310.094880075157, 4357.932306584907, 4362.934555981663, 4387.3857659927635, 4466.196084704719, 3437.1407148212948, 3517.1910460593695, 3528.8764893228613, 3538.8230588470988, 3575.3564358415215, 623.0965100604052, 635.5773410681937, 638.6870433487558, 650.1905128115526, 652.4780175859388, 566.1620801214226, 570.261511342812, 595.7974288741849, 656.9934300657028, 658.8649326887977, 7918.524888506593, 8249.29880960125, 8280.917190828135, 9131.634734610674, 9154.31342649304, 625.3933684326196, 639.1818472355393, 640.9104522312089, 657.7697805768039, 671.8589096289736, 4088.100617864065, 4217.493900731932, 4382.430284063115, 4414.779261036935, 4716.434027916672, 100531.49202761965, 102555.0, 107422.46288768662, 108813.36779948307, 112432.0, 66789.66605166942, 68573.2856678587, 84978.72531912026, 89520.6571901422, 92576.0, 79800.0, 79863.0, 80480.0, 81531.0, 81797.0, 8811.559422028744, 8872.955635221782, 8968.910310897068, 9029.683961061332, 9199.356045077047, 50067.0, 52939.73530132325, 54306.0, 54805.45194548003, 55378.16932746093, 73181.0, 77091.2290877084, 77286.6135669318, 77787.61106194434, 79553.0, 11575.247608905394, 11687.707807304885, 11942.880571194524, 11952.940235298767, 11976.520939162336, 12519.749605008032, 12696.555620553236, 12701.93649031749, 13265.754080696028, 13398.866011340151, 11486.483108259981, 11771.705707357385, 11890.643280701594, 12182.878171218174, 12203.69490762738, 9521.666741664285, 11979.520819167135, 12026.75946481083, 12411.317377544321, 12441.315727635067, 11916.52333906634, 11920.165588408729, 11946.163768536466, 11958.64124076279, 11973.880261197277, 10191.337563058378, 10217.182625389802, 10394.324368915995, 10427.478626068514, 10491.370517768959, 10113.949430252802, 10448.477576121011, 10451.581936722447, 10452.372857628567, 10457.633982810565], "value": 7292.4748240603185, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 118.0, 118.0, 116.0, 117.0, 117.0, 92.0, 168.0, 170.0], "value": 123.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 142.0, 117.0, 146.0, 115.0, 116.0, 112.0, 115.0, 133.0, 112.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 211.0, 277.0, 188.0, 273.0, 285.0, 199.0, 197.0, 191.0, 196.0], "value": 221.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 78.0, 83.0, 88.0, 60.0, 59.0, 62.0, 59.0, 74.0, 60.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 83.0, 73.0, 70.0, 86.0, 108.0, 69.0, 70.0, 84.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 115.0, 126.0, 109.0, 110.0, 139.0, 103.0, 171.0, 123.0, 119.0], "value": 125.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 130.0, 112.0, 173.0, 132.0, 143.0, 174.0, 103.0, 101.0, 133.0], "value": 130.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 148.0, 122.0, 92.0, 92.0, 92.0, 93.0, 93.0, 96.0, 104.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 57.0, 56.0, 58.0, 60.0, 60.0, 59.0, 56.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 79.0, 82.0, 77.0, 68.0, 70.0, 71.0, 73.0, 71.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 95.0, 91.0, 90.0, 81.0, 108.0, 80.0, 111.0, 109.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 147.0, 132.0, 149.0, 152.0, 149.0, 149.0, 156.0, 186.0, 156.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 175.0, 174.0, 176.0, 172.0, 178.0, 172.0, 178.0, 176.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 59.0, 61.0, 64.0, 72.0, 91.0, 88.0, 89.0, 92.0], "value": 75.6, "unit": "score"}]}]} 08:37:10 INFO - Return code: 0 08:37:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:37:10 ERROR - # TBPL SUCCESS # 08:37:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:37:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:37:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:37:10 INFO - 2016-12-20 08:37:10.819 screenresolution[1662:11035] starting screenresolution argv=screenresolution get 08:37:10 INFO - 2016-12-20 08:37:10.822 screenresolution[1662:11035] Display 0: 1600x1200x32@60 08:37:10 INFO - 2016-12-20 08:37:10.828 screenresolution[1663:11038] starting screenresolution argv=screenresolution list 08:37:10 INFO - Available Modes on Display 0 08:37:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:37:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:37:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:37:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:37:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:37:11 INFO - Intel Iris: 08:37:11 INFO - Chipset Model: Intel Iris 08:37:11 INFO - Type: GPU 08:37:11 INFO - Bus: Built-In 08:37:11 INFO - VRAM (Dynamic, Max): 1536 MB 08:37:11 INFO - Vendor: Intel (0x8086) 08:37:11 INFO - Device ID: 0x0a2e 08:37:11 INFO - Revision ID: 0x0009 08:37:11 INFO - Displays: 08:37:11 INFO - Display: 08:37:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:37:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:37:11 INFO - Display Serial Number: 1600x1200 60 08:37:11 INFO - Main Display: Yes 08:37:11 INFO - Mirror: Off 08:37:11 INFO - Online: Yes 08:37:11 INFO - Rotation: Supported 08:37:11 INFO - Return code: 0 08:37:11 INFO - Running post-action listener: _resource_record_post_action 08:37:11 INFO - [mozharness: 2016-12-20 16:37:11.065785Z] Finished run-tests step (success) 08:37:11 INFO - Running post-run listener: _resource_record_post_run 08:37:11 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38763008; Write bytes: 553528832; Read time: 242; Write time: 1718 08:37:11 INFO - TinderboxPrint: CPU usage
54.8% 08:37:11 INFO - TinderboxPrint: I/O read bytes / time
38,763,008 / 242 08:37:11 INFO - TinderboxPrint: I/O write bytes / time
553,528,832 / 1,718 08:37:11 INFO - TinderboxPrint: CPU idle
2,171.3 (45.2%) 08:37:11 INFO - TinderboxPrint: CPU system
973.9 (20.3%) 08:37:11 INFO - TinderboxPrint: CPU user
1,656.5 (34.5%) 08:37:11 INFO - TinderboxPrint: Swap in / out
288,382,976 / 0 08:37:11 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 234101248; Write bytes: 222602752; Read time: 16476; Write time: 499 08:37:11 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32494080; Write bytes: 301717504; Read time: 188; Write time: 1153 08:37:11 INFO - Running post-run listener: _upload_blobber_files 08:37:11 INFO - Blob upload gear active. 08:37:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:37:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:37:11 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'] 08:37:11 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 08:37:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:37:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:37:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:13 INFO - (blobuploader) - INFO - Done attempting. 08:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:14 INFO - (blobuploader) - INFO - Done attempting. 08:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:15 INFO - (blobuploader) - INFO - Done attempting. 08:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:17 INFO - (blobuploader) - INFO - Done attempting. 08:37:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:37:17 INFO - Return code: 0 08:37:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:37:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:37:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b797bc3c78440c8c216a9a94991a5d9c1842cea341198b51388c51909fc1f67fac2ef38a2878d0c39971e7850c6833f3a1426e2f9725974ec8b92cdf2c765a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0b66de7029160ef03cca54cbd5d9fc1fb58147b509a52c5c260f06f1ca8f15ac5e3c87070e826996b72ce26b4fe53737984d4fce209401e36eacbfda8022f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b977a926526254a46eb953a4a988866eac2c743c22f0677e8b859b4138940b938aefb7d4f6b460f069a680d7598908d05d69deb9576885086877fd533bb603ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fe5fb12042997412f00efa150865f9f09ef735bff89651f815de88d797654b953113aad2ac0906c0a7859a838a47bd9af6455ab4726d7e7f35a61a54480090"} 08:37:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:37:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:37:17 INFO - Contents: 08:37:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b797bc3c78440c8c216a9a94991a5d9c1842cea341198b51388c51909fc1f67fac2ef38a2878d0c39971e7850c6833f3a1426e2f9725974ec8b92cdf2c765a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0b66de7029160ef03cca54cbd5d9fc1fb58147b509a52c5c260f06f1ca8f15ac5e3c87070e826996b72ce26b4fe53737984d4fce209401e36eacbfda8022f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b977a926526254a46eb953a4a988866eac2c743c22f0677e8b859b4138940b938aefb7d4f6b460f069a680d7598908d05d69deb9576885086877fd533bb603ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fe5fb12042997412f00efa150865f9f09ef735bff89651f815de88d797654b953113aad2ac0906c0a7859a838a47bd9af6455ab4726d7e7f35a61a54480090"} 08:37:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:37:17 INFO - Copying logs to upload dir... 08:37:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:37:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.216028 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-20 08:37:17.563894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 08:37:17.567361) ========= 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.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/b797bc3c78440c8c216a9a94991a5d9c1842cea341198b51388c51909fc1f67fac2ef38a2878d0c39971e7850c6833f3a1426e2f9725974ec8b92cdf2c765a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0b66de7029160ef03cca54cbd5d9fc1fb58147b509a52c5c260f06f1ca8f15ac5e3c87070e826996b72ce26b4fe53737984d4fce209401e36eacbfda8022f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b977a926526254a46eb953a4a988866eac2c743c22f0677e8b859b4138940b938aefb7d4f6b460f069a680d7598908d05d69deb9576885086877fd533bb603ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fe5fb12042997412f00efa150865f9f09ef735bff89651f815de88d797654b953113aad2ac0906c0a7859a838a47bd9af6455ab4726d7e7f35a61a54480090"} build_url:https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010874 build_url: 'https://queue.taskcluster.net/v1/task/FwouraAcT3K2in7LyJk59A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b797bc3c78440c8c216a9a94991a5d9c1842cea341198b51388c51909fc1f67fac2ef38a2878d0c39971e7850c6833f3a1426e2f9725974ec8b92cdf2c765a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0b66de7029160ef03cca54cbd5d9fc1fb58147b509a52c5c260f06f1ca8f15ac5e3c87070e826996b72ce26b4fe53737984d4fce209401e36eacbfda8022f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b977a926526254a46eb953a4a988866eac2c743c22f0677e8b859b4138940b938aefb7d4f6b460f069a680d7598908d05d69deb9576885086877fd533bb603ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fe5fb12042997412f00efa150865f9f09ef735bff89651f815de88d797654b953113aad2ac0906c0a7859a838a47bd9af6455ab4726d7e7f35a61a54480090"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 08:37:17.600777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:37:17.601191) ========= 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.FWlCDfdFv0/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.H7y2OTumjg/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.005112 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 08:37:17.650165) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 08:37:17.650486) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 08:37:17.650859) ========= ========= Total master_lag: 0.18 =========