builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0190 starttime: 1484897407.72 results: success (0) buildid: 20170119220525 builduid: 50e5a3f8aa864768bf86cdf0a8d4b49f revision: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.718550) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.719054) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.719333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.762417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.762703) ========= 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.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-19 23:30:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.6M=0.001s 2017-01-19 23:30:07 (20.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156652 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.949631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:07.950053) ========= 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.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.027200 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:08.000581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-19 23:30:08.000937) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 --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 aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-19 23:30:08,117 truncating revision to first 12 chars 2017-01-19 23:30:08,117 Setting DEBUG logging. 2017-01-19 23:30:08,117 attempt 1/10 2017-01-19 23:30:08,117 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aa3e49299a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 23:30:09,952 unpacking tar archive at: mozilla-central-aa3e49299a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.108442 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-19 23:30:10.453334) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:10.453617) ========= script_repo_revision: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:10.453970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:10.454241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 23:30:10.668616) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-19 23:30:10.668882) ========= /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.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:10 INFO - MultiFileLogger online at 20170119 23:30:10 in /builds/slave/test 23:30:10 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 23:30:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:30:10 INFO - {'append_to_log': False, 23:30:10 INFO - 'base_work_dir': '/builds/slave/test', 23:30:10 INFO - 'blob_upload_branch': 'Firefox', 23:30:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:30:10 INFO - 'branch': 'Firefox', 23:30:10 INFO - 'buildbot_json_path': 'buildprops.json', 23:30:10 INFO - 'config_files': ('talos/mac_config.py',), 23:30:10 INFO - 'default_actions': ('clobber', 23:30:10 INFO - 'read-buildbot-config', 23:30:10 INFO - 'download-and-extract', 23:30:10 INFO - 'populate-webroot', 23:30:10 INFO - 'create-virtualenv', 23:30:10 INFO - 'install', 23:30:10 INFO - 'run-tests'), 23:30:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:30:10 INFO - 'download_minidump_stackwalk': True, 23:30:10 INFO - 'download_symbols': 'ondemand', 23:30:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:30:10 INFO - 'tooltool.py': '/tools/tooltool.py', 23:30:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:30:10 INFO - '/tools/misc-python/virtualenv.py')}, 23:30:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:30:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:30:10 INFO - 'installer_path': 'installer.exe', 23:30:10 INFO - 'log_level': 'info', 23:30:10 INFO - 'log_name': 'talos', 23:30:10 INFO - 'log_to_console': True, 23:30:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:30:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:30:10 INFO - 'opt_config_files': (), 23:30:10 INFO - 'pip_index': False, 23:30:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:30:10 INFO - 'cmd': ('bash', 23:30:10 INFO - '-c', 23:30:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:30:10 INFO - 'enabled': True, 23:30:10 INFO - 'halt_on_failure': False, 23:30:10 INFO - 'name': 'check_screen_resolution'},), 23:30:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:30:10 INFO - 'cmd': ('bash', 23:30:10 INFO - '-c', 23:30:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:30:10 INFO - 'enabled': True, 23:30:10 INFO - 'halt_on_failure': False, 23:30:10 INFO - 'name': 'check_screen_resolution'},), 23:30:10 INFO - 'run_cmd_checks_enabled': True, 23:30:10 INFO - 'sps_profile': False, 23:30:10 INFO - 'sps_profile_interval': 0, 23:30:10 INFO - 'suite': 'dromaeojs-e10s', 23:30:10 INFO - 'system_bits': '32', 23:30:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:30:10 INFO - 'title': 't-yosemite-r7-0190', 23:30:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:30:10 INFO - 'use_talos_json': True, 23:30:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:30:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:30:10 INFO - 'work_dir': 'build'} 23:30:10 INFO - [mozharness: 2017-01-20 07:30:10.904933Z] Running clobber step. 23:30:10 INFO - Running pre-action listener: _resource_record_pre_action 23:30:10 INFO - Running main action method: clobber 23:30:10 INFO - rmtree: /builds/slave/test/build 23:30:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:30:13 INFO - Running post-action listener: _resource_record_post_action 23:30:13 INFO - [mozharness: 2017-01-20 07:30:13.433952Z] Finished clobber step (success) 23:30:13 INFO - [mozharness: 2017-01-20 07:30:13.434127Z] Running read-buildbot-config step. 23:30:13 INFO - Running pre-action listener: _resource_record_pre_action 23:30:13 INFO - Running main action method: read_buildbot_config 23:30:13 INFO - Using buildbot properties: 23:30:13 INFO - { 23:30:13 INFO - "project": "", 23:30:13 INFO - "product": "firefox", 23:30:13 INFO - "script_repo_revision": "production", 23:30:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:30:13 INFO - "repository": "", 23:30:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:30:13 INFO - "buildid": "20170119220525", 23:30:13 INFO - "pgo_build": "False", 23:30:13 INFO - "basedir": "/builds/slave/test", 23:30:13 INFO - "buildnumber": 18, 23:30:13 INFO - "slavename": "t-yosemite-r7-0190", 23:30:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:30:13 INFO - "platform": "yosemite_r7", 23:30:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:30:13 INFO - "branch": "mozilla-central", 23:30:13 INFO - "repo_path": "mozilla-central", 23:30:13 INFO - "revision": "aa3e49299a3aa5cb0db570532e3df9e75d30c2d1", 23:30:13 INFO - "stage_platform": "macosx64", 23:30:13 INFO - "builduid": "50e5a3f8aa864768bf86cdf0a8d4b49f", 23:30:13 INFO - "slavebuilddir": "test" 23:30:13 INFO - } 23:30:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 23:30:13 INFO - Running post-action listener: _resource_record_post_action 23:30:13 INFO - [mozharness: 2017-01-20 07:30:13.438529Z] Finished read-buildbot-config step (success) 23:30:13 INFO - [mozharness: 2017-01-20 07:30:13.438646Z] Running download-and-extract step. 23:30:13 INFO - Running pre-action listener: _resource_record_pre_action 23:30:13 INFO - Running main action method: download_and_extract 23:30:13 INFO - mkdir: /builds/slave/test/build/tests 23:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:30:13 INFO - trying https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 23:30:13 INFO - Downloading https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:30:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 23:30:14 INFO - Downloaded 1199 bytes. 23:30:14 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:30:14 INFO - Using the following test package requirements: 23:30:14 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:30:14 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 23:30:14 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 23:30:14 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'jsshell-mac.zip'], 23:30:14 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 23:30:14 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:30:14 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 23:30:14 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 23:30:14 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 23:30:14 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:30:14 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 23:30:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:30:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:30:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:30:14 INFO - Fetch https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:30:15 INFO - Expected file size: 16344808 23:30:15 INFO - Obtained file size: 16344808 23:30:22 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:30:23 INFO - Expected file size: 16344808 23:30:23 INFO - Obtained file size: 16344808 23:30:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 23:30:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:30:29 INFO - Fetch https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 23:30:30 INFO - Expected file size: 14403476 23:30:30 INFO - Obtained file size: 14403476 23:30:31 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')]} 23:30:31 INFO - trying https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:30:31 INFO - Downloading https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 23:30:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 23:30:33 INFO - Downloaded 69935903 bytes. 23:30:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:30:33 INFO - mkdir: /builds/slave/test/properties 23:30:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:30:33 INFO - Writing to file /builds/slave/test/properties/build_url 23:30:33 INFO - Contents: 23:30:33 INFO - build_url:https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:30:33 INFO - Running post-action listener: _resource_record_post_action 23:30:33 INFO - Running post-action listener: set_extra_try_arguments 23:30:33 INFO - [mozharness: 2017-01-20 07:30:33.715712Z] Finished download-and-extract step (success) 23:30:33 INFO - [mozharness: 2017-01-20 07:30:33.715833Z] Running populate-webroot step. 23:30:33 INFO - Running pre-action listener: _resource_record_pre_action 23:30:33 INFO - Running main action method: populate_webroot 23:30:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:30:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:30:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:30:33 INFO - '--tppagecycles', 23:30:33 INFO - '7'], 23:30:33 INFO - 'tests': ['tsvgm']}}, 23:30:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:30:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:30:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:30:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:30:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:30:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:30:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['damp', 'tps']}, 23:30:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'tests': ['damp', 'tps']}, 23:30:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['dromaeo_dom']}, 23:30:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:30:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['basic_compositor_video']}, 23:30:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:30:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'other-e10s': {'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['a11yr', 23:30:33 INFO - 'ts_paint', 23:30:33 INFO - 'tpaint', 23:30:33 INFO - 'sessionrestore', 23:30:33 INFO - 'sessionrestore_no_auto_restore', 23:30:33 INFO - 'tabpaint']}, 23:30:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['tsvgx', 23:30:33 INFO - 'tsvgr_opacity', 23:30:33 INFO - 'tart', 23:30:33 INFO - 'tscrollx', 23:30:33 INFO - 'cart', 23:30:33 INFO - 'tsvg_static']}, 23:30:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:30:33 INFO - 'tsvgr_opacity', 23:30:33 INFO - 'tart', 23:30:33 INFO - 'tscrollx', 23:30:33 INFO - 'cart', 23:30:33 INFO - 'tsvg_static']}, 23:30:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'talos_options': ['--disable-e10s'], 23:30:33 INFO - 'tests': ['tp5o']}, 23:30:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:30:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'talos_options': ['--disable-e10s', 23:30:33 INFO - '--xperf_path', 23:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:30:33 INFO - 'tests': ['tp5n']}, 23:30:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:30:33 INFO - 'talos_options': ['--xperf_path', 23:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:30:33 INFO - 'tests': ['tp5n']}}, 23:30:33 INFO - 'talos.zip': {'path': '', 23:30:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:30:33 INFO - Running post-action listener: _resource_record_post_action 23:30:33 INFO - [mozharness: 2017-01-20 07:30:33.734720Z] Finished populate-webroot step (success) 23:30:33 INFO - [mozharness: 2017-01-20 07:30:33.734865Z] Running create-virtualenv step. 23:30:33 INFO - Running pre-action listener: _resource_record_pre_action 23:30:33 INFO - Running main action method: create_virtualenv 23:30:33 INFO - Creating virtualenv /builds/slave/test/build/venv 23:30:33 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 23:30:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:30:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:30:33 INFO - Using real prefix '/tools/python27' 23:30:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:30:34 INFO - Installing distribute.............................................................................................................................................................................................done. 23:30:37 INFO - Installing pip.................done. 23:30:37 INFO - Return code: 0 23:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:30:37 INFO - Reading from file tmpfile_stdout 23:30:37 INFO - Output received: 23:30:37 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) 23:30:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:30:37 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')]} 23:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:38 INFO - 'HOME': '/Users/cltbld', 23:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:38 INFO - 'LOGNAME': 'cltbld', 23:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:38 INFO - 'MOZ_NO_REMOTE': '1', 23:30:38 INFO - 'NO_EM_RESTART': '1', 23:30:38 INFO - 'PAGER': '/bin/cat', 23:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:38 INFO - 'PWD': '/builds/slave/test', 23:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:38 INFO - 'SHELL': '/bin/bash', 23:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:38 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:38 INFO - 'USER': 'cltbld', 23:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:38 INFO - 'XPC_FLAGS': '0x0', 23:30:38 INFO - 'XPC_SERVICE_NAME': '0', 23:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:39 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 23:30:39 INFO - Cleaning up... 23:30:39 INFO - Return code: 0 23:30:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:30: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')]} 23:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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')]} 23:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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', '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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23:30: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 psutil>=3.1.1 23:30:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:39 INFO - 'HOME': '/Users/cltbld', 23:30:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:39 INFO - 'LOGNAME': 'cltbld', 23:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:39 INFO - 'MOZ_NO_REMOTE': '1', 23:30:39 INFO - 'NO_EM_RESTART': '1', 23:30:39 INFO - 'PAGER': '/bin/cat', 23:30:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:39 INFO - 'PWD': '/builds/slave/test', 23:30:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:39 INFO - 'SHELL': '/bin/bash', 23:30:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:39 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:39 INFO - 'USER': 'cltbld', 23:30:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:39 INFO - 'XPC_FLAGS': '0x0', 23:30:39 INFO - 'XPC_SERVICE_NAME': '0', 23:30:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:39 INFO - Downloading/unpacking psutil>=3.1.1 23:30:39 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 23:30:39 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 23:30:39 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 23:30:39 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 23:30:39 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 23:30:39 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 23:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:30:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:30:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:30:43 INFO - Installing collected packages: psutil 23:30:43 INFO - Running setup.py install for psutil 23:30:43 INFO - building 'psutil._psutil_osx' extension 23:30:43 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 23:30:43 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 23:30:43 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 23:30:43 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 23:30:44 INFO - building 'psutil._psutil_posix' extension 23:30:44 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 23:30:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:30:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:30:44 INFO - ^ 23:30:44 INFO - 1 warning generated. 23:30:44 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 23:30:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:30:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:30:44 INFO - Successfully installed psutil 23:30:44 INFO - Cleaning up... 23:30:44 INFO - Return code: 0 23:30:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:44 INFO - 'HOME': '/Users/cltbld', 23:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:44 INFO - 'LOGNAME': 'cltbld', 23:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:44 INFO - 'MOZ_NO_REMOTE': '1', 23:30:44 INFO - 'NO_EM_RESTART': '1', 23:30:44 INFO - 'PAGER': '/bin/cat', 23:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:44 INFO - 'PWD': '/builds/slave/test', 23:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:44 INFO - 'SHELL': '/bin/bash', 23:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:44 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:44 INFO - 'USER': 'cltbld', 23:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:44 INFO - 'XPC_FLAGS': '0x0', 23:30:44 INFO - 'XPC_SERVICE_NAME': '0', 23:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:30:47 INFO - Installing collected packages: mozsystemmonitor 23:30:47 INFO - Running setup.py install for mozsystemmonitor 23:30:47 INFO - Successfully installed mozsystemmonitor 23:30:47 INFO - Cleaning up... 23:30:47 INFO - Return code: 0 23:30:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:30: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')]} 23:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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')]} 23:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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', '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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:30: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 jsonschema==2.5.1 23:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:47 INFO - 'HOME': '/Users/cltbld', 23:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:47 INFO - 'LOGNAME': 'cltbld', 23:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:47 INFO - 'MOZ_NO_REMOTE': '1', 23:30:47 INFO - 'NO_EM_RESTART': '1', 23:30:47 INFO - 'PAGER': '/bin/cat', 23:30:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:47 INFO - 'PWD': '/builds/slave/test', 23:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:47 INFO - 'SHELL': '/bin/bash', 23:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:47 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:47 INFO - 'USER': 'cltbld', 23:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:47 INFO - 'XPC_FLAGS': '0x0', 23:30:47 INFO - 'XPC_SERVICE_NAME': '0', 23:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:47 INFO - Downloading/unpacking jsonschema==2.5.1 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:30:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30: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 23:30:50 INFO - Downloading functools32-3.2.3-2.tar.gz 23:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:30:50 INFO - warning: no files found matching '*.txt' 23:30:50 INFO - no previously-included directories found matching 'build' 23:30:50 INFO - no previously-included directories found matching 'dist' 23:30:50 INFO - no previously-included directories found matching '.git*' 23:30:51 INFO - Installing collected packages: jsonschema, functools32 23:30:51 INFO - Running setup.py install for functools32 23:30:51 INFO - warning: no files found matching '*.txt' 23:30:51 INFO - no previously-included directories found matching 'build' 23:30:51 INFO - no previously-included directories found matching 'dist' 23:30:51 INFO - no previously-included directories found matching '.git*' 23:30:51 INFO - Successfully installed jsonschema functools32 23:30:51 INFO - Cleaning up... 23:30:51 INFO - Return code: 0 23:30:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:30:51 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')]} 23:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:51 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')]} 23:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:51 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:51 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 23:30:51 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 23:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:51 INFO - 'HOME': '/Users/cltbld', 23:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:51 INFO - 'LOGNAME': 'cltbld', 23:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:51 INFO - 'MOZ_NO_REMOTE': '1', 23:30:51 INFO - 'NO_EM_RESTART': '1', 23:30:51 INFO - 'PAGER': '/bin/cat', 23:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:51 INFO - 'PWD': '/builds/slave/test', 23:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:51 INFO - 'SHELL': '/bin/bash', 23:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:51 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:51 INFO - 'USER': 'cltbld', 23:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:51 INFO - 'XPC_FLAGS': '0x0', 23:30:51 INFO - 'XPC_SERVICE_NAME': '0', 23:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:30:51 INFO - Cleaning up... 23:30:51 INFO - Return code: 0 23:30:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:30:51 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')]} 23:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:51 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')]} 23:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:51 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:51 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 23:30:51 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 23:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:51 INFO - 'HOME': '/Users/cltbld', 23:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:51 INFO - 'LOGNAME': 'cltbld', 23:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:51 INFO - 'MOZ_NO_REMOTE': '1', 23:30:51 INFO - 'NO_EM_RESTART': '1', 23:30:51 INFO - 'PAGER': '/bin/cat', 23:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:51 INFO - 'PWD': '/builds/slave/test', 23:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:51 INFO - 'SHELL': '/bin/bash', 23:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:51 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:51 INFO - 'USER': 'cltbld', 23:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:51 INFO - 'XPC_FLAGS': '0x0', 23:30:51 INFO - 'XPC_SERVICE_NAME': '0', 23:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:51 INFO - Downloading/unpacking blobuploader==1.2.4 23:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:54 INFO - Downloading blobuploader-1.2.4.tar.gz 23:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:30:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:30:54 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 23:30:54 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 23:30:54 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 23:30:54 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 23:30:54 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 23:30:54 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 23:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:30:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:30:55 INFO - Downloading docopt-0.6.1.tar.gz 23:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:30:55 INFO - Installing collected packages: blobuploader, requests, docopt 23:30:55 INFO - Running setup.py install for blobuploader 23:30:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:30:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:30:55 INFO - Running setup.py install for requests 23:30:56 INFO - Running setup.py install for docopt 23:30:56 INFO - Successfully installed blobuploader requests docopt 23:30:56 INFO - Cleaning up... 23:30:56 INFO - Return code: 0 23:30:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:30: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')]} 23:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30: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')]} 23:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:30:56 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:30:56 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 23:30:56 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 23:30:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:30:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:30:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:30:56 INFO - 'HOME': '/Users/cltbld', 23:30:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:30:56 INFO - 'LOGNAME': 'cltbld', 23:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:30:56 INFO - 'MOZ_NO_REMOTE': '1', 23:30:56 INFO - 'NO_EM_RESTART': '1', 23:30:56 INFO - 'PAGER': '/bin/cat', 23:30:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:30:56 INFO - 'PWD': '/builds/slave/test', 23:30:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:30:56 INFO - 'SHELL': '/bin/bash', 23:30:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:30:56 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:30:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:30:56 INFO - 'USER': 'cltbld', 23:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:30:56 INFO - 'XPC_FLAGS': '0x0', 23:30:56 INFO - 'XPC_SERVICE_NAME': '0', 23:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:30:56 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-oQC0fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:30:56 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-7tNDjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-gcGo6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-sLKqlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-oqP6wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-UCEFXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-vGt_mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-PlsBHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-LqdxKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-OOeEt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-fRw8UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:30:57 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-C5hifk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:30:58 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Xfwe5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:30:58 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-2PyHxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:58 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-JKGvP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:30:58 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-I7_jzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:30:58 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-rjoEmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:30:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:30:58 INFO - Running setup.py install for manifestparser 23:30:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:30:58 INFO - Running setup.py install for mozcrash 23:30:58 INFO - Running setup.py install for mozdebug 23:30:59 INFO - Running setup.py install for mozdevice 23:30:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Running setup.py install for mozfile 23:30:59 INFO - Running setup.py install for mozhttpd 23:30:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Running setup.py install for mozinfo 23:30:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Running setup.py install for mozInstall 23:30:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:30:59 INFO - Running setup.py install for mozleak 23:30:59 INFO - Running setup.py install for mozlog 23:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Running setup.py install for moznetwork 23:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Running setup.py install for mozprocess 23:31:00 INFO - Running setup.py install for mozprofile 23:31:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Running setup.py install for mozrunner 23:31:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:31:00 INFO - Running setup.py install for mozscreenshot 23:31:00 INFO - Running setup.py install for moztest 23:31:01 INFO - Running setup.py install for mozversion 23:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:31:01 INFO - Cleaning up... 23:31:01 INFO - Return code: 0 23:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:01 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:31:01 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 23:31:01 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 23:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:31:01 INFO - 'HOME': '/Users/cltbld', 23:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:31:01 INFO - 'LOGNAME': 'cltbld', 23:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:31:01 INFO - 'MOZ_NO_REMOTE': '1', 23:31:01 INFO - 'NO_EM_RESTART': '1', 23:31:01 INFO - 'PAGER': '/bin/cat', 23:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:31:01 INFO - 'PWD': '/builds/slave/test', 23:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:31:01 INFO - 'SHELL': '/bin/bash', 23:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:31:01 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:31:01 INFO - 'USER': 'cltbld', 23:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:31:01 INFO - 'XPC_FLAGS': '0x0', 23:31:01 INFO - 'XPC_SERVICE_NAME': '0', 23:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:31:01 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-MHhqcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:31:01 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)) 23:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:31:01 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-xuYivQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:31:01 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)) 23:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:31:01 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-b_xYTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:31:01 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)) 23:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:31:01 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-D_Xv2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:31:01 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-riAx3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-8kVh_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ifxXNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-cJQcUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-SZ8qkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Zxe5KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-yOoNbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-7h7AcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-j_s0Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-iN8QDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:31:02 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)) 23:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:31:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ikpmd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:31:03 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)) 23:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:31:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-A8FyX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:31:03 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)) 23:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:31:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-XYarn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:31:03 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)) 23:31:03 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)) 23:31:03 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)) 23:31:03 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)) 23:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:31:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:31:03 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 23:31:03 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 23:31:03 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 23:31:03 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 23:31:03 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 23:31:03 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 23:31:05 INFO - Downloading blessings-1.6.tar.gz 23:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:31:06 INFO - Installing collected packages: blessings 23:31:06 INFO - Running setup.py install for blessings 23:31:06 INFO - Successfully installed blessings 23:31:06 INFO - Cleaning up... 23:31:06 INFO - Return code: 0 23:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:31:06 INFO - Reading from file tmpfile_stdout 23:31:06 INFO - Current package versions: 23:31:06 INFO - blessings == 1.6 23:31:06 INFO - blobuploader == 1.2.4 23:31:06 INFO - docopt == 0.6.1 23:31:06 INFO - functools32 == 3.2.3-2 23:31:06 INFO - jsonschema == 2.5.1 23:31:06 INFO - manifestparser == 1.1 23:31:06 INFO - mozInstall == 1.12 23:31:06 INFO - mozcrash == 1.0 23:31:06 INFO - mozdebug == 0.1 23:31:06 INFO - mozdevice == 0.49 23:31:06 INFO - mozfile == 1.2 23:31:06 INFO - mozhttpd == 0.7 23:31:06 INFO - mozinfo == 0.9 23:31:06 INFO - mozleak == 0.1 23:31:06 INFO - mozlog == 3.4 23:31:06 INFO - moznetwork == 0.27 23:31:06 INFO - mozprocess == 0.24 23:31:06 INFO - mozprofile == 0.28 23:31:06 INFO - mozrunner == 6.13 23:31:06 INFO - mozscreenshot == 0.1 23:31:06 INFO - mozsystemmonitor == 0.3 23:31:06 INFO - moztest == 0.8 23:31:06 INFO - mozversion == 1.4 23:31:06 INFO - psutil == 3.1.1 23:31:06 INFO - requests == 1.2.3 23:31:06 INFO - wsgiref == 0.1.2 23:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:31:06 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')]} 23:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:06 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')]} 23:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:06 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:31:06 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 23:31:06 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 23:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:31:06 INFO - 'HOME': '/Users/cltbld', 23:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:31:06 INFO - 'LOGNAME': 'cltbld', 23:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:31:06 INFO - 'MOZ_NO_REMOTE': '1', 23:31:06 INFO - 'NO_EM_RESTART': '1', 23:31:06 INFO - 'PAGER': '/bin/cat', 23:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:31:06 INFO - 'PWD': '/builds/slave/test', 23:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:31:06 INFO - 'SHELL': '/bin/bash', 23:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:31:06 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:31:06 INFO - 'USER': 'cltbld', 23:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:31:06 INFO - 'XPC_FLAGS': '0x0', 23:31:06 INFO - 'XPC_SERVICE_NAME': '0', 23:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 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)) 23:31:06 INFO - Cleaning up... 23:31:06 INFO - Return code: 0 23:31:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:31:06 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')]} 23:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:06 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')]} 23:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:31:06 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 0x10275fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91005665b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, '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.J7zbrhwPxp/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.BReGDUO8ht/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 23:31:06 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 23:31:06 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 23:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:31:06 INFO - 'HOME': '/Users/cltbld', 23:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:31:06 INFO - 'LOGNAME': 'cltbld', 23:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:31:06 INFO - 'MOZ_NO_REMOTE': '1', 23:31:06 INFO - 'NO_EM_RESTART': '1', 23:31:06 INFO - 'PAGER': '/bin/cat', 23:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:31:06 INFO - 'PWD': '/builds/slave/test', 23:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:31:06 INFO - 'SHELL': '/bin/bash', 23:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:31:06 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:31:06 INFO - 'USER': 'cltbld', 23:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:31:06 INFO - 'XPC_FLAGS': '0x0', 23:31:06 INFO - 'XPC_SERVICE_NAME': '0', 23:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:31:07 INFO - Cleaning up... 23:31:07 INFO - Return code: 0 23:31:07 INFO - Running post-action listener: _resource_record_post_action 23:31:07 INFO - Running post-action listener: _start_resource_monitoring 23:31:07 INFO - Starting resource monitoring. 23:31:07 INFO - [mozharness: 2017-01-20 07:31:07.135446Z] Finished create-virtualenv step (success) 23:31:07 INFO - [mozharness: 2017-01-20 07:31:07.136404Z] Running install step. 23:31:07 INFO - Running pre-action listener: _resource_record_pre_action 23:31:07 INFO - Running main action method: install 23:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:31:07 INFO - Reading from file tmpfile_stdout 23:31:07 INFO - Detecting whether we're running mozinstall >=1.0... 23:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:31:07 INFO - Reading from file tmpfile_stdout 23:31:07 INFO - Output received: 23:31:07 INFO - Usage: mozinstall [options] installer 23:31:07 INFO - Options: 23:31:07 INFO - -h, --help show this help message and exit 23:31:07 INFO - -d DEST, --destination=DEST 23:31:07 INFO - Directory to install application into. [default: 23:31:07 INFO - "/builds/slave/test"] 23:31:07 INFO - --app=APP Application being installed. [default: firefox] 23:31:07 INFO - mkdir: /builds/slave/test/build/application 23:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:31:29 INFO - Reading from file tmpfile_stdout 23:31:29 INFO - Output received: 23:31:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:31:29 INFO - Running post-action listener: _resource_record_post_action 23:31:29 INFO - [mozharness: 2017-01-20 07:31:29.510787Z] Finished install step (success) 23:31:29 INFO - [mozharness: 2017-01-20 07:31:29.510949Z] Running run-tests step. 23:31:29 INFO - Running pre-action listener: _resource_record_pre_action 23:31:29 INFO - Running main action method: run_tests 23:31:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:31:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:31:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:31:29 INFO - 2017-01-19 23:31:29.525 screenresolution[1633:7499] starting screenresolution argv=screenresolution get 23:31:29 INFO - 2017-01-19 23:31:29.528 screenresolution[1633:7499] Display 0: 1600x1200x32@60 23:31:29 INFO - 2017-01-19 23:31:29.534 screenresolution[1634:7502] starting screenresolution argv=screenresolution list 23:31:29 INFO - Available Modes on Display 0 23:31:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:31:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:31:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:31:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:31:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:31:29 INFO - Intel Iris: 23:31:29 INFO - Chipset Model: Intel Iris 23:31:29 INFO - Type: GPU 23:31:29 INFO - Bus: Built-In 23:31:29 INFO - VRAM (Dynamic, Max): 1536 MB 23:31:29 INFO - Vendor: Intel (0x8086) 23:31:29 INFO - Device ID: 0x0a2e 23:31:29 INFO - Revision ID: 0x0009 23:31:29 INFO - Displays: 23:31:29 INFO - Display: 23:31:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:31:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:31:29 INFO - Display Serial Number: 1600x1200 60 23:31:29 INFO - Main Display: Yes 23:31:29 INFO - Mirror: Off 23:31:29 INFO - Online: Yes 23:31:29 INFO - Rotation: Supported 23:31:29 INFO - Return code: 0 23:31:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:31:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:31:29 INFO - Python 2.7.3 23:31:29 INFO - Return code: 0 23:31:29 INFO - grabbing minidump binary from tooltool 23:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:31:29 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 0x10253dd30>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102532be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:31:29 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 23:31:29 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 23:31:29 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 23:31:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:31:29 INFO - Return code: 0 23:31:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:31:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:31:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:31:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:31:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:31:29 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/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--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 23:31:29 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/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0190 --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 23:31:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J7zbrhwPxp/Render', 23:31:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:31:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:31:29 INFO - 'HOME': '/Users/cltbld', 23:31:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:31:29 INFO - 'LOGNAME': 'cltbld', 23:31:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:31:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:31:29 INFO - 'MOZ_NO_REMOTE': '1', 23:31:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:31:29 INFO - 'NO_EM_RESTART': '1', 23:31:29 INFO - 'PAGER': '/bin/cat', 23:31:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:31:29 INFO - 'PWD': '/builds/slave/test', 23:31:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:31:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:31:29 INFO - 'SHELL': '/bin/bash', 23:31:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BReGDUO8ht/Listeners', 23:31:29 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 23:31:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:31:29 INFO - 'USER': 'cltbld', 23:31:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:31:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:31:29 INFO - 'XPC_FLAGS': '0x0', 23:31:29 INFO - 'XPC_SERVICE_NAME': '0', 23:31:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:31:29 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/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--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 23:31:30 INFO - mozversion application_buildid: 20170119220525 23:31:30 INFO - mozversion application_changeset: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 23:31:30 INFO - mozversion application_display_name: Nightly 23:31:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:31:30 INFO - mozversion application_name: Firefox 23:31:30 INFO - mozversion application_remotingname: firefox 23:31:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:31:30 INFO - mozversion application_vendor: Mozilla 23:31:30 INFO - mozversion application_version: 53.0a1 23:31:30 INFO - mozversion platform_buildid: 20170119220525 23:31:30 INFO - mozversion platform_changeset: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 23:31:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:31:30 INFO - mozversion platform_version: 53.0a1 23:31:30 INFO - using testdate: 1484897490 23:31:30 INFO - actual date: 1484897490 23:31:30 INFO - starting webserver on 'localhost:49237' 23:31:30 INFO - SUITE-START | Running 2 tests 23:31:30 INFO - TEST-START | dromaeo_css 23:31:30 INFO - operating with platform_type : mac_ 23:31:30 INFO - Initialising browser for dromaeo_css test... 23:31:30 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpILKEv7/profile http://localhost:49237/getInfo.html) 23:31:33 INFO - PROCESS | 1644 | 2017-01-19 23:31:33.291 plugin-container[1646:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x904b, name = 'com.apple.tsm.portname' 23:31:33 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:33 INFO - PROCESS | 1644 | 2017-01-19 23:31:33.294 plugin-container[1646:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 23:31:33 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:33 INFO - PROCESS | 1644 | 2017-01-19 23:31:33.295 plugin-container[1646:7665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:31:33 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 23:31:33 INFO - PROCESS | 1644 | colorDepth:24 23:31:33 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 23:31:33 INFO - PROCESS | 1644 | __metrics 23:31:33 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 23:31:33 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:31:34 INFO - TEST-INFO | 1644: exit 0 23:31:34 INFO - Browser initialized. 23:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:31:34 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpILKEv7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:31:36 INFO - PROCESS | 1647 | 2017-01-19 23:31:36.580 plugin-container[1648:7784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 23:31:36 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:36 INFO - PROCESS | 1647 | 2017-01-19 23:31:36.581 plugin-container[1648:7784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 23:31:36 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:36 INFO - PROCESS | 1647 | 2017-01-19 23:31:36.581 plugin-container[1648:7784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:31:46 INFO - PROCESS | 1647 | RSS: Main: 232116224 23:31:46 INFO - PROCESS | 1647 | 23:34:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 23:34:50 INFO - PROCESS | 1647 | RSS: Main: 251195392 23:34:50 INFO - PROCESS | 1647 | 23:36:34 INFO - PROCESS | 1647 | ************************* 23:36:34 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 23:36:34 INFO - PROCESS | 1647 | 23:36:34 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:36:34 INFO - PROCESS | 1647 | Full stack: 23:36:34 INFO - PROCESS | 1647 | ************************* 23:37:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 23:37:53 INFO - PROCESS | 1647 | RSS: Main: 260718592 23:37:53 INFO - PROCESS | 1647 | 23:40:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 23:40:56 INFO - PROCESS | 1647 | RSS: Main: 269373440 23:40:56 INFO - PROCESS | 1647 | 23:43:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 23:43:58 INFO - PROCESS | 1647 | RSS: Main: 266018816 23:43:58 INFO - PROCESS | 1647 | 23:47:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 23:47:02 INFO - PROCESS | 1647 | RSS: Main: 270708736 23:47:02 INFO - PROCESS | 1647 | 23:50:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 23:50:05 INFO - PROCESS | 1647 | RSS: Main: 272650240 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | __start_tp_report 23:50:05 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 23:50:05 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 23:50:05 INFO - PROCESS | 1647 | |i|pagename|runs| 23:50:05 INFO - PROCESS | 1647 | |0;dojo.html;3274.1814546363394;3300.125466751311;3309.7848639838394;3334.5164951082093;3342.882999095032;5517.641353312032;5528.612997090206;5540.002799496089;5598.1602759586085;5618.971905140479;4068.776217308047;4471.910561788763;5623.690697011662;5667.064934285848;5812.738426770795;4525.343825145352;4883.681406020391;5132.435432102485;5268.736563171826;5297.655652382602;5102.974485127569;5282.102042652739;5284.735763211843;5317.175837745156;5329.147336426173;5139.229115632685;5273.551748101416;5275.182346736224;5295.602829787743;5332.626716129873;5119.744012799345;5155.2524883892;5168.405633352175;5253.185756207794;5283.418823929364;4685.4377474702915;4877.707407537006;5118.590512759008;5136.357955255593;5146.794128234867;4742.762861856928;5250.422453530146;5706.971465142689;5714.971425142848;5755.942440575583;6058.878822423571;6069.02895536712;6076.787312444088;6109.633421994695;6215.254169499689;6123.908141377883;6138.1431767337435;6176.84557886052;6184.9485587450245;6204.410580994843;4761.285807128958;5925.111233315038;5927.555433342473;5944.108383742473;5956.791512297092;6124.167343420691;6179.104029915727;6238.875222495525;6289.182406287245;6290.465310448663;5993.220881285405;6035.818925432244;6044.576879618472;6056.636601803818;6114.814446323713;6195.752169913243;6220.782272620437;6225.470834979077;6230.813075607739;6230.968845155745;6041.697915104227;6064.454199122012;6090.421409966046;6100.969495152496;6120.599512025131;6178.227721534809;6203.8138855834395;6212.1303017577575;6224.470919971763;6247.406496382836;4842.491538388418;4941.604671626261;4956.405231372186;5006.524380183877;5032.119379108642;4300.8709738707885;4403.581659742319;4410.404595379647;4425.911481770411;4439.533848945879;5820.88358232833;5839.416058394127;5847.883042339215;5878.177055212273;5885.087811389241;6080.847978800477;6118.131909153847;6223.782167624111;6250.812475625738;6271;5704.572157088235;5729.28383952006;5734.139879018097;5774.451427114417;5795.33353664334;5434.483724046288;5441.755121019484;5453.400125986138;5459.727013649302;5476.452354764571;6161.599496032743;6255.843603909939;6279.654618995816;6285.8742825143245;6286.811395658137;6133.334663918993;6161.661108639068;6165.321814600301;6180.81457556274;6230.563860529719;6281.654509001866;6302.1492098566105;6306.558540902093;6316.873662526816;6332.335104813928;6144.56988010835;6208.161898143693;6223.377662233922;6228.065790131516;6228.875422491616;6137.8979746733885;6145.446909778141;6147.047207682816;6149.20060392164;6166.845828854314;4813.927791083207;5836.007878660553;6104;6137.570370074052;6151.6001459904965;5983.162357269899;6027.336992930862;6116.35778243278;6125.173101631222;6129.816105516842;5182.844514664566;5815.331236907809;5983.820485385445;5985.371535988658;6048.939510605013;6090.725917333642;6092.786752463642;6103.084537319438;6128.4790792783115;6137.815865524041;5798.5651076168615;5968.22413086296;5986.491148252448;6001.819945401645;6002.489788368038 23:50:05 INFO - PROCESS | 1647 | |1;ext.html;466.13066630733675;500.0548962460947;508.3848543262652;513.9665921715089;524.9631976845728;39175.80412097939;41567.16865662688;41663.16673666525;41835.95410114745;43662.90842728933;42227.788861055655;42232;42535.14929701404;42552.78723606385;42752.3587146193;8352.290055345304;8582.270507006911;9176.265898728103;9423.81152376952;9465.29010324225;11189.776204475906;11473;11475.655730328104;11665;11720.179587428884;11240.775184496306;11358.60244891429;11366;11372.601958931438;11405.37270450125;11395.373254471;11455.942720286428;11465.968062874297;11493.597724079616;11514.769704605944;11230.55077796887;11304.773904521864;11321.094312455067;11327.093832493381;11340.6030788922;40285;40464.595354046374;40563;40597.594024059974;40635;4054.3107671695884;4056.979715101435;4108.301588729923;4152.211079894841;4250.128723611652;30457.23856903595;30674.693253067406;30846.52725354811;31096;31125.533117003262;3214.437473442138;3222.983885080583;3245.0589329094532;3269.1663625775514;3323.9833800831084;465480;466040;468351;469204.65397673135;469412;59197.81604367888;59321.70339148363;59520;59606.40393596095;59760;61280.38719612836;61674.38325616776;61701.69149154225;62016.06975895365;62129.06806397907;13817.861821381859;15333.846661533466;15679.608009799618;15828.52514424569;15848.52454426369;11098.167637427065;11357.750647428777;12166.81749773754;12329.815052774216;12441.688957776129;16040.278187481592;16116.113613751122;16121.758173627404;16129.274182661808;16138.273777680033;5326.6537675050995;5917.822465326047;6308.116863639094;6313.652749098752;6335;867.4535042922918;886.4946980221341;886.9600867960958;889.1464194373344;914.5610107148606;29807;29823.254418639706;29914;29932.700672992993;30501.69498305033;936.9297302702324;953.046953046953;994.5126887824914;1036.362636978253;1176.4235524592889;14386.064905781092;14616.492041910078;14949.551513454613;15372.231388430533;15435.768463473058;485512;585138;585457;586335.0683246641;588446;59341.10988335178;59396.406035939086;59451;59504;59569.702151488964;1285.5050805439973;1353.9255340956145;1416.6316757643087;1427.7858321251895;1439.9136051836713;28212.43575128527;28776.280592984505;29134.8346066155;29738.851305743334;29741;44891.10217795691;49738.00523989428;49743;49787.02555693195;49915.75042124766;494374;495329;498453;498469;499147;38416.966218547765;38944.80527597344;39019.829405116754;39376.40935386029;39376.652984496635;44481.77759111184;45149.77425112985;45432.772836135606;46077.539224607324;46460.76769616266;38177.42733858939;38375.84872453831;39111.217775644895;39188.41217381799;39673.60326396814;35963.68332095185;37804.62195378011;38095.61904380921;38705.612943871325;39157.60842391539;31622.05133845988;35485.46771798374;37360;37529;37667.62332376641;26647.33381665396;31474.05577832669;33862.3227535443;33906.32187356288;33926;24272.63591046171;24433.75566244386;24542.141025064037;24916.75083249144;25197.11810086638 23:50:05 INFO - PROCESS | 1647 | |2;jquery.html;385.93246181918175;386.6655343128195;396.75401251224247;405.0360142859993;416.88118886117445;19876.50308742282;21238.150473981063;21293;21411.57176856462;21650.24224152151;21378.35864924051;21454.463638409048;21792.455188620293;21866.781332186674;22075.889620551876;5185.507376799198;5699.059655156904;6025.969870150643;6029.909551356734;6048.123022161784;5475.780968761244;5597.63615365002;5966.403359664042;6032.7285272162635;6041.637501749887;4962.454130045692;5953.3451320354725;6010.429009244115;6023.819285421444;6027.306859711123;5783.103618939072;5863.560232982543;5879.118132280149;5935.49548288396;6000.729967151467;5805.477507024346;5811.27359080115;5828.883422331572;5829.504492118175;5836.737346819404;19572.608547828964;24844.751552484424;25143;25439.491210175693;25704.228873133838;2188.8577242479187;2690.4350086481863;2690.6905705843883;2695.3665888516184;2941.3970136122257;9928.553215105265;10284.691459256233;10384.428856412895;11706.356150411724;14612.123272603678;1784.2060283173994;1826.1782198010815;1840.429466865276;1872.1388161445752;1872.6348362069402;95851;129228.81593153931;129280;133106;152250.9999999989;9728.951355243178;9905.325562098336;11818.763624727457;11843.407829608484;11898.524059037714;12067.396630168632;13921.373538190806;15368.462103826312;15452.922735386252;15500.844991550166;7599.202083781123;8160.67357305713;8322;8597.398182127192;8721.563921803885;8223.301019413297;8290.917090829134;8364.817591204397;8472.915270847336;8473.067962524117;4652.209124448852;4726.149293127201;4769.122556741118;4786.44955830084;4793.7708965331285;1742.9019717577844;1985.7914918933304;2026.4731169895933;2035.9185632574827;2042.1831267493121;763.85486757517;842.9452085614415;881.6120906800987;896.4128495835108;898.2006014646969;17932.820671793113;20624.381268561967;20858.165673373198;21696.78303216979;22239;970.1850445625709;970.6893793985998;977.2475194100645;998.6504723346916;999.9500024998721;6948.27043160471;7087.787366379017;7098.148222213367;7284.6721897514735;7289.708411663474;513030;546855;702615;714804;717261;118143;124608.37695811463;127578.36210818887;153691.15772105564;167519;887.8179973105498;1080.3140006096185;1094.6934858239547;1099.4228030284164;1100.8128618134936;19196.71204931955;19235;19686.27529392964;20436.69344959797;20445.386638400872;45225.773871130434;55039.62400940008;58814.705926470095;65236;65836.01245981218;87966;92117;93146.06853931374;93240;93874.06125938654;7173.852183650591;8249.29880960125;8307.127751586238;8402.41183117176;8818.647254109765;9319.6272149116;12755.362231888183;13276.933615331862;13506;13588.388522516709;10817.930150147029;11567.652970410902;11619;11782.646520604396;11788.469518871843;11300.604478843201;11598.130140239347;11614.825777613512;11847.644570662895;11945.581904633296;9802.65690700822;11580.247283926517;11639.941800290944;11678.70803229926;11991.64025079249;8333.874991875247;8448.112948140357;8474.237318641352;8540.231379175904;8548.871766923377;10625.415602141957;11123.22137450403;11463.598774042868;11664.591739289084;11705.297682139098 23:50:05 INFO - PROCESS | 1647 | |3;mootools.html;1449.4492093004656;1452.1287227663404;1465.7361674898518;1477.3573495529438;1482.5107714454232;7330.7067717291375;7571.280728330814;7839.0985036720795;8280.047794503635;8282.047564530074;7736.38108951284;8024.67901283948;8053.033635963692;8128.796780080501;8134.959325203366;2259.920902768399;2307.5961706701346;2309;2316.3630001749484;2322.222055611364;2295.472041430464;2323.500447403808;2330.347502699247;2331.4987277735286;2336.8247381446376;2277.772222777717;2279.2022792022826;2290.141197051106;2307.849989750669;2312.606856834334;2278.270953294946;2281;2301.355620426283;2305.0549274797336;2318.8492747971327;2277.146070223666;2281.589313923493;2316.455632926261;2318.501522172724;2326.5346930613778;8162.673493060271;8164.469309494923;8216.21945915137;8222.671093156267;8240.958795206045;4968.130577148984;5109.770060347256;5119.66722163062;5124.282600435942;5134.204198349262;3242.5460435539044;3340.8997730067895;3406.6593340665736;3456.654334566548;3457.481377793351;1035.0942924940678;1188.6671731915078;1191.8450601421846;1203.3862730007672;1221.2428294457493;10350.275480716353;10355.378677279312;10388.220883433773;10440.477976101165;10445.373277603292;4449.9555004450185;4534.342520334533;4642.72143671374;4657.347971284023;4658.510856360102;9146.2225710814;9238.214751746042;9242.306826987868;9248.583813728399;9272.72181834546;4712.717236965793;5125.923111153335;5140.460251673595;5141.331626888481;5145.614078944094;4229.809658565372;4299.484061912528;4361.127774445124;4542.318652202196;4645.74448405341;3746.183101195918;3800.847966081381;3833.290841194388;3855.7879316637855;3866.4007078902814;1236.6599185224136;1265.4432049898016;1278.8273583066166;1279.270815635079;1292.5799115287568;866.8656358264479;873.5370253765515;883.8806761087171;892.9241014513709;908.5003248213588;4990.17662085759;5273.709945953009;5439.558516993;5445.9652665993335;5572.442755724395;888.4269646078243;951.8905325887473;983.409954027592;1002.0680766886866;1009.369144284822;3393.253484233465;3577.449894751546;3618.511500947391;3804.4782087164804;3835.347990841564;8815.647374105092;9686.9031309686;9693.41839489633;9696.6121355145;9740;9603.759906002266;9683.757906052406;9693.273004524688;9704.689866867882;9717.951410242904;7532.246775322424;7551.73568925085;7641.235876412315;7663.157052724196;7731.613419329011;4564.726116433025;4721.7166969980535;5501.807436739855;5564.777408903598;5571.247881535941;8680;8789.472631642122;8980.114176023108;9010.81978360416;9056.773580660536;7457.776266712007;8649.221570058726;8881.511516866634;9000.324975626723;9032.819343613222;6548.574342667712;6703.93296067033;6875.89686154698;6984.4761642878;7174.390176834819;7675.769726908202;7836.686532538634;7858.096318923397;7865.882011769942;7874;6277.717502712298;6607.603543787196;6756.966215168893;7010.859782804418;7127.144742630918;6916.55042422241;6931.1682598088555;6938.098047253825;6948.270431604608;6979.092717946837;6270.968645156745;6317.052442133533;6382.521310901608;6386.105945167773;6412;3709.5733990590347;4786.425628924551;4811.4707382188635;4823.589994850477;4937.604991600735;4336.913261734685;4468.4637843458995;4868.513148685103;4884.316195732529;4940.851774446773 23:50:05 INFO - PROCESS | 1647 | |4;prototype.html;311.64161214603206;350.27667865857;365.32050386281514;367.97424180307354;372.3298063485725;5127.461616530267;5137.964794501391;5283.973580132094;5292.867678308045;5445.346558412991;4165.458490396244;4699.201135806912;5158.716270605115;5366.6190300110975;5368.577327008336;3422.8288585570745;4179.435776170209;4199.2441360555085;4234.903722657289;4266.7439953602725;3587.3722098632766;4038.454808600831;4207.221663992172;4225.133847561258;4247.638950689196;3322.401967645823;3799.411091280856;4017.5580686124504;4078.8368465261356;4096.160287141129;3461;4060.533038700558;4085.651734927482;4100.65144462721;4121.670266378683;3552.7335449841366;3566.821658917044;3566.946495802565;3580.856765729368;3609.404448266033;4974.975125124387;5248.926673963763;5328.893422131575;5351.625386222966;5364.570834333244;3923.4311024901517;3942.8314427064997;4374.628156606692;4616.469106052779;4693.671442998992;5067.569256613192;5451.04606693827;5485.177223416479;5716.4565567296595;5743.684097374643;3323.93352132956;3558.484019817138;3594.1130906274416;3882.786446745428;3914;5450.454954504517;5768.278965129359;5938.802643022066;5971.611845230046;5994.250718660168;3363.983180084133;3551.6448355164794;3912.68698504119;3936.8031598419966;4009.4316058707773;3479.8434070466888;3860.092878173627;3983.6813054955533;4003.798860341944;4034.7175697700877;3938.4092386141733;4622.6764126510825;4666.926606880436;4675.298705194248;4682.695624784378;5080;5803.593748437569;5840.240768700041;5859.472647461664;5925.466707996301;1155.9595414160465;1159.3739380734478;1228.7173949991552;1244.993775031119;1272.3129510064646;739.9186089530148;816.9346452283803;835.7797615481466;866.7703058689452;881.6164968238836;4379.4525684289465;4415.072834704748;5068.771905264272;5128.307678463434;5217.521677203374;4837.258586908781;4996.850094497171;5434.313977202871;5646.350669672958;5692.904850159167;4203.856033422976;4423.756693381895;4860.756962151878;5040.218766091261;5047.141985862412;2589.8575578342993;2826.7809769330975;2896.840673762921;2916.2417771379596;2952.2619345163707;5042.848219862586;5047.621428392885;5405.918911216335;5839.292049548482;5895.793647222326;4935.605151587864;5412.024611017029;5688.288963879515;5790.594658373873;5844.113664413203;5094.337736094358;5241.816536421207;5313.601479889101;5705.115707065412;5835.241418615553;4838.830640927551;5499.075323636695;5549.250851135045;5608.887822243614;5661.320641523043;4754.0016596515115;4776.928346074742;4800.375951126471;5321.137601839325;5531.400241886307;5620.494155526022;5838.241028666246;5869.559783016205;5873;5879.706014699163;3441.8451169696923;3625.4380571011534;3688.834002469842;3693.7045036396485;3711.146436319608;5062.949370506248;5450.754716037709;5515.420880807457;5569.916451253315;5585.609007369445;4584.312353147055;4631.004334068191;4736.928946065881;5359;5369.624126311121;4526.343680166423;4739.502352252964;4762.380890484214;4818.927716084331;5463.72681365938;4615.569053708477;5298.337707786527;5510.559155267489;5538.806141785019;5564.276644036249;5458.781648734165;5614.494695477427;5773.325735536648;5848.912266316093;5860.472557469678;5508.586855985897;5646.209530665792;5685.688885337355;5690.756268926672;5716 23:50:05 INFO - PROCESS | 1647 | |5;yui.html;700.3591713582073;712.4620911212035;752.3943225703307;826.2357319479482;868.7263511993724;14549.199794011325;15574.45489407871;15601.53195404137;15617.921910390447;15647.217639118055;13822.101563398372;14250.71498570028;14449.711005779878;14464.493742719007;14484.986050976437;838.710644827535;846.3229416466833;847.9830403391928;851.1233429567556;851.7231899632613;682.4949537342355;702.1258533126265;836.0302049622439;855.110684887716;864.7751584588021;901.693424235763;909.122696597783;910.8178364327127;912.3795818843217;912.7991841794794;897.5915958238986;902.6299217320867;904.6562306323588;905.1175104273314;905.9943462756336;669.9765508207189;670.7585269303047;673.8955461903413;674.2616834566143;674.9156355455568;15237.847621523753;15294;15343.23283835815;15434.922825385913;15555.45555905538;14437.205953672545;14826.258687065603;14840.406383744637;14843.406263749437;14890.851091489054;11819;11868.406579670982;11946.10404219687;11956.52173913051;11991.280523168638;3631.4371272452813;3651.580068292154;3697.1126929536717;3709.9072523187137;3841.308564458396;106125.93874061237;107357;108429.37355939667;109733;111386.32920506118;9388.483633400228;11147.243816078451;11254.15593830466;11322.207445478738;11453.089530271793;23009.46895841456;23250;23264.53470930572;23313.300600982;23392;6844.589324640538;7762.650680719381;7833.099193592696;7834.294913457702;7840.215978402114;3959.703022273341;4076.9796151019054;4143.91712165755;4255.044934741778;4318.395424640553;2775.431036637525;3618.131648404364;3625;3633.5458067741533;3635.4365073413665;577.9537636989115;617.3394467919373;648.0700195219874;657.5660064357501;670.902719105727;547.9779018711807;659.6866488418059;701.7438634898225;711.434409644334;727.4725823777719;10151.94924025375;10314.638987635397;10368.792624147625;10612.522436490377;10658.893411065945;581.4360070731384;644.3813938618978;699.5452955578914;715.9785206443815;717.587387252332;3997.320455522568;4504.684672072989;4926.950730492649;4946.629002824802;4966.503349665077;95479.52260238794;96260;96558;98066.52900206503;99621.00378996262;69901.65049174824;72068.55862882818;78428.21571784324;80635.32376114903;85593;52635.736821316415;77417;77806.61096694594;78046;79225;9660.75848103803;9677.9032209677;9686.70939871805;9689.370190937567;9707.70876873695;47296;47757;48948.5105148944;50550.241746373096;51249.46251612309;64662.676686616265;66559.66720166369;67936;68216.6589167051;68611.65694171497;13646.931765341109;14165.36255868509;14218.715625687635;14248.64378390507;14263.358148883117;14554.01854330887;14651.780223296446;14868.330925108612;15069.698606028038;15105.018173818667;13888.652783680489;13994.510192143227;14041.648958776112;14514.346854391366;14562.563123106323;13771.311434428038;13850.722985540435;13935;13960.790588140982;14047.157170569799;13008.609741707764;13121.343932803513;13167.802482962954;13227.206367617973;13664.863351366359;10700;10765.730856728644;11053.173104778132;11291.24985627229;11354.659360219206;11018.44907754593;11241.269317494663;11277.71805704864;11308;11320.490577924178 23:50:05 INFO - PROCESS | 1647 | __end_tp_report 23:50:05 INFO - PROCESS | 1647 | __start_cc_report 23:50:05 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,378 23:50:05 INFO - PROCESS | 1647 | __end_cc_report 23:50:05 INFO - PROCESS | 1647 | __startTimestamp1484898605121__endTimestamp 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | ------- Summary: start ------- 23:50:05 INFO - PROCESS | 1647 | Number of tests: 6 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5678.09 Median:5983.82 stddev:674.38 (11.3%) stddev-sans-first:649.55 23:50:05 INFO - PROCESS | 1647 | Values: 3274.2 3300.1 3309.8 3334.5 3342.9 5517.6 5528.6 5540.0 5598.2 5619.0 4068.8 4471.9 5623.7 5667.1 5812.7 4525.3 4883.7 5132.4 5268.7 5297.7 5103.0 5282.1 5284.7 5317.2 5329.1 5139.2 5273.6 5275.2 5295.6 5332.6 5119.7 5155.3 5168.4 5253.2 5283.4 4685.4 4877.7 5118.6 5136.4 5146.8 4742.8 5250.4 5707.0 5715.0 5755.9 6058.9 6069.0 6076.8 6109.6 6215.3 6123.9 6138.1 6176.8 6184.9 6204.4 4761.3 5925.1 5927.6 5944.1 5956.8 6124.2 6179.1 6238.9 6289.2 6290.5 5993.2 6035.8 6044.6 6056.6 6114.8 6195.8 6220.8 6225.5 6230.8 6231.0 6041.7 6064.5 6090.4 6101.0 6120.6 6178.2 6203.8 6212.1 6224.5 6247.4 4842.5 4941.6 4956.4 5006.5 5032.1 4300.9 4403.6 4410.4 4425.9 4439.5 5820.9 5839.4 5847.9 5878.2 5885.1 6080.8 6118.1 6223.8 6250.8 6271.0 5704.6 5729.3 5734.1 5774.5 5795.3 5434.5 5441.8 5453.4 5459.7 5476.5 6161.6 6255.8 6279.7 6285.9 6286.8 6133.3 6161.7 6165.3 6180.8 6230.6 6281.7 6302.1 6306.6 6316.9 6332.3 6144.6 6208.2 6223.4 6228.1 6228.9 6137.9 6145.4 6147.0 6149.2 6166.8 4813.9 5836.0 6104.0 6137.6 6151.6 5983.2 6027.3 6116.4 6125.2 6129.8 5182.8 5815.3 5983.8 5985.4 6048.9 6090.7 6092.8 6103.1 6128.5 6137.8 5798.6 5968.2 5986.5 6001.8 6002.5 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:65530.34 Median:39065.52 stddev:136359.89 (349.1%) stddev-sans-first:136654.91 23:50:05 INFO - PROCESS | 1647 | Values: 466.1 500.1 508.4 514.0 525.0 39175.8 41567.2 41663.2 41836.0 43662.9 42227.8 42232.0 42535.1 42552.8 42752.4 8352.3 8582.3 9176.3 9423.8 9465.3 11189.8 11473.0 11475.7 11665.0 11720.2 11240.8 11358.6 11366.0 11372.6 11405.4 11395.4 11455.9 11466.0 11493.6 11514.8 11230.6 11304.8 11321.1 11327.1 11340.6 40285.0 40464.6 40563.0 40597.6 40635.0 4054.3 4057.0 4108.3 4152.2 4250.1 30457.2 30674.7 30846.5 31096.0 31125.5 3214.4 3223.0 3245.1 3269.2 3324.0 465480.0 466040.0 468351.0 469204.7 469412.0 59197.8 59321.7 59520.0 59606.4 59760.0 61280.4 61674.4 61701.7 62016.1 62129.1 13817.9 15333.8 15679.6 15828.5 15848.5 11098.2 11357.8 12166.8 12329.8 12441.7 16040.3 16116.1 16121.8 16129.3 16138.3 5326.7 5917.8 6308.1 6313.7 6335.0 867.5 886.5 887.0 889.1 914.6 29807.0 29823.3 29914.0 29932.7 30501.7 936.9 953.0 994.5 1036.4 1176.4 14386.1 14616.5 14949.6 15372.2 15435.8 485512.0 585138.0 585457.0 586335.1 588446.0 59341.1 59396.4 59451.0 59504.0 59569.7 1285.5 1353.9 1416.6 1427.8 1439.9 28212.4 28776.3 29134.8 29738.9 29741.0 44891.1 49738.0 49743.0 49787.0 49915.8 494374.0 495329.0 498453.0 498469.0 499147.0 38417.0 38944.8 39019.8 39376.4 39376.7 44481.8 45149.8 45432.8 46077.5 46460.8 38177.4 38375.8 39111.2 39188.4 39673.6 35963.7 37804.6 38095.6 38705.6 39157.6 31622.1 35485.5 37360.0 37529.0 37667.6 26647.3 31474.1 33862.3 33906.3 33926.0 24272.6 24433.8 24542.1 24916.8 25197.1 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:37276.32 Median:400.90 stddev:108101.12 (26964.9%) stddev-sans-first:108368.89 23:50:05 INFO - PROCESS | 1647 | Values: 385.9 386.7 396.8 405.0 416.9 19876.5 21238.2 21293.0 21411.6 21650.2 21378.4 21454.5 21792.5 21866.8 22075.9 5185.5 5699.1 6026.0 6029.9 6048.1 5475.8 5597.6 5966.4 6032.7 6041.6 4962.5 5953.3 6010.4 6023.8 6027.3 5783.1 5863.6 5879.1 5935.5 6000.7 5805.5 5811.3 5828.9 5829.5 5836.7 19572.6 24844.8 25143.0 25439.5 25704.2 2188.9 2690.4 2690.7 2695.4 2941.4 9928.6 10284.7 10384.4 11706.4 14612.1 1784.2 1826.2 1840.4 1872.1 1872.6 95851.0 129228.8 129280.0 133106.0 152251.0 9729.0 9905.3 11818.8 11843.4 11898.5 12067.4 13921.4 15368.5 15452.9 15500.8 7599.2 8160.7 8322.0 8597.4 8721.6 8223.3 8290.9 8364.8 8472.9 8473.1 4652.2 4726.1 4769.1 4786.4 4793.8 1742.9 1985.8 2026.5 2035.9 2042.2 763.9 842.9 881.6 896.4 898.2 17932.8 20624.4 20858.2 21696.8 22239.0 970.2 970.7 977.2 998.7 1000.0 6948.3 7087.8 7098.1 7284.7 7289.7 513030.0 546855.0 702615.0 714804.0 717261.0 118143.0 124608.4 127578.4 153691.2 167519.0 887.8 1080.3 1094.7 1099.4 1100.8 19196.7 19235.0 19686.3 20436.7 20445.4 45225.8 55039.6 58814.7 65236.0 65836.0 87966.0 92117.0 93146.1 93240.0 93874.1 7173.9 8249.3 8307.1 8402.4 8818.6 9319.6 12755.4 13276.9 13506.0 13588.4 10817.9 11567.7 11619.0 11782.6 11788.5 11300.6 11598.1 11614.8 11847.6 11945.6 9802.7 11580.2 11639.9 11678.7 11991.6 8333.9 8448.1 8474.2 8540.2 8548.9 10625.4 11123.2 11463.6 11664.6 11705.3 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5274.72 Median:5130.06 stddev:2837.51 (55.3%) stddev-sans-first:2830.91 23:50:05 INFO - PROCESS | 1647 | Values: 1449.4 1452.1 1465.7 1477.4 1482.5 7330.7 7571.3 7839.1 8280.0 8282.0 7736.4 8024.7 8053.0 8128.8 8135.0 2259.9 2307.6 2309.0 2316.4 2322.2 2295.5 2323.5 2330.3 2331.5 2336.8 2277.8 2279.2 2290.1 2307.8 2312.6 2278.3 2281.0 2301.4 2305.1 2318.8 2277.1 2281.6 2316.5 2318.5 2326.5 8162.7 8164.5 8216.2 8222.7 8241.0 4968.1 5109.8 5119.7 5124.3 5134.2 3242.5 3340.9 3406.7 3456.7 3457.5 1035.1 1188.7 1191.8 1203.4 1221.2 10350.3 10355.4 10388.2 10440.5 10445.4 4450.0 4534.3 4642.7 4657.3 4658.5 9146.2 9238.2 9242.3 9248.6 9272.7 4712.7 5125.9 5140.5 5141.3 5145.6 4229.8 4299.5 4361.1 4542.3 4645.7 3746.2 3800.8 3833.3 3855.8 3866.4 1236.7 1265.4 1278.8 1279.3 1292.6 866.9 873.5 883.9 892.9 908.5 4990.2 5273.7 5439.6 5446.0 5572.4 888.4 951.9 983.4 1002.1 1009.4 3393.3 3577.4 3618.5 3804.5 3835.3 8815.6 9686.9 9693.4 9696.6 9740.0 9603.8 9683.8 9693.3 9704.7 9718.0 7532.2 7551.7 7641.2 7663.2 7731.6 4564.7 4721.7 5501.8 5564.8 5571.2 8680.0 8789.5 8980.1 9010.8 9056.8 7457.8 8649.2 8881.5 9000.3 9032.8 6548.6 6703.9 6875.9 6984.5 7174.4 7675.8 7836.7 7858.1 7865.9 7874.0 6277.7 6607.6 6757.0 7010.9 7127.1 6916.6 6931.2 6938.1 6948.3 6979.1 6271.0 6317.1 6382.5 6386.1 6412.0 3709.6 4786.4 4811.5 4823.6 4937.6 4336.9 4468.5 4868.5 4884.3 4940.9 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4457.31 Median:4955.29 stddev:1375.86 (27.8%) stddev-sans-first:1344.07 23:50:05 INFO - PROCESS | 1647 | Values: 311.6 350.3 365.3 368.0 372.3 5127.5 5138.0 5284.0 5292.9 5445.3 4165.5 4699.2 5158.7 5366.6 5368.6 3422.8 4179.4 4199.2 4234.9 4266.7 3587.4 4038.5 4207.2 4225.1 4247.6 3322.4 3799.4 4017.6 4078.8 4096.2 3461.0 4060.5 4085.7 4100.7 4121.7 3552.7 3566.8 3566.9 3580.9 3609.4 4975.0 5248.9 5328.9 5351.6 5364.6 3923.4 3942.8 4374.6 4616.5 4693.7 5067.6 5451.0 5485.2 5716.5 5743.7 3323.9 3558.5 3594.1 3882.8 3914.0 5450.5 5768.3 5938.8 5971.6 5994.3 3364.0 3551.6 3912.7 3936.8 4009.4 3479.8 3860.1 3983.7 4003.8 4034.7 3938.4 4622.7 4666.9 4675.3 4682.7 5080.0 5803.6 5840.2 5859.5 5925.5 1156.0 1159.4 1228.7 1245.0 1272.3 739.9 816.9 835.8 866.8 881.6 4379.5 4415.1 5068.8 5128.3 5217.5 4837.3 4996.9 5434.3 5646.4 5692.9 4203.9 4423.8 4860.8 5040.2 5047.1 2589.9 2826.8 2896.8 2916.2 2952.3 5042.8 5047.6 5405.9 5839.3 5895.8 4935.6 5412.0 5688.3 5790.6 5844.1 5094.3 5241.8 5313.6 5705.1 5835.2 4838.8 5499.1 5549.3 5608.9 5661.3 4754.0 4776.9 4800.4 5321.1 5531.4 5620.5 5838.2 5869.6 5873.0 5879.7 3441.8 3625.4 3688.8 3693.7 3711.1 5062.9 5450.8 5515.4 5569.9 5585.6 4584.3 4631.0 4736.9 5359.0 5369.6 4526.3 4739.5 4762.4 4818.9 5463.7 4615.6 5298.3 5510.6 5538.8 5564.3 5458.8 5614.5 5773.3 5848.9 5860.5 5508.6 5646.2 5685.7 5690.8 5716.0 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20043.80 Median:4037.15 stddev:28184.40 (698.1%) stddev-sans-first:28226.04 23:50:05 INFO - PROCESS | 1647 | Values: 700.4 712.5 752.4 826.2 868.7 14549.2 15574.5 15601.5 15617.9 15647.2 13822.1 14250.7 14449.7 14464.5 14485.0 838.7 846.3 848.0 851.1 851.7 682.5 702.1 836.0 855.1 864.8 901.7 909.1 910.8 912.4 912.8 897.6 902.6 904.7 905.1 906.0 670.0 670.8 673.9 674.3 674.9 15237.8 15294.0 15343.2 15434.9 15555.5 14437.2 14826.3 14840.4 14843.4 14890.9 11819.0 11868.4 11946.1 11956.5 11991.3 3631.4 3651.6 3697.1 3709.9 3841.3 106125.9 107357.0 108429.4 109733.0 111386.3 9388.5 11147.2 11254.2 11322.2 11453.1 23009.5 23250.0 23264.5 23313.3 23392.0 6844.6 7762.7 7833.1 7834.3 7840.2 3959.7 4077.0 4143.9 4255.0 4318.4 2775.4 3618.1 3625.0 3633.5 3635.4 578.0 617.3 648.1 657.6 670.9 548.0 659.7 701.7 711.4 727.5 10151.9 10314.6 10368.8 10612.5 10658.9 581.4 644.4 699.5 716.0 717.6 3997.3 4504.7 4927.0 4946.6 4966.5 95479.5 96260.0 96558.0 98066.5 99621.0 69901.7 72068.6 78428.2 80635.3 85593.0 52635.7 77417.0 77806.6 78046.0 79225.0 9660.8 9677.9 9686.7 9689.4 9707.7 47296.0 47757.0 48948.5 50550.2 51249.5 64662.7 66559.7 67936.0 68216.7 68611.7 13646.9 14165.4 14218.7 14248.6 14263.4 14554.0 14651.8 14868.3 15069.7 15105.0 13888.7 13994.5 14041.6 14514.3 14562.6 13771.3 13850.7 13935.0 13960.8 14047.2 13008.6 13121.3 13167.8 13227.2 13664.9 10700.0 10765.7 11053.2 11291.2 11354.7 11018.4 11241.3 11277.7 11308.0 11320.5 23:50:05 INFO - PROCESS | 1647 | -------- Summary: end -------- 23:50:05 INFO - PROCESS | 1647 | 23:50:05 INFO - TEST-INFO | 1647: exit 0 23:50:05 INFO - TEST-OK | dromaeo_css | took 1115681ms 23:50:05 INFO - TEST-START | kraken 23:50:05 INFO - operating with platform_type : mac_ 23:50:05 INFO - Initialising browser for kraken test... 23:50:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpNts4Vq/profile http://localhost:49237/getInfo.html) 23:50:08 INFO - PROCESS | 1655 | 2017-01-19 23:50:08.618 plugin-container[1656:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 23:50:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:50:08 INFO - PROCESS | 1655 | 2017-01-19 23:50:08.620 plugin-container[1656:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 23:50:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:50:08 INFO - PROCESS | 1655 | 2017-01-19 23:50:08.621 plugin-container[1656:9984] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:50:08 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 23:50:08 INFO - PROCESS | 1655 | colorDepth:24 23:50:08 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 23:50:08 INFO - PROCESS | 1655 | __metrics 23:50:08 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 23:50:08 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:50:09 INFO - TEST-INFO | 1655: exit 0 23:50:09 INFO - Browser initialized. 23:50:09 INFO - Running cycle 1/1 for kraken test... 23:50:09 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpNts4Vq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:50:12 INFO - PROCESS | 1657 | 2017-01-19 23:50:12.043 plugin-container[1658:10101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 23:50:12 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:50:12 INFO - PROCESS | 1657 | 2017-01-19 23:50:12.043 plugin-container[1658:10101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 23:50:12 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:50:12 INFO - PROCESS | 1657 | 2017-01-19 23:50:12.043 plugin-container[1658:10101] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:50:21 INFO - PROCESS | 1657 | RSS: Main: 232157184 23:50:21 INFO - PROCESS | 1657 | 23:50:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 23:50:25 INFO - PROCESS | 1657 | RSS: Main: 245587968 23:50:25 INFO - PROCESS | 1657 | 23:50:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 23:50:29 INFO - PROCESS | 1657 | RSS: Main: 253702144 23:50:29 INFO - PROCESS | 1657 | 23:50:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 23:50:33 INFO - PROCESS | 1657 | RSS: Main: 256405504 23:50:33 INFO - PROCESS | 1657 | 23:50:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 23:50:36 INFO - PROCESS | 1657 | RSS: Main: 257507328 23:50:36 INFO - PROCESS | 1657 | 23:50:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:50:38 INFO - PROCESS | 1657 | RSS: Main: 259158016 23:50:38 INFO - PROCESS | 1657 | 23:50:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 23:50:42 INFO - PROCESS | 1657 | RSS: Main: 264343552 23:50:42 INFO - PROCESS | 1657 | 23:50:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 23:50:46 INFO - PROCESS | 1657 | RSS: Main: 263680000 23:50:46 INFO - PROCESS | 1657 | 23:50:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 23:50:49 INFO - PROCESS | 1657 | RSS: Main: 263299072 23:50:49 INFO - PROCESS | 1657 | 23:50:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:50:51 INFO - PROCESS | 1657 | RSS: Main: 262922240 23:50:51 INFO - PROCESS | 1657 | 23:50:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:50:54 INFO - PROCESS | 1657 | RSS: Main: 262963200 23:50:54 INFO - PROCESS | 1657 | 23:50:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:50:57 INFO - PROCESS | 1657 | RSS: Main: 263032832 23:50:57 INFO - PROCESS | 1657 | 23:51:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:51:01 INFO - PROCESS | 1657 | RSS: Main: 263528448 23:51:01 INFO - PROCESS | 1657 | 23:51:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:51:04 INFO - PROCESS | 1657 | RSS: Main: 263221248 23:51:04 INFO - PROCESS | 1657 | 23:51:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 23:51:07 INFO - PROCESS | 1657 | RSS: Main: 266641408 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | __start_tp_report 23:51:07 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 23:51:07 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 23:51:07 INFO - PROCESS | 1657 | |i|pagename|runs| 23:51:07 INFO - PROCESS | 1657 | |0;ai-astar;104;117;117;124;163;176;154;118;115;118 23:51:07 INFO - PROCESS | 1657 | |1;audio-beat-detection;146;114;113;171;145;126;140;113;127;119 23:51:07 INFO - PROCESS | 1657 | |2;audio-dft;202;268;259;197;198;191;199;195;235;199 23:51:07 INFO - PROCESS | 1657 | |3;audio-fft;84;58;51;104;75;52;69;82;96;86 23:51:07 INFO - PROCESS | 1657 | |4;audio-oscillator;89;70;84;80;102;66;77;102;100;111 23:51:07 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;93;88;92;93;110;132;131;88;92;87 23:51:07 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;97;97;97;96;126;106;155;101;139 23:51:07 INFO - PROCESS | 1657 | |7;imaging-desaturate;75;144;139;92;92;92;92;92;93;93 23:51:07 INFO - PROCESS | 1657 | |8;json-parse-financial;62;62;63;63;63;64;63;63;64;62 23:51:07 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;69;73;76;76;75;69;71;73;71;72 23:51:07 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;94;94;89;87;87;106;115;108;108 23:51:07 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;128;178;149;159;156;145;142;169;193;161 23:51:07 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;177;178;179;179;179;177;176;243;190;255 23:51:07 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;69;72;87;88;92;88;88;76;67 23:51:07 INFO - PROCESS | 1657 | __end_tp_report 23:51:07 INFO - PROCESS | 1657 | __start_cc_report 23:51:07 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,912 23:51:07 INFO - PROCESS | 1657 | __end_cc_report 23:51:07 INFO - PROCESS | 1657 | __startTimestamp1484898667065__endTimestamp 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | ------- Summary: start ------- 23:51:07 INFO - PROCESS | 1657 | Number of tests: 14 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:130.60 Median:121.00 stddev:24.36 (20.1%) stddev-sans-first:23.86 23:51:07 INFO - PROCESS | 1657 | Values: 104.0 117.0 117.0 124.0 163.0 176.0 154.0 118.0 115.0 118.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:133.50 stddev:18.92 (14.2%) stddev-sans-first:19.32 23:51:07 INFO - PROCESS | 1657 | Values: 146.0 114.0 113.0 171.0 145.0 126.0 140.0 113.0 127.0 119.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:214.30 Median:200.50 stddev:28.69 (14.3%) stddev-sans-first:30.09 23:51:07 INFO - PROCESS | 1657 | Values: 202.0 268.0 259.0 197.0 198.0 191.0 199.0 195.0 235.0 199.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:75.70 Median:78.50 stddev:18.13 (23.1%) stddev-sans-first:18.98 23:51:07 INFO - PROCESS | 1657 | Values: 84.0 58.0 51.0 104.0 75.0 52.0 69.0 82.0 96.0 86.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:73.50 stddev:15.18 (20.7%) stddev-sans-first:16.10 23:51:07 INFO - PROCESS | 1657 | Values: 89.0 70.0 84.0 80.0 102.0 66.0 77.0 102.0 100.0 111.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:100.60 Median:90.00 stddev:17.53 (19.5%) stddev-sans-first:18.37 23:51:07 INFO - PROCESS | 1657 | Values: 93.0 88.0 92.0 93.0 110.0 132.0 131.0 88.0 92.0 87.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:96.50 stddev:21.29 (22.1%) stddev-sans-first:21.96 23:51:07 INFO - PROCESS | 1657 | Values: 97.0 97.0 97.0 97.0 96.0 126.0 106.0 155.0 101.0 139.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:100.40 Median:92.00 stddev:22.36 (24.3%) stddev-sans-first:21.74 23:51:07 INFO - PROCESS | 1657 | Values: 75.0 144.0 139.0 92.0 92.0 92.0 92.0 92.0 93.0 93.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 23:51:07 INFO - PROCESS | 1657 | Values: 62.0 62.0 63.0 63.0 63.0 64.0 63.0 63.0 64.0 62.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:2.59 (3.6%) stddev-sans-first:2.42 23:51:07 INFO - PROCESS | 1657 | Values: 69.0 73.0 76.0 76.0 75.0 69.0 71.0 73.0 71.0 72.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:97.80 Median:88.00 stddev:10.39 (11.8%) stddev-sans-first:10.63 23:51:07 INFO - PROCESS | 1657 | Values: 90.0 94.0 94.0 89.0 87.0 87.0 106.0 115.0 108.0 108.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:160.00 stddev:18.76 (11.7%) stddev-sans-first:16.45 23:51:07 INFO - PROCESS | 1657 | Values: 128.0 178.0 149.0 159.0 156.0 145.0 142.0 169.0 193.0 161.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:179.00 stddev:29.75 (16.6%) stddev-sans-first:30.97 23:51:07 INFO - PROCESS | 1657 | Values: 177.0 178.0 179.0 179.0 179.0 177.0 176.0 243.0 190.0 255.0 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:87.50 stddev:10.11 (11.6%) stddev-sans-first:9.68 23:51:07 INFO - PROCESS | 1657 | Values: 67.0 69.0 72.0 87.0 88.0 92.0 88.0 88.0 76.0 67.0 23:51:07 INFO - PROCESS | 1657 | -------- Summary: end -------- 23:51:07 INFO - PROCESS | 1657 | 23:51:07 INFO - TEST-INFO | 1657: exit 0 23:51:07 INFO - TEST-OK | kraken | took 62144ms 23:51:07 INFO - SUITE-END | took 1177s 23:51:08 INFO - Completed test suite (00:19:38) 23:51:08 INFO - javascript benchmark 23:51:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7234.859150212958, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3274.1814546363394, 3300.125466751311, 3309.7848639838394, 3334.5164951082093, 3342.882999095032, 5517.641353312032, 5528.612997090206, 5540.002799496089, 5598.1602759586085, 5618.971905140479, 4068.776217308047, 4471.910561788763, 5623.690697011662, 5667.064934285848, 5812.738426770795, 4525.343825145352, 4883.681406020391, 5132.435432102485, 5268.736563171826, 5297.655652382602, 5102.974485127569, 5282.102042652739, 5284.735763211843, 5317.175837745156, 5329.147336426173, 5139.229115632685, 5273.551748101416, 5275.182346736224, 5295.602829787743, 5332.626716129873, 5119.744012799345, 5155.2524883892, 5168.405633352175, 5253.185756207794, 5283.418823929364, 4685.4377474702915, 4877.707407537006, 5118.590512759008, 5136.357955255593, 5146.794128234867, 4742.762861856928, 5250.422453530146, 5706.971465142689, 5714.971425142848, 5755.942440575583, 6058.878822423571, 6069.02895536712, 6076.787312444088, 6109.633421994695, 6215.254169499689, 6123.908141377883, 6138.1431767337435, 6176.84557886052, 6184.9485587450245, 6204.410580994843, 4761.285807128958, 5925.111233315038, 5927.555433342473, 5944.108383742473, 5956.791512297092, 6124.167343420691, 6179.104029915727, 6238.875222495525, 6289.182406287245, 6290.465310448663, 5993.220881285405, 6035.818925432244, 6044.576879618472, 6056.636601803818, 6114.814446323713, 6195.752169913243, 6220.782272620437, 6225.470834979077, 6230.813075607739, 6230.968845155745, 6041.697915104227, 6064.454199122012, 6090.421409966046, 6100.969495152496, 6120.599512025131, 6178.227721534809, 6203.8138855834395, 6212.1303017577575, 6224.470919971763, 6247.406496382836, 4842.491538388418, 4941.604671626261, 4956.405231372186, 5006.524380183877, 5032.119379108642, 4300.8709738707885, 4403.581659742319, 4410.404595379647, 4425.911481770411, 4439.533848945879, 5820.88358232833, 5839.416058394127, 5847.883042339215, 5878.177055212273, 5885.087811389241, 6080.847978800477, 6118.131909153847, 6223.782167624111, 6250.812475625738, 6271.0, 5704.572157088235, 5729.28383952006, 5734.139879018097, 5774.451427114417, 5795.33353664334, 5434.483724046288, 5441.755121019484, 5453.400125986138, 5459.727013649302, 5476.452354764571, 6161.599496032743, 6255.843603909939, 6279.654618995816, 6285.8742825143245, 6286.811395658137, 6133.334663918993, 6161.661108639068, 6165.321814600301, 6180.81457556274, 6230.563860529719, 6281.654509001866, 6302.1492098566105, 6306.558540902093, 6316.873662526816, 6332.335104813928, 6144.56988010835, 6208.161898143693, 6223.377662233922, 6228.065790131516, 6228.875422491616, 6137.8979746733885, 6145.446909778141, 6147.047207682816, 6149.20060392164, 6166.845828854314, 4813.927791083207, 5836.007878660553, 6104.0, 6137.570370074052, 6151.6001459904965, 5983.162357269899, 6027.336992930862, 6116.35778243278, 6125.173101631222, 6129.816105516842, 5182.844514664566, 5815.331236907809, 5983.820485385445, 5985.371535988658, 6048.939510605013, 6090.725917333642, 6092.786752463642, 6103.084537319438, 6128.4790792783115, 6137.815865524041, 5798.5651076168615, 5968.22413086296, 5986.491148252448, 6001.819945401645, 6002.489788368038], "value": 5635.413961000969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.13066630733675, 500.0548962460947, 508.3848543262652, 513.9665921715089, 524.9631976845728, 39175.80412097939, 41567.16865662688, 41663.16673666525, 41835.95410114745, 43662.90842728933, 42227.788861055655, 42232.0, 42535.14929701404, 42552.78723606385, 42752.3587146193, 8352.290055345304, 8582.270507006911, 9176.265898728103, 9423.81152376952, 9465.29010324225, 11189.776204475906, 11473.0, 11475.655730328104, 11665.0, 11720.179587428884, 11240.775184496306, 11358.60244891429, 11366.0, 11372.601958931438, 11405.37270450125, 11395.373254471, 11455.942720286428, 11465.968062874297, 11493.597724079616, 11514.769704605944, 11230.55077796887, 11304.773904521864, 11321.094312455067, 11327.093832493381, 11340.6030788922, 40285.0, 40464.595354046374, 40563.0, 40597.594024059974, 40635.0, 4054.3107671695884, 4056.979715101435, 4108.301588729923, 4152.211079894841, 4250.128723611652, 30457.23856903595, 30674.693253067406, 30846.52725354811, 31096.0, 31125.533117003262, 3214.437473442138, 3222.983885080583, 3245.0589329094532, 3269.1663625775514, 3323.9833800831084, 465480.0, 466040.0, 468351.0, 469204.65397673135, 469412.0, 59197.81604367888, 59321.70339148363, 59520.0, 59606.40393596095, 59760.0, 61280.38719612836, 61674.38325616776, 61701.69149154225, 62016.06975895365, 62129.06806397907, 13817.861821381859, 15333.846661533466, 15679.608009799618, 15828.52514424569, 15848.52454426369, 11098.167637427065, 11357.750647428777, 12166.81749773754, 12329.815052774216, 12441.688957776129, 16040.278187481592, 16116.113613751122, 16121.758173627404, 16129.274182661808, 16138.273777680033, 5326.6537675050995, 5917.822465326047, 6308.116863639094, 6313.652749098752, 6335.0, 867.4535042922918, 886.4946980221341, 886.9600867960958, 889.1464194373344, 914.5610107148606, 29807.0, 29823.254418639706, 29914.0, 29932.700672992993, 30501.69498305033, 936.9297302702324, 953.046953046953, 994.5126887824914, 1036.362636978253, 1176.4235524592889, 14386.064905781092, 14616.492041910078, 14949.551513454613, 15372.231388430533, 15435.768463473058, 485512.0, 585138.0, 585457.0, 586335.0683246641, 588446.0, 59341.10988335178, 59396.406035939086, 59451.0, 59504.0, 59569.702151488964, 1285.5050805439973, 1353.9255340956145, 1416.6316757643087, 1427.7858321251895, 1439.9136051836713, 28212.43575128527, 28776.280592984505, 29134.8346066155, 29738.851305743334, 29741.0, 44891.10217795691, 49738.00523989428, 49743.0, 49787.02555693195, 49915.75042124766, 494374.0, 495329.0, 498453.0, 498469.0, 499147.0, 38416.966218547765, 38944.80527597344, 39019.829405116754, 39376.40935386029, 39376.652984496635, 44481.77759111184, 45149.77425112985, 45432.772836135606, 46077.539224607324, 46460.76769616266, 38177.42733858939, 38375.84872453831, 39111.217775644895, 39188.41217381799, 39673.60326396814, 35963.68332095185, 37804.62195378011, 38095.61904380921, 38705.612943871325, 39157.60842391539, 31622.05133845988, 35485.46771798374, 37360.0, 37529.0, 37667.62332376641, 26647.33381665396, 31474.05577832669, 33862.3227535443, 33906.32187356288, 33926.0, 24272.63591046171, 24433.75566244386, 24542.141025064037, 24916.75083249144, 25197.11810086638], "value": 20001.356243045826, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.93246181918175, 386.6655343128195, 396.75401251224247, 405.0360142859993, 416.88118886117445, 19876.50308742282, 21238.150473981063, 21293.0, 21411.57176856462, 21650.24224152151, 21378.35864924051, 21454.463638409048, 21792.455188620293, 21866.781332186674, 22075.889620551876, 5185.507376799198, 5699.059655156904, 6025.969870150643, 6029.909551356734, 6048.123022161784, 5475.780968761244, 5597.63615365002, 5966.403359664042, 6032.7285272162635, 6041.637501749887, 4962.454130045692, 5953.3451320354725, 6010.429009244115, 6023.819285421444, 6027.306859711123, 5783.103618939072, 5863.560232982543, 5879.118132280149, 5935.49548288396, 6000.729967151467, 5805.477507024346, 5811.27359080115, 5828.883422331572, 5829.504492118175, 5836.737346819404, 19572.608547828964, 24844.751552484424, 25143.0, 25439.491210175693, 25704.228873133838, 2188.8577242479187, 2690.4350086481863, 2690.6905705843883, 2695.3665888516184, 2941.3970136122257, 9928.553215105265, 10284.691459256233, 10384.428856412895, 11706.356150411724, 14612.123272603678, 1784.2060283173994, 1826.1782198010815, 1840.429466865276, 1872.1388161445752, 1872.6348362069402, 95851.0, 129228.81593153931, 129280.0, 133106.0, 152250.9999999989, 9728.951355243178, 9905.325562098336, 11818.763624727457, 11843.407829608484, 11898.524059037714, 12067.396630168632, 13921.373538190806, 15368.462103826312, 15452.922735386252, 15500.844991550166, 7599.202083781123, 8160.67357305713, 8322.0, 8597.398182127192, 8721.563921803885, 8223.301019413297, 8290.917090829134, 8364.817591204397, 8472.915270847336, 8473.067962524117, 4652.209124448852, 4726.149293127201, 4769.122556741118, 4786.44955830084, 4793.7708965331285, 1742.9019717577844, 1985.7914918933304, 2026.4731169895933, 2035.9185632574827, 2042.1831267493121, 763.85486757517, 842.9452085614415, 881.6120906800987, 896.4128495835108, 898.2006014646969, 17932.820671793113, 20624.381268561967, 20858.165673373198, 21696.78303216979, 22239.0, 970.1850445625709, 970.6893793985998, 977.2475194100645, 998.6504723346916, 999.9500024998721, 6948.27043160471, 7087.787366379017, 7098.148222213367, 7284.6721897514735, 7289.708411663474, 513030.0, 546855.0, 702615.0, 714804.0, 717261.0, 118143.0, 124608.37695811463, 127578.36210818887, 153691.15772105564, 167519.0, 887.8179973105498, 1080.3140006096185, 1094.6934858239547, 1099.4228030284164, 1100.8128618134936, 19196.71204931955, 19235.0, 19686.27529392964, 20436.69344959797, 20445.386638400872, 45225.773871130434, 55039.62400940008, 58814.705926470095, 65236.0, 65836.01245981218, 87966.0, 92117.0, 93146.06853931374, 93240.0, 93874.06125938654, 7173.852183650591, 8249.29880960125, 8307.127751586238, 8402.41183117176, 8818.647254109765, 9319.6272149116, 12755.362231888183, 13276.933615331862, 13506.0, 13588.388522516709, 10817.930150147029, 11567.652970410902, 11619.0, 11782.646520604396, 11788.469518871843, 11300.604478843201, 11598.130140239347, 11614.825777613512, 11847.644570662895, 11945.581904633296, 9802.65690700822, 11580.247283926517, 11639.941800290944, 11678.70803229926, 11991.64025079249, 8333.874991875247, 8448.112948140357, 8474.237318641352, 8540.231379175904, 8548.871766923377, 10625.415602141957, 11123.22137450403, 11463.598774042868, 11664.591739289084, 11705.297682139098], "value": 9746.325674047977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.4492093004656, 1452.1287227663404, 1465.7361674898518, 1477.3573495529438, 1482.5107714454232, 7330.7067717291375, 7571.280728330814, 7839.0985036720795, 8280.047794503635, 8282.047564530074, 7736.38108951284, 8024.67901283948, 8053.033635963692, 8128.796780080501, 8134.959325203366, 2259.920902768399, 2307.5961706701346, 2309.0, 2316.3630001749484, 2322.222055611364, 2295.472041430464, 2323.500447403808, 2330.347502699247, 2331.4987277735286, 2336.8247381446376, 2277.772222777717, 2279.2022792022826, 2290.141197051106, 2307.849989750669, 2312.606856834334, 2278.270953294946, 2281.0, 2301.355620426283, 2305.0549274797336, 2318.8492747971327, 2277.146070223666, 2281.589313923493, 2316.455632926261, 2318.501522172724, 2326.5346930613778, 8162.673493060271, 8164.469309494923, 8216.21945915137, 8222.671093156267, 8240.958795206045, 4968.130577148984, 5109.770060347256, 5119.66722163062, 5124.282600435942, 5134.204198349262, 3242.5460435539044, 3340.8997730067895, 3406.6593340665736, 3456.654334566548, 3457.481377793351, 1035.0942924940678, 1188.6671731915078, 1191.8450601421846, 1203.3862730007672, 1221.2428294457493, 10350.275480716353, 10355.378677279312, 10388.220883433773, 10440.477976101165, 10445.373277603292, 4449.9555004450185, 4534.342520334533, 4642.72143671374, 4657.347971284023, 4658.510856360102, 9146.2225710814, 9238.214751746042, 9242.306826987868, 9248.583813728399, 9272.72181834546, 4712.717236965793, 5125.923111153335, 5140.460251673595, 5141.331626888481, 5145.614078944094, 4229.809658565372, 4299.484061912528, 4361.127774445124, 4542.318652202196, 4645.74448405341, 3746.183101195918, 3800.847966081381, 3833.290841194388, 3855.7879316637855, 3866.4007078902814, 1236.6599185224136, 1265.4432049898016, 1278.8273583066166, 1279.270815635079, 1292.5799115287568, 866.8656358264479, 873.5370253765515, 883.8806761087171, 892.9241014513709, 908.5003248213588, 4990.17662085759, 5273.709945953009, 5439.558516993, 5445.9652665993335, 5572.442755724395, 888.4269646078243, 951.8905325887473, 983.409954027592, 1002.0680766886866, 1009.369144284822, 3393.253484233465, 3577.449894751546, 3618.511500947391, 3804.4782087164804, 3835.347990841564, 8815.647374105092, 9686.9031309686, 9693.41839489633, 9696.6121355145, 9740.0, 9603.759906002266, 9683.757906052406, 9693.273004524688, 9704.689866867882, 9717.951410242904, 7532.246775322424, 7551.73568925085, 7641.235876412315, 7663.157052724196, 7731.613419329011, 4564.726116433025, 4721.7166969980535, 5501.807436739855, 5564.777408903598, 5571.247881535941, 8680.0, 8789.472631642122, 8980.114176023108, 9010.81978360416, 9056.773580660536, 7457.776266712007, 8649.221570058726, 8881.511516866634, 9000.324975626723, 9032.819343613222, 6548.574342667712, 6703.93296067033, 6875.89686154698, 6984.4761642878, 7174.390176834819, 7675.769726908202, 7836.686532538634, 7858.096318923397, 7865.882011769942, 7874.0, 6277.717502712298, 6607.603543787196, 6756.966215168893, 7010.859782804418, 7127.144742630918, 6916.55042422241, 6931.1682598088555, 6938.098047253825, 6948.270431604608, 6979.092717946837, 6270.968645156745, 6317.052442133533, 6382.521310901608, 6386.105945167773, 6412.0, 3709.5733990590347, 4786.425628924551, 4811.4707382188635, 4823.589994850477, 4937.604991600735, 4336.913261734685, 4468.4637843458995, 4868.513148685103, 4884.316195732529, 4940.851774446773], "value": 4321.473839315903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.64161214603206, 350.27667865857, 365.32050386281514, 367.97424180307354, 372.3298063485725, 5127.461616530267, 5137.964794501391, 5283.973580132094, 5292.867678308045, 5445.346558412991, 4165.458490396244, 4699.201135806912, 5158.716270605115, 5366.6190300110975, 5368.577327008336, 3422.8288585570745, 4179.435776170209, 4199.2441360555085, 4234.903722657289, 4266.7439953602725, 3587.3722098632766, 4038.454808600831, 4207.221663992172, 4225.133847561258, 4247.638950689196, 3322.401967645823, 3799.411091280856, 4017.5580686124504, 4078.8368465261356, 4096.160287141129, 3461.0, 4060.533038700558, 4085.651734927482, 4100.65144462721, 4121.670266378683, 3552.7335449841366, 3566.821658917044, 3566.946495802565, 3580.856765729368, 3609.404448266033, 4974.975125124387, 5248.926673963763, 5328.893422131575, 5351.625386222966, 5364.570834333244, 3923.4311024901517, 3942.8314427064997, 4374.628156606692, 4616.469106052779, 4693.671442998992, 5067.569256613192, 5451.04606693827, 5485.177223416479, 5716.4565567296595, 5743.684097374643, 3323.93352132956, 3558.484019817138, 3594.1130906274416, 3882.786446745428, 3914.0, 5450.454954504517, 5768.278965129359, 5938.802643022066, 5971.611845230046, 5994.250718660168, 3363.983180084133, 3551.6448355164794, 3912.68698504119, 3936.8031598419966, 4009.4316058707773, 3479.8434070466888, 3860.092878173627, 3983.6813054955533, 4003.798860341944, 4034.7175697700877, 3938.4092386141733, 4622.6764126510825, 4666.926606880436, 4675.298705194248, 4682.695624784378, 5080.0, 5803.593748437569, 5840.240768700041, 5859.472647461664, 5925.466707996301, 1155.9595414160465, 1159.3739380734478, 1228.7173949991552, 1244.993775031119, 1272.3129510064646, 739.9186089530148, 816.9346452283803, 835.7797615481466, 866.7703058689452, 881.6164968238836, 4379.4525684289465, 4415.072834704748, 5068.771905264272, 5128.307678463434, 5217.521677203374, 4837.258586908781, 4996.850094497171, 5434.313977202871, 5646.350669672958, 5692.904850159167, 4203.856033422976, 4423.756693381895, 4860.756962151878, 5040.218766091261, 5047.141985862412, 2589.8575578342993, 2826.7809769330975, 2896.840673762921, 2916.2417771379596, 2952.2619345163707, 5042.848219862586, 5047.621428392885, 5405.918911216335, 5839.292049548482, 5895.793647222326, 4935.605151587864, 5412.024611017029, 5688.288963879515, 5790.594658373873, 5844.113664413203, 5094.337736094358, 5241.816536421207, 5313.601479889101, 5705.115707065412, 5835.241418615553, 4838.830640927551, 5499.075323636695, 5549.250851135045, 5608.887822243614, 5661.320641523043, 4754.0016596515115, 4776.928346074742, 4800.375951126471, 5321.137601839325, 5531.400241886307, 5620.494155526022, 5838.241028666246, 5869.559783016205, 5873.0, 5879.706014699163, 3441.8451169696923, 3625.4380571011534, 3688.834002469842, 3693.7045036396485, 3711.146436319608, 5062.949370506248, 5450.754716037709, 5515.420880807457, 5569.916451253315, 5585.609007369445, 4584.312353147055, 4631.004334068191, 4736.928946065881, 5359.0, 5369.624126311121, 4526.343680166423, 4739.502352252964, 4762.380890484214, 4818.927716084331, 5463.72681365938, 4615.569053708477, 5298.337707786527, 5510.559155267489, 5538.806141785019, 5564.276644036249, 5458.781648734165, 5614.494695477427, 5773.325735536648, 5848.912266316093, 5860.472557469678, 5508.586855985897, 5646.209530665792, 5685.688885337355, 5690.756268926672, 5716.0], "value": 4027.6762931443914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.3591713582073, 712.4620911212035, 752.3943225703307, 826.2357319479482, 868.7263511993724, 14549.199794011325, 15574.45489407871, 15601.53195404137, 15617.921910390447, 15647.217639118055, 13822.101563398372, 14250.71498570028, 14449.711005779878, 14464.493742719007, 14484.986050976437, 838.710644827535, 846.3229416466833, 847.9830403391928, 851.1233429567556, 851.7231899632613, 682.4949537342355, 702.1258533126265, 836.0302049622439, 855.110684887716, 864.7751584588021, 901.693424235763, 909.122696597783, 910.8178364327127, 912.3795818843217, 912.7991841794794, 897.5915958238986, 902.6299217320867, 904.6562306323588, 905.1175104273314, 905.9943462756336, 669.9765508207189, 670.7585269303047, 673.8955461903413, 674.2616834566143, 674.9156355455568, 15237.847621523753, 15294.0, 15343.23283835815, 15434.922825385913, 15555.45555905538, 14437.205953672545, 14826.258687065603, 14840.406383744637, 14843.406263749437, 14890.851091489054, 11819.0, 11868.406579670982, 11946.10404219687, 11956.52173913051, 11991.280523168638, 3631.4371272452813, 3651.580068292154, 3697.1126929536717, 3709.9072523187137, 3841.308564458396, 106125.93874061237, 107357.0, 108429.37355939667, 109733.0, 111386.32920506118, 9388.483633400228, 11147.243816078451, 11254.15593830466, 11322.207445478738, 11453.089530271793, 23009.46895841456, 23250.0, 23264.53470930572, 23313.300600982, 23392.0, 6844.589324640538, 7762.650680719381, 7833.099193592696, 7834.294913457702, 7840.215978402114, 3959.703022273341, 4076.9796151019054, 4143.91712165755, 4255.044934741778, 4318.395424640553, 2775.431036637525, 3618.131648404364, 3625.0, 3633.5458067741533, 3635.4365073413665, 577.9537636989115, 617.3394467919373, 648.0700195219874, 657.5660064357501, 670.902719105727, 547.9779018711807, 659.6866488418059, 701.7438634898225, 711.434409644334, 727.4725823777719, 10151.94924025375, 10314.638987635397, 10368.792624147625, 10612.522436490377, 10658.893411065945, 581.4360070731384, 644.3813938618978, 699.5452955578914, 715.9785206443815, 717.587387252332, 3997.320455522568, 4504.684672072989, 4926.950730492649, 4946.629002824802, 4966.503349665077, 95479.52260238794, 96260.0, 96558.0, 98066.52900206503, 99621.00378996262, 69901.65049174824, 72068.55862882818, 78428.21571784324, 80635.32376114903, 85593.0, 52635.736821316415, 77417.0, 77806.61096694594, 78046.0, 79225.0, 9660.75848103803, 9677.9032209677, 9686.70939871805, 9689.370190937567, 9707.70876873695, 47296.0, 47757.0, 48948.5105148944, 50550.241746373096, 51249.46251612309, 64662.676686616265, 66559.66720166369, 67936.0, 68216.6589167051, 68611.65694171497, 13646.931765341109, 14165.36255868509, 14218.715625687635, 14248.64378390507, 14263.358148883117, 14554.01854330887, 14651.780223296446, 14868.330925108612, 15069.698606028038, 15105.018173818667, 13888.652783680489, 13994.510192143227, 14041.648958776112, 14514.346854391366, 14562.563123106323, 13771.311434428038, 13850.722985540435, 13935.0, 13960.790588140982, 14047.157170569799, 13008.609741707764, 13121.343932803513, 13167.802482962954, 13227.206367617973, 13664.863351366359, 10700.0, 10765.730856728644, 11053.173104778132, 11291.24985627229, 11354.659360219206, 11018.44907754593, 11241.269317494663, 11277.71805704864, 11308.0, 11320.490577924178], "value": 7499.260782982222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1616.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 117.0, 124.0, 163.0, 176.0, 154.0, 118.0, 115.0, 118.0], "value": 130.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 113.0, 171.0, 145.0, 126.0, 140.0, 113.0, 127.0, 119.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 268.0, 259.0, 197.0, 198.0, 191.0, 199.0, 195.0, 235.0, 199.0], "value": 214.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 51.0, 104.0, 75.0, 52.0, 69.0, 82.0, 96.0, 86.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 84.0, 80.0, 102.0, 66.0, 77.0, 102.0, 100.0, 111.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 92.0, 93.0, 110.0, 132.0, 131.0, 88.0, 92.0, 87.0], "value": 100.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 96.0, 126.0, 106.0, 155.0, 101.0, 139.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 144.0, 139.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0], "value": 100.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 64.0, 62.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 76.0, 76.0, 75.0, 69.0, 71.0, 73.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 94.0, 89.0, 87.0, 87.0, 106.0, 115.0, 108.0, 108.0], "value": 97.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 178.0, 149.0, 159.0, 156.0, 145.0, 142.0, 169.0, 193.0, 161.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 179.0, 179.0, 179.0, 177.0, 176.0, 243.0, 190.0, 255.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 72.0, 87.0, 88.0, 92.0, 88.0, 88.0, 76.0, 67.0], "value": 79.4, "unit": "score"}]}]} 23:51:08 INFO - Return code: 0 23:51:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:51:08 ERROR - # TBPL SUCCESS # 23:51:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:51:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:51:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:51:08 INFO - 2017-01-19 23:51:08.369 screenresolution[1662:10481] starting screenresolution argv=screenresolution get 23:51:08 INFO - 2017-01-19 23:51:08.372 screenresolution[1662:10481] Display 0: 1600x1200x32@60 23:51:08 INFO - 2017-01-19 23:51:08.378 screenresolution[1663:10484] starting screenresolution argv=screenresolution list 23:51:08 INFO - Available Modes on Display 0 23:51:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:51:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:51:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:51:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:51:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:51:08 INFO - Intel Iris: 23:51:08 INFO - Chipset Model: Intel Iris 23:51:08 INFO - Type: GPU 23:51:08 INFO - Bus: Built-In 23:51:08 INFO - VRAM (Dynamic, Max): 1536 MB 23:51:08 INFO - Vendor: Intel (0x8086) 23:51:08 INFO - Device ID: 0x0a2e 23:51:08 INFO - Revision ID: 0x0009 23:51:08 INFO - Displays: 23:51:08 INFO - Display: 23:51:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:51:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:51:08 INFO - Display Serial Number: 1600x1200 60 23:51:08 INFO - Main Display: Yes 23:51:08 INFO - Mirror: Off 23:51:08 INFO - Online: Yes 23:51:08 INFO - Rotation: Supported 23:51:08 INFO - Return code: 0 23:51:08 INFO - Running post-action listener: _resource_record_post_action 23:51:08 INFO - [mozharness: 2017-01-20 07:51:08.604108Z] Finished run-tests step (success) 23:51:08 INFO - Running post-run listener: _resource_record_post_run 23:51:08 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 39434752; Write bytes: 551765504; Read time: 385; Write time: 1661 23:51:08 INFO - TinderboxPrint: CPU usage
54.6% 23:51:08 INFO - TinderboxPrint: I/O read bytes / time
39,434,752 / 385 23:51:08 INFO - TinderboxPrint: I/O write bytes / time
551,765,504 / 1,661 23:51:08 INFO - TinderboxPrint: CPU idle
2,177.6 (45.4%) 23:51:08 INFO - TinderboxPrint: CPU system
971.3 (20.2%) 23:51:08 INFO - TinderboxPrint: CPU user
1,652.8 (34.4%) 23:51:08 INFO - TinderboxPrint: Swap in / out
290,439,168 / 0 23:51:08 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 250335744; Write bytes: 239798272; Read time: 17947; Write time: 505 23:51:08 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32031232; Write bytes: 296316416; Read time: 294; Write time: 1116 23:51:09 INFO - Running post-run listener: _upload_blobber_files 23:51:09 INFO - Blob upload gear active. 23:51:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:51:09 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 23:51:09 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'] 23:51:09 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 23:51:09 INFO - (blobuploader) - INFO - Open directory for files ... 23:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:51:10 INFO - (blobuploader) - INFO - Done attempting. 23:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:51:11 INFO - (blobuploader) - INFO - Done attempting. 23:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:51:12 INFO - (blobuploader) - INFO - Done attempting. 23:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:51:14 INFO - (blobuploader) - INFO - Done attempting. 23:51:14 INFO - (blobuploader) - INFO - Iteration through files over. 23:51:14 INFO - Return code: 0 23:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cffdd241d68660c1ea6853e03dd5185a5dc3147c16f9e6ce3d0d92ae3eea3f26e8d2934007fcb666fb64329b760496d3fc1f5c2d7778da2ee9a3389cc15bebe3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf53c498890ba8d29fca5c8f0a4474a613d10e379bfb2e697a3cfd48b5859cfbab79c38bacda0ec42ebbb76c7ebcc93c366593199a1c36a915fb874c004bb3c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f18986e58721003e18cc4ad997015a1ad5835e2eef50f42e6745f06929dc85e5b9a4b6bb83de19076109e6e24d619843d444f6e8865e11ed9a29782a6124620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f11ac00306850b82e9ec8b9dd0d529edc9f0a3d0cc8ee581e018c409fd098970f611d93baa2e593def6c842cfa134f5e5dc2a38324bac01b8b17d5b29a758655"} 23:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:51:14 INFO - Contents: 23:51:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cffdd241d68660c1ea6853e03dd5185a5dc3147c16f9e6ce3d0d92ae3eea3f26e8d2934007fcb666fb64329b760496d3fc1f5c2d7778da2ee9a3389cc15bebe3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf53c498890ba8d29fca5c8f0a4474a613d10e379bfb2e697a3cfd48b5859cfbab79c38bacda0ec42ebbb76c7ebcc93c366593199a1c36a915fb874c004bb3c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f18986e58721003e18cc4ad997015a1ad5835e2eef50f42e6745f06929dc85e5b9a4b6bb83de19076109e6e24d619843d444f6e8865e11ed9a29782a6124620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f11ac00306850b82e9ec8b9dd0d529edc9f0a3d0cc8ee581e018c409fd098970f611d93baa2e593def6c842cfa134f5e5dc2a38324bac01b8b17d5b29a758655"} 23:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 23:51:14 INFO - Copying logs to upload dir... 23:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 23:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.858562 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-19 23:51:14.609172) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 23:51:14.615805) ========= 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.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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/cffdd241d68660c1ea6853e03dd5185a5dc3147c16f9e6ce3d0d92ae3eea3f26e8d2934007fcb666fb64329b760496d3fc1f5c2d7778da2ee9a3389cc15bebe3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf53c498890ba8d29fca5c8f0a4474a613d10e379bfb2e697a3cfd48b5859cfbab79c38bacda0ec42ebbb76c7ebcc93c366593199a1c36a915fb874c004bb3c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f18986e58721003e18cc4ad997015a1ad5835e2eef50f42e6745f06929dc85e5b9a4b6bb83de19076109e6e24d619843d444f6e8865e11ed9a29782a6124620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f11ac00306850b82e9ec8b9dd0d529edc9f0a3d0cc8ee581e018c409fd098970f611d93baa2e593def6c842cfa134f5e5dc2a38324bac01b8b17d5b29a758655"} build_url:https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/T2YXmNRwQXKMFw461lJ3lQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cffdd241d68660c1ea6853e03dd5185a5dc3147c16f9e6ce3d0d92ae3eea3f26e8d2934007fcb666fb64329b760496d3fc1f5c2d7778da2ee9a3389cc15bebe3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf53c498890ba8d29fca5c8f0a4474a613d10e379bfb2e697a3cfd48b5859cfbab79c38bacda0ec42ebbb76c7ebcc93c366593199a1c36a915fb874c004bb3c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f18986e58721003e18cc4ad997015a1ad5835e2eef50f42e6745f06929dc85e5b9a4b6bb83de19076109e6e24d619843d444f6e8865e11ed9a29782a6124620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f11ac00306850b82e9ec8b9dd0d529edc9f0a3d0cc8ee581e018c409fd098970f611d93baa2e593def6c842cfa134f5e5dc2a38324bac01b8b17d5b29a758655"}' ========= master_lag: 0.97 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 23:51:15.598419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:51:15.598733) ========= 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.J7zbrhwPxp/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.BReGDUO8ht/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.005098 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 23:51:15.649058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 23:51:15.652407) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 23:51:15.652748) ========= ========= Total master_lag: 1.50 =========