builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1493493523.74 results: success (0) buildid: 20170429114946 builduid: ec94f56ea5d04263a3a32e962bd968e8 revision: 21640269841ace0b6ab6818f89994558ef984b7a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:43.742403) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:43.742829) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:43.743108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.030929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.031247) ========= 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.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-29 12:18:44-- 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% 171M=0s 2017-04-29 12:18:44 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.556563 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.667908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.668230) ========= 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.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.028485 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.737720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:44.738037) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 21640269841ace0b6ab6818f89994558ef984b7a --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 21640269841ace0b6ab6818f89994558ef984b7a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-29 12:18:44,855 truncating revision to first 12 chars 2017-04-29 12:18:44,855 Setting DEBUG logging. 2017-04-29 12:18:44,855 attempt 1/10 2017-04-29 12:18:44,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/21640269841a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-29 12:18:45,486 unpacking tar archive at: mozilla-central-21640269841a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926693 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:45.699294) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:45.699911) ========= script_repo_revision: 21640269841ace0b6ab6818f89994558ef984b7a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:45.700700) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:45.701243) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-29 12:18:45.723283) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-29 12:18:45.724045) ========= /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.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:18:45 INFO - MultiFileLogger online at 20170429 12:18:45 in /builds/slave/test 12:18:45 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:18:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:18:45 INFO - {'append_to_log': False, 12:18:45 INFO - 'base_work_dir': '/builds/slave/test', 12:18:45 INFO - 'blob_upload_branch': 'Firefox', 12:18:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:18:45 INFO - 'branch': 'Firefox', 12:18:45 INFO - 'buildbot_json_path': 'buildprops.json', 12:18:45 INFO - 'config_files': ('talos/mac_config.py',), 12:18:45 INFO - 'default_actions': ('clobber', 12:18:45 INFO - 'read-buildbot-config', 12:18:45 INFO - 'download-and-extract', 12:18:45 INFO - 'populate-webroot', 12:18:45 INFO - 'create-virtualenv', 12:18:45 INFO - 'install', 12:18:45 INFO - 'run-tests'), 12:18:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:18:45 INFO - 'download_minidump_stackwalk': True, 12:18:45 INFO - 'download_symbols': 'ondemand', 12:18:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:18:45 INFO - 'tooltool.py': '/tools/tooltool.py', 12:18:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:18:45 INFO - '/tools/misc-python/virtualenv.py')}, 12:18:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:18:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:18:45 INFO - 'gecko_profile': False, 12:18:45 INFO - 'gecko_profile_interval': 0, 12:18:45 INFO - 'installer_path': 'installer.exe', 12:18:45 INFO - 'log_level': 'info', 12:18:45 INFO - 'log_name': 'talos', 12:18:45 INFO - 'log_to_console': True, 12:18:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:18:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:18:45 INFO - 'opt_config_files': (), 12:18:45 INFO - 'pip_index': False, 12:18:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:45 INFO - 'cmd': ('bash', 12:18:45 INFO - '-c', 12:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:45 INFO - 'enabled': True, 12:18:45 INFO - 'halt_on_failure': False, 12:18:45 INFO - 'name': 'check_screen_resolution'},), 12:18:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:45 INFO - 'cmd': ('bash', 12:18:45 INFO - '-c', 12:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:45 INFO - 'enabled': True, 12:18:45 INFO - 'halt_on_failure': False, 12:18:45 INFO - 'name': 'check_screen_resolution'},), 12:18:45 INFO - 'run_cmd_checks_enabled': True, 12:18:45 INFO - 'suite': 'dromaeojs-e10s', 12:18:45 INFO - 'system_bits': '32', 12:18:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:18:45 INFO - 'title': 't-yosemite-r7-0188', 12:18:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:18:45 INFO - 'use_talos_json': True, 12:18:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:18:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:18:45 INFO - 'work_dir': 'build'} 12:18:45 INFO - [mozharness: 2017-04-29 19:18:45.953093Z] Running clobber step. 12:18:45 INFO - Running pre-action listener: _resource_record_pre_action 12:18:45 INFO - Running main action method: clobber 12:18:45 INFO - rmtree: /builds/slave/test/build 12:18:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:18:48 INFO - Running post-action listener: _resource_record_post_action 12:18:48 INFO - [mozharness: 2017-04-29 19:18:48.430835Z] Finished clobber step (success) 12:18:48 INFO - [mozharness: 2017-04-29 19:18:48.431022Z] Running read-buildbot-config step. 12:18:48 INFO - Running pre-action listener: _resource_record_pre_action 12:18:48 INFO - Running main action method: read_buildbot_config 12:18:48 INFO - Using buildbot properties: 12:18:48 INFO - { 12:18:48 INFO - "project": "", 12:18:48 INFO - "product": "firefox", 12:18:48 INFO - "script_repo_revision": "production", 12:18:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:18:48 INFO - "repository": "", 12:18:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:18:48 INFO - "buildid": "20170429114946", 12:18:48 INFO - "pgo_build": "False", 12:18:48 INFO - "basedir": "/builds/slave/test", 12:18:48 INFO - "buildnumber": 56, 12:18:48 INFO - "slavename": "t-yosemite-r7-0188", 12:18:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:18:48 INFO - "platform": "yosemite_r7", 12:18:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:18:48 INFO - "branch": "mozilla-central", 12:18:48 INFO - "repo_path": "mozilla-central", 12:18:48 INFO - "revision": "21640269841ace0b6ab6818f89994558ef984b7a", 12:18:48 INFO - "stage_platform": "macosx64", 12:18:48 INFO - "builduid": "ec94f56ea5d04263a3a32e962bd968e8", 12:18:48 INFO - "slavebuilddir": "test" 12:18:48 INFO - } 12:18:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:18:48 INFO - Running post-action listener: _resource_record_post_action 12:18:48 INFO - [mozharness: 2017-04-29 19:18:48.437098Z] Finished read-buildbot-config step (success) 12:18:48 INFO - [mozharness: 2017-04-29 19:18:48.437308Z] Running download-and-extract step. 12:18:48 INFO - Running pre-action listener: _resource_record_pre_action 12:18:48 INFO - Running main action method: download_and_extract 12:18:48 INFO - mkdir: /builds/slave/test/build/tests 12:18: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:18:48 INFO - trying https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:18:48 INFO - Downloading https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:18:49 INFO - Downloaded 1325 bytes. 12:18:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:18:49 INFO - Using the following test package requirements: 12:18:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:18:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:18:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:18:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:18:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'jsshell-mac.zip'], 12:18:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:18:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:18:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:18:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:18:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:18:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:18:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:18:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:18:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:18:49 INFO - Fetch https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:18:52 INFO - Content-Length response header: 52698519 12:18:52 INFO - Bytes received: 52698519 12:19:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:19:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:19:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:19:08 INFO - Fetch https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:19:10 INFO - Content-Length response header: 52698519 12:19:10 INFO - Bytes received: 52698519 12:19:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:19:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:19:25 INFO - Fetch https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:19:26 INFO - Content-Length response header: 14408811 12:19:26 INFO - Bytes received: 14408811 12:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:26 INFO - trying https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:19:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:19:29 INFO - Downloaded 71196328 bytes. 12:19:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:19:29 INFO - mkdir: /builds/slave/test/properties 12:19:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:19:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:19:29 INFO - Contents: 12:19:29 INFO - build_url:https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:19:30 INFO - Running post-action listener: _resource_record_post_action 12:19:30 INFO - Running post-action listener: set_extra_try_arguments 12:19:30 INFO - [mozharness: 2017-04-29 19:19:30.288663Z] Finished download-and-extract step (success) 12:19:30 INFO - [mozharness: 2017-04-29 19:19:30.288804Z] Running populate-webroot step. 12:19:30 INFO - Running pre-action listener: _resource_record_pre_action 12:19:30 INFO - Running main action method: populate_webroot 12:19:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:19:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:19:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:19:30 INFO - '--tppagecycles', 12:19:30 INFO - '7'], 12:19:30 INFO - 'tests': ['tsvgm']}}, 12:19:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:19:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:19:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:19:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:19:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['damp', 'tps']}, 12:19:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'tests': ['damp', 'tps']}, 12:19:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['dromaeo_dom']}, 12:19:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:19:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:19:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:19:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'other-e10s': {'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['a11yr', 12:19:30 INFO - 'ts_paint', 12:19:30 INFO - 'tpaint', 12:19:30 INFO - 'sessionrestore', 12:19:30 INFO - 'sessionrestore_no_auto_restore', 12:19:30 INFO - 'tabpaint']}, 12:19:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:19:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:19:30 INFO - 'bloom_basic_ref']}, 12:19:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['tsvgx', 12:19:30 INFO - 'tsvgr_opacity', 12:19:30 INFO - 'tart', 12:19:30 INFO - 'tscrollx', 12:19:30 INFO - 'cart', 12:19:30 INFO - 'tsvg_static']}, 12:19:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:19:30 INFO - 'tsvgr_opacity', 12:19:30 INFO - 'tart', 12:19:30 INFO - 'tscrollx', 12:19:30 INFO - 'cart', 12:19:30 INFO - 'tsvg_static']}, 12:19:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'talos_options': ['--disable-e10s'], 12:19:30 INFO - 'tests': ['tp5o']}, 12:19:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:19:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'talos_options': ['--disable-e10s', 12:19:30 INFO - '--xperf_path', 12:19:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:30 INFO - 'tests': ['tp5n']}, 12:19:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:19:30 INFO - 'talos_options': ['--xperf_path', 12:19:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:30 INFO - 'tests': ['tp5n']}}, 12:19:30 INFO - 'talos.zip': {'path': '', 12:19:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:19:30 INFO - Running post-action listener: _resource_record_post_action 12:19:30 INFO - [mozharness: 2017-04-29 19:19:30.308002Z] Finished populate-webroot step (success) 12:19:30 INFO - [mozharness: 2017-04-29 19:19:30.308113Z] Running create-virtualenv step. 12:19:30 INFO - Running pre-action listener: _resource_record_pre_action 12:19:30 INFO - Running main action method: create_virtualenv 12:19:30 INFO - Creating virtualenv /builds/slave/test/build/venv 12:19:30 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:19:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:19:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:19:30 INFO - Using real prefix '/tools/python27' 12:19:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:19:31 INFO - Installing distribute.............................................................................................................................................................................................done. 12:19:34 INFO - Installing pip.................done. 12:19:34 INFO - Return code: 0 12:19:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:19:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:19:34 INFO - Reading from file tmpfile_stdout 12:19:34 INFO - Output received: 12:19:34 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:19:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:34 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:34 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:19:34 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:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:34 INFO - 'HOME': '/Users/cltbld', 12:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:34 INFO - 'LOGNAME': 'cltbld', 12:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:34 INFO - 'MOZ_NO_REMOTE': '1', 12:19:34 INFO - 'NO_EM_RESTART': '1', 12:19:34 INFO - 'PAGER': '/bin/cat', 12:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:34 INFO - 'PWD': '/builds/slave/test', 12:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:34 INFO - 'SHELL': '/bin/bash', 12:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:34 INFO - 'USER': 'cltbld', 12:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:34 INFO - 'XPC_FLAGS': '0x0', 12:19:34 INFO - 'XPC_SERVICE_NAME': '0', 12:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:34 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:19:34 INFO - Cleaning up... 12:19:34 INFO - Return code: 0 12:19:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:34 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:34 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:19:34 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:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:34 INFO - 'HOME': '/Users/cltbld', 12:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:34 INFO - 'LOGNAME': 'cltbld', 12:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:34 INFO - 'MOZ_NO_REMOTE': '1', 12:19:34 INFO - 'NO_EM_RESTART': '1', 12:19:34 INFO - 'PAGER': '/bin/cat', 12:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:34 INFO - 'PWD': '/builds/slave/test', 12:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:34 INFO - 'SHELL': '/bin/bash', 12:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:34 INFO - 'USER': 'cltbld', 12:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:34 INFO - 'XPC_FLAGS': '0x0', 12:19:34 INFO - 'XPC_SERVICE_NAME': '0', 12:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:34 INFO - Downloading/unpacking psutil>=3.1.1 12:19:34 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:19:34 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:19:34 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:19:34 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:19:34 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:19:34 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:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:19:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:38 INFO - Installing collected packages: psutil 12:19:38 INFO - Running setup.py install for psutil 12:19:38 INFO - building 'psutil._psutil_osx' extension 12:19:38 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:19:39 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:19:39 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:19:39 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:19:39 INFO - building 'psutil._psutil_posix' extension 12:19:39 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:19:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:19:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:19:39 INFO - ^ 12:19:39 INFO - 1 warning generated. 12:19:39 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:19:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:39 INFO - Successfully installed psutil 12:19:39 INFO - Cleaning up... 12:19:39 INFO - Return code: 0 12:19:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:19:39 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:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:39 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:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:39 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:39 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:19:39 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:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:39 INFO - 'HOME': '/Users/cltbld', 12:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:39 INFO - 'LOGNAME': 'cltbld', 12:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:39 INFO - 'MOZ_NO_REMOTE': '1', 12:19:39 INFO - 'NO_EM_RESTART': '1', 12:19:39 INFO - 'PAGER': '/bin/cat', 12:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:39 INFO - 'PWD': '/builds/slave/test', 12:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:39 INFO - 'SHELL': '/bin/bash', 12:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:39 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:39 INFO - 'USER': 'cltbld', 12:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:39 INFO - 'XPC_FLAGS': '0x0', 12:19:39 INFO - 'XPC_SERVICE_NAME': '0', 12:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:19:40 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:19:40 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:19:40 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:19:40 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:19:40 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:19:40 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:19:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:19:42 INFO - Installing collected packages: mozsystemmonitor 12:19:42 INFO - Running setup.py install for mozsystemmonitor 12:19:42 INFO - Successfully installed mozsystemmonitor 12:19:42 INFO - Cleaning up... 12:19:43 INFO - Return code: 0 12:19:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:19: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:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:43 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:43 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:19:43 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:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:43 INFO - 'HOME': '/Users/cltbld', 12:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:43 INFO - 'LOGNAME': 'cltbld', 12:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:43 INFO - 'MOZ_NO_REMOTE': '1', 12:19:43 INFO - 'NO_EM_RESTART': '1', 12:19:43 INFO - 'PAGER': '/bin/cat', 12:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:43 INFO - 'PWD': '/builds/slave/test', 12:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:43 INFO - 'SHELL': '/bin/bash', 12:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:43 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:43 INFO - 'USER': 'cltbld', 12:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:43 INFO - 'XPC_FLAGS': '0x0', 12:19:43 INFO - 'XPC_SERVICE_NAME': '0', 12:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:43 INFO - Downloading/unpacking jsonschema==2.5.1 12:19:43 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:19:43 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:19:43 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:19:43 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:19:43 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:19:43 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:19:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:19:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:19: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:19: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:19: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:19: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:19: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:19: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:19:46 INFO - Downloading functools32-3.2.3-2.tar.gz 12:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:19:46 INFO - warning: no files found matching '*.txt' 12:19:46 INFO - no previously-included directories found matching 'build' 12:19:46 INFO - no previously-included directories found matching 'dist' 12:19:46 INFO - no previously-included directories found matching '.git*' 12:19:46 INFO - Installing collected packages: jsonschema, functools32 12:19:46 INFO - Running setup.py install for functools32 12:19:46 INFO - warning: no files found matching '*.txt' 12:19:46 INFO - no previously-included directories found matching 'build' 12:19:46 INFO - no previously-included directories found matching 'dist' 12:19:46 INFO - no previously-included directories found matching '.git*' 12:19:46 INFO - Successfully installed jsonschema functools32 12:19:46 INFO - Cleaning up... 12:19:46 INFO - Return code: 0 12:19:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:19:46 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:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:46 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:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:46 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:46 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:19:46 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:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:46 INFO - 'HOME': '/Users/cltbld', 12:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:46 INFO - 'LOGNAME': 'cltbld', 12:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:46 INFO - 'MOZ_NO_REMOTE': '1', 12:19:46 INFO - 'NO_EM_RESTART': '1', 12:19:46 INFO - 'PAGER': '/bin/cat', 12:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:46 INFO - 'PWD': '/builds/slave/test', 12:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:46 INFO - 'SHELL': '/bin/bash', 12:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:46 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:46 INFO - 'USER': 'cltbld', 12:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:46 INFO - 'XPC_FLAGS': '0x0', 12:19:46 INFO - 'XPC_SERVICE_NAME': '0', 12:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:19:47 INFO - Cleaning up... 12:19:47 INFO - Return code: 0 12:19:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:47 INFO - 'HOME': '/Users/cltbld', 12:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:47 INFO - 'LOGNAME': 'cltbld', 12:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:47 INFO - 'MOZ_NO_REMOTE': '1', 12:19:47 INFO - 'NO_EM_RESTART': '1', 12:19:47 INFO - 'PAGER': '/bin/cat', 12:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:47 INFO - 'PWD': '/builds/slave/test', 12:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:47 INFO - 'SHELL': '/bin/bash', 12:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:47 INFO - 'USER': 'cltbld', 12:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:47 INFO - 'XPC_FLAGS': '0x0', 12:19:47 INFO - 'XPC_SERVICE_NAME': '0', 12:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:47 INFO - Downloading/unpacking blobuploader==1.2.4 12:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:19:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:19:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:51 INFO - Downloading docopt-0.6.1.tar.gz 12:19:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:19:51 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:51 INFO - Running setup.py install for blobuploader 12:19:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:19:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:19:51 INFO - Running setup.py install for requests 12:19:51 INFO - Running setup.py install for docopt 12:19:52 INFO - Successfully installed blobuploader requests docopt 12:19:52 INFO - Cleaning up... 12:19:52 INFO - Return code: 0 12:19:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:52 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:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:52 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:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:52 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:52 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:19:52 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:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:52 INFO - 'HOME': '/Users/cltbld', 12:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:52 INFO - 'LOGNAME': 'cltbld', 12:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:52 INFO - 'MOZ_NO_REMOTE': '1', 12:19:52 INFO - 'NO_EM_RESTART': '1', 12:19:52 INFO - 'PAGER': '/bin/cat', 12:19:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:52 INFO - 'PWD': '/builds/slave/test', 12:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:52 INFO - 'SHELL': '/bin/bash', 12:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:52 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:52 INFO - 'USER': 'cltbld', 12:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:52 INFO - 'XPC_FLAGS': '0x0', 12:19:52 INFO - 'XPC_SERVICE_NAME': '0', 12:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-_c_qXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-I2eD_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-A0F7A2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-0lqMMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-lvkkIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-dub8lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-k4e04r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZDILnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-uS2Ql9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-9wtU76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-YCc91F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-nZTxJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-GM10wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Xy4iXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-EUDUEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QCOMNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-O9TXNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:54 INFO - Running setup.py install for manifestparser 12:19:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:19:54 INFO - Running setup.py install for mozcrash 12:19:54 INFO - Running setup.py install for mozdebug 12:19:54 INFO - Running setup.py install for mozdevice 12:19:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:19:54 INFO - Running setup.py install for mozfile 12:19:54 INFO - Running setup.py install for mozhttpd 12:19:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Running setup.py install for mozinfo 12:19:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Running setup.py install for mozInstall 12:19:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Running setup.py install for mozleak 12:19:55 INFO - Running setup.py install for mozlog 12:19:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Running setup.py install for moznetwork 12:19:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:19:55 INFO - Running setup.py install for mozprocess 12:19:55 INFO - Running setup.py install for mozprofile 12:19:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:19:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:19:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:19:56 INFO - Running setup.py install for mozrunner 12:19:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:56 INFO - Running setup.py install for mozscreenshot 12:19:56 INFO - Running setup.py install for moztest 12:19:56 INFO - Running setup.py install for mozversion 12:19:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:56 INFO - Cleaning up... 12:19:56 INFO - Return code: 0 12:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:56 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:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:56 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:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:56 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:19:56 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:19:56 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:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:56 INFO - 'HOME': '/Users/cltbld', 12:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:56 INFO - 'LOGNAME': 'cltbld', 12:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:56 INFO - 'MOZ_NO_REMOTE': '1', 12:19:56 INFO - 'NO_EM_RESTART': '1', 12:19:56 INFO - 'PAGER': '/bin/cat', 12:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:56 INFO - 'PWD': '/builds/slave/test', 12:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:56 INFO - 'SHELL': '/bin/bash', 12:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:19:56 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:56 INFO - 'USER': 'cltbld', 12:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:56 INFO - 'XPC_FLAGS': '0x0', 12:19:56 INFO - 'XPC_SERVICE_NAME': '0', 12:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-bwuDys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-b6oA4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-aXX7V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-L9KQJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-eoSUvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QWZ6KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-k9WLwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-wg_DIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-NL9pxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:57 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:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-om5HD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:58 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:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ixpJbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:58 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:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QEwdfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-DykN9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:58 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:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-0FXq6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:58 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:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-P8J2Xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:58 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:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-s10hoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-8bcru6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:58 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:19:58 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:19:58 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:19:58 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:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:01 INFO - Downloading blessings-1.6.tar.gz 12:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:20:01 INFO - Installing collected packages: blessings 12:20:01 INFO - Running setup.py install for blessings 12:20:01 INFO - Successfully installed blessings 12:20:01 INFO - Cleaning up... 12:20:01 INFO - Return code: 0 12:20:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:02 INFO - Reading from file tmpfile_stdout 12:20:02 INFO - Current package versions: 12:20:02 INFO - blessings == 1.6 12:20:02 INFO - blobuploader == 1.2.4 12:20:02 INFO - docopt == 0.6.1 12:20:02 INFO - functools32 == 3.2.3-2 12:20:02 INFO - jsonschema == 2.5.1 12:20:02 INFO - manifestparser == 1.1 12:20:02 INFO - mozInstall == 1.12 12:20:02 INFO - mozcrash == 1.0 12:20:02 INFO - mozdebug == 0.1 12:20:02 INFO - mozdevice == 0.50 12:20:02 INFO - mozfile == 1.2 12:20:02 INFO - mozhttpd == 0.7 12:20:02 INFO - mozinfo == 0.9 12:20:02 INFO - mozleak == 0.1 12:20:02 INFO - mozlog == 3.4 12:20:02 INFO - moznetwork == 0.27 12:20:02 INFO - mozprocess == 0.25 12:20:02 INFO - mozprofile == 0.28 12:20:02 INFO - mozrunner == 6.13 12:20:02 INFO - mozscreenshot == 0.1 12:20:02 INFO - mozsystemmonitor == 0.3 12:20:02 INFO - moztest == 0.8 12:20:02 INFO - mozversion == 1.4 12:20:02 INFO - psutil == 3.1.1 12:20:02 INFO - requests == 1.2.3 12:20:02 INFO - wsgiref == 0.1.2 12:20:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:02 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 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:20:02 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:20:02 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:20:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:20:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:02 INFO - 'HOME': '/Users/cltbld', 12:20:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:02 INFO - 'LOGNAME': 'cltbld', 12:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:02 INFO - 'MOZ_NO_REMOTE': '1', 12:20:02 INFO - 'NO_EM_RESTART': '1', 12:20:02 INFO - 'PAGER': '/bin/cat', 12:20:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:02 INFO - 'PWD': '/builds/slave/test', 12:20:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:02 INFO - 'SHELL': '/bin/bash', 12:20:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:20:02 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:20:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:02 INFO - 'USER': 'cltbld', 12:20:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:02 INFO - 'XPC_FLAGS': '0x0', 12:20:02 INFO - 'XPC_SERVICE_NAME': '0', 12:20:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 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:20:02 INFO - Cleaning up... 12:20:02 INFO - Return code: 0 12:20:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be4fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be52768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f59d4b520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, '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.Ira1QeNAvm/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.4D7b1shEp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:20:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:20:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:20:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:02 INFO - 'HOME': '/Users/cltbld', 12:20:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:02 INFO - 'LOGNAME': 'cltbld', 12:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:02 INFO - 'MOZ_NO_REMOTE': '1', 12:20:02 INFO - 'NO_EM_RESTART': '1', 12:20:02 INFO - 'PAGER': '/bin/cat', 12:20:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:02 INFO - 'PWD': '/builds/slave/test', 12:20:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:02 INFO - 'SHELL': '/bin/bash', 12:20:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:20:02 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:20:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:02 INFO - 'USER': 'cltbld', 12:20:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:02 INFO - 'XPC_FLAGS': '0x0', 12:20:02 INFO - 'XPC_SERVICE_NAME': '0', 12:20:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:20:02 INFO - Cleaning up... 12:20:02 INFO - Return code: 0 12:20:02 INFO - Running post-action listener: _resource_record_post_action 12:20:02 INFO - Running post-action listener: _start_resource_monitoring 12:20:02 INFO - Starting resource monitoring. 12:20:02 INFO - [mozharness: 2017-04-29 19:20:02.688332Z] Finished create-virtualenv step (success) 12:20:02 INFO - [mozharness: 2017-04-29 19:20:02.689312Z] Running install step. 12:20:02 INFO - Running pre-action listener: _resource_record_pre_action 12:20:02 INFO - Running main action method: install 12:20:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:02 INFO - Reading from file tmpfile_stdout 12:20:02 INFO - Detecting whether we're running mozinstall >=1.0... 12:20:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:20:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:20:03 INFO - Reading from file tmpfile_stdout 12:20:03 INFO - Output received: 12:20:03 INFO - Usage: mozinstall [options] installer 12:20:03 INFO - Options: 12:20:03 INFO - -h, --help show this help message and exit 12:20:03 INFO - -d DEST, --destination=DEST 12:20:03 INFO - Directory to install application into. [default: 12:20:03 INFO - "/builds/slave/test"] 12:20:03 INFO - --app=APP Application being installed. [default: firefox] 12:20:03 INFO - mkdir: /builds/slave/test/build/application 12:20:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:20:23 INFO - Reading from file tmpfile_stdout 12:20:23 INFO - Output received: 12:20:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:20:23 INFO - Running post-action listener: _resource_record_post_action 12:20:23 INFO - [mozharness: 2017-04-29 19:20:23.109919Z] Finished install step (success) 12:20:23 INFO - [mozharness: 2017-04-29 19:20:23.110075Z] Running run-tests step. 12:20:23 INFO - Running pre-action listener: _resource_record_pre_action 12:20:23 INFO - Running main action method: run_tests 12:20:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:20:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:20:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:20:23 INFO - 2017-04-29 12:20:23.124 screenresolution[1635:8712] starting screenresolution argv=screenresolution get 12:20:23 INFO - 2017-04-29 12:20:23.127 screenresolution[1635:8712] Display 0: 1600x1200x32@60 12:20:23 INFO - 2017-04-29 12:20:23.132 screenresolution[1636:8714] starting screenresolution argv=screenresolution list 12:20:23 INFO - Available Modes on Display 0 12:20:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:20:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:20:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:20:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:20:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:20:23 INFO - Intel Iris: 12:20:23 INFO - Chipset Model: Intel Iris 12:20:23 INFO - Type: GPU 12:20:23 INFO - Bus: Built-In 12:20:23 INFO - VRAM (Dynamic, Max): 1536 MB 12:20:23 INFO - Vendor: Intel (0x8086) 12:20:23 INFO - Device ID: 0x0a2e 12:20:23 INFO - Revision ID: 0x0009 12:20:23 INFO - Displays: 12:20:23 INFO - Display: 12:20:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:20:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:20:23 INFO - Display Serial Number: 1600x1200 60 12:20:23 INFO - Main Display: Yes 12:20:23 INFO - Mirror: Off 12:20:23 INFO - Online: Yes 12:20:23 INFO - Rotation: Supported 12:20:23 INFO - Return code: 0 12:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:20:23 INFO - Python 2.7.3 12:20:23 INFO - Return code: 0 12:20:23 INFO - grabbing minidump binary from tooltool 12:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10bb53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc22be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:20:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:20:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:20:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:20:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:20:23 INFO - Return code: 0 12:20:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:20:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:20:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:20:23 INFO - ENV: RUST_BACKTRACE is now 1 12:20:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:20:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:20:23 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/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:20:23 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/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ira1QeNAvm/Render', 12:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:23 INFO - 'HOME': '/Users/cltbld', 12:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:23 INFO - 'LOGNAME': 'cltbld', 12:20:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:23 INFO - 'MOZ_NO_REMOTE': '1', 12:20:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:23 INFO - 'NO_EM_RESTART': '1', 12:20:23 INFO - 'PAGER': '/bin/cat', 12:20:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:23 INFO - 'PWD': '/builds/slave/test', 12:20:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:23 INFO - 'RUST_BACKTRACE': '1', 12:20:23 INFO - 'SHELL': '/bin/bash', 12:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4D7b1shEp3/Listeners', 12:20:23 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 12:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:23 INFO - 'USER': 'cltbld', 12:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:23 INFO - 'XPC_FLAGS': '0x0', 12:20:23 INFO - 'XPC_SERVICE_NAME': '0', 12:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:23 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/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:20:23 INFO - mozversion application_buildid: 20170429114946 12:20:23 INFO - mozversion application_changeset: 21640269841ace0b6ab6818f89994558ef984b7a 12:20:23 INFO - mozversion application_display_name: Nightly 12:20:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:20:23 INFO - mozversion application_name: Firefox 12:20:23 INFO - mozversion application_remotingname: firefox 12:20:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:20:23 INFO - mozversion application_vendor: Mozilla 12:20:23 INFO - mozversion application_version: 55.0a1 12:20:23 INFO - mozversion platform_buildid: 20170429114946 12:20:23 INFO - mozversion platform_changeset: 21640269841ace0b6ab6818f89994558ef984b7a 12:20:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:20:23 INFO - mozversion platform_version: 55.0a1 12:20:23 INFO - using testdate: 1493493623 12:20:23 INFO - actual date: 1493493623 12:20:23 INFO - starting webserver on 'localhost:49231' 12:20:23 INFO - SUITE-START | Running 2 tests 12:20:23 INFO - TEST-START | dromaeo_css 12:20:23 INFO - operating with platform_type : mac_ 12:20:23 INFO - Initialising browser for dromaeo_css test... 12:20:23 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpAuVnSR/profile http://localhost:49231/getInfo.html) 12:20:26 INFO - PID 1646 | 2017-04-29 12:20:26.858 plugin-container[1648:8892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a57, name = 'com.apple.tsm.portname' 12:20:26 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:26 INFO - PID 1646 | 2017-04-29 12:20:26.861 plugin-container[1648:8892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b27, name = 'com.apple.CFPasteboardClient' 12:20:26 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:26 INFO - PID 1646 | 2017-04-29 12:20:26.861 plugin-container[1648:8892] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:26 INFO - PID 1646 | __metrics Screen width/height:1600/1200 12:20:26 INFO - PID 1646 | colorDepth:24 12:20:26 INFO - PID 1646 | Browser inner width/height: 1024/689 12:20:26 INFO - PID 1646 | __metrics 12:20:27 INFO - TEST-INFO | 1646: exit 0 12:20:27 INFO - Browser initialized. 12:20:27 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:27 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpAuVnSR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:30 INFO - PID 1650 | 2017-04-29 12:20:30.478 plugin-container[1651:9026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933b, name = 'com.apple.tsm.portname' 12:20:30 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:30 INFO - PID 1650 | 2017-04-29 12:20:30.478 plugin-container[1651:9026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 12:20:30 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:30 INFO - PID 1650 | 2017-04-29 12:20:30.478 plugin-container[1651:9026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:40 INFO - PID 1650 | RSS: Main: 235233280 12:20:40 INFO - PID 1650 | 12:23:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 12:23:45 INFO - PID 1650 | RSS: Main: 251691008 12:23:45 INFO - PID 1650 | 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:25:28 INFO - PID 1650 | 12:25:28 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:25:28 INFO - PID 1650 | Full stack: 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:25:28 INFO - PID 1650 | 12:25:28 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:25:28 INFO - PID 1650 | Full stack: 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:25:28 INFO - PID 1650 | 12:25:28 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:25:28 INFO - PID 1650 | Full stack: 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | ************************* 12:25:28 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:25:28 INFO - PID 1650 | 12:25:28 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:25:28 INFO - PID 1650 | Full stack: 12:25:28 INFO - PID 1650 | ************************* 12:26:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 12:26:48 INFO - PID 1650 | RSS: Main: 257785856 12:26:48 INFO - PID 1650 | 12:29:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 12:29:50 INFO - PID 1650 | RSS: Main: 268361728 12:29:50 INFO - PID 1650 | 12:32:52 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 12:32:53 INFO - PID 1650 | RSS: Main: 260849664 12:32:53 INFO - PID 1650 | 12:35:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 12:35:56 INFO - PID 1650 | RSS: Main: 269979648 12:35:56 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 12:38:58 INFO - PID 1650 | RSS: Main: 264634368 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | __start_tp_report 12:38:58 INFO - PID 1650 | _x_x_mozilla_page_load 12:38:58 INFO - PID 1650 | _x_x_mozilla_page_load_details 12:38:58 INFO - PID 1650 | |i|pagename|runs| 12:38:58 INFO - PID 1650 | |0;dojo.html;3056.297051678114;3574.2315402188524;3591.2458383739413;3605.2068544920107;3606.9458958115624;6070.180525629039;6167.414095660911;6193.535484838638;6197.659128747917;6242.968785156079;6127.586602260202;6147.569670123094;6159.384061593838;6162.445379915806;6198.473129783963;4837.492063333352;4863.708177509361;5272.657277276984;5301.045811753883;5374.838754837351;4734.9109704767925;5340.519353258206;5429.185622156669;5477.643953143053;5491.862703432406;5253.08071087562;5406.405295417501;5423.972880135574;5454.91817622736;5468.56251499881;5335.33308336458;5339.70631615261;5419.810306639249;5421.783128674848;5471.179323101527;5342.973285133549;5370.060239458118;5371.167469042305;5380.596455265871;5395.865103372408;5943.3759455257;6164.506839452833;6169.889419904415;6180.81457556274;6187.257529096492;6411.935880641227;6481.513886458487;6557.967210163919;6683.899741503881;6692.732290708366;6740.32596740327;6759.222689390734;6764.5603035802515;6765.729370825161;6781.966090169567;6226.813195604093;6243.125962365272;6305.527085468608;6309.211348581427;6326.240851097898;6615.63614001225;6662.400383965466;6863.210730765926;6864.7940561783225;6905.37851593359;6408.58344207625;6499.3175716549085;6507.056476810931;6525.4453371463005;6559.836004099936;6699.765508207287;6732.899006514906;6754.18949726036;6763.830904227335;6777.1189745332795;6395.840103997344;6513.641749703812;6649.867002659919;6654.900176497356;6665.366790154928;6474.708638111297;6570.605763654196;6632.369924857131;6643.269240383554;6687.331266873371;5279.973600132052;5292.44429334922;5306.336707911511;5309.230161626542;5331.546818520469;4729.668923175344;4732.100900828831;4733.431988161374;4739.218029025241;4762.119007983534;5031.7987280509105;6044.818655440344;6199;6254.8123556293385;6257.499400047986;6667.399934005963;6707.49693772969;6743.561668491532;6768.800139954436;6784.59292442455;6289.40250676185;6292.119103325538;6311.715972781236;6313.494920406357;6322.683865806691;5629.915551266734;5776.942230577724;5788.710564471759;5853.648781073064;5902.793402230901;6748.561343512754;6837.35045170708;6886.965565172142;6916.066331045245;6957.9304206959305;6518.152640156749;6715.664216789043;6733;6746.595204287759;6782.830429239309;6664.633445160369;6724.663766811737;6796.184457865088;6900;6916.723331066701;6654.800355989328;6664.566803157779;6670.266270710121;6675.966620166868;6678.866422671422;6142.161149281699;6588.901166482602;6653.367930046637;6716.1604799400075;6739.191297044386;4877.853664390074;6418.261899881387;6528.151340325728;6569.770058047945;6572.277049524453;6542.705578249085;6555.082288479522;6565.310642382481;6568.507361947777;6587.374199451045;5299.9470005301;6231.1276421300145;6273;6308.684565771785;6382.234131904202;5435.320584926884;6485.319041500763;6513.120728701565;6570.211574610886;6588.242352129567;6343.397377249154;6354.332795056453;6366.267879193952;6408.455281301141;6410.391012853772 12:38:58 INFO - PID 1650 | |1;ext.html;821.2813787935556;924.6948506992693;1138.1350173867859;1258.1193164784647;1264.1024872340638;36307.09232269195;37093.476119768646;37130;37222.8138859306;37233.81383093084;36981.63018369816;37342.43986340207;37417;37446.62553374466;37528.24943501128;7557;7839.098503672094;7904.446688731791;7990.560519171443;8600.483970961732;8565.700200492984;8615.052344242129;8692.47845129291;8694.174053464942;8700.390972631918;8634.395592308541;8700.869486957701;8700.912990870105;8706.912930870674;8724.607392667314;8625.741227763177;8649.178328058824;8677.913220867773;8691.435056721293;8700.260477859327;8351.039630442516;8403.831923361498;8477.448965817264;8480.236778689887;8485.490870547786;37481.81259093687;37548;37560.81219593912;37582.62417375819;37649.0587735306;3596.7841929484052;3622.6196249393697;3629.274145170976;3630.981845090758;3640.3811352069947;30641.387172256655;30727.38545229083;30788.538171927437;30895.845520772476;30961.535576966362;3146.9842650786827;3232.3858466891215;3242.983785081083;3253.316803471274;3253.9349213015844;287900;288672;289384;289446.9999999979;289775;37890.43164352537;38183.618163818006;46134.07731845344;47658.52341476541;47793.5220647796;49553.513394598216;49826;49945.25082123771;50030;50123.74938125286;14186.929065354814;14222.715545689236;14338.784918226234;14345.569632911029;14356.928215358856;8900.73297801067;10539.63111291101;11360.715982100513;11484.196106272648;11498.195126341243;11054.944725276482;13610;13633.863661363259;13660.726785464236;13675.794863077062;6025.186599809057;6050.727717252734;6087;6093.908591371133;6105.786297479567;1035.1511760356586;1035.6789395287485;1042.1298215989582;1074.5648012554927;1077.876044254905;22431.87840607963;23147.421314467272;24072.759272407402;24332.270031899072;28247;1120.7920396818256;1121.2599684208383;1125.2235957189678;1163.6450882480924;1168.9415529223504;12247;12409.414449046295;12530.81203781944;12544.24734515932;12557.434915428828;297143;298135;299445;299751;300606.49696751375;52645.73677131667;52862;53013.469865301624;53023.20465192948;53147.67130821761;1516.3555488917166;1559.134680252448;1566.3421363027328;1568.7646852972146;1580.123031717385;21470.46323841854;21710.565788684453;23984;24015.159469418486;24060;33287.83356083301;38223;38233.2795024219;38391.808040959615;38397.6160238394;280649;280836;281663;281878;282130;33490.665093348754;34264.828675856465;35888.82055589705;36052.4592131113;36059.27881442409;37734.81160086816;42809;42837.92905177396;42841.35737963995;44661.330080048174;29107.126786196448;29861.70138298589;30502.847485763315;31017.8449107753;31350.216244594067;24860.50278994446;26818.65906704696;30416.239594010327;30770.230744231572;30858.228544286572;29400.7059929398;30354.089377318716;35765.46351804673;36458.63541364553;37413.81293093517;29286.41427171487;29511.26221844471;30794.692053079183;31444.842775785975;31463.68536314699;21483.71097734141;23293.650595240746;23915;24514.50970980535;24991.375215619028 12:38:58 INFO - PID 1650 | |2;jquery.html;381.8392088052321;438.54610478155104;440.6474820143884;452.2628116170639;454.15753776744134;20323.59352812943;20931;22235;22344.21795237167;22455.21406750764;21805.890970545166;22713;22846.77153228467;22886.427839304026;22959.42601434965;5863.648181109136;6087.360827113156;6088.391160883921;6095.878082438348;6100.023996160615;5814.360420353758;5937.37657545958;5985.4912332451795;6035.577509574332;6041.818745437622;5956;5973.820785376424;5985.700714964256;5987.311459182184;6009.819705408845;5773.56698247631;5840.707964601774;5865.296164460292;5911.408859114075;5928.377520360386;4249.490061192646;4430.1582699287355;5249.976254630348;5260.132078207092;5329.333833270841;25365;25469.962403007714;26003.60994585083;26430.207093787216;26885.32786680329;2174.325958952714;2228.799408053283;2231.1187081103035;2235.798778109978;2244.450109723128;13427.86572134276;17222.483325500256;17579.73630395545;17723.911380443016;17795.73306400405;2057.1051592557287;2136.75427325858;2155.4288113649895;2162.826973842089;2163.437506248387;150636.49363506335;153432.2328388362;155182;156103.21948390186;164806.9999999988;11395.886041139649;11976.461059252355;12159.452824622913;12190.695232619255;12246.938765306117;15823.762643560356;16026.599335016484;16053.270943939558;16108.355665773472;16125.838741612668;8698.695545655872;8901.154390332907;8914.55427228649;8925.054731938444;9055.094490551024;8472.940882389701;8481.067082620906;8497.022842373082;8541.359398045171;8626.741197764077;7622.047244094489;7748.728794492165;7761.8447631047065;7795.688172473037;7802.141764405911;1708.0605666883164;1846.8799528030636;2096.454921720333;2113.968290475644;2306.9077236910334;962.1965658674944;971.2181693736469;986.9636881274633;998.2779705008802;1014.1227837920146;20707.4823129423;20755.066022029045;20821.68767468489;20910;20925.162993480393;924.3945215883607;993.5777294649745;1012.9898701012895;1022.8084281811606;1034.8292531732332;8222.753317400488;9367.718968430958;9485.383450075724;10244.01450303147;10856.891431085745;531495;535380;547625;551459;552421;113015;125093;125141.37429312977;127960.36019819841;128829;1024.7284469615558;1173.1464286427424;1233.8889499945228;1241.8944389726794;1247.5882958623813;21406.464838379165;21763.782362176808;21792.78207217971;22018.779812202312;22046;56227.71886140543;56282.43717562772;57887.421125788205;57934.4206557929;58748.4125158743;79272;80425.59787201027;80699.19300806917;80829.59585202036;82069.17930820854;8682.34882383811;8892;8928.375013748975;8976.237019853386;9053.68312109073;13423.328833558478;13497.055206135476;14223.71986521218;14505.637359066108;14723.92638036803;10355.482225888825;10654.360738355723;11968.640940771791;12014.399280035788;12044.879551204725;11218.99029087386;11323.547058117912;11417.1437142213;11681.948624623825;11790.351530665896;11889.702757430787;12091.08370903363;12177.817332740193;12304.077194210291;12337.814932776195;8623.353248506262;8634.56827158652;8679.479231245892;8900.955495222483;9059.22996545301;9732.464714440774;11864.22882512634;11943.641690749291;12082.637520874388;12112.939435302767 12:38:58 INFO - PID 1650 | |3;mootools.html;1373.9244341561216;1399.9160050396983;1424.39463228128;1426.5791591480513;1435.0958895895587;11335.319880807147;11721.648350549498;11840.881591184085;11843.170978031541;12109.636710898665;11720.648380548597;11857;11871.584494542692;11874.16880818343;11887.524499020028;2529.1021687301018;2543.4531575711217;2570.421655127591;2572.5755250383663;2573.4724381501746;2497.1634502441702;2551.107112510616;2555.3483861615264;2557.8593177375237;2561.9615705764427;2488.875556222191;2514.585093459577;2515.6478093066894;2543.160756950211;2549.146036077907;2470.962935555968;2498.475320182773;2536.365908522869;2536.4927014597156;2541.6187571864184;2402.579548579009;2509.2095989763166;2514.421683012899;2515.207709571479;2523.4826860493554;10254.692359229235;11689.298642081418;11760;11873.287602743776;11908.821367679406;5794.362620111784;6587.93412065878;6846.760363387308;6866.244713081557;6868.759593414206;3889.9886029632216;3895.6299151580556;4377.715448495869;4427.712198707074;4456.242438785414;1272.3193091695991;1279.405076639367;1290.1098242212936;1323.7881938889732;1323.9669008274773;15775.447859324984;16329.755053674204;16393.262303196385;16447.83552164475;16487.587810304838;5520.613557051048;5525.806596769094;5644.588852786804;5663.527482854405;5788.82633520995;11740.823887641693;11746.647600571996;11837.526498939946;11856.525738970517;11862.822057669142;6527.412532872065;6548.8035358939305;6590.439812615981;6602.900956485657;6606.306337834458;6153.29237137726;6193.442590166816;6226.25284965798;6279.466245369194;6284.340144284784;3729.987184146592;4277.695302932635;4372.366006928977;4396.60430561251;4412.183746006999;1087.6845714742635;1265.1207410849372;1281.135233717237;1294.113532230425;1298.9480420783252;792.8453951479521;815.1807433529265;830.3689196210923;833.9708110216113;852.1606217875387;5822.243108395882;6072.392760723892;6809.965950170316;6881.552699074544;7050.894236586455;582.7756313819125;601.3865856826013;609.2475792396386;610.3408319015452;615.802943058226;3611.6930060944587;4116.1767646470225;4178.456800615962;4375.16871794358;4456.242438785414;14403.567892963229;14875.107493550422;14891.70216595662;14937.925310373379;15017.849821501864;14552.563423097325;14668.926655366655;14923.40306387754;14927.626809329853;14953.40186392554;7235.855282894313;7526.473146879666;8179.713710020121;8661.740147795575;8680.609372578123;6619.0071489277;7142.64286785669;7146.535475194096;7216.386607138452;7227.349538541556;10639.882812304597;12597.244165350108;12602.747945041232;12606.936965315115;12638.431270592662;12575.245485270912;12902.612921612366;12948.676283092998;12949.223046617233;12987.220766754024;8208.58957052133;8673.826523469621;9358.625654973997;9471.289653275893;9472.336936414385;10936.78126437451;11011.394373309546;11024.007839294502;11026.173037022096;11067.612633557786;8158.5920703963375;8473.66105355779;8568.957155214184;8644.178803013956;8680.392372534114;8827.293816494794;8858.601362938554;8917.509536975529;8980.640774369213;9063.818723625622;6899.931000689929;8161.918380816116;8235.83528329442;8299.83400332002;8319;6043.12374705674;6137.41694539018;6140.078988151813;6188.783392581238;6260.968695156495;6075.483583895241;6078.149059131637;6177.227846519185;6221.5333849962335;6348.873022539616 12:38:58 INFO - PID 1650 | |4;prototype.html;386.69644329201566;408.7956021989005;410.597614337949;414.8236999275309;417.7577005336904;5988.161657367972;6158.137860699506;6165.445109940105;6253.874922501547;6261.43647071764;5937.821865344046;6070.9392906070925;6087.665178415185;6103.69481525923;6146.569740118193;4667.579917807397;4673.626109911199;4676.929846052312;4688.953110468903;4694.929576056362;4619.607333376667;4653.115907977491;4678.087772884289;4705.647076469261;4707.670463067587;3773.754705944105;4557.840475583355;4574.565416285464;4638.837640682576;4653.27874179501;4060.3503439449546;4613.385315139691;4643.907121857561;4653.464851542082;4687.9531204687855;4010.1177740896956;4110.34234522478;4133.276676581586;4147.543770185277;4151.243124971906;4729.314249433813;5702.743376548024;5811.941880581182;6006.21919150512;6061.454469097758;4943.876403089916;5044.6973181608855;5129.435762066169;5583.441655834425;5588.4411558844195;6422.935770642281;6551.213854337462;6575.868482630369;6578.901316480256;6618.815790789076;4329.848455304049;4329.917520619845;4331.545187755303;4332.241857674894;4335.891602709926;6810.250872404032;6821.147356580427;6920.204176519715;7004.614746188958;7013.649317534103;4358.585934336233;4362.847300344472;4372.387865698741;4376.256036473807;4380.540043295472;3941.192055628589;4074.1647962167685;4083.9381760741826;4196.769177695195;4435.689501734848;4758.288534942751;5061.7975280989085;5192.402873669501;5218.66078704883;5354.00950824091;5127;5698.772049118072;6631.938889777709;6645.205794435476;6650.733970641217;1376.717772856562;1472.4625511688157;1496.2668292536578;1508.615303097703;1518.5444366689949;867.7136544940155;894.4499133033268;962.5812771444304;982.7617202324979;983.9655612053543;5878.500327472213;6185.8453538661;6196.690165491707;6203.503719702503;6213.63300073983;6254.6872656366995;6737.292584278678;6854.931450685529;6856.760013399607;6899.068625735562;5691.516221121251;6147.510258345377;6159.784407545714;6171.981623032239;6205.596636218631;2965.9110226693233;3432.7253819694365;3531.768431884848;3568.518250036264;3570.768085010667;5867.853303667357;6663.733450662016;7018.894716579255;7027.262137475594;7035.507514473938;5173.81891633791;5668.376478587352;6396.921000674799;6835.555688880207;6979.9409174293705;7016.298370163045;7088.503804733619;7093.716251349888;7115.146182458035;7142.428605711634;5923.851903702442;6701.262861085179;6757.729690812313;6844.794656160323;6879.243283238739;5361.51746342831;5558.499735023867;5838.620489668158;6769.559978601375;6831.59010459374;6890.483213759088;6998.675629076148;7104.680289387094;7108.3602475777425;7115.857682846417;4317.805698743501;4355.73865568067;4363.476382833953;4374.693771435969;4388.71473354231;6843.315668433117;6878.55289406187;6898.379145876896;6912.516123871281;6939.167299924042;5148.407933087743;6373;6592;6602.1747281589805;6629.005649152666;6106.267247930099;6524.869502609827;6564.671766411756;6568.507361947969;6631.204255489372;5212.635115541876;6025.307089684742;6274.905876411949;6444.452221561032;6526.804195874131;5735;6510.707018184288;6553.148090748172;6600.372964568358;6605.834854128493;4682.367880336247;5057.494250574913;6138.404014956054;6404.558974230761;6418.4544313731985 12:38:58 INFO - PID 1650 | |5;yui.html;795.5505139596128;852.4160949749426;854.6239654551997;876.2814492116468;888.0187392930811;16340.754888676665;16754.999999999985;16788.664226715475;18408.447746567625;19437.902810485964;17761.91119044403;18083.367082152126;18129.00290484023;18234.361797337064;18464.98442585657;977.7408986618552;992.5186284651957;1005.2109094360928;1008.7276435362447;1014.8173328800813;1020.6325722739808;1021.9948900255488;1023.9897601024006;1024.4058446101244;1026.440589878518;1043.958241670336;1073.88187299397;1076.8492411062457;1077.2566928819128;1086.0876863434714;1062.0706881478707;1068.0547715271962;1075.1237741240864;1076.682378698286;1078.487718333793;776.5107981971379;784.1883650421843;786.1627366854339;788.6490511722286;792.7859477940981;16773.91613041939;17080.402185923558;17234.96590204592;17258.913705431518;17268.740968885475;16973.745393819103;17149.485515434557;17217.91391043037;17502.737458938125;17671.823281767145;13789.172649641054;13932.442702291895;13972.091814032157;14140.575782726535;14159.22124283164;3465.6880880720855;3538.115471132217;3544.0962554548682;3548.183917698944;3551.058969373118;111895;120442;134670;139785;144306;13062.477500900048;13115.081944264;13132.934335328262;13209.207447553177;13342.399592018382;25519.87240063825;25665;25676.8716156418;25718.871405643225;25844;7547.924520754832;7577.052868391451;7602.391808655294;7665.386769058462;7679.308862202429;3933.940990885139;3960.524737031517;4004.4193591929006;4018;4032.6572241359813;3798.810059497014;4320.567943205654;4373.147236288957;4397.73613583186;4429.424174857248;880.5201165364891;884.9778755531163;889.675268526996;893.3344658229661;897.9910200898038;648.1735786871682;672.7241830849329;677.11298199359;687.1788213085315;703.7536862098327;10284.228682848667;11007;12028.939855300667;12034.81947770784;12151.453184606715;650.4341223135903;661.9569727967666;667.2693400726197;671.664167916042;675.864827034595;4132.855350062793;5517.282753242052;5517.303182829756;5628.60599758013;5669.546436285087;106760;106803;108889.27776805643;109055;109850.45074774575;88447.11552884517;98343.03313933681;99681.50159249158;100124;102359.46460803093;74681.62659186669;87659;88309.55845220733;88410.55794720985;88973;9400.952995235253;12888.935555322163;12889.484420623445;12938.805917911051;13008.089433739548;39475.223614936825;39875.800620996706;41220.1755964885;41542.338306467405;42047.3692894613;51507.48492515027;70968.64515677388;72039.27960720325;73787.26212738019;75944.86082708875;16376.918115409348;16878;17048.65902681964;17050.06224657606;17174.39889603858;17918;18247.81752182461;18466.81533184651;18499.260029598667;18628.348007820205;16988.40540581123;17043.892147010403;17186.96878187313;17214.139293035547;17633.118344082486;13565.728685426038;15751.44869929547;15846.366145354517;16372.75440868364;16421.58946026359;15934.760978585562;16253.51239462818;16672.416465423652;16779.496615101565;16879.071651058817;10121.88659247497;11579.07367410622;11623.76752464963;11837.408129593658;12219.450124744586;12049;12983.805242921553;12988.220706757626;13018.609441716764;13031.543895963596 12:38:58 INFO - PID 1650 | __end_tp_report 12:38:58 INFO - PID 1650 | __start_cc_report 12:38:58 INFO - PID 1650 | _x_x_mozilla_cycle_collect,421 12:38:58 INFO - PID 1650 | __end_cc_report 12:38:58 INFO - PID 1650 | __startTimestamp1493494738291__endTimestamp 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | ------- Summary: start ------- 12:38:58 INFO - PID 1650 | Number of tests: 6 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6101.49 Median:6343.40 stddev:758.59 (12.0%) stddev-sans-first:722.32 12:38:58 INFO - PID 1650 | Values: 3056.3 3574.2 3591.2 3605.2 3606.9 6070.2 6167.4 6193.5 6197.7 6243.0 6127.6 6147.6 6159.4 6162.4 6198.5 4837.5 4863.7 5272.7 5301.0 5374.8 4734.9 5340.5 5429.2 5477.6 5491.9 5253.1 5406.4 5424.0 5454.9 5468.6 5335.3 5339.7 5419.8 5421.8 5471.2 5343.0 5370.1 5371.2 5380.6 5395.9 5943.4 6164.5 6169.9 6180.8 6187.3 6411.9 6481.5 6558.0 6683.9 6692.7 6740.3 6759.2 6764.6 6765.7 6782.0 6226.8 6243.1 6305.5 6309.2 6326.2 6615.6 6662.4 6863.2 6864.8 6905.4 6408.6 6499.3 6507.1 6525.4 6559.8 6699.8 6732.9 6754.2 6763.8 6777.1 6395.8 6513.6 6649.9 6654.9 6665.4 6474.7 6570.6 6632.4 6643.3 6687.3 5280.0 5292.4 5306.3 5309.2 5331.5 4729.7 4732.1 4733.4 4739.2 4762.1 5031.8 6044.8 6199.0 6254.8 6257.5 6667.4 6707.5 6743.6 6768.8 6784.6 6289.4 6292.1 6311.7 6313.5 6322.7 5629.9 5776.9 5788.7 5853.6 5902.8 6748.6 6837.4 6887.0 6916.1 6957.9 6518.2 6715.7 6733.0 6746.6 6782.8 6664.6 6724.7 6796.2 6900.0 6916.7 6654.8 6664.6 6670.3 6676.0 6678.9 6142.2 6588.9 6653.4 6716.2 6739.2 4877.9 6418.3 6528.2 6569.8 6572.3 6542.7 6555.1 6565.3 6568.5 6587.4 5299.9 6231.1 6273.0 6308.7 6382.2 5435.3 6485.3 6513.1 6570.2 6588.2 6343.4 6354.3 6366.3 6408.5 6410.4 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43851.05 Median:3237.68 stddev:75911.11 (2344.6%) stddev-sans-first:76055.31 12:38:58 INFO - PID 1650 | Values: 821.3 924.7 1138.1 1258.1 1264.1 36307.1 37093.5 37130.0 37222.8 37233.8 36981.6 37342.4 37417.0 37446.6 37528.2 7557.0 7839.1 7904.4 7990.6 8600.5 8565.7 8615.1 8692.5 8694.2 8700.4 8634.4 8700.9 8700.9 8706.9 8724.6 8625.7 8649.2 8677.9 8691.4 8700.3 8351.0 8403.8 8477.4 8480.2 8485.5 37481.8 37548.0 37560.8 37582.6 37649.1 3596.8 3622.6 3629.3 3631.0 3640.4 30641.4 30727.4 30788.5 30895.8 30961.5 3147.0 3232.4 3243.0 3253.3 3253.9 287900.0 288672.0 289384.0 289447.0 289775.0 37890.4 38183.6 46134.1 47658.5 47793.5 49553.5 49826.0 49945.3 50030.0 50123.7 14186.9 14222.7 14338.8 14345.6 14356.9 8900.7 10539.6 11360.7 11484.2 11498.2 11054.9 13610.0 13633.9 13660.7 13675.8 6025.2 6050.7 6087.0 6093.9 6105.8 1035.2 1035.7 1042.1 1074.6 1077.9 22431.9 23147.4 24072.8 24332.3 28247.0 1120.8 1121.3 1125.2 1163.6 1168.9 12247.0 12409.4 12530.8 12544.2 12557.4 297143.0 298135.0 299445.0 299751.0 300606.5 52645.7 52862.0 53013.5 53023.2 53147.7 1516.4 1559.1 1566.3 1568.8 1580.1 21470.5 21710.6 23984.0 24015.2 24060.0 33287.8 38223.0 38233.3 38391.8 38397.6 280649.0 280836.0 281663.0 281878.0 282130.0 33490.7 34264.8 35888.8 36052.5 36059.3 37734.8 42809.0 42837.9 42841.4 44661.3 29107.1 29861.7 30502.8 31017.8 31350.2 24860.5 26818.7 30416.2 30770.2 30858.2 29400.7 30354.1 35765.5 36458.6 37413.8 29286.4 29511.3 30794.7 31444.8 31463.7 21483.7 23293.7 23915.0 24514.5 24991.4 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35317.33 Median:22779.89 stddev:92298.47 (405.2%) stddev-sans-first:92520.12 12:38:58 INFO - PID 1650 | Values: 381.8 438.5 440.6 452.3 454.2 20323.6 20931.0 22235.0 22344.2 22455.2 21805.9 22713.0 22846.8 22886.4 22959.4 5863.6 6087.4 6088.4 6095.9 6100.0 5814.4 5937.4 5985.5 6035.6 6041.8 5956.0 5973.8 5985.7 5987.3 6009.8 5773.6 5840.7 5865.3 5911.4 5928.4 4249.5 4430.2 5250.0 5260.1 5329.3 25365.0 25470.0 26003.6 26430.2 26885.3 2174.3 2228.8 2231.1 2235.8 2244.5 13427.9 17222.5 17579.7 17723.9 17795.7 2057.1 2136.8 2155.4 2162.8 2163.4 150636.5 153432.2 155182.0 156103.2 164807.0 11395.9 11976.5 12159.5 12190.7 12246.9 15823.8 16026.6 16053.3 16108.4 16125.8 8698.7 8901.2 8914.6 8925.1 9055.1 8472.9 8481.1 8497.0 8541.4 8626.7 7622.0 7748.7 7761.8 7795.7 7802.1 1708.1 1846.9 2096.5 2114.0 2306.9 962.2 971.2 987.0 998.3 1014.1 20707.5 20755.1 20821.7 20910.0 20925.2 924.4 993.6 1013.0 1022.8 1034.8 8222.8 9367.7 9485.4 10244.0 10856.9 531495.0 535380.0 547625.0 551459.0 552421.0 113015.0 125093.0 125141.4 127960.4 128829.0 1024.7 1173.1 1233.9 1241.9 1247.6 21406.5 21763.8 21792.8 22018.8 22046.0 56227.7 56282.4 57887.4 57934.4 58748.4 79272.0 80425.6 80699.2 80829.6 82069.2 8682.3 8892.0 8928.4 8976.2 9053.7 13423.3 13497.1 14223.7 14505.6 14723.9 10355.5 10654.4 11968.6 12014.4 12044.9 11219.0 11323.5 11417.1 11681.9 11790.4 11889.7 12091.1 12177.8 12304.1 12337.8 8623.4 8634.6 8679.5 8901.0 9059.2 9732.5 11864.2 11943.6 12082.6 12112.9 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7028.33 Median:3670.84 stddev:4405.45 (120.0%) stddev-sans-first:4397.32 12:38:58 INFO - PID 1650 | Values: 1373.9 1399.9 1424.4 1426.6 1435.1 11335.3 11721.6 11840.9 11843.2 12109.6 11720.6 11857.0 11871.6 11874.2 11887.5 2529.1 2543.5 2570.4 2572.6 2573.5 2497.2 2551.1 2555.3 2557.9 2562.0 2488.9 2514.6 2515.6 2543.2 2549.1 2471.0 2498.5 2536.4 2536.5 2541.6 2402.6 2509.2 2514.4 2515.2 2523.5 10254.7 11689.3 11760.0 11873.3 11908.8 5794.4 6587.9 6846.8 6866.2 6868.8 3890.0 3895.6 4377.7 4427.7 4456.2 1272.3 1279.4 1290.1 1323.8 1324.0 15775.4 16329.8 16393.3 16447.8 16487.6 5520.6 5525.8 5644.6 5663.5 5788.8 11740.8 11746.6 11837.5 11856.5 11862.8 6527.4 6548.8 6590.4 6602.9 6606.3 6153.3 6193.4 6226.3 6279.5 6284.3 3730.0 4277.7 4372.4 4396.6 4412.2 1087.7 1265.1 1281.1 1294.1 1298.9 792.8 815.2 830.4 834.0 852.2 5822.2 6072.4 6810.0 6881.6 7050.9 582.8 601.4 609.2 610.3 615.8 3611.7 4116.2 4178.5 4375.2 4456.2 14403.6 14875.1 14891.7 14937.9 15017.8 14552.6 14668.9 14923.4 14927.6 14953.4 7235.9 7526.5 8179.7 8661.7 8680.6 6619.0 7142.6 7146.5 7216.4 7227.3 10639.9 12597.2 12602.7 12606.9 12638.4 12575.2 12902.6 12948.7 12949.2 12987.2 8208.6 8673.8 9358.6 9471.3 9472.3 10936.8 11011.4 11024.0 11026.2 11067.6 8158.6 8473.7 8569.0 8644.2 8680.4 8827.3 8858.6 8917.5 8980.6 9063.8 6899.9 8161.9 8235.8 8299.8 8319.0 6043.1 6137.4 6140.1 6188.8 6261.0 6075.5 6078.1 6177.2 6221.5 6348.9 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5287.17 Median:5997.19 stddev:1673.93 (27.9%) stddev-sans-first:1637.72 12:38:58 INFO - PID 1650 | Values: 386.7 408.8 410.6 414.8 417.8 5988.2 6158.1 6165.4 6253.9 6261.4 5937.8 6070.9 6087.7 6103.7 6146.6 4667.6 4673.6 4676.9 4689.0 4694.9 4619.6 4653.1 4678.1 4705.6 4707.7 3773.8 4557.8 4574.6 4638.8 4653.3 4060.4 4613.4 4643.9 4653.5 4688.0 4010.1 4110.3 4133.3 4147.5 4151.2 4729.3 5702.7 5811.9 6006.2 6061.5 4943.9 5044.7 5129.4 5583.4 5588.4 6422.9 6551.2 6575.9 6578.9 6618.8 4329.8 4329.9 4331.5 4332.2 4335.9 6810.3 6821.1 6920.2 7004.6 7013.6 4358.6 4362.8 4372.4 4376.3 4380.5 3941.2 4074.2 4083.9 4196.8 4435.7 4758.3 5061.8 5192.4 5218.7 5354.0 5127.0 5698.8 6631.9 6645.2 6650.7 1376.7 1472.5 1496.3 1508.6 1518.5 867.7 894.4 962.6 982.8 984.0 5878.5 6185.8 6196.7 6203.5 6213.6 6254.7 6737.3 6854.9 6856.8 6899.1 5691.5 6147.5 6159.8 6172.0 6205.6 2965.9 3432.7 3531.8 3568.5 3570.8 5867.9 6663.7 7018.9 7027.3 7035.5 5173.8 5668.4 6396.9 6835.6 6979.9 7016.3 7088.5 7093.7 7115.1 7142.4 5923.9 6701.3 6757.7 6844.8 6879.2 5361.5 5558.5 5838.6 6769.6 6831.6 6890.5 6998.7 7104.7 7108.4 7115.9 4317.8 4355.7 4363.5 4374.7 4388.7 6843.3 6878.6 6898.4 6912.5 6939.2 5148.4 6373.0 6592.0 6602.2 6629.0 6106.3 6524.9 6564.7 6568.5 6631.2 5212.6 6025.3 6274.9 6444.5 6526.8 5735.0 6510.7 6553.1 6600.4 6605.8 4682.4 5057.5 6138.4 6404.6 6418.5 12:38:58 INFO - PID 1650 | 12:38:58 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:22773.52 Median:17652.47 stddev:32583.20 (184.6%) stddev-sans-first:32632.81 12:38:58 INFO - PID 1650 | Values: 795.6 852.4 854.6 876.3 888.0 16340.8 16755.0 16788.7 18408.4 19437.9 17761.9 18083.4 18129.0 18234.4 18465.0 977.7 992.5 1005.2 1008.7 1014.8 1020.6 1022.0 1024.0 1024.4 1026.4 1044.0 1073.9 1076.8 1077.3 1086.1 1062.1 1068.1 1075.1 1076.7 1078.5 776.5 784.2 786.2 788.6 792.8 16773.9 17080.4 17235.0 17258.9 17268.7 16973.7 17149.5 17217.9 17502.7 17671.8 13789.2 13932.4 13972.1 14140.6 14159.2 3465.7 3538.1 3544.1 3548.2 3551.1 111895.0 120442.0 134670.0 139785.0 144306.0 13062.5 13115.1 13132.9 13209.2 13342.4 25519.9 25665.0 25676.9 25718.9 25844.0 7547.9 7577.1 7602.4 7665.4 7679.3 3933.9 3960.5 4004.4 4018.0 4032.7 3798.8 4320.6 4373.1 4397.7 4429.4 880.5 885.0 889.7 893.3 898.0 648.2 672.7 677.1 687.2 703.8 10284.2 11007.0 12028.9 12034.8 12151.5 650.4 662.0 667.3 671.7 675.9 4132.9 5517.3 5517.3 5628.6 5669.5 106760.0 106803.0 108889.3 109055.0 109850.5 88447.1 98343.0 99681.5 100124.0 102359.5 74681.6 87659.0 88309.6 88410.6 88973.0 9401.0 12888.9 12889.5 12938.8 13008.1 39475.2 39875.8 41220.2 41542.3 42047.4 51507.5 70968.6 72039.3 73787.3 75944.9 16376.9 16878.0 17048.7 17050.1 17174.4 17918.0 18247.8 18466.8 18499.3 18628.3 16988.4 17043.9 17187.0 17214.1 17633.1 13565.7 15751.4 15846.4 16372.8 16421.6 15934.8 16253.5 16672.4 16779.5 16879.1 10121.9 11579.1 11623.8 11837.4 12219.5 12049.0 12983.8 12988.2 13018.6 13031.5 12:38:58 INFO - PID 1650 | -------- Summary: end -------- 12:38:58 INFO - PID 1650 | 12:38:58 INFO - TEST-INFO | 1650: exit 0 12:38:58 INFO - TEST-OK | dromaeo_css | took 1115173ms 12:38:58 INFO - TEST-START | kraken 12:38:58 INFO - operating with platform_type : mac_ 12:38:58 INFO - Initialising browser for kraken test... 12:38:58 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpeMRUaD/profile http://localhost:49231/getInfo.html) 12:39:01 INFO - PID 1661 | 2017-04-29 12:39:01.879 plugin-container[1662:11377] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 12:39:01 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:01 INFO - PID 1661 | 2017-04-29 12:39:01.882 plugin-container[1662:11377] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 12:39:01 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:01 INFO - PID 1661 | 2017-04-29 12:39:01.882 plugin-container[1662:11377] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:01 INFO - PID 1661 | __metrics Screen width/height:1600/1200 12:39:01 INFO - PID 1661 | colorDepth:24 12:39:01 INFO - PID 1661 | Browser inner width/height: 1024/689 12:39:01 INFO - PID 1661 | __metrics 12:39:03 INFO - TEST-INFO | 1661: exit 0 12:39:03 INFO - Browser initialized. 12:39:03 INFO - Running cycle 1/1 for kraken test... 12:39:03 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpeMRUaD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:05 INFO - PID 1663 | 2017-04-29 12:39:05.401 plugin-container[1664:11503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 12:39:05 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:05 INFO - PID 1663 | 2017-04-29 12:39:05.401 plugin-container[1664:11503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 12:39:05 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:05 INFO - PID 1663 | 2017-04-29 12:39:05.402 plugin-container[1664:11503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:15 INFO - PID 1663 | RSS: Main: 234848256 12:39:15 INFO - PID 1663 | 12:39:18 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 12:39:19 INFO - PID 1663 | RSS: Main: 247885824 12:39:19 INFO - PID 1663 | 12:39:21 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 12:39:22 INFO - PID 1663 | RSS: Main: 256606208 12:39:22 INFO - PID 1663 | 12:39:25 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 12:39:26 INFO - PID 1663 | RSS: Main: 258654208 12:39:26 INFO - PID 1663 | 12:39:28 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 12:39:29 INFO - PID 1663 | RSS: Main: 259907584 12:39:29 INFO - PID 1663 | 12:39:30 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:31 INFO - PID 1663 | RSS: Main: 261763072 12:39:31 INFO - PID 1663 | 12:39:34 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:35 INFO - PID 1663 | RSS: Main: 267374592 12:39:35 INFO - PID 1663 | 12:39:37 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:38 INFO - PID 1663 | RSS: Main: 269307904 12:39:38 INFO - PID 1663 | 12:39:41 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 12:39:41 INFO - PID 1663 | RSS: Main: 268537856 12:39:41 INFO - PID 1663 | 12:39:43 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:44 INFO - PID 1663 | RSS: Main: 268087296 12:39:44 INFO - PID 1663 | 12:39:45 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:46 INFO - PID 1663 | RSS: Main: 267862016 12:39:46 INFO - PID 1663 | 12:39:48 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:49 INFO - PID 1663 | RSS: Main: 267649024 12:39:49 INFO - PID 1663 | 12:39:51 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:52 INFO - PID 1663 | RSS: Main: 267702272 12:39:52 INFO - PID 1663 | 12:39:55 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:56 INFO - PID 1663 | RSS: Main: 268005376 12:39:56 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 12:39:58 INFO - PID 1663 | RSS: Main: 269766656 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | __start_tp_report 12:39:58 INFO - PID 1663 | _x_x_mozilla_page_load 12:39:58 INFO - PID 1663 | _x_x_mozilla_page_load_details 12:39:58 INFO - PID 1663 | |i|pagename|runs| 12:39:58 INFO - PID 1663 | |0;ai-astar;121;101;160;169;156;114;119;164;93;158 12:39:58 INFO - PID 1663 | |1;audio-beat-detection;148;111;118;178;93;149;146;146;157;136 12:39:58 INFO - PID 1663 | |2;audio-dft;202;203;210;232;195;200;195;197;199;198 12:39:58 INFO - PID 1663 | |3;audio-fft;78;58;58;84;58;58;68;57;79;57 12:39:58 INFO - PID 1663 | |4;audio-oscillator;76;69;82;72;69;70;86;70;75;118 12:39:58 INFO - PID 1663 | |5;imaging-gaussian-blur;89;89;88;92;90;88;98;89;90;88 12:39:58 INFO - PID 1663 | |6;imaging-darkroom;96;96;96;96;96;95;96;96;96;96 12:39:58 INFO - PID 1663 | |7;imaging-desaturate;91;94;92;92;90;93;91;93;91;88 12:39:58 INFO - PID 1663 | |8;json-parse-financial;50;50;59;45;47;50;51;49;49;51 12:39:58 INFO - PID 1663 | |9;json-stringify-tinderbox;57;62;54;56;61;57;56;55;54;55 12:39:58 INFO - PID 1663 | |10;stanford-crypto-aes;81;84;84;84;85;84;82;86;82;87 12:39:58 INFO - PID 1663 | |11;stanford-crypto-ccm;132;137;139;144;150;150;141;147;198;152 12:39:58 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;217;185;180;178;222;232;211;185;230;172 12:39:58 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;70;70;69;67;81;68;65;67;70;65 12:39:58 INFO - PID 1663 | __end_tp_report 12:39:58 INFO - PID 1663 | __start_cc_report 12:39:58 INFO - PID 1663 | _x_x_mozilla_cycle_collect,989 12:39:58 INFO - PID 1663 | __end_cc_report 12:39:58 INFO - PID 1663 | __startTimestamp1493494798571__endTimestamp 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | ------- Summary: start ------- 12:39:58 INFO - PID 1663 | Number of tests: 14 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:135.50 Median:159.00 stddev:28.68 (18.0%) stddev-sans-first:29.94 12:39:58 INFO - PID 1663 | Values: 121.0 101.0 160.0 169.0 156.0 114.0 119.0 164.0 93.0 158.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:148.50 stddev:24.65 (16.6%) stddev-sans-first:25.89 12:39:58 INFO - PID 1663 | Values: 148.0 111.0 118.0 178.0 93.0 149.0 146.0 146.0 157.0 136.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:203.10 Median:201.00 stddev:11.08 (5.5%) stddev-sans-first:11.74 12:39:58 INFO - PID 1663 | Values: 202.0 203.0 210.0 232.0 195.0 200.0 195.0 197.0 199.0 198.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:65.50 Median:63.00 stddev:10.83 (17.2%) stddev-sans-first:10.51 12:39:58 INFO - PID 1663 | Values: 78.0 58.0 58.0 84.0 58.0 58.0 68.0 57.0 79.0 57.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:73.50 stddev:14.96 (20.4%) stddev-sans-first:15.84 12:39:58 INFO - PID 1663 | Values: 76.0 69.0 82.0 72.0 69.0 70.0 86.0 70.0 75.0 118.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:89.50 stddev:3.03 (3.4%) stddev-sans-first:3.19 12:39:58 INFO - PID 1663 | Values: 89.0 89.0 88.0 92.0 90.0 88.0 98.0 89.0 90.0 88.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:39:58 INFO - PID 1663 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.72 (1.9%) stddev-sans-first:1.81 12:39:58 INFO - PID 1663 | Values: 91.0 94.0 92.0 92.0 90.0 93.0 91.0 93.0 91.0 88.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:3.63 (7.3%) stddev-sans-first:3.86 12:39:58 INFO - PID 1663 | Values: 50.0 50.0 59.0 45.0 47.0 50.0 51.0 49.0 49.0 51.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:56.50 stddev:2.75 (4.9%) stddev-sans-first:2.92 12:39:58 INFO - PID 1663 | Values: 57.0 62.0 54.0 56.0 61.0 57.0 56.0 55.0 54.0 55.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.85 (2.2%) stddev-sans-first:1.64 12:39:58 INFO - PID 1663 | Values: 81.0 84.0 84.0 84.0 85.0 84.0 82.0 86.0 82.0 87.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:148.50 stddev:18.37 (12.4%) stddev-sans-first:18.43 12:39:58 INFO - PID 1663 | Values: 132.0 137.0 139.0 144.0 150.0 150.0 141.0 147.0 198.0 152.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.20 Median:214.00 stddev:23.38 (10.9%) stddev-sans-first:24.09 12:39:58 INFO - PID 1663 | Values: 217.0 185.0 180.0 178.0 222.0 232.0 211.0 185.0 230.0 172.0 12:39:58 INFO - PID 1663 | 12:39:58 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:4.57 (6.6%) stddev-sans-first:4.83 12:39:58 INFO - PID 1663 | Values: 70.0 70.0 69.0 67.0 81.0 68.0 65.0 67.0 70.0 65.0 12:39:58 INFO - PID 1663 | -------- Summary: end -------- 12:39:58 INFO - PID 1663 | 12:39:59 INFO - TEST-INFO | 1663: exit 0 12:39:59 INFO - TEST-OK | kraken | took 60898ms 12:39:59 INFO - SUITE-END | took 1176s 12:40:00 INFO - Completed test suite (00:19:36) 12:40:00 INFO - javascript benchmark 12:40:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7851.064029840079, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.297051678114, 3574.2315402188524, 3591.2458383739413, 3605.2068544920107, 3606.9458958115624, 6070.180525629039, 6167.414095660911, 6193.535484838638, 6197.659128747917, 6242.968785156079, 6127.586602260202, 6147.569670123094, 6159.384061593838, 6162.445379915806, 6198.473129783963, 4837.492063333352, 4863.708177509361, 5272.657277276984, 5301.045811753883, 5374.838754837351, 4734.9109704767925, 5340.519353258206, 5429.185622156669, 5477.643953143053, 5491.862703432406, 5253.08071087562, 5406.405295417501, 5423.972880135574, 5454.91817622736, 5468.56251499881, 5335.33308336458, 5339.70631615261, 5419.810306639249, 5421.783128674848, 5471.179323101527, 5342.973285133549, 5370.060239458118, 5371.167469042305, 5380.596455265871, 5395.865103372408, 5943.3759455257, 6164.506839452833, 6169.889419904415, 6180.81457556274, 6187.257529096492, 6411.935880641227, 6481.513886458487, 6557.967210163919, 6683.899741503881, 6692.732290708366, 6740.32596740327, 6759.222689390734, 6764.5603035802515, 6765.729370825161, 6781.966090169567, 6226.813195604093, 6243.125962365272, 6305.527085468608, 6309.211348581427, 6326.240851097898, 6615.63614001225, 6662.400383965466, 6863.210730765926, 6864.7940561783225, 6905.37851593359, 6408.58344207625, 6499.3175716549085, 6507.056476810931, 6525.4453371463005, 6559.836004099936, 6699.765508207287, 6732.899006514906, 6754.18949726036, 6763.830904227335, 6777.1189745332795, 6395.840103997344, 6513.641749703812, 6649.867002659919, 6654.900176497356, 6665.366790154928, 6474.708638111297, 6570.605763654196, 6632.369924857131, 6643.269240383554, 6687.331266873371, 5279.973600132052, 5292.44429334922, 5306.336707911511, 5309.230161626542, 5331.546818520469, 4729.668923175344, 4732.100900828831, 4733.431988161374, 4739.218029025241, 4762.119007983534, 5031.7987280509105, 6044.818655440344, 6199.0, 6254.8123556293385, 6257.499400047986, 6667.399934005963, 6707.49693772969, 6743.561668491532, 6768.800139954436, 6784.59292442455, 6289.40250676185, 6292.119103325538, 6311.715972781236, 6313.494920406357, 6322.683865806691, 5629.915551266734, 5776.942230577724, 5788.710564471759, 5853.648781073064, 5902.793402230901, 6748.561343512754, 6837.35045170708, 6886.965565172142, 6916.066331045245, 6957.9304206959305, 6518.152640156749, 6715.664216789043, 6733.0, 6746.595204287759, 6782.830429239309, 6664.633445160369, 6724.663766811737, 6796.184457865088, 6900.0, 6916.723331066701, 6654.800355989328, 6664.566803157779, 6670.266270710121, 6675.966620166868, 6678.866422671422, 6142.161149281699, 6588.901166482602, 6653.367930046637, 6716.1604799400075, 6739.191297044386, 4877.853664390074, 6418.261899881387, 6528.151340325728, 6569.770058047945, 6572.277049524453, 6542.705578249085, 6555.082288479522, 6565.310642382481, 6568.507361947777, 6587.374199451045, 5299.9470005301, 6231.1276421300145, 6273.0, 6308.684565771785, 6382.234131904202, 5435.320584926884, 6485.319041500763, 6513.120728701565, 6570.211574610886, 6588.242352129567, 6343.397377249154, 6354.332795056453, 6366.267879193952, 6408.455281301141, 6410.391012853772], "value": 6049.9485195964735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.2813787935556, 924.6948506992693, 1138.1350173867859, 1258.1193164784647, 1264.1024872340638, 36307.09232269195, 37093.476119768646, 37130.0, 37222.8138859306, 37233.81383093084, 36981.63018369816, 37342.43986340207, 37417.0, 37446.62553374466, 37528.24943501128, 7557.0, 7839.098503672094, 7904.446688731791, 7990.560519171443, 8600.483970961732, 8565.700200492984, 8615.052344242129, 8692.47845129291, 8694.174053464942, 8700.390972631918, 8634.395592308541, 8700.869486957701, 8700.912990870105, 8706.912930870674, 8724.607392667314, 8625.741227763177, 8649.178328058824, 8677.913220867773, 8691.435056721293, 8700.260477859327, 8351.039630442516, 8403.831923361498, 8477.448965817264, 8480.236778689887, 8485.490870547786, 37481.81259093687, 37548.0, 37560.81219593912, 37582.62417375819, 37649.0587735306, 3596.7841929484052, 3622.6196249393697, 3629.274145170976, 3630.981845090758, 3640.3811352069947, 30641.387172256655, 30727.38545229083, 30788.538171927437, 30895.845520772476, 30961.535576966362, 3146.9842650786827, 3232.3858466891215, 3242.983785081083, 3253.316803471274, 3253.9349213015844, 287900.0, 288672.0, 289384.0, 289446.9999999979, 289775.0, 37890.43164352537, 38183.618163818006, 46134.07731845344, 47658.52341476541, 47793.5220647796, 49553.513394598216, 49826.0, 49945.25082123771, 50030.0, 50123.74938125286, 14186.929065354814, 14222.715545689236, 14338.784918226234, 14345.569632911029, 14356.928215358856, 8900.73297801067, 10539.63111291101, 11360.715982100513, 11484.196106272648, 11498.195126341243, 11054.944725276482, 13610.0, 13633.863661363259, 13660.726785464236, 13675.794863077062, 6025.186599809057, 6050.727717252734, 6087.0, 6093.908591371133, 6105.786297479567, 1035.1511760356586, 1035.6789395287485, 1042.1298215989582, 1074.5648012554927, 1077.876044254905, 22431.87840607963, 23147.421314467272, 24072.759272407402, 24332.270031899072, 28247.0, 1120.7920396818256, 1121.2599684208383, 1125.2235957189678, 1163.6450882480924, 1168.9415529223504, 12247.0, 12409.414449046295, 12530.81203781944, 12544.24734515932, 12557.434915428828, 297143.0, 298135.0, 299445.0, 299751.0, 300606.49696751375, 52645.73677131667, 52862.0, 53013.469865301624, 53023.20465192948, 53147.67130821761, 1516.3555488917166, 1559.134680252448, 1566.3421363027328, 1568.7646852972146, 1580.123031717385, 21470.46323841854, 21710.565788684453, 23984.0, 24015.159469418486, 24060.0, 33287.83356083301, 38223.0, 38233.2795024219, 38391.808040959615, 38397.6160238394, 280649.0, 280836.0, 281663.0, 281878.0, 282130.0, 33490.665093348754, 34264.828675856465, 35888.82055589705, 36052.4592131113, 36059.27881442409, 37734.81160086816, 42809.0, 42837.92905177396, 42841.35737963995, 44661.330080048174, 29107.126786196448, 29861.70138298589, 30502.847485763315, 31017.8449107753, 31350.216244594067, 24860.50278994446, 26818.65906704696, 30416.239594010327, 30770.230744231572, 30858.228544286572, 29400.7059929398, 30354.089377318716, 35765.46351804673, 36458.63541364553, 37413.81293093517, 29286.41427171487, 29511.26221844471, 30794.692053079183, 31444.842775785975, 31463.68536314699, 21483.71097734141, 23293.650595240746, 23915.0, 24514.50970980535, 24991.375215619028], "value": 17416.341134510774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.8392088052321, 438.54610478155104, 440.6474820143884, 452.2628116170639, 454.15753776744134, 20323.59352812943, 20931.0, 22235.0, 22344.21795237167, 22455.21406750764, 21805.890970545166, 22713.0, 22846.77153228467, 22886.427839304026, 22959.42601434965, 5863.648181109136, 6087.360827113156, 6088.391160883921, 6095.878082438348, 6100.023996160615, 5814.360420353758, 5937.37657545958, 5985.4912332451795, 6035.577509574332, 6041.818745437622, 5956.0, 5973.820785376424, 5985.700714964256, 5987.311459182184, 6009.819705408845, 5773.56698247631, 5840.707964601774, 5865.296164460292, 5911.408859114075, 5928.377520360386, 4249.490061192646, 4430.1582699287355, 5249.976254630348, 5260.132078207092, 5329.333833270841, 25365.0, 25469.962403007714, 26003.60994585083, 26430.207093787216, 26885.32786680329, 2174.325958952714, 2228.799408053283, 2231.1187081103035, 2235.798778109978, 2244.450109723128, 13427.86572134276, 17222.483325500256, 17579.73630395545, 17723.911380443016, 17795.73306400405, 2057.1051592557287, 2136.75427325858, 2155.4288113649895, 2162.826973842089, 2163.437506248387, 150636.49363506335, 153432.2328388362, 155182.0, 156103.21948390186, 164806.9999999988, 11395.886041139649, 11976.461059252355, 12159.452824622913, 12190.695232619255, 12246.938765306117, 15823.762643560356, 16026.599335016484, 16053.270943939558, 16108.355665773472, 16125.838741612668, 8698.695545655872, 8901.154390332907, 8914.55427228649, 8925.054731938444, 9055.094490551024, 8472.940882389701, 8481.067082620906, 8497.022842373082, 8541.359398045171, 8626.741197764077, 7622.047244094489, 7748.728794492165, 7761.8447631047065, 7795.688172473037, 7802.141764405911, 1708.0605666883164, 1846.8799528030636, 2096.454921720333, 2113.968290475644, 2306.9077236910334, 962.1965658674944, 971.2181693736469, 986.9636881274633, 998.2779705008802, 1014.1227837920146, 20707.4823129423, 20755.066022029045, 20821.68767468489, 20910.0, 20925.162993480393, 924.3945215883607, 993.5777294649745, 1012.9898701012895, 1022.8084281811606, 1034.8292531732332, 8222.753317400488, 9367.718968430958, 9485.383450075724, 10244.01450303147, 10856.891431085745, 531495.0, 535380.0, 547625.0, 551459.0, 552421.0, 113015.0, 125093.0, 125141.37429312977, 127960.36019819841, 128829.0, 1024.7284469615558, 1173.1464286427424, 1233.8889499945228, 1241.8944389726794, 1247.5882958623813, 21406.464838379165, 21763.782362176808, 21792.78207217971, 22018.779812202312, 22046.0, 56227.71886140543, 56282.43717562772, 57887.421125788205, 57934.4206557929, 58748.4125158743, 79272.0, 80425.59787201027, 80699.19300806917, 80829.59585202036, 82069.17930820854, 8682.34882383811, 8892.0, 8928.375013748975, 8976.237019853386, 9053.68312109073, 13423.328833558478, 13497.055206135476, 14223.71986521218, 14505.637359066108, 14723.92638036803, 10355.482225888825, 10654.360738355723, 11968.640940771791, 12014.399280035788, 12044.879551204725, 11218.99029087386, 11323.547058117912, 11417.1437142213, 11681.948624623825, 11790.351530665896, 11889.702757430787, 12091.08370903363, 12177.817332740193, 12304.077194210291, 12337.814932776195, 8623.353248506262, 8634.56827158652, 8679.479231245892, 8900.955495222483, 9059.22996545301, 9732.464714440774, 11864.22882512634, 11943.641690749291, 12082.637520874388, 12112.939435302767], "value": 10323.042837088295, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.9244341561216, 1399.9160050396983, 1424.39463228128, 1426.5791591480513, 1435.0958895895587, 11335.319880807147, 11721.648350549498, 11840.881591184085, 11843.170978031541, 12109.636710898665, 11720.648380548597, 11857.0, 11871.584494542692, 11874.16880818343, 11887.524499020028, 2529.1021687301018, 2543.4531575711217, 2570.421655127591, 2572.5755250383663, 2573.4724381501746, 2497.1634502441702, 2551.107112510616, 2555.3483861615264, 2557.8593177375237, 2561.9615705764427, 2488.875556222191, 2514.585093459577, 2515.6478093066894, 2543.160756950211, 2549.146036077907, 2470.962935555968, 2498.475320182773, 2536.365908522869, 2536.4927014597156, 2541.6187571864184, 2402.579548579009, 2509.2095989763166, 2514.421683012899, 2515.207709571479, 2523.4826860493554, 10254.692359229235, 11689.298642081418, 11760.0, 11873.287602743776, 11908.821367679406, 5794.362620111784, 6587.93412065878, 6846.760363387308, 6866.244713081557, 6868.759593414206, 3889.9886029632216, 3895.6299151580556, 4377.715448495869, 4427.712198707074, 4456.242438785414, 1272.3193091695991, 1279.405076639367, 1290.1098242212936, 1323.7881938889732, 1323.9669008274773, 15775.447859324984, 16329.755053674204, 16393.262303196385, 16447.83552164475, 16487.587810304838, 5520.613557051048, 5525.806596769094, 5644.588852786804, 5663.527482854405, 5788.82633520995, 11740.823887641693, 11746.647600571996, 11837.526498939946, 11856.525738970517, 11862.822057669142, 6527.412532872065, 6548.8035358939305, 6590.439812615981, 6602.900956485657, 6606.306337834458, 6153.29237137726, 6193.442590166816, 6226.25284965798, 6279.466245369194, 6284.340144284784, 3729.987184146592, 4277.695302932635, 4372.366006928977, 4396.60430561251, 4412.183746006999, 1087.6845714742635, 1265.1207410849372, 1281.135233717237, 1294.113532230425, 1298.9480420783252, 792.8453951479521, 815.1807433529265, 830.3689196210923, 833.9708110216113, 852.1606217875387, 5822.243108395882, 6072.392760723892, 6809.965950170316, 6881.552699074544, 7050.894236586455, 582.7756313819125, 601.3865856826013, 609.2475792396386, 610.3408319015452, 615.802943058226, 3611.6930060944587, 4116.1767646470225, 4178.456800615962, 4375.16871794358, 4456.242438785414, 14403.567892963229, 14875.107493550422, 14891.70216595662, 14937.925310373379, 15017.849821501864, 14552.563423097325, 14668.926655366655, 14923.40306387754, 14927.626809329853, 14953.40186392554, 7235.855282894313, 7526.473146879666, 8179.713710020121, 8661.740147795575, 8680.609372578123, 6619.0071489277, 7142.64286785669, 7146.535475194096, 7216.386607138452, 7227.349538541556, 10639.882812304597, 12597.244165350108, 12602.747945041232, 12606.936965315115, 12638.431270592662, 12575.245485270912, 12902.612921612366, 12948.676283092998, 12949.223046617233, 12987.220766754024, 8208.58957052133, 8673.826523469621, 9358.625654973997, 9471.289653275893, 9472.336936414385, 10936.78126437451, 11011.394373309546, 11024.007839294502, 11026.173037022096, 11067.612633557786, 8158.5920703963375, 8473.66105355779, 8568.957155214184, 8644.178803013956, 8680.392372534114, 8827.293816494794, 8858.601362938554, 8917.509536975529, 8980.640774369213, 9063.818723625622, 6899.931000689929, 8161.918380816116, 8235.83528329442, 8299.83400332002, 8319.0, 6043.12374705674, 6137.41694539018, 6140.078988151813, 6188.783392581238, 6260.968695156495, 6075.483583895241, 6078.149059131637, 6177.227846519185, 6221.5333849962335, 6348.873022539616], "value": 5301.757117463188, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.69644329201566, 408.7956021989005, 410.597614337949, 414.8236999275309, 417.7577005336904, 5988.161657367972, 6158.137860699506, 6165.445109940105, 6253.874922501547, 6261.43647071764, 5937.821865344046, 6070.9392906070925, 6087.665178415185, 6103.69481525923, 6146.569740118193, 4667.579917807397, 4673.626109911199, 4676.929846052312, 4688.953110468903, 4694.929576056362, 4619.607333376667, 4653.115907977491, 4678.087772884289, 4705.647076469261, 4707.670463067587, 3773.754705944105, 4557.840475583355, 4574.565416285464, 4638.837640682576, 4653.27874179501, 4060.3503439449546, 4613.385315139691, 4643.907121857561, 4653.464851542082, 4687.9531204687855, 4010.1177740896956, 4110.34234522478, 4133.276676581586, 4147.543770185277, 4151.243124971906, 4729.314249433813, 5702.743376548024, 5811.941880581182, 6006.21919150512, 6061.454469097758, 4943.876403089916, 5044.6973181608855, 5129.435762066169, 5583.441655834425, 5588.4411558844195, 6422.935770642281, 6551.213854337462, 6575.868482630369, 6578.901316480256, 6618.815790789076, 4329.848455304049, 4329.917520619845, 4331.545187755303, 4332.241857674894, 4335.891602709926, 6810.250872404032, 6821.147356580427, 6920.204176519715, 7004.614746188958, 7013.649317534103, 4358.585934336233, 4362.847300344472, 4372.387865698741, 4376.256036473807, 4380.540043295472, 3941.192055628589, 4074.1647962167685, 4083.9381760741826, 4196.769177695195, 4435.689501734848, 4758.288534942751, 5061.7975280989085, 5192.402873669501, 5218.66078704883, 5354.00950824091, 5127.0, 5698.772049118072, 6631.938889777709, 6645.205794435476, 6650.733970641217, 1376.717772856562, 1472.4625511688157, 1496.2668292536578, 1508.615303097703, 1518.5444366689949, 867.7136544940155, 894.4499133033268, 962.5812771444304, 982.7617202324979, 983.9655612053543, 5878.500327472213, 6185.8453538661, 6196.690165491707, 6203.503719702503, 6213.63300073983, 6254.6872656366995, 6737.292584278678, 6854.931450685529, 6856.760013399607, 6899.068625735562, 5691.516221121251, 6147.510258345377, 6159.784407545714, 6171.981623032239, 6205.596636218631, 2965.9110226693233, 3432.7253819694365, 3531.768431884848, 3568.518250036264, 3570.768085010667, 5867.853303667357, 6663.733450662016, 7018.894716579255, 7027.262137475594, 7035.507514473938, 5173.81891633791, 5668.376478587352, 6396.921000674799, 6835.555688880207, 6979.9409174293705, 7016.298370163045, 7088.503804733619, 7093.716251349888, 7115.146182458035, 7142.428605711634, 5923.851903702442, 6701.262861085179, 6757.729690812313, 6844.794656160323, 6879.243283238739, 5361.51746342831, 5558.499735023867, 5838.620489668158, 6769.559978601375, 6831.59010459374, 6890.483213759088, 6998.675629076148, 7104.680289387094, 7108.3602475777425, 7115.857682846417, 4317.805698743501, 4355.73865568067, 4363.476382833953, 4374.693771435969, 4388.71473354231, 6843.315668433117, 6878.55289406187, 6898.379145876896, 6912.516123871281, 6939.167299924042, 5148.407933087743, 6373.0, 6592.0, 6602.1747281589805, 6629.005649152666, 6106.267247930099, 6524.869502609827, 6564.671766411756, 6568.507361947969, 6631.204255489372, 5212.635115541876, 6025.307089684742, 6274.905876411949, 6444.452221561032, 6526.804195874131, 5735.0, 6510.707018184288, 6553.148090748172, 6600.372964568358, 6605.834854128493, 4682.367880336247, 5057.494250574913, 6138.404014956054, 6404.558974230761, 6418.4544313731985], "value": 4761.293220445276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.5505139596128, 852.4160949749426, 854.6239654551997, 876.2814492116468, 888.0187392930811, 16340.754888676665, 16754.999999999985, 16788.664226715475, 18408.447746567625, 19437.902810485964, 17761.91119044403, 18083.367082152126, 18129.00290484023, 18234.361797337064, 18464.98442585657, 977.7408986618552, 992.5186284651957, 1005.2109094360928, 1008.7276435362447, 1014.8173328800813, 1020.6325722739808, 1021.9948900255488, 1023.9897601024006, 1024.4058446101244, 1026.440589878518, 1043.958241670336, 1073.88187299397, 1076.8492411062457, 1077.2566928819128, 1086.0876863434714, 1062.0706881478707, 1068.0547715271962, 1075.1237741240864, 1076.682378698286, 1078.487718333793, 776.5107981971379, 784.1883650421843, 786.1627366854339, 788.6490511722286, 792.7859477940981, 16773.91613041939, 17080.402185923558, 17234.96590204592, 17258.913705431518, 17268.740968885475, 16973.745393819103, 17149.485515434557, 17217.91391043037, 17502.737458938125, 17671.823281767145, 13789.172649641054, 13932.442702291895, 13972.091814032157, 14140.575782726535, 14159.22124283164, 3465.6880880720855, 3538.115471132217, 3544.0962554548682, 3548.183917698944, 3551.058969373118, 111895.0, 120442.0, 134670.0, 139785.0, 144306.0, 13062.477500900048, 13115.081944264, 13132.934335328262, 13209.207447553177, 13342.399592018382, 25519.87240063825, 25665.0, 25676.8716156418, 25718.871405643225, 25844.0, 7547.924520754832, 7577.052868391451, 7602.391808655294, 7665.386769058462, 7679.308862202429, 3933.940990885139, 3960.524737031517, 4004.4193591929006, 4018.0, 4032.6572241359813, 3798.810059497014, 4320.567943205654, 4373.147236288957, 4397.73613583186, 4429.424174857248, 880.5201165364891, 884.9778755531163, 889.675268526996, 893.3344658229661, 897.9910200898038, 648.1735786871682, 672.7241830849329, 677.11298199359, 687.1788213085315, 703.7536862098327, 10284.228682848667, 11007.0, 12028.939855300667, 12034.81947770784, 12151.453184606715, 650.4341223135903, 661.9569727967666, 667.2693400726197, 671.664167916042, 675.864827034595, 4132.855350062793, 5517.282753242052, 5517.303182829756, 5628.60599758013, 5669.546436285087, 106760.0, 106803.0, 108889.27776805643, 109055.0, 109850.45074774575, 88447.11552884517, 98343.03313933681, 99681.50159249158, 100124.0, 102359.46460803093, 74681.62659186669, 87659.0, 88309.55845220733, 88410.55794720985, 88973.0, 9400.952995235253, 12888.935555322163, 12889.484420623445, 12938.805917911051, 13008.089433739548, 39475.223614936825, 39875.800620996706, 41220.1755964885, 41542.338306467405, 42047.3692894613, 51507.48492515027, 70968.64515677388, 72039.27960720325, 73787.26212738019, 75944.86082708875, 16376.918115409348, 16878.0, 17048.65902681964, 17050.06224657606, 17174.39889603858, 17918.0, 18247.81752182461, 18466.81533184651, 18499.260029598667, 18628.348007820205, 16988.40540581123, 17043.892147010403, 17186.96878187313, 17214.139293035547, 17633.118344082486, 13565.728685426038, 15751.44869929547, 15846.366145354517, 16372.75440868364, 16421.58946026359, 15934.760978585562, 16253.51239462818, 16672.416465423652, 16779.496615101565, 16879.071651058817, 10121.88659247497, 11579.07367410622, 11623.76752464963, 11837.408129593658, 12219.450124744586, 12049.0, 12983.805242921553, 12988.220706757626, 13018.609441716764, 13031.543895963596], "value": 8528.62647203244, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 101.0, 160.0, 169.0, 156.0, 114.0, 119.0, 164.0, 93.0, 158.0], "value": 135.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 118.0, 178.0, 93.0, 149.0, 146.0, 146.0, 157.0, 136.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 203.0, 210.0, 232.0, 195.0, 200.0, 195.0, 197.0, 199.0, 198.0], "value": 203.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 84.0, 58.0, 58.0, 68.0, 57.0, 79.0, 57.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 69.0, 82.0, 72.0, 69.0, 70.0, 86.0, 70.0, 75.0, 118.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 92.0, 90.0, 88.0, 98.0, 89.0, 90.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 92.0, 92.0, 90.0, 93.0, 91.0, 93.0, 91.0, 88.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 59.0, 45.0, 47.0, 50.0, 51.0, 49.0, 49.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 54.0, 56.0, 61.0, 57.0, 56.0, 55.0, 54.0, 55.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 84.0, 84.0, 85.0, 84.0, 82.0, 86.0, 82.0, 87.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 137.0, 139.0, 144.0, 150.0, 150.0, 141.0, 147.0, 198.0, 152.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 185.0, 180.0, 178.0, 222.0, 232.0, 211.0, 185.0, 230.0, 172.0], "value": 201.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 67.0, 81.0, 68.0, 65.0, 67.0, 70.0, 65.0], "value": 69.2, "unit": "score"}]}]} 12:40:00 INFO - Return code: 0 12:40:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:40:00 ERROR - # TBPL SUCCESS # 12:40:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:40:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:40:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:40:00 INFO - 2017-04-29 12:40:00.397 screenresolution[1669:11900] starting screenresolution argv=screenresolution get 12:40:00 INFO - 2017-04-29 12:40:00.400 screenresolution[1669:11900] Display 0: 1600x1200x32@60 12:40:00 INFO - 2017-04-29 12:40:00.406 screenresolution[1670:11903] starting screenresolution argv=screenresolution list 12:40:00 INFO - Available Modes on Display 0 12:40:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:40:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:40:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:40:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:40:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:40:00 INFO - Intel Iris: 12:40:00 INFO - Chipset Model: Intel Iris 12:40:00 INFO - Type: GPU 12:40:00 INFO - Bus: Built-In 12:40:00 INFO - VRAM (Dynamic, Max): 1536 MB 12:40:00 INFO - Vendor: Intel (0x8086) 12:40:00 INFO - Device ID: 0x0a2e 12:40:00 INFO - Revision ID: 0x0009 12:40:00 INFO - Displays: 12:40:00 INFO - Display: 12:40:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:40:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:40:00 INFO - Display Serial Number: 1600x1200 60 12:40:00 INFO - Main Display: Yes 12:40:00 INFO - Mirror: Off 12:40:00 INFO - Online: Yes 12:40:00 INFO - Rotation: Supported 12:40:00 INFO - Return code: 0 12:40:00 INFO - Running post-action listener: _resource_record_post_action 12:40:00 INFO - [mozharness: 2017-04-29 19:40:00.640944Z] Finished run-tests step (success) 12:40:00 INFO - Running post-run listener: _resource_record_post_run 12:40:00 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 34780160; Write bytes: 515815424; Read time: 333; Write time: 1777 12:40:00 INFO - TinderboxPrint: CPU usage
54.1% 12:40:00 INFO - TinderboxPrint: I/O read bytes / time
34,780,160 / 333 12:40:00 INFO - TinderboxPrint: I/O write bytes / time
515,815,424 / 1,777 12:40:00 INFO - TinderboxPrint: CPU idle
2,196.3 (45.9%) 12:40:00 INFO - TinderboxPrint: CPU system
962.6 (20.1%) 12:40:00 INFO - TinderboxPrint: CPU user
1,630.7 (34.0%) 12:40:00 INFO - TinderboxPrint: Swap in / out
240,275,456 / 0 12:40:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206080512; Write bytes: 203624448; Read time: 16484; Write time: 423 12:40:00 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 32678912; Write bytes: 305313792; Read time: 303; Write time: 1326 12:40:01 INFO - Running post-run listener: _upload_blobber_files 12:40:01 INFO - Blob upload gear active. 12:40:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:40:01 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:40:01 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:40:01 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:40:01 INFO - (blobuploader) - INFO - Open directory for files ... 12:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:40:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:02 INFO - (blobuploader) - INFO - Done attempting. 12:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:03 INFO - (blobuploader) - INFO - Done attempting. 12:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:04 INFO - (blobuploader) - INFO - Done attempting. 12:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:07 INFO - (blobuploader) - INFO - Done attempting. 12:40:07 INFO - (blobuploader) - INFO - Iteration through files over. 12:40:07 INFO - Return code: 0 12:40:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:40:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:40:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5dfbb1e11b1bc557e987e3985ae535cd367c12bfaa0493d224ada7b40564799f38dcd6bca313153d9225d8e2b6da3523b9304ae4279b2314106c0c7b64bf9a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575fdd2329ab526120831b35d6688c70ac5ab392d609528beb571a3f25a143eda39e6fdcab75aa3f5f31350f8baa3fe9b4ef429b30187fe027bb92b8d3da08c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e44dc281fcb10fa274905e2dba6a3175007aeddd9eeaa944573e15c631dfcfdc87fce88fbf1d5d0ae0f6fe148914f4b90efe484a8566de423810ddf5aba734f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c027922ea919fa1149e113184da024cc359f79005753649bc4ab6087309014294ef6201e95aa1d6c798ed6dc87558dfe2bb770e5135ee975b2146d1da8e5d8"} 12:40:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:40:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:40:07 INFO - Contents: 12:40:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5dfbb1e11b1bc557e987e3985ae535cd367c12bfaa0493d224ada7b40564799f38dcd6bca313153d9225d8e2b6da3523b9304ae4279b2314106c0c7b64bf9a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575fdd2329ab526120831b35d6688c70ac5ab392d609528beb571a3f25a143eda39e6fdcab75aa3f5f31350f8baa3fe9b4ef429b30187fe027bb92b8d3da08c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e44dc281fcb10fa274905e2dba6a3175007aeddd9eeaa944573e15c631dfcfdc87fce88fbf1d5d0ae0f6fe148914f4b90efe484a8566de423810ddf5aba734f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c027922ea919fa1149e113184da024cc359f79005753649bc4ab6087309014294ef6201e95aa1d6c798ed6dc87558dfe2bb770e5135ee975b2146d1da8e5d8"} 12:40:07 INFO - Running post-run listener: copy_logs_to_upload_dir 12:40:07 INFO - Copying logs to upload dir... 12:40:07 INFO - mkdir: /builds/slave/test/build/upload/logs 12:40:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.357587 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-29 12:40:07.130588) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-29 12:40:07.134035) ========= 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.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/e5dfbb1e11b1bc557e987e3985ae535cd367c12bfaa0493d224ada7b40564799f38dcd6bca313153d9225d8e2b6da3523b9304ae4279b2314106c0c7b64bf9a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575fdd2329ab526120831b35d6688c70ac5ab392d609528beb571a3f25a143eda39e6fdcab75aa3f5f31350f8baa3fe9b4ef429b30187fe027bb92b8d3da08c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e44dc281fcb10fa274905e2dba6a3175007aeddd9eeaa944573e15c631dfcfdc87fce88fbf1d5d0ae0f6fe148914f4b90efe484a8566de423810ddf5aba734f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c027922ea919fa1149e113184da024cc359f79005753649bc4ab6087309014294ef6201e95aa1d6c798ed6dc87558dfe2bb770e5135ee975b2146d1da8e5d8"} build_url:https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/SJsRl0NPQEagXF5tqxGaqw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5dfbb1e11b1bc557e987e3985ae535cd367c12bfaa0493d224ada7b40564799f38dcd6bca313153d9225d8e2b6da3523b9304ae4279b2314106c0c7b64bf9a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575fdd2329ab526120831b35d6688c70ac5ab392d609528beb571a3f25a143eda39e6fdcab75aa3f5f31350f8baa3fe9b4ef429b30187fe027bb92b8d3da08c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e44dc281fcb10fa274905e2dba6a3175007aeddd9eeaa944573e15c631dfcfdc87fce88fbf1d5d0ae0f6fe148914f4b90efe484a8566de423810ddf5aba734f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c027922ea919fa1149e113184da024cc359f79005753649bc4ab6087309014294ef6201e95aa1d6c798ed6dc87558dfe2bb770e5135ee975b2146d1da8e5d8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-29 12:40:07.169852) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:40:07.170189) ========= 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.Ira1QeNAvm/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.4D7b1shEp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005034 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-29 12:40:07.218596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-29 12:40:07.218937) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-29 12:40:07.219287) ========= ========= Total master_lag: 0.27 =========