builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0168 starttime: 1481486930.77 results: success (0) buildid: 20161211113350 builduid: c391ad0505ea48538de1d9722a3a8c2c revision: 272a12b8d16a4a1fcf16233a159b821798e57c94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:50.767130) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:50.767691) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:50.768008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:50.794827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:50.795261) ========= 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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-11 12:08:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-12-11 12:08:51 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.507864 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:51.327773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:51.328132) ========= 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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.027231 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:51.378913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 12:08:51.379301) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 272a12b8d16a4a1fcf16233a159b821798e57c94 --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 272a12b8d16a4a1fcf16233a159b821798e57c94 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-11 12:08:51,471 truncating revision to first 12 chars 2016-12-11 12:08:51,471 Setting DEBUG logging. 2016-12-11 12:08:51,471 attempt 1/10 2016-12-11 12:08:51,471 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/272a12b8d16a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-11 12:08:52,652 unpacking tar archive at: mozilla-central-272a12b8d16a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.435894 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 12:08:52.840144) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:52.840463) ========= script_repo_revision: 272a12b8d16a4a1fcf16233a159b821798e57c94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:52.840849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:52.841137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 12:08:52.860652) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-11 12:08:52.860932) ========= /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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:08:53 INFO - MultiFileLogger online at 20161211 12:08:53 in /builds/slave/test 12:08:53 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 12:08:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:53 INFO - {'append_to_log': False, 12:08:53 INFO - 'base_work_dir': '/builds/slave/test', 12:08:53 INFO - 'blob_upload_branch': 'Firefox', 12:08:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:53 INFO - 'branch': 'Firefox', 12:08:53 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:53 INFO - 'config_files': ('talos/mac_config.py',), 12:08:53 INFO - 'default_actions': ('clobber', 12:08:53 INFO - 'read-buildbot-config', 12:08:53 INFO - 'download-and-extract', 12:08:53 INFO - 'populate-webroot', 12:08:53 INFO - 'create-virtualenv', 12:08:53 INFO - 'install', 12:08:53 INFO - 'run-tests'), 12:08:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:53 INFO - 'download_minidump_stackwalk': True, 12:08:53 INFO - 'download_symbols': 'ondemand', 12:08:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:53 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:53 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:53 INFO - 'installer_path': 'installer.exe', 12:08:53 INFO - 'log_level': 'info', 12:08:53 INFO - 'log_name': 'talos', 12:08:53 INFO - 'log_to_console': True, 12:08:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:53 INFO - 'opt_config_files': (), 12:08:53 INFO - 'pip_index': False, 12:08:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:53 INFO - 'cmd': ('bash', 12:08:53 INFO - '-c', 12:08:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:53 INFO - 'enabled': True, 12:08:53 INFO - 'halt_on_failure': False, 12:08:53 INFO - 'name': 'check_screen_resolution'},), 12:08:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:53 INFO - 'cmd': ('bash', 12:08:53 INFO - '-c', 12:08:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:53 INFO - 'enabled': True, 12:08:53 INFO - 'halt_on_failure': False, 12:08:53 INFO - 'name': 'check_screen_resolution'},), 12:08:53 INFO - 'run_cmd_checks_enabled': True, 12:08:53 INFO - 'sps_profile': False, 12:08:53 INFO - 'sps_profile_interval': 0, 12:08:53 INFO - 'suite': 'dromaeojs-e10s', 12:08:53 INFO - 'system_bits': '32', 12:08:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:53 INFO - 'title': 't-yosemite-r7-0168', 12:08:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:53 INFO - 'use_talos_json': True, 12:08:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:53 INFO - 'work_dir': 'build'} 12:08:53 INFO - [mozharness: 2016-12-11 20:08:53.065215Z] Running clobber step. 12:08:53 INFO - Running pre-action listener: _resource_record_pre_action 12:08:53 INFO - Running main action method: clobber 12:08:53 INFO - rmtree: /builds/slave/test/build 12:08:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:55 INFO - Running post-action listener: _resource_record_post_action 12:08:55 INFO - [mozharness: 2016-12-11 20:08:55.492802Z] Finished clobber step (success) 12:08:55 INFO - [mozharness: 2016-12-11 20:08:55.492962Z] Running read-buildbot-config step. 12:08:55 INFO - Running pre-action listener: _resource_record_pre_action 12:08:55 INFO - Running main action method: read_buildbot_config 12:08:55 INFO - Using buildbot properties: 12:08:55 INFO - { 12:08:55 INFO - "project": "", 12:08:55 INFO - "product": "firefox", 12:08:55 INFO - "script_repo_revision": "production", 12:08:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:08:55 INFO - "repository": "", 12:08:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:08:55 INFO - "buildid": "20161211113350", 12:08:55 INFO - "pgo_build": "False", 12:08:55 INFO - "basedir": "/builds/slave/test", 12:08:55 INFO - "buildnumber": 24, 12:08:55 INFO - "slavename": "t-yosemite-r7-0168", 12:08:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:08:55 INFO - "platform": "yosemite_r7", 12:08:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:08:55 INFO - "branch": "mozilla-central", 12:08:55 INFO - "repo_path": "mozilla-central", 12:08:55 INFO - "revision": "272a12b8d16a4a1fcf16233a159b821798e57c94", 12:08:55 INFO - "stage_platform": "macosx64", 12:08:55 INFO - "builduid": "c391ad0505ea48538de1d9722a3a8c2c", 12:08:55 INFO - "slavebuilddir": "test" 12:08:55 INFO - } 12:08:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg. 12:08:55 INFO - Running post-action listener: _resource_record_post_action 12:08:55 INFO - [mozharness: 2016-12-11 20:08:55.496266Z] Finished read-buildbot-config step (success) 12:08:55 INFO - [mozharness: 2016-12-11 20:08:55.496406Z] Running download-and-extract step. 12:08:55 INFO - Running pre-action listener: _resource_record_pre_action 12:08:55 INFO - Running main action method: download_and_extract 12:08:55 INFO - mkdir: /builds/slave/test/build/tests 12:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:55 INFO - trying https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json'}, attempt #1 12:08:56 INFO - Downloaded 1235 bytes. 12:08:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:08:56 INFO - Using the following test package requirements: 12:08:56 INFO - {u'common': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 12:08:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.cppunittest.tests.zip'], 12:08:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.gtest.tests.zip'], 12:08:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'jsshell-mac64.zip'], 12:08:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.mochitest.tests.zip'], 12:08:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 12:08:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.reftest.tests.zip'], 12:08:56 INFO - u'talos': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.talos.tests.zip'], 12:08:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.web-platform.tests.zip'], 12:08:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:08:56 INFO - u'firefox-53.0a1.en-US.mac64.xpcshell.tests.zip']} 12:08:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip'] for test suite category: common 12:08:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 12:08:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 12:08:56 INFO - Fetch https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 12:08:57 INFO - Expected file size: 16288856 12:08:57 INFO - Obtained file size: 16288856 12:09:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip', u'firefox-53.0a1.en-US.mac64.talos.tests.zip'] for test suite category: talos 12:09:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 12:09:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 12:09:04 INFO - Fetch https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 12:09:05 INFO - Expected file size: 16288856 12:09:05 INFO - Obtained file size: 16288856 12:09:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip 12:09:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip'}, attempt #1 12:09:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip into memory 12:09:13 INFO - Expected file size: 14409734 12:09:13 INFO - Obtained file size: 14409734 12:09:13 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')]} 12:09:13 INFO - trying https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:09:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg 12:09:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg'}, attempt #1 12:09:15 INFO - Downloaded 69697719 bytes. 12:09:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:09:15 INFO - mkdir: /builds/slave/test/properties 12:09:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:15 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:15 INFO - Contents: 12:09:15 INFO - build_url:https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:09:16 INFO - Running post-action listener: _resource_record_post_action 12:09:16 INFO - Running post-action listener: set_extra_try_arguments 12:09:16 INFO - [mozharness: 2016-12-11 20:09:16.656748Z] Finished download-and-extract step (success) 12:09:16 INFO - [mozharness: 2016-12-11 20:09:16.656898Z] Running populate-webroot step. 12:09:16 INFO - Running pre-action listener: _resource_record_pre_action 12:09:16 INFO - Running main action method: populate_webroot 12:09:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:16 INFO - '--tppagecycles', 12:09:16 INFO - '7'], 12:09:16 INFO - 'tests': ['tsvgm']}}, 12:09:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:09:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['damp', 'tps']}, 12:09:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'tests': ['damp', 'tps']}, 12:09:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['dromaeo_dom']}, 12:09:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['basic_compositor_video']}, 12:09:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:09:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['a11yr', 12:09:16 INFO - 'ts_paint', 12:09:16 INFO - 'tpaint', 12:09:16 INFO - 'sessionrestore', 12:09:16 INFO - 'sessionrestore_no_auto_restore', 12:09:16 INFO - 'tabpaint']}, 12:09:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['tsvgx', 12:09:16 INFO - 'tsvgr_opacity', 12:09:16 INFO - 'tart', 12:09:16 INFO - 'tscrollx', 12:09:16 INFO - 'cart', 12:09:16 INFO - 'tsvg_static']}, 12:09:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:16 INFO - 'tsvgr_opacity', 12:09:16 INFO - 'tart', 12:09:16 INFO - 'tscrollx', 12:09:16 INFO - 'cart', 12:09:16 INFO - 'tsvg_static']}, 12:09:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'talos_options': ['--disable-e10s'], 12:09:16 INFO - 'tests': ['tp5o']}, 12:09:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:09:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'talos_options': ['--disable-e10s', 12:09:16 INFO - '--xperf_path', 12:09:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:16 INFO - 'tests': ['tp5n']}, 12:09:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:09:16 INFO - 'talos_options': ['--xperf_path', 12:09:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:16 INFO - 'tests': ['tp5n']}}, 12:09:16 INFO - 'talos.zip': {'path': '', 12:09:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:16 INFO - Running post-action listener: _resource_record_post_action 12:09:16 INFO - [mozharness: 2016-12-11 20:09:16.675077Z] Finished populate-webroot step (success) 12:09:16 INFO - [mozharness: 2016-12-11 20:09:16.675177Z] Running create-virtualenv step. 12:09:16 INFO - Running pre-action listener: _resource_record_pre_action 12:09:16 INFO - Running main action method: create_virtualenv 12:09:16 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:16 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 12:09:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:16 INFO - Using real prefix '/tools/python27' 12:09:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:17 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:20 INFO - Installing pip.................done. 12:09:20 INFO - Return code: 0 12:09:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:09:20 INFO - Reading from file tmpfile_stdout 12:09:20 INFO - Output received: 12:09:20 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) 12:09:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:20 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')]} 12:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 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')]} 12:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:20 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 12:09:20 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 12:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:20 INFO - 'HOME': '/Users/cltbld', 12:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:20 INFO - 'LOGNAME': 'cltbld', 12:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:20 INFO - 'MOZ_NO_REMOTE': '1', 12:09:20 INFO - 'NO_EM_RESTART': '1', 12:09:20 INFO - 'PAGER': '/bin/cat', 12:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:20 INFO - 'PWD': '/builds/slave/test', 12:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:20 INFO - 'SHELL': '/bin/bash', 12:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:20 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:20 INFO - 'USER': 'cltbld', 12:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:20 INFO - 'XPC_FLAGS': '0x0', 12:09:20 INFO - 'XPC_SERVICE_NAME': '0', 12:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:20 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 12:09:20 INFO - Cleaning up... 12:09:20 INFO - Return code: 0 12:09:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09:20 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')]} 12:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 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')]} 12:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:20 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 12:09:20 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 12:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:20 INFO - 'HOME': '/Users/cltbld', 12:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:20 INFO - 'LOGNAME': 'cltbld', 12:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:20 INFO - 'MOZ_NO_REMOTE': '1', 12:09:20 INFO - 'NO_EM_RESTART': '1', 12:09:20 INFO - 'PAGER': '/bin/cat', 12:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:20 INFO - 'PWD': '/builds/slave/test', 12:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:20 INFO - 'SHELL': '/bin/bash', 12:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:20 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:20 INFO - 'USER': 'cltbld', 12:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:20 INFO - 'XPC_FLAGS': '0x0', 12:09:20 INFO - 'XPC_SERVICE_NAME': '0', 12:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:21 INFO - Downloading/unpacking psutil>=3.1.1 12:09:21 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 12:09:21 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 12:09:21 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 12:09:21 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 12:09:21 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 12:09:21 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 12:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:24 INFO - Installing collected packages: psutil 12:09:24 INFO - Running setup.py install for psutil 12:09:25 INFO - building 'psutil._psutil_osx' extension 12:09:25 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 12:09:25 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 12:09:25 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 12:09:25 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 12:09:25 INFO - building 'psutil._psutil_posix' extension 12:09:25 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 12:09:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:25 INFO - ^ 12:09:25 INFO - 1 warning generated. 12:09:25 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 12:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:26 INFO - Successfully installed psutil 12:09:26 INFO - Cleaning up... 12:09:26 INFO - Return code: 0 12:09:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:26 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:26 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 12:09:26 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 12:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:26 INFO - 'HOME': '/Users/cltbld', 12:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:26 INFO - 'LOGNAME': 'cltbld', 12:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:26 INFO - 'MOZ_NO_REMOTE': '1', 12:09:26 INFO - 'NO_EM_RESTART': '1', 12:09:26 INFO - 'PAGER': '/bin/cat', 12:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:26 INFO - 'PWD': '/builds/slave/test', 12:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:26 INFO - 'SHELL': '/bin/bash', 12:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:26 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:26 INFO - 'USER': 'cltbld', 12:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:26 INFO - 'XPC_FLAGS': '0x0', 12:09:26 INFO - 'XPC_SERVICE_NAME': '0', 12:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:26 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 12:09:26 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 12:09:26 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 12:09:26 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 12:09:26 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 12:09:26 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 12:09:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:29 INFO - Installing collected packages: mozsystemmonitor 12:09:29 INFO - Running setup.py install for mozsystemmonitor 12:09:29 INFO - Successfully installed mozsystemmonitor 12:09:29 INFO - Cleaning up... 12:09:29 INFO - Return code: 0 12:09:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:29 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')]} 12:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 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')]} 12:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:29 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 12:09:29 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 12:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:29 INFO - 'HOME': '/Users/cltbld', 12:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:29 INFO - 'LOGNAME': 'cltbld', 12:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:29 INFO - 'MOZ_NO_REMOTE': '1', 12:09:29 INFO - 'NO_EM_RESTART': '1', 12:09:29 INFO - 'PAGER': '/bin/cat', 12:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:29 INFO - 'PWD': '/builds/slave/test', 12:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:29 INFO - 'SHELL': '/bin/bash', 12:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:29 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:29 INFO - 'USER': 'cltbld', 12:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:29 INFO - 'XPC_FLAGS': '0x0', 12:09:29 INFO - 'XPC_SERVICE_NAME': '0', 12:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:29 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:29 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 12:09:29 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 12:09:29 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 12:09:29 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 12:09:29 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 12:09:29 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 12:09:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:32 INFO - warning: no files found matching '*.txt' 12:09:32 INFO - no previously-included directories found matching 'build' 12:09:32 INFO - no previously-included directories found matching 'dist' 12:09:32 INFO - no previously-included directories found matching '.git*' 12:09:32 INFO - Installing collected packages: jsonschema, functools32 12:09:32 INFO - Running setup.py install for functools32 12:09:32 INFO - warning: no files found matching '*.txt' 12:09:32 INFO - no previously-included directories found matching 'build' 12:09:32 INFO - no previously-included directories found matching 'dist' 12:09:32 INFO - no previously-included directories found matching '.git*' 12:09:32 INFO - Successfully installed jsonschema functools32 12:09:32 INFO - Cleaning up... 12:09:33 INFO - Return code: 0 12:09:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:33 INFO - 'HOME': '/Users/cltbld', 12:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:33 INFO - 'LOGNAME': 'cltbld', 12:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:33 INFO - 'MOZ_NO_REMOTE': '1', 12:09:33 INFO - 'NO_EM_RESTART': '1', 12:09:33 INFO - 'PAGER': '/bin/cat', 12:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:33 INFO - 'PWD': '/builds/slave/test', 12:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:33 INFO - 'SHELL': '/bin/bash', 12:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:33 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:33 INFO - 'USER': 'cltbld', 12:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:33 INFO - 'XPC_FLAGS': '0x0', 12:09:33 INFO - 'XPC_SERVICE_NAME': '0', 12:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:33 INFO - Cleaning up... 12:09:33 INFO - Return code: 0 12:09:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:33 INFO - 'HOME': '/Users/cltbld', 12:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:33 INFO - 'LOGNAME': 'cltbld', 12:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:33 INFO - 'MOZ_NO_REMOTE': '1', 12:09:33 INFO - 'NO_EM_RESTART': '1', 12:09:33 INFO - 'PAGER': '/bin/cat', 12:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:33 INFO - 'PWD': '/builds/slave/test', 12:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:33 INFO - 'SHELL': '/bin/bash', 12:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:33 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:33 INFO - 'USER': 'cltbld', 12:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:33 INFO - 'XPC_FLAGS': '0x0', 12:09:33 INFO - 'XPC_SERVICE_NAME': '0', 12:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:33 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:36 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:37 INFO - Downloading docopt-0.6.1.tar.gz 12:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:37 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:37 INFO - Running setup.py install for blobuploader 12:09:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:37 INFO - Running setup.py install for requests 12:09:38 INFO - Running setup.py install for docopt 12:09:38 INFO - Successfully installed blobuploader requests docopt 12:09:38 INFO - Cleaning up... 12:09:38 INFO - Return code: 0 12:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:38 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 12:09:38 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 12:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:38 INFO - 'HOME': '/Users/cltbld', 12:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:38 INFO - 'LOGNAME': 'cltbld', 12:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:38 INFO - 'MOZ_NO_REMOTE': '1', 12:09:38 INFO - 'NO_EM_RESTART': '1', 12:09:38 INFO - 'PAGER': '/bin/cat', 12:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:38 INFO - 'PWD': '/builds/slave/test', 12:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:38 INFO - 'SHELL': '/bin/bash', 12:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:38 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:38 INFO - 'USER': 'cltbld', 12:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:38 INFO - 'XPC_FLAGS': '0x0', 12:09:38 INFO - 'XPC_SERVICE_NAME': '0', 12:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:38 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tsBvDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:38 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-KzNRbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:38 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-KnsISv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-z_FEJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ZlFtXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-hpFblE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-VwZFbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-YVc6Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-9xPtix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-mp1C5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Tg_iJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-TyhLqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:39 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UTzdut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:40 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ERCnbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:40 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-9YQ07W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:40 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-wypuDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:40 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-uipaE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:40 INFO - Running setup.py install for manifestparser 12:09:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Running setup.py install for mozcrash 12:09:40 INFO - Running setup.py install for mozdebug 12:09:40 INFO - Running setup.py install for mozdevice 12:09:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozfile 12:09:41 INFO - Running setup.py install for mozhttpd 12:09:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozinfo 12:09:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozInstall 12:09:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozleak 12:09:41 INFO - Running setup.py install for mozlog 12:09:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Running setup.py install for moznetwork 12:09:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Running setup.py install for mozprocess 12:09:42 INFO - Running setup.py install for mozprofile 12:09:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Running setup.py install for mozrunner 12:09:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Running setup.py install for mozscreenshot 12:09:42 INFO - Running setup.py install for moztest 12:09:43 INFO - Running setup.py install for mozversion 12:09:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:43 INFO - Cleaning up... 12:09:43 INFO - Return code: 0 12:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:43 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')]} 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:43 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')]} 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:43 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:43 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 12:09:43 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 12:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:43 INFO - 'HOME': '/Users/cltbld', 12:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:43 INFO - 'LOGNAME': 'cltbld', 12:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:43 INFO - 'MOZ_NO_REMOTE': '1', 12:09:43 INFO - 'NO_EM_RESTART': '1', 12:09:43 INFO - 'PAGER': '/bin/cat', 12:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:43 INFO - 'PWD': '/builds/slave/test', 12:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:43 INFO - 'SHELL': '/bin/bash', 12:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:43 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:43 INFO - 'USER': 'cltbld', 12:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:43 INFO - 'XPC_FLAGS': '0x0', 12:09:43 INFO - 'XPC_SERVICE_NAME': '0', 12:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:43 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QvX_sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:43 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-urKavn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:43 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-1P1He_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:43 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-KLaSTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:43 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tSlmzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-jFzFXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ZVU6Go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-RjfSw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-dHu0YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-OxU8Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QlbFFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-PfREwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QSUM5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:44 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QoEV4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:45 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)) 12:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:45 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-JH3vcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:45 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)) 12:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:45 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-1g3jj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:45 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)) 12:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:45 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-TTPbCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:45 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)) 12:09:45 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)) 12:09:45 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)) 12:09:45 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)) 12:09:45 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)) 12:09:45 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)) 12:09:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:47 INFO - Downloading blessings-1.6.tar.gz 12:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:48 INFO - Installing collected packages: blessings 12:09:48 INFO - Running setup.py install for blessings 12:09:48 INFO - Successfully installed blessings 12:09:48 INFO - Cleaning up... 12:09:48 INFO - Return code: 0 12:09:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:48 INFO - Reading from file tmpfile_stdout 12:09:48 INFO - Current package versions: 12:09:48 INFO - blessings == 1.6 12:09:48 INFO - blobuploader == 1.2.4 12:09:48 INFO - docopt == 0.6.1 12:09:48 INFO - functools32 == 3.2.3-2 12:09:48 INFO - jsonschema == 2.5.1 12:09:48 INFO - manifestparser == 1.1 12:09:48 INFO - mozInstall == 1.12 12:09:48 INFO - mozcrash == 1.0 12:09:48 INFO - mozdebug == 0.1 12:09:48 INFO - mozdevice == 0.49 12:09:48 INFO - mozfile == 1.2 12:09:48 INFO - mozhttpd == 0.7 12:09:48 INFO - mozinfo == 0.9 12:09:48 INFO - mozleak == 0.1 12:09:48 INFO - mozlog == 3.4 12:09:48 INFO - moznetwork == 0.27 12:09:48 INFO - mozprocess == 0.23 12:09:48 INFO - mozprofile == 0.28 12:09:48 INFO - mozrunner == 6.13 12:09:48 INFO - mozscreenshot == 0.1 12:09:48 INFO - mozsystemmonitor == 0.3 12:09:48 INFO - moztest == 0.7 12:09:48 INFO - mozversion == 1.4 12:09:48 INFO - psutil == 3.1.1 12:09:48 INFO - requests == 1.2.3 12:09:48 INFO - wsgiref == 0.1.2 12:09:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:48 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')]} 12:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:48 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')]} 12:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:48 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:48 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 12:09:48 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 12:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:48 INFO - 'HOME': '/Users/cltbld', 12:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:48 INFO - 'LOGNAME': 'cltbld', 12:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:48 INFO - 'MOZ_NO_REMOTE': '1', 12:09:48 INFO - 'NO_EM_RESTART': '1', 12:09:48 INFO - 'PAGER': '/bin/cat', 12:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:48 INFO - 'PWD': '/builds/slave/test', 12:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:48 INFO - 'SHELL': '/bin/bash', 12:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:48 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:48 INFO - 'USER': 'cltbld', 12:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:48 INFO - 'XPC_FLAGS': '0x0', 12:09:48 INFO - 'XPC_SERVICE_NAME': '0', 12:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 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)) 12:09:48 INFO - Cleaning up... 12:09:48 INFO - Return code: 0 12:09:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:48 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')]} 12:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:48 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')]} 12:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:48 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 0x102c123f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f924257fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, '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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 12:09:48 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 12:09:48 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 12:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:48 INFO - 'HOME': '/Users/cltbld', 12:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:48 INFO - 'LOGNAME': 'cltbld', 12:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:48 INFO - 'MOZ_NO_REMOTE': '1', 12:09:48 INFO - 'NO_EM_RESTART': '1', 12:09:48 INFO - 'PAGER': '/bin/cat', 12:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:48 INFO - 'PWD': '/builds/slave/test', 12:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:48 INFO - 'SHELL': '/bin/bash', 12:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:09:48 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:48 INFO - 'USER': 'cltbld', 12:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:48 INFO - 'XPC_FLAGS': '0x0', 12:09:48 INFO - 'XPC_SERVICE_NAME': '0', 12:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:49 INFO - Cleaning up... 12:09:49 INFO - Return code: 0 12:09:49 INFO - Running post-action listener: _resource_record_post_action 12:09:49 INFO - Running post-action listener: _start_resource_monitoring 12:09:49 INFO - Starting resource monitoring. 12:09:49 INFO - [mozharness: 2016-12-11 20:09:49.146969Z] Finished create-virtualenv step (success) 12:09:49 INFO - [mozharness: 2016-12-11 20:09:49.147953Z] Running install step. 12:09:49 INFO - Running pre-action listener: _resource_record_pre_action 12:09:49 INFO - Running main action method: install 12:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:49 INFO - Reading from file tmpfile_stdout 12:09:49 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:49 INFO - Reading from file tmpfile_stdout 12:09:49 INFO - Output received: 12:09:49 INFO - Usage: mozinstall [options] installer 12:09:49 INFO - Options: 12:09:49 INFO - -h, --help show this help message and exit 12:09:49 INFO - -d DEST, --destination=DEST 12:09:49 INFO - Directory to install application into. [default: 12:09:49 INFO - "/builds/slave/test"] 12:09:49 INFO - --app=APP Application being installed. [default: firefox] 12:09:49 INFO - mkdir: /builds/slave/test/build/application 12:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg --destination /builds/slave/test/build/application 12:10:11 INFO - Reading from file tmpfile_stdout 12:10:11 INFO - Output received: 12:10:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:10:11 INFO - Running post-action listener: _resource_record_post_action 12:10:11 INFO - [mozharness: 2016-12-11 20:10:11.296166Z] Finished install step (success) 12:10:11 INFO - [mozharness: 2016-12-11 20:10:11.296319Z] Running run-tests step. 12:10:11 INFO - Running pre-action listener: _resource_record_pre_action 12:10:11 INFO - Running main action method: run_tests 12:10:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:11 INFO - 2016-12-11 12:10:11.310 screenresolution[1629:7517] starting screenresolution argv=screenresolution get 12:10:11 INFO - 2016-12-11 12:10:11.313 screenresolution[1629:7517] Display 0: 1600x1200x32@60 12:10:11 INFO - 2016-12-11 12:10:11.319 screenresolution[1630:7519] starting screenresolution argv=screenresolution list 12:10:11 INFO - Available Modes on Display 0 12:10:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:11 INFO - Intel Iris: 12:10:11 INFO - Chipset Model: Intel Iris 12:10:11 INFO - Type: GPU 12:10:11 INFO - Bus: Built-In 12:10:11 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:11 INFO - Vendor: Intel (0x8086) 12:10:11 INFO - Device ID: 0x0a2e 12:10:11 INFO - Revision ID: 0x0009 12:10:11 INFO - Displays: 12:10:11 INFO - Display: 12:10:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:11 INFO - Display Serial Number: 1600x1200 60 12:10:11 INFO - Main Display: Yes 12:10:11 INFO - Mirror: Off 12:10:11 INFO - Online: Yes 12:10:11 INFO - Rotation: Supported 12:10:11 INFO - Return code: 0 12:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:11 INFO - Python 2.7.3 12:10:11 INFO - Return code: 0 12:10:11 INFO - grabbing minidump binary from tooltool 12:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:11 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 0x102a27d30>, '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 0x10288edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a1fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:11 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 12:10:11 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 12:10:11 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 12:10:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:11 INFO - Return code: 0 12:10:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:11 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/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 12:10:11 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/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 12:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p8bpqS0NDn/Render', 12:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:11 INFO - 'HOME': '/Users/cltbld', 12:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:11 INFO - 'LOGNAME': 'cltbld', 12:10:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:11 INFO - 'MOZ_NO_REMOTE': '1', 12:10:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:11 INFO - 'NO_EM_RESTART': '1', 12:10:11 INFO - 'PAGER': '/bin/cat', 12:10:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:11 INFO - 'PWD': '/builds/slave/test', 12:10:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:11 INFO - 'SHELL': '/bin/bash', 12:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ANoR5Vg9ay/Listeners', 12:10:11 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 12:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:11 INFO - 'USER': 'cltbld', 12:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:11 INFO - 'XPC_FLAGS': '0x0', 12:10:11 INFO - 'XPC_SERVICE_NAME': '0', 12:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:11 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/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 12:10:11 INFO - mozversion application_buildid: 20161211113350 12:10:11 INFO - mozversion application_changeset: 272a12b8d16a4a1fcf16233a159b821798e57c94 12:10:11 INFO - mozversion application_display_name: Nightly 12:10:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:11 INFO - mozversion application_name: Firefox 12:10:11 INFO - mozversion application_remotingname: firefox 12:10:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:10:11 INFO - mozversion application_vendor: Mozilla 12:10:11 INFO - mozversion application_version: 53.0a1 12:10:11 INFO - mozversion platform_buildid: 20161211113350 12:10:11 INFO - mozversion platform_changeset: 272a12b8d16a4a1fcf16233a159b821798e57c94 12:10:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:10:11 INFO - mozversion platform_version: 53.0a1 12:10:11 INFO - using testdate: 1481487011 12:10:11 INFO - actual date: 1481487011 12:10:11 INFO - starting webserver on 'localhost:49246' 12:10:11 INFO - SUITE-START | Running 2 tests 12:10:11 INFO - TEST-START | dromaeo_css 12:10:11 INFO - operating with platform_type : mac_ 12:10:11 INFO - Initialising browser for dromaeo_css test... 12:10:11 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmp5wyswz/profile http://localhost:49246/getInfo.html) 12:10:15 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 12:10:15 INFO - PROCESS | 1640 | colorDepth:24 12:10:15 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 12:10:15 INFO - PROCESS | 1640 | __metrics 12:10:15 INFO - PROCESS | 1640 | 2016-12-11 12:10:15.076 plugin-container[1642:7685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 12:10:15 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:15 INFO - PROCESS | 1640 | 2016-12-11 12:10:15.081 plugin-container[1642:7685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 12:10:15 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:15 INFO - PROCESS | 1640 | 2016-12-11 12:10:15.081 plugin-container[1642:7685] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:15 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 12:10:15 INFO - TEST-INFO | 1640: exit 0 12:10:15 INFO - Browser initialized. 12:10:15 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:15 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmp5wyswz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:18 INFO - PROCESS | 1643 | 2016-12-11 12:10:18.329 plugin-container[1644:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 12:10:18 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:18 INFO - PROCESS | 1643 | 2016-12-11 12:10:18.330 plugin-container[1644:7807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 12:10:18 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:18 INFO - PROCESS | 1643 | 2016-12-11 12:10:18.330 plugin-container[1644:7807] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:27 INFO - PROCESS | 1643 | RSS: Main: 236867584 12:10:27 INFO - PROCESS | 1643 | 12:11:17 INFO - PROCESS | 1643 | 1481487077525 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] 12:13:30 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 12:13:32 INFO - PROCESS | 1643 | RSS: Main: 252821504 12:13:32 INFO - PROCESS | 1643 | 12:16:35 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 12:16:36 INFO - PROCESS | 1643 | RSS: Main: 265719808 12:16:36 INFO - PROCESS | 1643 | 12:19:38 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 12:19:38 INFO - PROCESS | 1643 | RSS: Main: 269877248 12:19:38 INFO - PROCESS | 1643 | 12:22:40 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 12:22:41 INFO - PROCESS | 1643 | RSS: Main: 268660736 12:22:41 INFO - PROCESS | 1643 | 12:25:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 12:25:45 INFO - PROCESS | 1643 | RSS: Main: 273981440 12:25:45 INFO - PROCESS | 1643 | 12:28:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 12:28:48 INFO - PROCESS | 1643 | RSS: Main: 276557824 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | __start_tp_report 12:28:48 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 12:28:48 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 12:28:48 INFO - PROCESS | 1643 | |i|pagename|runs| 12:28:48 INFO - PROCESS | 1643 | |0;dojo.html;2978.8382530812987;3341.849616767247;3372.578427696538;3389.966100338996;3408.488726690996;5503.229547863302;5512.200730894018;5545.833624991252;5573.219749235105;5574.526165275946;4490.438695163105;5546.86132846679;5615.129654903486;5635.915461268084;5702.800401985931;4896.363472748538;5089.592832573404;5127.769250383742;5212.609054320922;5226.08543504887;5045.470225626312;5201.921971170416;5217.243499692542;5241.633085684004;5287.233351164079;4034.9596504034876;4230.76730779807;4772.832950846704;5169.121249387613;5169.974150129263;3601.603823579404;4384.89037774055;4966.528179822911;5134.383873935151;5174.611904107207;5003.4996500350035;5105.693658380472;5111.872203194913;5123.692578445305;5171;5347.331583552056;5458.317710286215;5566.916496252559;5583.5254003409755;5613.859653508654;5984.8503787405225;6031.728572214261;6137.622145770991;6141.578063794062;6143.788236234952;5637.830865074055;5687.549674832932;6195.380461953813;6234.781782637631;6250.7812226572305;5029.924551131736;5706.543476521868;5722.2561067061015;5725.370209276976;5744.942550574524;4978.278149668277;6141.700809433325;6153.507719382439;6161.075838624152;6207.441330280297;5987.491063259672;6019.518438524908;6066.241719785027;6100;6115.327313995457;6172.444479996821;6177.413145751147;6179.3202747697715;6199.535034872402;6211.627302361872;5903.557233207526;5948.2862056553495;5960.850978725566;5993.910091348634;6039.275286965592;6067.1202675611785;6077.726502307407;6079;6089.482393996472;6110.900037993204;4838.153323168432;4842.782074806642;4844.394450693663;4846.29728689338;4883.609311255091;3646.726495512847;3739.682127019234;4331.242032644274;4355.934660980088;4370.912581748347;5577.386487486373;5599.692016939026;5602.187682786055;5605.663660180402;5703.486686198262;6116.335771508238;6140.13616995768;6142.253225532421;6142.692865356715;6150.446459818638;5621.775128994795;5661.547076233891;5671.2060311556415;5679.858003549862;5726.713664316768;5284.630075894651;5291.947080529222;5295.682259064469;5297.258383826267;5299.549538289211;6274.6548939808745;6284.905726414107;6288.339724328879;6293.874122517433;6305.306416294204;6058.757649693963;6111.633302001894;6118.192813661829;6128.019516877278;6182.350853160354;6195.504359651127;6205.317415084427;6237.5633705641985;6297.811065668037;6311.431971122621;6091.1472393864005;6106.175666284995;6110.450059494667;6111.694415279308;6119.969400153149;5783.797567085132;6174.876502470015;6180.443760061616;6181.258249010148;6186.536009799193;4593.793279302507;5763.250777398911;5825.0679891217205;5910.143029260819;5926.140709596998;5040.092783299041;5649.519790817662;5989.490893274121;6001.819945401645;6036.788712395045;5705.657660540381;5724.341700704305;5781.884362312814;5782.826515204551;5838.211841401527;5748.338941021836;5825.126231065374;5860.15027820955;5970.731317090828;6013.9699301503215;4108.322126849156;5737.340205876378;5753.194552762533;5788.9131663024245;5843.444872737083 12:28:48 INFO - PROCESS | 1643 | |1;ext.html;441.364435213293;462.5074295874892;471.443696438203;479.171034110988;516.98670605613;34354.96935091945;35416.822915885416;39006.82979510619;39217.80391098044;39666.603333966654;30069.94755183569;34363.96908092755;38498.690078595304;42324.57675423245;42338.364924526155;8145.959270203641;8183.549904755236;8366.991921938721;8609.56952152393;9304.720858374243;11079.944600276987;11452.942735286353;11462.369569673665;11626.128040396961;11634.301941883472;11424.657260282205;11482.311061336304;11482.368469734161;11495.080393568494;11506;11309.491072901697;11322.377269250146;11337.886621133766;11367.943160284229;11413.029892459068;11229.157813163964;11307.434628268637;11318.717032074264;11320.603778867782;11339.943300283529;31200.531992020136;38847.02882427934;39172.60827391718;39281.41077883834;39356.40965385522;3155.9053228403186;3283.770136090475;3285.852136653856;3621.0530521731175;4055.7972101395108;26422.73577264222;26510.734892651213;26657.800398982094;27793.722062779518;28356.43287134246;3149.921251968696;3184.2357834119875;3184.8407579621157;3187.856546455415;3241.6110066792135;421532;439341;479680;482922;483717;52568;52766.73616631892;52838;52908.65096188482;53016;55381.44618553763;55678;55683;55968;56074.43925560692;14955.70088598222;15095.773563396559;15173.772393414107;15196.620084497976;15216.619584510476;10586.682399528027;10813.134949204044;11539.711507212387;11804;11858.466369013242;16989.49031529056;17073.7438938416;17139.828601713823;17147.828521714622;17195;6885.621290829053;7103.0766000420645;7121.28787121294;7122.786316410516;7145.285471452813;860.9139086091341;1025.4565080507343;1040.6201736366172;1046.3721766939775;1065.3448129400517;29664.406711865642;29670.851645742066;29804.850975744983;30212.24469388239;30349.696503035128;912.6030176872948;998.9650362237287;1048.3290693955873;1145.255583870474;1149.8620165580185;14089.436422542984;14993;15418.537443876701;15477;15818.68362632764;812590;865988;870737;871411;872184;49467.75266123646;50049.999000019794;50072.74818129503;50126.997460050596;50292;1327.9933600331935;1338.397721025523;1365.4333451617665;1367.466687991704;1369.4043091255335;27226.72773272242;27459.862700686368;27687.723122768515;27832.16503504898;27952.30119247035;42723.14553708971;43253;43318.566814331454;43348;43443.56556434395;505007;508026.67893283855;508901;509157;509870;20405.48986275355;24406.75593244116;24638.260852174462;24810;24860.875695621406;27341.589876152273;30331.241718957204;38286.80856595699;40357.798211008754;41314.79342603268;26559.734402655726;29228.853855731435;34542.30915381729;34727.47908781421;34830.47754283637;28688.569671455363;33857.83071084629;33908.49137262894;34404.13989650279;35108.473372898916;33062.50406243956;33204.1698957528;33475.83262083674;33705.494417583264;33827.32345353128;27584.172474825788;30597;30641.54037689481;30743.231419213804;30851.845740771154;23371;23420.946057427114;23709.407264818517;23835.761642383353;23839 12:28:48 INFO - PROCESS | 1643 | |2;jquery.html;337.22774845603567;347.97738147020436;363.9472276519905;384.20277923309106;397.00154112407284;18160.54598635033;18668.439946801584;19745.308914188005;19911.303104391332;20131.295404660803;19625.705614415794;20010.599788004267;20085.995700214968;20314.49213769653;20362.796372036275;4744.525547445245;5864.530837533009;5874.471297583216;5881;5887.676177810219;7047.506674532785;7396.408287337027;7467.775966721007;7477.140128885193;7491.363234125106;7295.6352182390665;7469.47713660044;7540.2082781308;7568.772936811877;7621.390288776913;7286.526375785557;7480.775576732707;7505;7585.658645360972;7621.199774023703;6298.748050077991;7306.926930730678;7312.597807120606;7322.743703970389;7341.5962122083265;25557.744422555723;25732;26812.92748290066;26995.055173069046;27028.459430811472;2346.6480027995767;2559.5136923984383;2615.6076588511687;3261.3477304539188;3327.068420842168;14921.552353429306;16415.26131324093;16496.83503164965;16575.668486630322;16663.083530405824;1760.5334586334632;1919.9712004319947;2000.1699294792643;2003.6693945498976;2005.809448102428;90831.7072199439;145362;146717.99999999892;147293;150801.24599377043;20284.087216075313;20603.4849128773;20643.69034464484;20664.79335206629;20845.89577052105;26788;33442;33534.49698254528;33547.66452335494;33690.326193475994;8168.142345053802;9975.636035371366;10374.896251037393;10473.528691208914;10648;8607.870881936777;8633.136686331442;8637.179467950535;8780.385373023826;8781.560921953876;7949.052482141912;8694.13058694138;9442.480663563432;9537.427754334623;9584.712458626253;3345.7657963942293;3617.7105831533413;3649.6532829381167;3740.9064773380883;3751.5685696144747;645.9547831651739;787.338635546144;823.8466147393697;916.422653728147;934.0667487063901;17343.913280433517;17501.82498175002;19426.611467770566;20749.170033198803;21103.97997098986;855.7518319687346;889.8843150390408;905.3888625177979;921.318224513855;924.7087167542203;8443.704470343631;10969.945150274198;11045.723856903482;11052.944735276273;11078.944605277084;524900;541548;547954;548590;550400;89071;90844.54577727069;124661;125036.37481812533;127639.72360276464;940.5344354544544;950.7385468995943;978.3396207559869;979.3487330924855;1002.0230275481201;18816.435506934813;19152.138153782835;19272.614547709247;19295.131719072957;19356.32252871143;51515.48484515257;55514;55595.444045560645;55921.72039139778;56243;62665.37334626595;79008;80413.19586804291;81281.59359203166;81884.59057704674;7859.371250299848;7870.763877083697;7984.8802267967185;8287.751367458986;8342.08237093907;11075.501602427748;12120.515179392725;12472.625821225378;12661;12716.809247861105;10718.89281107179;10833;10987.505562249558;11027.558897644005;11103.611373601885;8894.688685895962;10302.381857088598;10660.62687805923;10813.080888124598;11155.163362747664;8279.420440569102;10624.468776560987;11146.275492092987;11269.323840569592;11303.321800691985;6115.908261375994;7891.881621775563;8063.395245356739;8255.215754503313;8285.4200205985;9277.993004845182;10679.946600266949;10955.50700218476;11052.502637381498;11059 12:28:48 INFO - PROCESS | 1643 | |3;mootools.html;1356.6947436826713;1375.7798752199647;1644.950651480456;1803.6663217304808;1849.4913898677862;7408.481406301561;7617.885731714029;7812.140664526904;8277.586120693972;8289.461185022978;6785.525013249075;7725.845483090335;7977.361811055116;8134.593270336489;8135.471194372377;2261.5363850410668;2304.7695230476984;2305.8501197422197;2307.2501437033034;2317.2700599311247;2310.2838120182714;2329.26628112145;2352.6588644646517;2358.1392791631015;2361.740208577055;2305.66567847662;2319.246244970391;2324.5931961906686;2332;2334.5914464968646;2294.0594356313914;2296.058615967462;2321.083172147001;2326.116075891159;2326.336993956714;2294.208498068164;2318.1538738360546;2327.2087490253225;2344.284993077111;2351.3063646224405;7050.660374528823;7362.852742945165;8087.878681819778;8161.795955101111;8166.510009399396;3795.5065841440714;4342.739435633841;4656.6973146745695;5103.642745007851;5107.106256405114;2979.4488019716205;3254.397936381777;3331.3004269103276;3523.982380088083;3568.072301201706;1029.9794004119951;1039.6205385034484;1059.0786016165985;1074.135321066541;1078.9136869050458;10324.793504129875;10456.58173673052;10535.367877927272;10535.73660658482;10549.472526373727;4409.801558929856;4499.932501012488;4591.058832939239;4591.9081618367445;4648.535146485325;9096;9135.132162444557;9165.266778657824;9177.587008584496;9206.539673016323;5132.615053870974;5135;5142.151544995108;5142.5628821549835;5152.562032227228;4245.830166793294;4319.178278556894;4503.864884053483;4635.397398338262;4728.810847566059;3692.9815350923072;3737.3272810893754;3795.3168429682923;3816.0650640592676;3836.5971572984995;1242.4967888005372;1256.2023115321654;1267.3029833591488;1276.9233845969086;1281.5258354408743;787.763670898728;871.2811930157645;890.9109089091169;913.0960349254191;926.4348747264163;5553.389127196006;5555.30558680165;5556.6943818089585;5632.662040277514;5851.975904216745;825.3273582030746;906.3247880329066;915.7390143808982;917.9908200918039;948.5257371314343;3608.80151591665;3790.3177428063213;3808.562015368268;3829.3107240696386;3886.4753258309765;9141.497217653094;9458.85811712825;9686.854697179548;9786.657466988621;9809.754756131153;9744.707658770249;9758.707238782848;9783.46190959504;9786.804263914682;9800.215982721365;5902.8819423611285;7414.777556673308;7488.251174882577;7587.468877178655;7611.086669599684;4850.296706977539;5156.690598564098;5509.531689806411;5626.5780066496;5628.746706398304;8637.654493820177;8871.866921996047;8902.73291801221;8973.461592304482;9120.361574689709;8929.73210803677;9002.819943601222;9060.229880460234;9073.818523629623;9130.269578433843;6214.658193799384;6451.290358060516;6644.667766611554;6815.114035175396;7241.637918103968;7587.279208475186;7687.692492300469;7770.728024519115;7782.066152061789;7788.454808163374;6825.726970921108;6943.722251109899;7002.474814388839;7008.22909479967;7048.718051277891;6315.747370105144;6480.092787009728;6808.795736127924;6810.863782724218;6930.549514281555;6249.125122482947;6396;6467.450266727193;6483.546151769331;6531.085648009187;4605.838795642137;4730.503297153749;4745.2882067690125;4757.167495688172;4888.2178851383605;4688.460827004938;4796.400449943757;4840.975795121002;4856.538628830255;4859.07677541266 12:28:48 INFO - PROCESS | 1643 | |4;prototype.html;262.40826935260986;313.7897608602236;346.9496922946173;349.35718278367796;362.13088587390274;4403.779811009449;4514.164879497287;4831.975840120799;5262.342207224097;5268.1834315681035;4329.653627709784;4997.450280469146;5051.585556044304;5226.817061402851;5250.553702935245;3092.922676933072;3654.3422184007;3898.571157172709;4091.4476545666253;4125.958740412595;3713.758605690635;4127.711060225785;4131.2357213915375;4154.729942553739;4181.766378918212;3436.4501679731247;4094.283500387435;4103.446034785311;4134;4151.564085770996;3732.3313830610973;3912.471816304805;4086.652634526069;4109.267337220745;4132.855350062733;3317.253617935969;3641.817909104534;3654.7624404413896;3675.650813172744;3680.9815950920074;4698.788554515055;4808.735519546423;5097.668651537638;5121.18198039698;5177.378714554277;4662.9067418651775;4668.9261469861785;4674.415698037746;4698.7650617469335;4714.245720684708;4867.70793752376;5505.917411238835;5557.916206339765;5576.749046292886;5586.329640443132;3352.3127758809387;3543.5747710274672;3783.848646054182;3987.029662901128;4013.538443079054;4727.503612120712;5386.272853164773;5620.606557540974;5798;5898.498627616615;3467.162403925923;3590.605033446319;3762.9275656437785;3879.553851307079;3894.6884249259992;3578.2645416972746;3812.237552489458;3909.5113110861143;3929.4695216146024;3956.8219430125714;4517.412736344254;4523.592876641118;4526.79629416677;4528.796204170821;4558.877169139084;4999.525045120708;5377.171761601075;5609.158626206102;5672.744726487235;5685.971570142123;1030.567161792034;1181.1790805390171;1189.7091655553647;1212.3816853404828;1249.7440072726852;742.7660287009575;786.6184900323339;798.4331124901385;858.9098144694842;868.5092922498768;4582.450105987303;4728.935989402426;4989.079204506291;5063.468335824759;5082.754725092376;4757.405324334458;5429.990903365701;5430.995265875826;5518.724063796794;5574.386817450078;4195.265828480004;4481.3726078349055;4728.4562275338085;4970.776315065831;5007.774650140679;2833.929151771181;2837.404145129546;2844.0756754055014;2853.571964205386;2863.2512787724017;4512.894340886438;4787.54518320766;4823.589994850477;5352.625316227826;5678.744456499468;4698.342232087511;4818.759062046884;5127.820601261731;5219.451957544479;5527.364353099365;5308.956051919961;5480.451954804567;5484.396716361277;5644.57665675076;5679.460451257124;4861.878453038702;5062.82280120194;5166.844994650167;5232.5552328052545;5404.567634589302;5484.698341591251;5657.15142728594;5660.745266462936;5660.773569057192;5677.829665110054;5918.763249469973;5937.287525496968;5946.345901950868;5965.972968246329;6029.397060293935;3072.187409428448;3127.217486032939;3586.7984225283776;3786.413105968579;3882.8446862125197;5762.827115186551;5818.796342128006;5834.38738932406;5839.328477225174;5862.912056319074;4913.508649135201;5259.60552958522;5623.003833639163;5624.606277560531;5646.745896434588;4968.826091086795;5412.592725891218;5532.1425179097305;5554.2779438672715;5603.411641777555;4343.695941284206;5148.9227661584355;5276.472352764693;5347.866303342447;5371.704556249444;5227.660202087004;5410.540104091039;5605.971970140124;5617.865211219711;5621.381648018633;4813.927791083067;5490.80782172622;5534.710599285425;5554.750036248298;5557.833265002057 12:28:48 INFO - PROCESS | 1643 | |5;yui.html;683.8666460040295;709.7515869445695;722.3643193989288;737.4174402222244;774.264448773665;14584.489542866002;15635.84364156358;15653.295601697908;15656.608584785386;15669.686606267882;14215.289235538234;14388;14411.92794036031;14439;14477.710445791077;855.6791203298762;884.0849720539211;887.7736177274786;888.6534251641832;896.3277541843618;874.6632546469591;878.6573236437795;878.8374150782093;882.2236431939884;884.4251236696167;789.5302295134386;928.8746019287378;931.0317270039185;931.2736065868634;934.3926447808944;921.5162039929024;921.7833809054866;921.9170274675245;922.9723108306695;923.6582464488149;686.193722376206;686.62922022108;686.7424715731601;687.1342108942769;690.2648679156682;15002.69994600102;15258.38966441341;15327.31027103783;15528.922355388264;15772.526824195293;13625.659358516017;13905.79141312881;13988.790168147487;13993.580192594238;13994.230317332542;11728.413579321084;11766.294022358687;11778.175527713063;11823.586174483939;11954.342511161884;3662.8168591570316;3668.4497325401135;3744.4383342498572;3804.1821008483034;3830.3296923038633;113190;115407.42296288548;117028;117166;117600.82399175984;10444.477776111164;10569.207309451822;10579.735506612396;10592.311499752492;10715.678529644123;21766.455838603844;21793.564128717655;21803.45491362697;21862.562748744935;21875.562488750136;7589.772306830804;7683.654235559413;7796.883046754303;7927.801804954922;7930.682772689143;4085.71400001997;4187.832486700559;4386.320120381346;4399.912001760011;4485.125400546862;2895.913122606325;3399.6260411354733;3467.046562195366;3499.160201551609;3521.9119522012156;556.8802707417925;614.5421660862686;620.1813606039984;622.0668996505242;636.0872148466965;627.2065836716557;638.9137466442064;651.391064071742;661.6394065234459;679.1103654212997;8646.351523635753;9261.50453295816;9454.716358509118;9539.18916892058;9661.855072173923;531.2983479009204;632.9367063293726;640.0814830717935;649.6913965866175;666.8032930285577;3353.698167164967;4490.663200259994;4622.168009758208;4647.721136731807;4822.469528351879;70034;101006;106144.46927765466;109658;112154;75731.62134189294;76696.84954725683;77287.61356193296;80599;81521.36957260822;73170;74328.25671743213;75096;76928;77700.61149694328;7712.884306735291;8054.677812887418;8620.310375169845;8722.825543489222;8834.779130521789;36439;48698.75650621724;49990.000199996524;50041.99916001732;52087.697807553595;67462.66268668625;68520;71814.56370872658;74132;74234.25765742273;8436.409451338348;9190.816183676156;11282.097432205568;11324.37715925632;11849;12046.75906481883;12135.514579416724;12169.330686811956;12290.569830056264;12299.077569182167;11562.537498499965;11684.357360345262;11712.121590880888;11971.98238149767;12085.456154472893;11791.587294444653;11996.160268781101;12127.029837612794;12332.630021099381;12340.753184936431;11663.941680291544;11850;12028.894003947378;12247.87752122503;12302.261864288172;8862.601182946653;9139.908600914172;9503.66737164196;9580.856287155559;9785.755356116155;7372.447066470144;8318.833623327379;10006.649767258112;10121.139703125022;10121.443320617436 12:28:48 INFO - PROCESS | 1643 | __end_tp_report 12:28:48 INFO - PROCESS | 1643 | __start_cc_report 12:28:48 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,379 12:28:48 INFO - PROCESS | 1643 | __end_cc_report 12:28:48 INFO - PROCESS | 1643 | __startTimestamp1481488128096__endTimestamp 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | ------- Summary: start ------- 12:28:48 INFO - PROCESS | 1643 | Number of tests: 6 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5556.65 Median:5726.71 stddev:707.53 (12.4%) stddev-sans-first:680.19 12:28:48 INFO - PROCESS | 1643 | Values: 2978.8 3341.8 3372.6 3390.0 3408.5 5503.2 5512.2 5545.8 5573.2 5574.5 4490.4 5546.9 5615.1 5635.9 5702.8 4896.4 5089.6 5127.8 5212.6 5226.1 5045.5 5201.9 5217.2 5241.6 5287.2 4035.0 4230.8 4772.8 5169.1 5170.0 3601.6 4384.9 4966.5 5134.4 5174.6 5003.5 5105.7 5111.9 5123.7 5171.0 5347.3 5458.3 5566.9 5583.5 5613.9 5984.9 6031.7 6137.6 6141.6 6143.8 5637.8 5687.5 6195.4 6234.8 6250.8 5029.9 5706.5 5722.3 5725.4 5744.9 4978.3 6141.7 6153.5 6161.1 6207.4 5987.5 6019.5 6066.2 6100.0 6115.3 6172.4 6177.4 6179.3 6199.5 6211.6 5903.6 5948.3 5960.9 5993.9 6039.3 6067.1 6077.7 6079.0 6089.5 6110.9 4838.2 4842.8 4844.4 4846.3 4883.6 3646.7 3739.7 4331.2 4355.9 4370.9 5577.4 5599.7 5602.2 5605.7 5703.5 6116.3 6140.1 6142.3 6142.7 6150.4 5621.8 5661.5 5671.2 5679.9 5726.7 5284.6 5291.9 5295.7 5297.3 5299.5 6274.7 6284.9 6288.3 6293.9 6305.3 6058.8 6111.6 6118.2 6128.0 6182.4 6195.5 6205.3 6237.6 6297.8 6311.4 6091.1 6106.2 6110.5 6111.7 6120.0 5783.8 6174.9 6180.4 6181.3 6186.5 4593.8 5763.3 5825.1 5910.1 5926.1 5040.1 5649.5 5989.5 6001.8 6036.8 5705.7 5724.3 5781.9 5782.8 5838.2 5748.3 5825.1 5860.2 5970.7 6014.0 4108.3 5737.3 5753.2 5788.9 5843.4 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:71375.05 Median:3170.07 stddev:171359.17 (5405.5%) stddev-sans-first:171757.12 12:28:48 INFO - PROCESS | 1643 | Values: 441.4 462.5 471.4 479.2 517.0 34355.0 35416.8 39006.8 39217.8 39666.6 30069.9 34364.0 38498.7 42324.6 42338.4 8146.0 8183.5 8367.0 8609.6 9304.7 11079.9 11452.9 11462.4 11626.1 11634.3 11424.7 11482.3 11482.4 11495.1 11506.0 11309.5 11322.4 11337.9 11367.9 11413.0 11229.2 11307.4 11318.7 11320.6 11339.9 31200.5 38847.0 39172.6 39281.4 39356.4 3155.9 3283.8 3285.9 3621.1 4055.8 26422.7 26510.7 26657.8 27793.7 28356.4 3149.9 3184.2 3184.8 3187.9 3241.6 421532.0 439341.0 479680.0 482922.0 483717.0 52568.0 52766.7 52838.0 52908.7 53016.0 55381.4 55678.0 55683.0 55968.0 56074.4 14955.7 15095.8 15173.8 15196.6 15216.6 10586.7 10813.1 11539.7 11804.0 11858.5 16989.5 17073.7 17139.8 17147.8 17195.0 6885.6 7103.1 7121.3 7122.8 7145.3 860.9 1025.5 1040.6 1046.4 1065.3 29664.4 29670.9 29804.9 30212.2 30349.7 912.6 999.0 1048.3 1145.3 1149.9 14089.4 14993.0 15418.5 15477.0 15818.7 812590.0 865988.0 870737.0 871411.0 872184.0 49467.8 50050.0 50072.7 50127.0 50292.0 1328.0 1338.4 1365.4 1367.5 1369.4 27226.7 27459.9 27687.7 27832.2 27952.3 42723.1 43253.0 43318.6 43348.0 43443.6 505007.0 508026.7 508901.0 509157.0 509870.0 20405.5 24406.8 24638.3 24810.0 24860.9 27341.6 30331.2 38286.8 40357.8 41314.8 26559.7 29228.9 34542.3 34727.5 34830.5 28688.6 33857.8 33908.5 34404.1 35108.5 33062.5 33204.2 33475.8 33705.5 33827.3 27584.2 30597.0 30641.5 30743.2 30851.8 23371.0 23420.9 23709.4 23835.8 23839.0 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:34743.77 Median:1990.83 stddev:91123.49 (4577.2%) stddev-sans-first:91342.50 12:28:48 INFO - PROCESS | 1643 | Values: 337.2 348.0 363.9 384.2 397.0 18160.5 18668.4 19745.3 19911.3 20131.3 19625.7 20010.6 20086.0 20314.5 20362.8 4744.5 5864.5 5874.5 5881.0 5887.7 7047.5 7396.4 7467.8 7477.1 7491.4 7295.6 7469.5 7540.2 7568.8 7621.4 7286.5 7480.8 7505.0 7585.7 7621.2 6298.7 7306.9 7312.6 7322.7 7341.6 25557.7 25732.0 26812.9 26995.1 27028.5 2346.6 2559.5 2615.6 3261.3 3327.1 14921.6 16415.3 16496.8 16575.7 16663.1 1760.5 1920.0 2000.2 2003.7 2005.8 90831.7 145362.0 146718.0 147293.0 150801.2 20284.1 20603.5 20643.7 20664.8 20845.9 26788.0 33442.0 33534.5 33547.7 33690.3 8168.1 9975.6 10374.9 10473.5 10648.0 8607.9 8633.1 8637.2 8780.4 8781.6 7949.1 8694.1 9442.5 9537.4 9584.7 3345.8 3617.7 3649.7 3740.9 3751.6 646.0 787.3 823.8 916.4 934.1 17343.9 17501.8 19426.6 20749.2 21104.0 855.8 889.9 905.4 921.3 924.7 8443.7 10969.9 11045.7 11052.9 11078.9 524900.0 541548.0 547954.0 548590.0 550400.0 89071.0 90844.5 124661.0 125036.4 127639.7 940.5 950.7 978.3 979.3 1002.0 18816.4 19152.1 19272.6 19295.1 19356.3 51515.5 55514.0 55595.4 55921.7 56243.0 62665.4 79008.0 80413.2 81281.6 81884.6 7859.4 7870.8 7984.9 8287.8 8342.1 11075.5 12120.5 12472.6 12661.0 12716.8 10718.9 10833.0 10987.5 11027.6 11103.6 8894.7 10302.4 10660.6 10813.1 11155.2 8279.4 10624.5 11146.3 11269.3 11303.3 6115.9 7891.9 8063.4 8255.2 8285.4 9278.0 10679.9 10955.5 11052.5 11059.0 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5264.03 Median:5154.63 stddev:2830.88 (54.9%) stddev-sans-first:2823.59 12:28:48 INFO - PROCESS | 1643 | Values: 1356.7 1375.8 1645.0 1803.7 1849.5 7408.5 7617.9 7812.1 8277.6 8289.5 6785.5 7725.8 7977.4 8134.6 8135.5 2261.5 2304.8 2305.9 2307.3 2317.3 2310.3 2329.3 2352.7 2358.1 2361.7 2305.7 2319.2 2324.6 2332.0 2334.6 2294.1 2296.1 2321.1 2326.1 2326.3 2294.2 2318.2 2327.2 2344.3 2351.3 7050.7 7362.9 8087.9 8161.8 8166.5 3795.5 4342.7 4656.7 5103.6 5107.1 2979.4 3254.4 3331.3 3524.0 3568.1 1030.0 1039.6 1059.1 1074.1 1078.9 10324.8 10456.6 10535.4 10535.7 10549.5 4409.8 4499.9 4591.1 4591.9 4648.5 9096.0 9135.1 9165.3 9177.6 9206.5 5132.6 5135.0 5142.2 5142.6 5152.6 4245.8 4319.2 4503.9 4635.4 4728.8 3693.0 3737.3 3795.3 3816.1 3836.6 1242.5 1256.2 1267.3 1276.9 1281.5 787.8 871.3 890.9 913.1 926.4 5553.4 5555.3 5556.7 5632.7 5852.0 825.3 906.3 915.7 918.0 948.5 3608.8 3790.3 3808.6 3829.3 3886.5 9141.5 9458.9 9686.9 9786.7 9809.8 9744.7 9758.7 9783.5 9786.8 9800.2 5902.9 7414.8 7488.3 7587.5 7611.1 4850.3 5156.7 5509.5 5626.6 5628.7 8637.7 8871.9 8902.7 8973.5 9120.4 8929.7 9002.8 9060.2 9073.8 9130.3 6214.7 6451.3 6644.7 6815.1 7241.6 7587.3 7687.7 7770.7 7782.1 7788.5 6825.7 6943.7 7002.5 7008.2 7048.7 6315.7 6480.1 6808.8 6810.9 6930.5 6249.1 6396.0 6467.5 6483.5 6531.1 4605.8 4730.5 4745.3 4757.2 4888.2 4688.5 4796.4 4841.0 4856.5 4859.1 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4434.78 Median:4864.79 stddev:1365.54 (28.1%) stddev-sans-first:1332.98 12:28:48 INFO - PROCESS | 1643 | Values: 262.4 313.8 346.9 349.4 362.1 4403.8 4514.2 4832.0 5262.3 5268.2 4329.7 4997.5 5051.6 5226.8 5250.6 3092.9 3654.3 3898.6 4091.4 4126.0 3713.8 4127.7 4131.2 4154.7 4181.8 3436.5 4094.3 4103.4 4134.0 4151.6 3732.3 3912.5 4086.7 4109.3 4132.9 3317.3 3641.8 3654.8 3675.7 3681.0 4698.8 4808.7 5097.7 5121.2 5177.4 4662.9 4668.9 4674.4 4698.8 4714.2 4867.7 5505.9 5557.9 5576.7 5586.3 3352.3 3543.6 3783.8 3987.0 4013.5 4727.5 5386.3 5620.6 5798.0 5898.5 3467.2 3590.6 3762.9 3879.6 3894.7 3578.3 3812.2 3909.5 3929.5 3956.8 4517.4 4523.6 4526.8 4528.8 4558.9 4999.5 5377.2 5609.2 5672.7 5686.0 1030.6 1181.2 1189.7 1212.4 1249.7 742.8 786.6 798.4 858.9 868.5 4582.5 4728.9 4989.1 5063.5 5082.8 4757.4 5430.0 5431.0 5518.7 5574.4 4195.3 4481.4 4728.5 4970.8 5007.8 2833.9 2837.4 2844.1 2853.6 2863.3 4512.9 4787.5 4823.6 5352.6 5678.7 4698.3 4818.8 5127.8 5219.5 5527.4 5309.0 5480.5 5484.4 5644.6 5679.5 4861.9 5062.8 5166.8 5232.6 5404.6 5484.7 5657.2 5660.7 5660.8 5677.8 5918.8 5937.3 5946.3 5966.0 6029.4 3072.2 3127.2 3586.8 3786.4 3882.8 5762.8 5818.8 5834.4 5839.3 5862.9 4913.5 5259.6 5623.0 5624.6 5646.7 4968.8 5412.6 5532.1 5554.3 5603.4 4343.7 5148.9 5276.5 5347.9 5371.7 5227.7 5410.5 5606.0 5617.9 5621.4 4813.9 5490.8 5534.7 5554.8 5557.8 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19915.74 Median:26760.61 stddev:29593.55 (110.6%) stddev-sans-first:29641.34 12:28:48 INFO - PROCESS | 1643 | Values: 683.9 709.8 722.4 737.4 774.3 14584.5 15635.8 15653.3 15656.6 15669.7 14215.3 14388.0 14411.9 14439.0 14477.7 855.7 884.1 887.8 888.7 896.3 874.7 878.7 878.8 882.2 884.4 789.5 928.9 931.0 931.3 934.4 921.5 921.8 921.9 923.0 923.7 686.2 686.6 686.7 687.1 690.3 15002.7 15258.4 15327.3 15528.9 15772.5 13625.7 13905.8 13988.8 13993.6 13994.2 11728.4 11766.3 11778.2 11823.6 11954.3 3662.8 3668.4 3744.4 3804.2 3830.3 113190.0 115407.4 117028.0 117166.0 117600.8 10444.5 10569.2 10579.7 10592.3 10715.7 21766.5 21793.6 21803.5 21862.6 21875.6 7589.8 7683.7 7796.9 7927.8 7930.7 4085.7 4187.8 4386.3 4399.9 4485.1 2895.9 3399.6 3467.0 3499.2 3521.9 556.9 614.5 620.2 622.1 636.1 627.2 638.9 651.4 661.6 679.1 8646.4 9261.5 9454.7 9539.2 9661.9 531.3 632.9 640.1 649.7 666.8 3353.7 4490.7 4622.2 4647.7 4822.5 70034.0 101006.0 106144.5 109658.0 112154.0 75731.6 76696.8 77287.6 80599.0 81521.4 73170.0 74328.3 75096.0 76928.0 77700.6 7712.9 8054.7 8620.3 8722.8 8834.8 36439.0 48698.8 49990.0 50042.0 52087.7 67462.7 68520.0 71814.6 74132.0 74234.3 8436.4 9190.8 11282.1 11324.4 11849.0 12046.8 12135.5 12169.3 12290.6 12299.1 11562.5 11684.4 11712.1 11972.0 12085.5 11791.6 11996.2 12127.0 12332.6 12340.8 11663.9 11850.0 12028.9 12247.9 12302.3 8862.6 9139.9 9503.7 9580.9 9785.8 7372.4 8318.8 10006.6 10121.1 10121.4 12:28:48 INFO - PROCESS | 1643 | -------- Summary: end -------- 12:28:48 INFO - PROCESS | 1643 | 12:28:48 INFO - TEST-INFO | 1643: exit 0 12:28:48 INFO - TEST-OK | dromaeo_css | took 1116850ms 12:28:48 INFO - TEST-START | kraken 12:28:48 INFO - operating with platform_type : mac_ 12:28:48 INFO - Initialising browser for kraken test... 12:28:48 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpieprfS/profile http://localhost:49246/getInfo.html) 12:28:51 INFO - PROCESS | 1651 | 2016-12-11 12:28:51.489 plugin-container[1652:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 12:28:51 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:51 INFO - PROCESS | 1651 | 2016-12-11 12:28:51.492 plugin-container[1652:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a13, name = 'com.apple.CFPasteboardClient' 12:28:51 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:51 INFO - PROCESS | 1651 | 2016-12-11 12:28:51.492 plugin-container[1652:10103] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:51 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 12:28:51 INFO - PROCESS | 1651 | colorDepth:24 12:28:51 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 12:28:51 INFO - PROCESS | 1651 | __metrics 12:28:51 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 12:28:52 INFO - TEST-INFO | 1651: exit 0 12:28:52 INFO - Browser initialized. 12:28:52 INFO - Running cycle 1/1 for kraken test... 12:28:52 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpieprfS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:54 INFO - PROCESS | 1653 | 2016-12-11 12:28:54.909 plugin-container[1654:10220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 12:28:54 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:54 INFO - PROCESS | 1653 | 2016-12-11 12:28:54.910 plugin-container[1654:10220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 12:28:54 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:54 INFO - PROCESS | 1653 | 2016-12-11 12:28:54.910 plugin-container[1654:10220] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:29:04 INFO - PROCESS | 1653 | RSS: Main: 236900352 12:29:04 INFO - PROCESS | 1653 | 12:29:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 12:29:08 INFO - PROCESS | 1653 | RSS: Main: 249344000 12:29:08 INFO - PROCESS | 1653 | 12:29:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 12:29:11 INFO - PROCESS | 1653 | RSS: Main: 256913408 12:29:11 INFO - PROCESS | 1653 | 12:29:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 12:29:15 INFO - PROCESS | 1653 | RSS: Main: 259522560 12:29:15 INFO - PROCESS | 1653 | 12:29:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 12:29:18 INFO - PROCESS | 1653 | RSS: Main: 261816320 12:29:18 INFO - PROCESS | 1653 | 12:29:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:21 INFO - PROCESS | 1653 | RSS: Main: 262119424 12:29:21 INFO - PROCESS | 1653 | 12:29:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:24 INFO - PROCESS | 1653 | RSS: Main: 268238848 12:29:24 INFO - PROCESS | 1653 | 12:29:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:28 INFO - PROCESS | 1653 | RSS: Main: 267771904 12:29:28 INFO - PROCESS | 1653 | 12:29:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 12:29:32 INFO - PROCESS | 1653 | RSS: Main: 268001280 12:29:32 INFO - PROCESS | 1653 | 12:29:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:34 INFO - PROCESS | 1653 | RSS: Main: 267108352 12:29:34 INFO - PROCESS | 1653 | 12:29:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:37 INFO - PROCESS | 1653 | RSS: Main: 267190272 12:29:37 INFO - PROCESS | 1653 | 12:29:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:40 INFO - PROCESS | 1653 | RSS: Main: 267227136 12:29:40 INFO - PROCESS | 1653 | 12:29:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:44 INFO - PROCESS | 1653 | RSS: Main: 267513856 12:29:44 INFO - PROCESS | 1653 | 12:29:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:47 INFO - PROCESS | 1653 | RSS: Main: 267366400 12:29:47 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 12:29:49 INFO - PROCESS | 1653 | RSS: Main: 268210176 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | __start_tp_report 12:29:49 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 12:29:49 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 12:29:49 INFO - PROCESS | 1653 | |i|pagename|runs| 12:29:49 INFO - PROCESS | 1653 | |0;ai-astar;117;120;119;118;118;118;118;138;152;117 12:29:49 INFO - PROCESS | 1653 | |1;audio-beat-detection;151;118;119;157;119;116;119;111;162;150 12:29:49 INFO - PROCESS | 1653 | |2;audio-dft;199;209;266;269;211;201;197;201;193;199 12:29:49 INFO - PROCESS | 1653 | |3;audio-fft;78;59;62;84;59;69;82;85;88;63 12:29:49 INFO - PROCESS | 1653 | |4;audio-oscillator;91;71;83;75;72;71;83;74;102;116 12:29:49 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;170;133;112;127;113;115;112;116;112;115 12:29:49 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;99;104;97;95;95;144;121;112;101 12:29:49 INFO - PROCESS | 1653 | |7;imaging-desaturate;79;130;129;141;123;94;93;93;115;79 12:29:49 INFO - PROCESS | 1653 | |8;json-parse-financial;60;57;57;58;60;59;60;85;88;58 12:29:49 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;74;77;77;70;71;74;74;71;74 12:29:49 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;108;93;87;102;76;101;105;109;109;90 12:29:49 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;158;160;165;165;158;181;162;156;219;191 12:29:49 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;181;181;185;177;176;176;178;178;180;181 12:29:49 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;69;76;64;78;85;90;89;89;87;70 12:29:49 INFO - PROCESS | 1653 | __end_tp_report 12:29:49 INFO - PROCESS | 1653 | __start_cc_report 12:29:49 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,961 12:29:49 INFO - PROCESS | 1653 | __end_cc_report 12:29:49 INFO - PROCESS | 1653 | __startTimestamp1481488189920__endTimestamp 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | ------- Summary: start ------- 12:29:49 INFO - PROCESS | 1653 | Number of tests: 14 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:123.50 Median:118.50 stddev:11.83 (10.0%) stddev-sans-first:12.32 12:29:49 INFO - PROCESS | 1653 | Values: 117.0 120.0 119.0 118.0 118.0 118.0 118.0 138.0 152.0 117.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:134.50 stddev:20.03 (14.9%) stddev-sans-first:20.05 12:29:49 INFO - PROCESS | 1653 | Values: 151.0 118.0 119.0 157.0 119.0 116.0 119.0 111.0 162.0 150.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:214.50 Median:205.00 stddev:28.44 (13.9%) stddev-sans-first:29.60 12:29:49 INFO - PROCESS | 1653 | Values: 199.0 209.0 266.0 269.0 211.0 201.0 197.0 201.0 193.0 199.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:72.90 Median:80.00 stddev:11.67 (14.6%) stddev-sans-first:12.23 12:29:49 INFO - PROCESS | 1653 | Values: 78.0 59.0 62.0 84.0 59.0 69.0 82.0 85.0 88.0 63.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:74.50 stddev:15.13 (20.3%) stddev-sans-first:15.83 12:29:49 INFO - PROCESS | 1653 | Values: 91.0 71.0 83.0 75.0 72.0 71.0 83.0 74.0 102.0 116.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:122.50 Median:115.50 stddev:18.14 (15.7%) stddev-sans-first:7.55 12:29:49 INFO - PROCESS | 1653 | Values: 170.0 133.0 112.0 127.0 113.0 115.0 112.0 116.0 112.0 115.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:95.00 stddev:15.73 (16.6%) stddev-sans-first:16.14 12:29:49 INFO - PROCESS | 1653 | Values: 95.0 99.0 104.0 97.0 95.0 95.0 144.0 121.0 112.0 101.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:107.60 Median:79.00 stddev:22.64 (28.7%) stddev-sans-first:21.52 12:29:49 INFO - PROCESS | 1653 | Values: 79.0 130.0 129.0 141.0 123.0 94.0 93.0 93.0 115.0 79.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:60.00 stddev:11.83 (19.7%) stddev-sans-first:12.45 12:29:49 INFO - PROCESS | 1653 | Values: 60.0 57.0 57.0 58.0 60.0 59.0 60.0 85.0 88.0 58.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:74.00 stddev:2.77 (3.7%) stddev-sans-first:2.51 12:29:49 INFO - PROCESS | 1653 | Values: 69.0 74.0 77.0 77.0 70.0 71.0 74.0 74.0 71.0 74.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:98.00 Median:92.50 stddev:11.11 (12.0%) stddev-sans-first:11.17 12:29:49 INFO - PROCESS | 1653 | Values: 108.0 93.0 87.0 102.0 76.0 101.0 105.0 109.0 109.0 90.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:171.50 Median:165.00 stddev:20.11 (12.2%) stddev-sans-first:20.72 12:29:49 INFO - PROCESS | 1653 | Values: 158.0 160.0 165.0 165.0 158.0 181.0 162.0 156.0 219.0 191.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:180.50 stddev:2.83 (1.6%) stddev-sans-first:2.93 12:29:49 INFO - PROCESS | 1653 | Values: 181.0 181.0 185.0 177.0 176.0 176.0 178.0 178.0 180.0 181.0 12:29:49 INFO - PROCESS | 1653 | 12:29:49 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.70 Median:86.00 stddev:9.62 (11.2%) stddev-sans-first:9.39 12:29:49 INFO - PROCESS | 1653 | Values: 69.0 76.0 64.0 78.0 85.0 90.0 89.0 89.0 87.0 70.0 12:29:49 INFO - PROCESS | 1653 | -------- Summary: end -------- 12:29:49 INFO - PROCESS | 1653 | 12:29:50 INFO - TEST-INFO | 1653: exit 0 12:29:50 INFO - TEST-OK | kraken | took 62091ms 12:29:50 INFO - SUITE-END | took 1178s 12:29:51 INFO - Completed test suite (00:19:39) 12:29:51 INFO - javascript benchmark 12:29:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7189.283821850379, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2978.8382530812987, 3341.849616767247, 3372.578427696538, 3389.966100338996, 3408.488726690996, 5503.229547863302, 5512.200730894018, 5545.833624991252, 5573.219749235105, 5574.526165275946, 4490.438695163105, 5546.86132846679, 5615.129654903486, 5635.915461268084, 5702.800401985931, 4896.363472748538, 5089.592832573404, 5127.769250383742, 5212.609054320922, 5226.08543504887, 5045.470225626312, 5201.921971170416, 5217.243499692542, 5241.633085684004, 5287.233351164079, 4034.9596504034876, 4230.76730779807, 4772.832950846704, 5169.121249387613, 5169.974150129263, 3601.603823579404, 4384.89037774055, 4966.528179822911, 5134.383873935151, 5174.611904107207, 5003.4996500350035, 5105.693658380472, 5111.872203194913, 5123.692578445305, 5171.0, 5347.331583552056, 5458.317710286215, 5566.916496252559, 5583.5254003409755, 5613.859653508654, 5984.8503787405225, 6031.728572214261, 6137.622145770991, 6141.578063794062, 6143.788236234952, 5637.830865074055, 5687.549674832932, 6195.380461953813, 6234.781782637631, 6250.7812226572305, 5029.924551131736, 5706.543476521868, 5722.2561067061015, 5725.370209276976, 5744.942550574524, 4978.278149668277, 6141.700809433325, 6153.507719382439, 6161.075838624152, 6207.441330280297, 5987.491063259672, 6019.518438524908, 6066.241719785027, 6100.0, 6115.327313995457, 6172.444479996821, 6177.413145751147, 6179.3202747697715, 6199.535034872402, 6211.627302361872, 5903.557233207526, 5948.2862056553495, 5960.850978725566, 5993.910091348634, 6039.275286965592, 6067.1202675611785, 6077.726502307407, 6079.0, 6089.482393996472, 6110.900037993204, 4838.153323168432, 4842.782074806642, 4844.394450693663, 4846.29728689338, 4883.609311255091, 3646.726495512847, 3739.682127019234, 4331.242032644274, 4355.934660980088, 4370.912581748347, 5577.386487486373, 5599.692016939026, 5602.187682786055, 5605.663660180402, 5703.486686198262, 6116.335771508238, 6140.13616995768, 6142.253225532421, 6142.692865356715, 6150.446459818638, 5621.775128994795, 5661.547076233891, 5671.2060311556415, 5679.858003549862, 5726.713664316768, 5284.630075894651, 5291.947080529222, 5295.682259064469, 5297.258383826267, 5299.549538289211, 6274.6548939808745, 6284.905726414107, 6288.339724328879, 6293.874122517433, 6305.306416294204, 6058.757649693963, 6111.633302001894, 6118.192813661829, 6128.019516877278, 6182.350853160354, 6195.504359651127, 6205.317415084427, 6237.5633705641985, 6297.811065668037, 6311.431971122621, 6091.1472393864005, 6106.175666284995, 6110.450059494667, 6111.694415279308, 6119.969400153149, 5783.797567085132, 6174.876502470015, 6180.443760061616, 6181.258249010148, 6186.536009799193, 4593.793279302507, 5763.250777398911, 5825.0679891217205, 5910.143029260819, 5926.140709596998, 5040.092783299041, 5649.519790817662, 5989.490893274121, 6001.819945401645, 6036.788712395045, 5705.657660540381, 5724.341700704305, 5781.884362312814, 5782.826515204551, 5838.211841401527, 5748.338941021836, 5825.126231065374, 5860.15027820955, 5970.731317090828, 6013.9699301503215, 4108.322126849156, 5737.340205876378, 5753.194552762533, 5788.9131663024245, 5843.444872737083], "value": 5511.367866784943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.364435213293, 462.5074295874892, 471.443696438203, 479.171034110988, 516.98670605613, 34354.96935091945, 35416.822915885416, 39006.82979510619, 39217.80391098044, 39666.603333966654, 30069.94755183569, 34363.96908092755, 38498.690078595304, 42324.57675423245, 42338.364924526155, 8145.959270203641, 8183.549904755236, 8366.991921938721, 8609.56952152393, 9304.720858374243, 11079.944600276987, 11452.942735286353, 11462.369569673665, 11626.128040396961, 11634.301941883472, 11424.657260282205, 11482.311061336304, 11482.368469734161, 11495.080393568494, 11506.0, 11309.491072901697, 11322.377269250146, 11337.886621133766, 11367.943160284229, 11413.029892459068, 11229.157813163964, 11307.434628268637, 11318.717032074264, 11320.603778867782, 11339.943300283529, 31200.531992020136, 38847.02882427934, 39172.60827391718, 39281.41077883834, 39356.40965385522, 3155.9053228403186, 3283.770136090475, 3285.852136653856, 3621.0530521731175, 4055.7972101395108, 26422.73577264222, 26510.734892651213, 26657.800398982094, 27793.722062779518, 28356.43287134246, 3149.921251968696, 3184.2357834119875, 3184.8407579621157, 3187.856546455415, 3241.6110066792135, 421532.0, 439341.0, 479680.0, 482922.0, 483717.0, 52568.0, 52766.73616631892, 52838.0, 52908.65096188482, 53016.0, 55381.44618553763, 55678.0, 55683.0, 55968.0, 56074.43925560692, 14955.70088598222, 15095.773563396559, 15173.772393414107, 15196.620084497976, 15216.619584510476, 10586.682399528027, 10813.134949204044, 11539.711507212387, 11804.0, 11858.466369013242, 16989.49031529056, 17073.7438938416, 17139.828601713823, 17147.828521714622, 17195.0, 6885.621290829053, 7103.0766000420645, 7121.28787121294, 7122.786316410516, 7145.285471452813, 860.9139086091341, 1025.4565080507343, 1040.6201736366172, 1046.3721766939775, 1065.3448129400517, 29664.406711865642, 29670.851645742066, 29804.850975744983, 30212.24469388239, 30349.696503035128, 912.6030176872948, 998.9650362237287, 1048.3290693955873, 1145.255583870474, 1149.8620165580185, 14089.436422542984, 14993.0, 15418.537443876701, 15477.0, 15818.68362632764, 812590.0, 865988.0, 870737.0, 871411.0, 872184.0, 49467.75266123646, 50049.999000019794, 50072.74818129503, 50126.997460050596, 50292.0, 1327.9933600331935, 1338.397721025523, 1365.4333451617665, 1367.466687991704, 1369.4043091255335, 27226.72773272242, 27459.862700686368, 27687.723122768515, 27832.16503504898, 27952.30119247035, 42723.14553708971, 43253.0, 43318.566814331454, 43348.0, 43443.56556434395, 505007.0, 508026.67893283855, 508901.0, 509157.0, 509870.0, 20405.48986275355, 24406.75593244116, 24638.260852174462, 24810.0, 24860.875695621406, 27341.589876152273, 30331.241718957204, 38286.80856595699, 40357.798211008754, 41314.79342603268, 26559.734402655726, 29228.853855731435, 34542.30915381729, 34727.47908781421, 34830.47754283637, 28688.569671455363, 33857.83071084629, 33908.49137262894, 34404.13989650279, 35108.473372898916, 33062.50406243956, 33204.1698957528, 33475.83262083674, 33705.494417583264, 33827.32345353128, 27584.172474825788, 30597.0, 30641.54037689481, 30743.231419213804, 30851.845740771154, 23371.0, 23420.946057427114, 23709.407264818517, 23835.761642383353, 23839.0], "value": 19014.721813927816, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.22774845603567, 347.97738147020436, 363.9472276519905, 384.20277923309106, 397.00154112407284, 18160.54598635033, 18668.439946801584, 19745.308914188005, 19911.303104391332, 20131.295404660803, 19625.705614415794, 20010.599788004267, 20085.995700214968, 20314.49213769653, 20362.796372036275, 4744.525547445245, 5864.530837533009, 5874.471297583216, 5881.0, 5887.676177810219, 7047.506674532785, 7396.408287337027, 7467.775966721007, 7477.140128885193, 7491.363234125106, 7295.6352182390665, 7469.47713660044, 7540.2082781308, 7568.772936811877, 7621.390288776913, 7286.526375785557, 7480.775576732707, 7505.0, 7585.658645360972, 7621.199774023703, 6298.748050077991, 7306.926930730678, 7312.597807120606, 7322.743703970389, 7341.5962122083265, 25557.744422555723, 25732.0, 26812.92748290066, 26995.055173069046, 27028.459430811472, 2346.6480027995767, 2559.5136923984383, 2615.6076588511687, 3261.3477304539188, 3327.068420842168, 14921.552353429306, 16415.26131324093, 16496.83503164965, 16575.668486630322, 16663.083530405824, 1760.5334586334632, 1919.9712004319947, 2000.1699294792643, 2003.6693945498976, 2005.809448102428, 90831.7072199439, 145362.0, 146717.99999999892, 147293.0, 150801.24599377043, 20284.087216075313, 20603.4849128773, 20643.69034464484, 20664.79335206629, 20845.89577052105, 26788.0, 33442.0, 33534.49698254528, 33547.66452335494, 33690.326193475994, 8168.142345053802, 9975.636035371366, 10374.896251037393, 10473.528691208914, 10648.0, 8607.870881936777, 8633.136686331442, 8637.179467950535, 8780.385373023826, 8781.560921953876, 7949.052482141912, 8694.13058694138, 9442.480663563432, 9537.427754334623, 9584.712458626253, 3345.7657963942293, 3617.7105831533413, 3649.6532829381167, 3740.9064773380883, 3751.5685696144747, 645.9547831651739, 787.338635546144, 823.8466147393697, 916.422653728147, 934.0667487063901, 17343.913280433517, 17501.82498175002, 19426.611467770566, 20749.170033198803, 21103.97997098986, 855.7518319687346, 889.8843150390408, 905.3888625177979, 921.318224513855, 924.7087167542203, 8443.704470343631, 10969.945150274198, 11045.723856903482, 11052.944735276273, 11078.944605277084, 524900.0, 541548.0, 547954.0, 548590.0, 550400.0, 89071.0, 90844.54577727069, 124661.0, 125036.37481812533, 127639.72360276464, 940.5344354544544, 950.7385468995943, 978.3396207559869, 979.3487330924855, 1002.0230275481201, 18816.435506934813, 19152.138153782835, 19272.614547709247, 19295.131719072957, 19356.32252871143, 51515.48484515257, 55514.0, 55595.444045560645, 55921.72039139778, 56243.0, 62665.37334626595, 79008.0, 80413.19586804291, 81281.59359203166, 81884.59057704674, 7859.371250299848, 7870.763877083697, 7984.8802267967185, 8287.751367458986, 8342.08237093907, 11075.501602427748, 12120.515179392725, 12472.625821225378, 12661.0, 12716.809247861105, 10718.89281107179, 10833.0, 10987.505562249558, 11027.558897644005, 11103.611373601885, 8894.688685895962, 10302.381857088598, 10660.62687805923, 10813.080888124598, 11155.163362747664, 8279.420440569102, 10624.468776560987, 11146.275492092987, 11269.323840569592, 11303.321800691985, 6115.908261375994, 7891.881621775563, 8063.395245356739, 8255.215754503313, 8285.4200205985, 9277.993004845182, 10679.946600266949, 10955.50700218476, 11052.502637381498, 11059.0], "value": 10653.494467261866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.6947436826713, 1375.7798752199647, 1644.950651480456, 1803.6663217304808, 1849.4913898677862, 7408.481406301561, 7617.885731714029, 7812.140664526904, 8277.586120693972, 8289.461185022978, 6785.525013249075, 7725.845483090335, 7977.361811055116, 8134.593270336489, 8135.471194372377, 2261.5363850410668, 2304.7695230476984, 2305.8501197422197, 2307.2501437033034, 2317.2700599311247, 2310.2838120182714, 2329.26628112145, 2352.6588644646517, 2358.1392791631015, 2361.740208577055, 2305.66567847662, 2319.246244970391, 2324.5931961906686, 2332.0, 2334.5914464968646, 2294.0594356313914, 2296.058615967462, 2321.083172147001, 2326.116075891159, 2326.336993956714, 2294.208498068164, 2318.1538738360546, 2327.2087490253225, 2344.284993077111, 2351.3063646224405, 7050.660374528823, 7362.852742945165, 8087.878681819778, 8161.795955101111, 8166.510009399396, 3795.5065841440714, 4342.739435633841, 4656.6973146745695, 5103.642745007851, 5107.106256405114, 2979.4488019716205, 3254.397936381777, 3331.3004269103276, 3523.982380088083, 3568.072301201706, 1029.9794004119951, 1039.6205385034484, 1059.0786016165985, 1074.135321066541, 1078.9136869050458, 10324.793504129875, 10456.58173673052, 10535.367877927272, 10535.73660658482, 10549.472526373727, 4409.801558929856, 4499.932501012488, 4591.058832939239, 4591.9081618367445, 4648.535146485325, 9096.0, 9135.132162444557, 9165.266778657824, 9177.587008584496, 9206.539673016323, 5132.615053870974, 5135.0, 5142.151544995108, 5142.5628821549835, 5152.562032227228, 4245.830166793294, 4319.178278556894, 4503.864884053483, 4635.397398338262, 4728.810847566059, 3692.9815350923072, 3737.3272810893754, 3795.3168429682923, 3816.0650640592676, 3836.5971572984995, 1242.4967888005372, 1256.2023115321654, 1267.3029833591488, 1276.9233845969086, 1281.5258354408743, 787.763670898728, 871.2811930157645, 890.9109089091169, 913.0960349254191, 926.4348747264163, 5553.389127196006, 5555.30558680165, 5556.6943818089585, 5632.662040277514, 5851.975904216745, 825.3273582030746, 906.3247880329066, 915.7390143808982, 917.9908200918039, 948.5257371314343, 3608.80151591665, 3790.3177428063213, 3808.562015368268, 3829.3107240696386, 3886.4753258309765, 9141.497217653094, 9458.85811712825, 9686.854697179548, 9786.657466988621, 9809.754756131153, 9744.707658770249, 9758.707238782848, 9783.46190959504, 9786.804263914682, 9800.215982721365, 5902.8819423611285, 7414.777556673308, 7488.251174882577, 7587.468877178655, 7611.086669599684, 4850.296706977539, 5156.690598564098, 5509.531689806411, 5626.5780066496, 5628.746706398304, 8637.654493820177, 8871.866921996047, 8902.73291801221, 8973.461592304482, 9120.361574689709, 8929.73210803677, 9002.819943601222, 9060.229880460234, 9073.818523629623, 9130.269578433843, 6214.658193799384, 6451.290358060516, 6644.667766611554, 6815.114035175396, 7241.637918103968, 7587.279208475186, 7687.692492300469, 7770.728024519115, 7782.066152061789, 7788.454808163374, 6825.726970921108, 6943.722251109899, 7002.474814388839, 7008.22909479967, 7048.718051277891, 6315.747370105144, 6480.092787009728, 6808.795736127924, 6810.863782724218, 6930.549514281555, 6249.125122482947, 6396.0, 6467.450266727193, 6483.546151769331, 6531.085648009187, 4605.838795642137, 4730.503297153749, 4745.2882067690125, 4757.167495688172, 4888.2178851383605, 4688.460827004938, 4796.400449943757, 4840.975795121002, 4856.538628830255, 4859.07677541266], "value": 4309.294219652619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.40826935260986, 313.7897608602236, 346.9496922946173, 349.35718278367796, 362.13088587390274, 4403.779811009449, 4514.164879497287, 4831.975840120799, 5262.342207224097, 5268.1834315681035, 4329.653627709784, 4997.450280469146, 5051.585556044304, 5226.817061402851, 5250.553702935245, 3092.922676933072, 3654.3422184007, 3898.571157172709, 4091.4476545666253, 4125.958740412595, 3713.758605690635, 4127.711060225785, 4131.2357213915375, 4154.729942553739, 4181.766378918212, 3436.4501679731247, 4094.283500387435, 4103.446034785311, 4134.0, 4151.564085770996, 3732.3313830610973, 3912.471816304805, 4086.652634526069, 4109.267337220745, 4132.855350062733, 3317.253617935969, 3641.817909104534, 3654.7624404413896, 3675.650813172744, 3680.9815950920074, 4698.788554515055, 4808.735519546423, 5097.668651537638, 5121.18198039698, 5177.378714554277, 4662.9067418651775, 4668.9261469861785, 4674.415698037746, 4698.7650617469335, 4714.245720684708, 4867.70793752376, 5505.917411238835, 5557.916206339765, 5576.749046292886, 5586.329640443132, 3352.3127758809387, 3543.5747710274672, 3783.848646054182, 3987.029662901128, 4013.538443079054, 4727.503612120712, 5386.272853164773, 5620.606557540974, 5798.0, 5898.498627616615, 3467.162403925923, 3590.605033446319, 3762.9275656437785, 3879.553851307079, 3894.6884249259992, 3578.2645416972746, 3812.237552489458, 3909.5113110861143, 3929.4695216146024, 3956.8219430125714, 4517.412736344254, 4523.592876641118, 4526.79629416677, 4528.796204170821, 4558.877169139084, 4999.525045120708, 5377.171761601075, 5609.158626206102, 5672.744726487235, 5685.971570142123, 1030.567161792034, 1181.1790805390171, 1189.7091655553647, 1212.3816853404828, 1249.7440072726852, 742.7660287009575, 786.6184900323339, 798.4331124901385, 858.9098144694842, 868.5092922498768, 4582.450105987303, 4728.935989402426, 4989.079204506291, 5063.468335824759, 5082.754725092376, 4757.405324334458, 5429.990903365701, 5430.995265875826, 5518.724063796794, 5574.386817450078, 4195.265828480004, 4481.3726078349055, 4728.4562275338085, 4970.776315065831, 5007.774650140679, 2833.929151771181, 2837.404145129546, 2844.0756754055014, 2853.571964205386, 2863.2512787724017, 4512.894340886438, 4787.54518320766, 4823.589994850477, 5352.625316227826, 5678.744456499468, 4698.342232087511, 4818.759062046884, 5127.820601261731, 5219.451957544479, 5527.364353099365, 5308.956051919961, 5480.451954804567, 5484.396716361277, 5644.57665675076, 5679.460451257124, 4861.878453038702, 5062.82280120194, 5166.844994650167, 5232.5552328052545, 5404.567634589302, 5484.698341591251, 5657.15142728594, 5660.745266462936, 5660.773569057192, 5677.829665110054, 5918.763249469973, 5937.287525496968, 5946.345901950868, 5965.972968246329, 6029.397060293935, 3072.187409428448, 3127.217486032939, 3586.7984225283776, 3786.413105968579, 3882.8446862125197, 5762.827115186551, 5818.796342128006, 5834.38738932406, 5839.328477225174, 5862.912056319074, 4913.508649135201, 5259.60552958522, 5623.003833639163, 5624.606277560531, 5646.745896434588, 4968.826091086795, 5412.592725891218, 5532.1425179097305, 5554.2779438672715, 5603.411641777555, 4343.695941284206, 5148.9227661584355, 5276.472352764693, 5347.866303342447, 5371.704556249444, 5227.660202087004, 5410.540104091039, 5605.971970140124, 5617.865211219711, 5621.381648018633, 4813.927791083067, 5490.80782172622, 5534.710599285425, 5554.750036248298, 5557.833265002057], "value": 3997.26948211885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.8666460040295, 709.7515869445695, 722.3643193989288, 737.4174402222244, 774.264448773665, 14584.489542866002, 15635.84364156358, 15653.295601697908, 15656.608584785386, 15669.686606267882, 14215.289235538234, 14388.0, 14411.92794036031, 14439.0, 14477.710445791077, 855.6791203298762, 884.0849720539211, 887.7736177274786, 888.6534251641832, 896.3277541843618, 874.6632546469591, 878.6573236437795, 878.8374150782093, 882.2236431939884, 884.4251236696167, 789.5302295134386, 928.8746019287378, 931.0317270039185, 931.2736065868634, 934.3926447808944, 921.5162039929024, 921.7833809054866, 921.9170274675245, 922.9723108306695, 923.6582464488149, 686.193722376206, 686.62922022108, 686.7424715731601, 687.1342108942769, 690.2648679156682, 15002.69994600102, 15258.38966441341, 15327.31027103783, 15528.922355388264, 15772.526824195293, 13625.659358516017, 13905.79141312881, 13988.790168147487, 13993.580192594238, 13994.230317332542, 11728.413579321084, 11766.294022358687, 11778.175527713063, 11823.586174483939, 11954.342511161884, 3662.8168591570316, 3668.4497325401135, 3744.4383342498572, 3804.1821008483034, 3830.3296923038633, 113190.0, 115407.42296288548, 117028.0, 117166.0, 117600.82399175984, 10444.477776111164, 10569.207309451822, 10579.735506612396, 10592.311499752492, 10715.678529644123, 21766.455838603844, 21793.564128717655, 21803.45491362697, 21862.562748744935, 21875.562488750136, 7589.772306830804, 7683.654235559413, 7796.883046754303, 7927.801804954922, 7930.682772689143, 4085.71400001997, 4187.832486700559, 4386.320120381346, 4399.912001760011, 4485.125400546862, 2895.913122606325, 3399.6260411354733, 3467.046562195366, 3499.160201551609, 3521.9119522012156, 556.8802707417925, 614.5421660862686, 620.1813606039984, 622.0668996505242, 636.0872148466965, 627.2065836716557, 638.9137466442064, 651.391064071742, 661.6394065234459, 679.1103654212997, 8646.351523635753, 9261.50453295816, 9454.716358509118, 9539.18916892058, 9661.855072173923, 531.2983479009204, 632.9367063293726, 640.0814830717935, 649.6913965866175, 666.8032930285577, 3353.698167164967, 4490.663200259994, 4622.168009758208, 4647.721136731807, 4822.469528351879, 70034.0, 101006.0, 106144.46927765466, 109658.0, 112154.0, 75731.62134189294, 76696.84954725683, 77287.61356193296, 80599.0, 81521.36957260822, 73170.0, 74328.25671743213, 75096.0, 76928.0, 77700.61149694328, 7712.884306735291, 8054.677812887418, 8620.310375169845, 8722.825543489222, 8834.779130521789, 36439.0, 48698.75650621724, 49990.000199996524, 50041.99916001732, 52087.697807553595, 67462.66268668625, 68520.0, 71814.56370872658, 74132.0, 74234.25765742273, 8436.409451338348, 9190.816183676156, 11282.097432205568, 11324.37715925632, 11849.0, 12046.75906481883, 12135.514579416724, 12169.330686811956, 12290.569830056264, 12299.077569182167, 11562.537498499965, 11684.357360345262, 11712.121590880888, 11971.98238149767, 12085.456154472893, 11791.587294444653, 11996.160268781101, 12127.029837612794, 12332.630021099381, 12340.753184936431, 11663.941680291544, 11850.0, 12028.894003947378, 12247.87752122503, 12302.261864288172, 8862.601182946653, 9139.908600914172, 9503.66737164196, 9580.856287155559, 9785.755356116155, 7372.447066470144, 8318.833623327379, 10006.649767258112, 10121.139703125022, 10121.443320617436], "value": 7178.816877187315, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 120.0, 119.0, 118.0, 118.0, 118.0, 118.0, 138.0, 152.0, 117.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 119.0, 157.0, 119.0, 116.0, 119.0, 111.0, 162.0, 150.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 209.0, 266.0, 269.0, 211.0, 201.0, 197.0, 201.0, 193.0, 199.0], "value": 214.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 62.0, 84.0, 59.0, 69.0, 82.0, 85.0, 88.0, 63.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 83.0, 75.0, 72.0, 71.0, 83.0, 74.0, 102.0, 116.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 133.0, 112.0, 127.0, 113.0, 115.0, 112.0, 116.0, 112.0, 115.0], "value": 122.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 104.0, 97.0, 95.0, 95.0, 144.0, 121.0, 112.0, 101.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 130.0, 129.0, 141.0, 123.0, 94.0, 93.0, 93.0, 115.0, 79.0], "value": 107.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 58.0, 60.0, 59.0, 60.0, 85.0, 88.0, 58.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 77.0, 77.0, 70.0, 71.0, 74.0, 74.0, 71.0, 74.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 93.0, 87.0, 102.0, 76.0, 101.0, 105.0, 109.0, 109.0, 90.0], "value": 98.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 165.0, 165.0, 158.0, 181.0, 162.0, 156.0, 219.0, 191.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 181.0, 185.0, 177.0, 176.0, 176.0, 178.0, 178.0, 180.0, 181.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 64.0, 78.0, 85.0, 90.0, 89.0, 89.0, 87.0, 70.0], "value": 79.7, "unit": "score"}]}]} 12:29:51 INFO - Return code: 0 12:29:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:51 ERROR - # TBPL SUCCESS # 12:29:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:51 INFO - 2016-12-11 12:29:51.259 screenresolution[1658:10607] starting screenresolution argv=screenresolution get 12:29:51 INFO - 2016-12-11 12:29:51.262 screenresolution[1658:10607] Display 0: 1600x1200x32@60 12:29:51 INFO - 2016-12-11 12:29:51.268 screenresolution[1659:10610] starting screenresolution argv=screenresolution list 12:29:51 INFO - Available Modes on Display 0 12:29:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:51 INFO - Intel Iris: 12:29:51 INFO - Chipset Model: Intel Iris 12:29:51 INFO - Type: GPU 12:29:51 INFO - Bus: Built-In 12:29:51 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:51 INFO - Vendor: Intel (0x8086) 12:29:51 INFO - Device ID: 0x0a2e 12:29:51 INFO - Revision ID: 0x0009 12:29:51 INFO - Displays: 12:29:51 INFO - Display: 12:29:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:51 INFO - Display Serial Number: 1600x1200 60 12:29:51 INFO - Main Display: Yes 12:29:51 INFO - Mirror: Off 12:29:51 INFO - Online: Yes 12:29:51 INFO - Rotation: Supported 12:29:51 INFO - Return code: 0 12:29:51 INFO - Running post-action listener: _resource_record_post_action 12:29:51 INFO - [mozharness: 2016-12-11 20:29:51.505013Z] Finished run-tests step (success) 12:29:51 INFO - Running post-run listener: _resource_record_post_run 12:29:51 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33915392; Write bytes: 550992384; Read time: 323; Write time: 1672 12:29:51 INFO - TinderboxPrint: CPU usage
54.5% 12:29:51 INFO - TinderboxPrint: I/O read bytes / time
33,915,392 / 323 12:29:51 INFO - TinderboxPrint: I/O write bytes / time
550,992,384 / 1,672 12:29:51 INFO - TinderboxPrint: CPU idle
2,187.1 (45.5%) 12:29:51 INFO - TinderboxPrint: CPU system
967.7 (20.1%) 12:29:51 INFO - TinderboxPrint: CPU user
1,650.9 (34.4%) 12:29:51 INFO - TinderboxPrint: Swap in / out
279,506,944 / 0 12:29:51 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250345984; Write bytes: 244328448; Read time: 18290; Write time: 508 12:29:51 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30020096; Write bytes: 297030144; Read time: 288; Write time: 1135 12:29:51 INFO - Running post-run listener: _upload_blobber_files 12:29:51 INFO - Blob upload gear active. 12:29:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:51 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 12:29:51 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'] 12:29:51 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 12:29:52 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:53 INFO - (blobuploader) - INFO - Done attempting. 12:29:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:54 INFO - (blobuploader) - INFO - Done attempting. 12:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:56 INFO - (blobuploader) - INFO - Done attempting. 12:29:56 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:56 INFO - Return code: 0 12:29:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e5862a6eaa8e4afa98a1722f3517f7588b290388048661abdec35fb73cb7b88692c3f6f15a6255fcc0b2a2dfa5db6d3907ad563baa631a4df2f76c071dde6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413fd604290e3ed4751f8d0eba9d7dd22dbc1bd2eed8f73e49a5399da51ac2bca42979eedbfcc0e2e05b7db6db3a0ef546d79f79860115a829242a55bf86199f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1e3fc45a8500970c6986dd0ee3f0e21bd86a5eca98c2e7e496dbc0bc1f694e13d591a543a8af811e882b7c0c2d75e8f88af21da21002df2747004b55274ea1"} 12:29:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:56 INFO - Contents: 12:29:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e5862a6eaa8e4afa98a1722f3517f7588b290388048661abdec35fb73cb7b88692c3f6f15a6255fcc0b2a2dfa5db6d3907ad563baa631a4df2f76c071dde6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413fd604290e3ed4751f8d0eba9d7dd22dbc1bd2eed8f73e49a5399da51ac2bca42979eedbfcc0e2e05b7db6db3a0ef546d79f79860115a829242a55bf86199f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1e3fc45a8500970c6986dd0ee3f0e21bd86a5eca98c2e7e496dbc0bc1f694e13d591a543a8af811e882b7c0c2d75e8f88af21da21002df2747004b55274ea1"} 12:29:56 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:56 INFO - Copying logs to upload dir... 12:29:56 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.975829 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-11 12:29:56.891090) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 12:29:56.896853) ========= 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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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/0e5862a6eaa8e4afa98a1722f3517f7588b290388048661abdec35fb73cb7b88692c3f6f15a6255fcc0b2a2dfa5db6d3907ad563baa631a4df2f76c071dde6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413fd604290e3ed4751f8d0eba9d7dd22dbc1bd2eed8f73e49a5399da51ac2bca42979eedbfcc0e2e05b7db6db3a0ef546d79f79860115a829242a55bf86199f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1e3fc45a8500970c6986dd0ee3f0e21bd86a5eca98c2e7e496dbc0bc1f694e13d591a543a8af811e882b7c0c2d75e8f88af21da21002df2747004b55274ea1"} build_url:https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg program finished with exit code 0 elapsedTime=0.010853 build_url: 'https://queue.taskcluster.net/v1/task/OGEOYUpiRN665IqCfQlUOQ/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e5862a6eaa8e4afa98a1722f3517f7588b290388048661abdec35fb73cb7b88692c3f6f15a6255fcc0b2a2dfa5db6d3907ad563baa631a4df2f76c071dde6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413fd604290e3ed4751f8d0eba9d7dd22dbc1bd2eed8f73e49a5399da51ac2bca42979eedbfcc0e2e05b7db6db3a0ef546d79f79860115a829242a55bf86199f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce1e3fc45a8500970c6986dd0ee3f0e21bd86a5eca98c2e7e496dbc0bc1f694e13d591a543a8af811e882b7c0c2d75e8f88af21da21002df2747004b55274ea1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 12:29:56.933166) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:29:56.933519) ========= 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.p8bpqS0NDn/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.ANoR5Vg9ay/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.005203 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 12:29:56.982155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 12:29:56.985523) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 12:29:56.985966) ========= ========= Total master_lag: 0.20 =========