builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0354 starttime: 1482126239.74 results: success (0) buildid: 20161218202235 builduid: 38c573c1c3354e26a9b52bb4cdca09db revision: a61af3f4bc6ee9fc425239806bdf988c65c1e81f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.744790) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.745276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.745570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.771668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.772091) ========= 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.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-18 21:43:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-12-18 21:43:59 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.934513) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.934879) ========= 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.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.020326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:43:59.983612) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 21:43:59.983987) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a61af3f4bc6ee9fc425239806bdf988c65c1e81f --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 a61af3f4bc6ee9fc425239806bdf988c65c1e81f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-18 21:44:00,067 truncating revision to first 12 chars 2016-12-18 21:44:00,067 Setting DEBUG logging. 2016-12-18 21:44:00,067 attempt 1/10 2016-12-18 21:44:00,067 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a61af3f4bc6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 21:44:01,322 unpacking tar archive at: mozilla-central-a61af3f4bc6e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.519758 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 21:44:01.530168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 21:44:01.530553) ========= script_repo_revision: a61af3f4bc6ee9fc425239806bdf988c65c1e81f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 21:44:01.531007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 21:44:01.531272) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 21:44:01.551517) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-18 21:44:01.552075) ========= /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.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:01 INFO - MultiFileLogger online at 20161218 21:44:01 in /builds/slave/test 21:44:01 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 21:44:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:44:01 INFO - {'append_to_log': False, 21:44:01 INFO - 'base_work_dir': '/builds/slave/test', 21:44:01 INFO - 'blob_upload_branch': 'Firefox', 21:44:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:44:01 INFO - 'branch': 'Firefox', 21:44:01 INFO - 'buildbot_json_path': 'buildprops.json', 21:44:01 INFO - 'config_files': ('talos/mac_config.py',), 21:44:01 INFO - 'default_actions': ('clobber', 21:44:01 INFO - 'read-buildbot-config', 21:44:01 INFO - 'download-and-extract', 21:44:01 INFO - 'populate-webroot', 21:44:01 INFO - 'create-virtualenv', 21:44:01 INFO - 'install', 21:44:01 INFO - 'run-tests'), 21:44:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:44:01 INFO - 'download_minidump_stackwalk': True, 21:44:01 INFO - 'download_symbols': 'ondemand', 21:44:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:44:01 INFO - 'tooltool.py': '/tools/tooltool.py', 21:44:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:44:01 INFO - '/tools/misc-python/virtualenv.py')}, 21:44:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:44:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:44:01 INFO - 'installer_path': 'installer.exe', 21:44:01 INFO - 'log_level': 'info', 21:44:01 INFO - 'log_name': 'talos', 21:44:01 INFO - 'log_to_console': True, 21:44:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:44:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:44:01 INFO - 'opt_config_files': (), 21:44:01 INFO - 'pip_index': False, 21:44:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:44:01 INFO - 'cmd': ('bash', 21:44:01 INFO - '-c', 21:44:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:44:01 INFO - 'enabled': True, 21:44:01 INFO - 'halt_on_failure': False, 21:44:01 INFO - 'name': 'check_screen_resolution'},), 21:44:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:44:01 INFO - 'cmd': ('bash', 21:44:01 INFO - '-c', 21:44:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:44:01 INFO - 'enabled': True, 21:44:01 INFO - 'halt_on_failure': False, 21:44:01 INFO - 'name': 'check_screen_resolution'},), 21:44:01 INFO - 'run_cmd_checks_enabled': True, 21:44:01 INFO - 'sps_profile': False, 21:44:01 INFO - 'sps_profile_interval': 0, 21:44:01 INFO - 'suite': 'dromaeojs-e10s', 21:44:01 INFO - 'system_bits': '32', 21:44:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:44:01 INFO - 'title': 't-yosemite-r7-0354', 21:44:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:44:01 INFO - 'use_talos_json': True, 21:44:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:44:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:44:01 INFO - 'work_dir': 'build'} 21:44:01 INFO - [mozharness: 2016-12-19 05:44:01.755224Z] Running clobber step. 21:44:01 INFO - Running pre-action listener: _resource_record_pre_action 21:44:01 INFO - Running main action method: clobber 21:44:01 INFO - rmtree: /builds/slave/test/build 21:44:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:44:01 INFO - Running post-action listener: _resource_record_post_action 21:44:01 INFO - [mozharness: 2016-12-19 05:44:01.915955Z] Finished clobber step (success) 21:44:01 INFO - [mozharness: 2016-12-19 05:44:01.916089Z] Running read-buildbot-config step. 21:44:01 INFO - Running pre-action listener: _resource_record_pre_action 21:44:01 INFO - Running main action method: read_buildbot_config 21:44:01 INFO - Using buildbot properties: 21:44:01 INFO - { 21:44:01 INFO - "project": "", 21:44:01 INFO - "product": "firefox", 21:44:01 INFO - "script_repo_revision": "production", 21:44:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:44:01 INFO - "repository": "", 21:44:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:44:01 INFO - "buildid": "20161218202235", 21:44:01 INFO - "pgo_build": "False", 21:44:01 INFO - "basedir": "/builds/slave/test", 21:44:01 INFO - "buildnumber": 25, 21:44:01 INFO - "slavename": "t-yosemite-r7-0354", 21:44:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 21:44:01 INFO - "platform": "yosemite_r7", 21:44:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:44:01 INFO - "branch": "mozilla-central", 21:44:01 INFO - "repo_path": "mozilla-central", 21:44:01 INFO - "revision": "a61af3f4bc6ee9fc425239806bdf988c65c1e81f", 21:44:01 INFO - "stage_platform": "macosx64", 21:44:01 INFO - "builduid": "38c573c1c3354e26a9b52bb4cdca09db", 21:44:01 INFO - "slavebuilddir": "test" 21:44:01 INFO - } 21:44:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:44:01 INFO - Running post-action listener: _resource_record_post_action 21:44:01 INFO - [mozharness: 2016-12-19 05:44:01.919610Z] Finished read-buildbot-config step (success) 21:44:01 INFO - [mozharness: 2016-12-19 05:44:01.919754Z] Running download-and-extract step. 21:44:01 INFO - Running pre-action listener: _resource_record_pre_action 21:44:01 INFO - Running main action method: download_and_extract 21:44:01 INFO - mkdir: /builds/slave/test/build/tests 21:44: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')]} 21:44:01 INFO - trying https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:44:01 INFO - Downloading https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/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 21:44:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/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 21:44:03 INFO - Downloaded 1199 bytes. 21:44:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:44:03 INFO - Using the following test package requirements: 21:44:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:44:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:44:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:44:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'jsshell-mac.zip'], 21:44:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:44:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:44:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:44:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:44:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:44:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:44:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:44:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:44:04 INFO - Expected file size: 16275296 21:44:04 INFO - Obtained file size: 16275296 21:44:11 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 21:44:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:44:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:44:11 INFO - Fetch https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:44:12 INFO - Expected file size: 16275296 21:44:12 INFO - Obtained file size: 16275296 21:44:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:44:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:44:18 INFO - Fetch https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:44:19 INFO - Expected file size: 14409734 21:44:19 INFO - Obtained file size: 14409734 21:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:20 INFO - trying https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:44:20 INFO - Downloading https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:44:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/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 21:44:22 INFO - Downloaded 69524273 bytes. 21:44:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:44:22 INFO - mkdir: /builds/slave/test/properties 21:44:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:44:22 INFO - Writing to file /builds/slave/test/properties/build_url 21:44:22 INFO - Contents: 21:44:22 INFO - build_url:https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:44:22 INFO - Running post-action listener: _resource_record_post_action 21:44:22 INFO - Running post-action listener: set_extra_try_arguments 21:44:22 INFO - [mozharness: 2016-12-19 05:44:22.914076Z] Finished download-and-extract step (success) 21:44:22 INFO - [mozharness: 2016-12-19 05:44:22.914197Z] Running populate-webroot step. 21:44:22 INFO - Running pre-action listener: _resource_record_pre_action 21:44:22 INFO - Running main action method: populate_webroot 21:44:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:44:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:44:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:44:22 INFO - '--tppagecycles', 21:44:22 INFO - '7'], 21:44:22 INFO - 'tests': ['tsvgm']}}, 21:44:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:44:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:44:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:44:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:44:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['damp', 'tps']}, 21:44:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'tests': ['damp', 'tps']}, 21:44:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['dromaeo_dom']}, 21:44:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:44:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['basic_compositor_video']}, 21:44:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:44:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'other-e10s': {'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['a11yr', 21:44:22 INFO - 'ts_paint', 21:44:22 INFO - 'tpaint', 21:44:22 INFO - 'sessionrestore', 21:44:22 INFO - 'sessionrestore_no_auto_restore', 21:44:22 INFO - 'tabpaint']}, 21:44:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['tsvgx', 21:44:22 INFO - 'tsvgr_opacity', 21:44:22 INFO - 'tart', 21:44:22 INFO - 'tscrollx', 21:44:22 INFO - 'cart', 21:44:22 INFO - 'tsvg_static']}, 21:44:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:44:22 INFO - 'tsvgr_opacity', 21:44:22 INFO - 'tart', 21:44:22 INFO - 'tscrollx', 21:44:22 INFO - 'cart', 21:44:22 INFO - 'tsvg_static']}, 21:44:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'talos_options': ['--disable-e10s'], 21:44:22 INFO - 'tests': ['tp5o']}, 21:44:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:44:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'talos_options': ['--disable-e10s', 21:44:22 INFO - '--xperf_path', 21:44:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:22 INFO - 'tests': ['tp5n']}, 21:44:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:44:22 INFO - 'talos_options': ['--xperf_path', 21:44:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:22 INFO - 'tests': ['tp5n']}}, 21:44:22 INFO - 'talos.zip': {'path': '', 21:44:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:44:22 INFO - Running post-action listener: _resource_record_post_action 21:44:22 INFO - [mozharness: 2016-12-19 05:44:22.931994Z] Finished populate-webroot step (success) 21:44:22 INFO - [mozharness: 2016-12-19 05:44:22.932131Z] Running create-virtualenv step. 21:44:22 INFO - Running pre-action listener: _resource_record_pre_action 21:44:22 INFO - Running main action method: create_virtualenv 21:44:22 INFO - Creating virtualenv /builds/slave/test/build/venv 21:44:22 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 21:44:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:44:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:44:23 INFO - Using real prefix '/tools/python27' 21:44:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:44:23 INFO - Installing distribute.............................................................................................................................................................................................done. 21:44:26 INFO - Installing pip.................done. 21:44:26 INFO - Return code: 0 21:44:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:44:26 INFO - Reading from file tmpfile_stdout 21:44:26 INFO - Output received: 21:44:26 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) 21:44:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:26 INFO - 'HOME': '/Users/cltbld', 21:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:26 INFO - 'LOGNAME': 'cltbld', 21:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:26 INFO - 'MOZ_NO_REMOTE': '1', 21:44:26 INFO - 'NO_EM_RESTART': '1', 21:44:26 INFO - 'PAGER': '/bin/cat', 21:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:26 INFO - 'PWD': '/builds/slave/test', 21:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:26 INFO - 'SHELL': '/bin/bash', 21:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:26 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:26 INFO - 'USER': 'cltbld', 21:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:26 INFO - 'XPC_FLAGS': '0x0', 21:44:26 INFO - 'XPC_SERVICE_NAME': '0', 21:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:27 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 21:44:27 INFO - Cleaning up... 21:44:27 INFO - Return code: 0 21:44:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:44:27 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')]} 21:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:27 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')]} 21:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:27 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:27 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 21:44:27 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 21:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:27 INFO - 'HOME': '/Users/cltbld', 21:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:27 INFO - 'LOGNAME': 'cltbld', 21:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:27 INFO - 'MOZ_NO_REMOTE': '1', 21:44:27 INFO - 'NO_EM_RESTART': '1', 21:44:27 INFO - 'PAGER': '/bin/cat', 21:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:27 INFO - 'PWD': '/builds/slave/test', 21:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:27 INFO - 'SHELL': '/bin/bash', 21:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:27 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:27 INFO - 'USER': 'cltbld', 21:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:27 INFO - 'XPC_FLAGS': '0x0', 21:44:27 INFO - 'XPC_SERVICE_NAME': '0', 21:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:27 INFO - Downloading/unpacking psutil>=3.1.1 21:44:27 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 21:44:27 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 21:44:27 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 21:44:27 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 21:44:27 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 21:44:27 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 21:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:31 INFO - Installing collected packages: psutil 21:44:31 INFO - Running setup.py install for psutil 21:44:31 INFO - building 'psutil._psutil_osx' extension 21:44:31 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 21:44:31 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 21:44:31 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 21:44:31 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 21:44:31 INFO - building 'psutil._psutil_posix' extension 21:44:31 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 21:44:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:44:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:44:31 INFO - ^ 21:44:31 INFO - 1 warning generated. 21:44:31 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 21:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:31 INFO - Successfully installed psutil 21:44:31 INFO - Cleaning up... 21:44:31 INFO - Return code: 0 21:44:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:31 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:31 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 21:44:31 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 21:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:31 INFO - 'HOME': '/Users/cltbld', 21:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:31 INFO - 'LOGNAME': 'cltbld', 21:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:31 INFO - 'MOZ_NO_REMOTE': '1', 21:44:31 INFO - 'NO_EM_RESTART': '1', 21:44:31 INFO - 'PAGER': '/bin/cat', 21:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:31 INFO - 'PWD': '/builds/slave/test', 21:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:31 INFO - 'SHELL': '/bin/bash', 21:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:31 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:31 INFO - 'USER': 'cltbld', 21:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:31 INFO - 'XPC_FLAGS': '0x0', 21:44:31 INFO - 'XPC_SERVICE_NAME': '0', 21:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:44:34 INFO - Installing collected packages: mozsystemmonitor 21:44:34 INFO - Running setup.py install for mozsystemmonitor 21:44:34 INFO - Successfully installed mozsystemmonitor 21:44:34 INFO - Cleaning up... 21:44:35 INFO - Return code: 0 21:44:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:44:35 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')]} 21:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:35 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')]} 21:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:35 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:35 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 21:44:35 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 21:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:35 INFO - 'HOME': '/Users/cltbld', 21:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:35 INFO - 'LOGNAME': 'cltbld', 21:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:35 INFO - 'MOZ_NO_REMOTE': '1', 21:44:35 INFO - 'NO_EM_RESTART': '1', 21:44:35 INFO - 'PAGER': '/bin/cat', 21:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:35 INFO - 'PWD': '/builds/slave/test', 21:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:35 INFO - 'SHELL': '/bin/bash', 21:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:35 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:35 INFO - 'USER': 'cltbld', 21:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:35 INFO - 'XPC_FLAGS': '0x0', 21:44:35 INFO - 'XPC_SERVICE_NAME': '0', 21:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:35 INFO - Downloading/unpacking jsonschema==2.5.1 21:44:35 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 21:44:35 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 21:44:35 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 21:44:35 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 21:44:35 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 21:44:35 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 21:44:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:44:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:44:37 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 21:44:37 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 21:44:37 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 21:44:37 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 21:44:37 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 21:44:37 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 21:44:38 INFO - Downloading functools32-3.2.3-2.tar.gz 21:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:44:38 INFO - warning: no files found matching '*.txt' 21:44:38 INFO - no previously-included directories found matching 'build' 21:44:38 INFO - no previously-included directories found matching 'dist' 21:44:38 INFO - no previously-included directories found matching '.git*' 21:44:38 INFO - Installing collected packages: jsonschema, functools32 21:44:38 INFO - Running setup.py install for functools32 21:44:38 INFO - warning: no files found matching '*.txt' 21:44:38 INFO - no previously-included directories found matching 'build' 21:44:38 INFO - no previously-included directories found matching 'dist' 21:44:38 INFO - no previously-included directories found matching '.git*' 21:44:38 INFO - Successfully installed jsonschema functools32 21:44:38 INFO - Cleaning up... 21:44:38 INFO - Return code: 0 21:44:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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', '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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:44: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 functools32==3.2.3-2 21:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:38 INFO - 'HOME': '/Users/cltbld', 21:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:38 INFO - 'LOGNAME': 'cltbld', 21:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:38 INFO - 'MOZ_NO_REMOTE': '1', 21:44:38 INFO - 'NO_EM_RESTART': '1', 21:44:38 INFO - 'PAGER': '/bin/cat', 21:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:38 INFO - 'PWD': '/builds/slave/test', 21:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:38 INFO - 'SHELL': '/bin/bash', 21:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:38 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:38 INFO - 'USER': 'cltbld', 21:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:38 INFO - 'XPC_FLAGS': '0x0', 21:44:38 INFO - 'XPC_SERVICE_NAME': '0', 21:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:44:38 INFO - Cleaning up... 21:44:38 INFO - Return code: 0 21:44:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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', '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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:44: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 blobuploader==1.2.4 21:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:38 INFO - 'HOME': '/Users/cltbld', 21:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:38 INFO - 'LOGNAME': 'cltbld', 21:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:38 INFO - 'MOZ_NO_REMOTE': '1', 21:44:38 INFO - 'NO_EM_RESTART': '1', 21:44:38 INFO - 'PAGER': '/bin/cat', 21:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:38 INFO - 'PWD': '/builds/slave/test', 21:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:38 INFO - 'SHELL': '/bin/bash', 21:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:38 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:38 INFO - 'USER': 'cltbld', 21:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:38 INFO - 'XPC_FLAGS': '0x0', 21:44:38 INFO - 'XPC_SERVICE_NAME': '0', 21:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:39 INFO - Downloading/unpacking blobuploader==1.2.4 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44:41 INFO - Downloading blobuploader-1.2.4.tar.gz 21:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:44:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:44:41 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 21:44:41 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 21:44:41 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 21:44:41 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 21:44:41 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 21:44:41 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 21:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:44:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:42 INFO - Downloading docopt-0.6.1.tar.gz 21:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:44:43 INFO - Installing collected packages: blobuploader, requests, docopt 21:44:43 INFO - Running setup.py install for blobuploader 21:44:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:44:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:44:43 INFO - Running setup.py install for requests 21:44:43 INFO - Running setup.py install for docopt 21:44:43 INFO - Successfully installed blobuploader requests docopt 21:44:43 INFO - Cleaning up... 21:44:43 INFO - Return code: 0 21:44:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:43 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:43 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 21:44:43 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 21:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:43 INFO - 'HOME': '/Users/cltbld', 21:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:43 INFO - 'LOGNAME': 'cltbld', 21:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:43 INFO - 'MOZ_NO_REMOTE': '1', 21:44:43 INFO - 'NO_EM_RESTART': '1', 21:44:43 INFO - 'PAGER': '/bin/cat', 21:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:43 INFO - 'PWD': '/builds/slave/test', 21:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:43 INFO - 'SHELL': '/bin/bash', 21:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:43 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:43 INFO - 'USER': 'cltbld', 21:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:43 INFO - 'XPC_FLAGS': '0x0', 21:44:43 INFO - 'XPC_SERVICE_NAME': '0', 21:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sCSNV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-dOtkcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-csMedi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-kV8MJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-HVherG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-WQBoEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-0Ej2Iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:44:44 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-VVGwgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-pckvzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-GTEqm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-hvXyZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-KM38CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-jpSqmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sFp_hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-bFKc7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-TKGG3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:44:45 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-MuYeZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:44:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:44:45 INFO - Running setup.py install for manifestparser 21:44:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:44:46 INFO - Running setup.py install for mozcrash 21:44:46 INFO - Running setup.py install for mozdebug 21:44:46 INFO - Running setup.py install for mozdevice 21:44:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:44:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:44:46 INFO - Running setup.py install for mozfile 21:44:46 INFO - Running setup.py install for mozhttpd 21:44:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:44:46 INFO - Running setup.py install for mozinfo 21:44:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Running setup.py install for mozInstall 21:44:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Running setup.py install for mozleak 21:44:47 INFO - Running setup.py install for mozlog 21:44:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Running setup.py install for moznetwork 21:44:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Running setup.py install for mozprocess 21:44:47 INFO - Running setup.py install for mozprofile 21:44:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:44:47 INFO - Running setup.py install for mozrunner 21:44:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:44:48 INFO - Running setup.py install for mozscreenshot 21:44:48 INFO - Running setup.py install for moztest 21:44:48 INFO - Running setup.py install for mozversion 21:44:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:44:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:44:48 INFO - Cleaning up... 21:44:48 INFO - Return code: 0 21:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:48 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:48 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 21:44:48 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 21:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:48 INFO - 'HOME': '/Users/cltbld', 21:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:48 INFO - 'LOGNAME': 'cltbld', 21:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:48 INFO - 'MOZ_NO_REMOTE': '1', 21:44:48 INFO - 'NO_EM_RESTART': '1', 21:44:48 INFO - 'PAGER': '/bin/cat', 21:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:48 INFO - 'PWD': '/builds/slave/test', 21:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:48 INFO - 'SHELL': '/bin/bash', 21:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:48 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:48 INFO - 'USER': 'cltbld', 21:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:48 INFO - 'XPC_FLAGS': '0x0', 21:44:48 INFO - 'XPC_SERVICE_NAME': '0', 21:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:44:48 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-tr9Nw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:44:48 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)) 21:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:44:48 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-70ujBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-LBHoiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-J2Mu89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-rSoV3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-NtDCHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-CGgUG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-oaLmNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Ot9dro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-uY_m_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:44:49 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-I_kmP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:44:49 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)) 21:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Gf39ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-_EqEiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:44:50 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)) 21:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-W6ZnEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:44:50 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)) 21:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-UT42bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:50 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)) 21:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-8ADYOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:44:50 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-GGAm7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:44:50 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)) 21:44:50 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)) 21:44:50 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)) 21:44:50 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)) 21:44:50 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)) 21:44:50 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)) 21:44:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44:53 INFO - Downloading blessings-1.6.tar.gz 21:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:44:53 INFO - Installing collected packages: blessings 21:44:53 INFO - Running setup.py install for blessings 21:44:53 INFO - Successfully installed blessings 21:44:53 INFO - Cleaning up... 21:44:53 INFO - Return code: 0 21:44:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:44:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:44:53 INFO - Reading from file tmpfile_stdout 21:44:53 INFO - Current package versions: 21:44:53 INFO - blessings == 1.6 21:44:53 INFO - blobuploader == 1.2.4 21:44:53 INFO - docopt == 0.6.1 21:44:53 INFO - functools32 == 3.2.3-2 21:44:53 INFO - jsonschema == 2.5.1 21:44:53 INFO - manifestparser == 1.1 21:44:53 INFO - mozInstall == 1.12 21:44:53 INFO - mozcrash == 1.0 21:44:53 INFO - mozdebug == 0.1 21:44:53 INFO - mozdevice == 0.49 21:44:53 INFO - mozfile == 1.2 21:44:53 INFO - mozhttpd == 0.7 21:44:53 INFO - mozinfo == 0.9 21:44:53 INFO - mozleak == 0.1 21:44:53 INFO - mozlog == 3.4 21:44:53 INFO - moznetwork == 0.27 21:44:53 INFO - mozprocess == 0.23 21:44:53 INFO - mozprofile == 0.28 21:44:53 INFO - mozrunner == 6.13 21:44:53 INFO - mozscreenshot == 0.1 21:44:53 INFO - mozsystemmonitor == 0.3 21:44:53 INFO - moztest == 0.7 21:44:53 INFO - mozversion == 1.4 21:44:53 INFO - psutil == 3.1.1 21:44:53 INFO - requests == 1.2.3 21:44:53 INFO - wsgiref == 0.1.2 21:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:53 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 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:53 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 21:44:53 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 21:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:53 INFO - 'HOME': '/Users/cltbld', 21:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:53 INFO - 'LOGNAME': 'cltbld', 21:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:53 INFO - 'MOZ_NO_REMOTE': '1', 21:44:53 INFO - 'NO_EM_RESTART': '1', 21:44:53 INFO - 'PAGER': '/bin/cat', 21:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:53 INFO - 'PWD': '/builds/slave/test', 21:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:53 INFO - 'SHELL': '/bin/bash', 21:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:53 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:53 INFO - 'USER': 'cltbld', 21:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:53 INFO - 'XPC_FLAGS': '0x0', 21:44:53 INFO - 'XPC_SERVICE_NAME': '0', 21:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 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)) 21:44:54 INFO - Cleaning up... 21:44:54 INFO - Return code: 0 21:44:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85aad74b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, '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.P434JF1BTQ/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.nvukjDpfRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 21:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:44:54 INFO - 'HOME': '/Users/cltbld', 21:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:44:54 INFO - 'LOGNAME': 'cltbld', 21:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:54 INFO - 'MOZ_NO_REMOTE': '1', 21:44:54 INFO - 'NO_EM_RESTART': '1', 21:44:54 INFO - 'PAGER': '/bin/cat', 21:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:54 INFO - 'PWD': '/builds/slave/test', 21:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:44:54 INFO - 'SHELL': '/bin/bash', 21:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:44:54 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:44:54 INFO - 'USER': 'cltbld', 21:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:54 INFO - 'XPC_FLAGS': '0x0', 21:44:54 INFO - 'XPC_SERVICE_NAME': '0', 21:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:44:54 INFO - Cleaning up... 21:44:54 INFO - Return code: 0 21:44:54 INFO - Running post-action listener: _resource_record_post_action 21:44:54 INFO - Running post-action listener: _start_resource_monitoring 21:44:54 INFO - Starting resource monitoring. 21:44:54 INFO - [mozharness: 2016-12-19 05:44:54.399721Z] Finished create-virtualenv step (success) 21:44:54 INFO - [mozharness: 2016-12-19 05:44:54.400804Z] Running install step. 21:44:54 INFO - Running pre-action listener: _resource_record_pre_action 21:44:54 INFO - Running main action method: install 21:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:44:54 INFO - Reading from file tmpfile_stdout 21:44:54 INFO - Detecting whether we're running mozinstall >=1.0... 21:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:44:54 INFO - Reading from file tmpfile_stdout 21:44:54 INFO - Output received: 21:44:54 INFO - Usage: mozinstall [options] installer 21:44:54 INFO - Options: 21:44:54 INFO - -h, --help show this help message and exit 21:44:54 INFO - -d DEST, --destination=DEST 21:44:54 INFO - Directory to install application into. [default: 21:44:54 INFO - "/builds/slave/test"] 21:44:54 INFO - --app=APP Application being installed. [default: firefox] 21:44:54 INFO - mkdir: /builds/slave/test/build/application 21:44:54 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'] 21:44:54 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 21:45:16 INFO - Reading from file tmpfile_stdout 21:45:16 INFO - Output received: 21:45:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:45:16 INFO - Running post-action listener: _resource_record_post_action 21:45:16 INFO - [mozharness: 2016-12-19 05:45:16.571811Z] Finished install step (success) 21:45:16 INFO - [mozharness: 2016-12-19 05:45:16.571985Z] Running run-tests step. 21:45:16 INFO - Running pre-action listener: _resource_record_pre_action 21:45:16 INFO - Running main action method: run_tests 21:45:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:45:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:45:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:45:16 INFO - 2016-12-18 21:45:16.585 screenresolution[1889:8873] starting screenresolution argv=screenresolution get 21:45:16 INFO - 2016-12-18 21:45:16.587 screenresolution[1889:8873] Display 0: 1600x1200x32@60 21:45:16 INFO - 2016-12-18 21:45:16.593 screenresolution[1890:8876] starting screenresolution argv=screenresolution list 21:45:16 INFO - Available Modes on Display 0 21:45:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:45:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:45:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:45:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:45:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:45:16 INFO - Intel Iris: 21:45:16 INFO - Chipset Model: Intel Iris 21:45:16 INFO - Type: GPU 21:45:16 INFO - Bus: Built-In 21:45:16 INFO - VRAM (Dynamic, Max): 1536 MB 21:45:16 INFO - Vendor: Intel (0x8086) 21:45:16 INFO - Device ID: 0x0a2e 21:45:16 INFO - Revision ID: 0x0009 21:45:16 INFO - Displays: 21:45:16 INFO - Display: 21:45:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:45:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:45:16 INFO - Display Serial Number: 1600x1200 60 21:45:16 INFO - Main Display: Yes 21:45:16 INFO - Mirror: Off 21:45:16 INFO - Online: Yes 21:45:16 INFO - Rotation: Supported 21:45:16 INFO - Return code: 0 21:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:45:16 INFO - Python 2.7.3 21:45:16 INFO - Return code: 0 21:45:16 INFO - grabbing minidump binary from tooltool 21:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:45:16 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 0x10395fd30>, '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 0x103886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103954be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:45:16 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 21:45:16 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 21:45:16 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 21:45:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:45:16 INFO - Return code: 0 21:45:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:45:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:45:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:45:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:45:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:45:16 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/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 21:45:16 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/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0354 --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 21:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P434JF1BTQ/Render', 21:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:45:16 INFO - 'HOME': '/Users/cltbld', 21:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:45:16 INFO - 'LOGNAME': 'cltbld', 21:45:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:45:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:16 INFO - 'MOZ_NO_REMOTE': '1', 21:45:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:45:16 INFO - 'NO_EM_RESTART': '1', 21:45:16 INFO - 'PAGER': '/bin/cat', 21:45:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:45:16 INFO - 'PWD': '/builds/slave/test', 21:45:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:45:16 INFO - 'SHELL': '/bin/bash', 21:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvukjDpfRR/Listeners', 21:45:16 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 21:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:45:16 INFO - 'USER': 'cltbld', 21:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:16 INFO - 'XPC_FLAGS': '0x0', 21:45:16 INFO - 'XPC_SERVICE_NAME': '0', 21:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:45:16 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/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 21:45:17 INFO - mozversion application_buildid: 20161218202235 21:45:17 INFO - mozversion application_changeset: a61af3f4bc6ee9fc425239806bdf988c65c1e81f 21:45:17 INFO - mozversion application_display_name: Nightly 21:45:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:45:17 INFO - mozversion application_name: Firefox 21:45:17 INFO - mozversion application_remotingname: firefox 21:45:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:45:17 INFO - mozversion application_vendor: Mozilla 21:45:17 INFO - mozversion application_version: 53.0a1 21:45:17 INFO - mozversion platform_buildid: 20161218202235 21:45:17 INFO - mozversion platform_changeset: a61af3f4bc6ee9fc425239806bdf988c65c1e81f 21:45:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:45:17 INFO - mozversion platform_version: 53.0a1 21:45:17 INFO - using testdate: 1482126317 21:45:17 INFO - actual date: 1482126317 21:45:17 INFO - starting webserver on 'localhost:49364' 21:45:17 INFO - SUITE-START | Running 2 tests 21:45:17 INFO - TEST-START | dromaeo_css 21:45:17 INFO - operating with platform_type : mac_ 21:45:17 INFO - Initialising browser for dromaeo_css test... 21:45:17 INFO - TEST-INFO | started process 1900 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmps5IeJz/profile http://localhost:49364/getInfo.html) 21:45:20 INFO - PROCESS | 1900 | 2016-12-18 21:45:20.015 plugin-container[1902:9038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 21:45:20 INFO - PROCESS | 1900 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:20 INFO - PROCESS | 1900 | 2016-12-18 21:45:20.017 plugin-container[1902:9038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b0f, name = 'com.apple.CFPasteboardClient' 21:45:20 INFO - PROCESS | 1900 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:20 INFO - PROCESS | 1900 | 2016-12-18 21:45:20.017 plugin-container[1902:9038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:20 INFO - PROCESS | 1900 | __metrics Screen width/height:1600/1200 21:45:20 INFO - PROCESS | 1900 | colorDepth:24 21:45:20 INFO - PROCESS | 1900 | Browser inner width/height: 1024/689 21:45:20 INFO - PROCESS | 1900 | __metrics 21:45:20 ERROR - PROCESS | 1900 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 21:45:20 INFO - TEST-INFO | 1900: exit 0 21:45:20 INFO - Browser initialized. 21:45:20 INFO - Running cycle 1/1 for dromaeo_css test... 21:45:20 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmps5IeJz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:45:23 INFO - PROCESS | 1903 | 2016-12-18 21:45:23.203 plugin-container[1904:9160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 21:45:23 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:23 INFO - PROCESS | 1903 | 2016-12-18 21:45:23.204 plugin-container[1904:9160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 21:45:23 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:23 INFO - PROCESS | 1903 | 2016-12-18 21:45:23.204 plugin-container[1904:9160] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:32 INFO - PROCESS | 1903 | RSS: Main: 236908544 21:45:32 INFO - PROCESS | 1903 | 21:46:22 INFO - PROCESS | 1903 | 1482126382578 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:48:36 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49364/tests/dromaeo/cssquery-ext.html) 21:48:38 INFO - PROCESS | 1903 | RSS: Main: 250200064 21:48:38 INFO - PROCESS | 1903 | 21:51:40 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-ext.html (next: http://localhost:49364/tests/dromaeo/cssquery-jquery.html) 21:51:41 INFO - PROCESS | 1903 | RSS: Main: 263479296 21:51:41 INFO - PROCESS | 1903 | 21:54:42 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49364/tests/dromaeo/cssquery-mootools.html) 21:54:43 INFO - PROCESS | 1903 | RSS: Main: 267874304 21:54:43 INFO - PROCESS | 1903 | 21:57:45 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49364/tests/dromaeo/cssquery-prototype.html) 21:57:46 INFO - PROCESS | 1903 | RSS: Main: 267767808 21:57:46 INFO - PROCESS | 1903 | 22:00:49 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49364/tests/dromaeo/cssquery-yui.html) 22:00:49 INFO - PROCESS | 1903 | RSS: Main: 273092608 22:00:49 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | Cycle 1(1): loaded http://localhost:49364/tests/dromaeo/cssquery-yui.html (next: http://localhost:49364/tests/dromaeo/cssquery-dojo.html) 22:03:52 INFO - PROCESS | 1903 | RSS: Main: 276086784 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | __start_tp_report 22:03:52 INFO - PROCESS | 1903 | _x_x_mozilla_page_load 22:03:52 INFO - PROCESS | 1903 | _x_x_mozilla_page_load_details 22:03:52 INFO - PROCESS | 1903 | |i|pagename|runs| 22:03:52 INFO - PROCESS | 1903 | |0;dojo.html;3398.5072164536127;3402.319536092781;3435.5018522314267;3442.311537692459;3446.2590543033243;5685.971570142154;5761.625494342865;5786.623869448488;5803.709814509279;5806.042003069494;5705.686187259709;5720.599558030939;5754.597178197528;5766.682832444214;5785.305763308398;5222.477752224785;5256.080185967459;5259.211118332261;5335.946640533583;5338.572914166877;5190.636655434102;5276.498732620395;5324.414314425429;5342.973285133569;5387.488188622075;5304.389995150568;5335.626506144571;5370.355557333126;5377.542908852753;5393.622446428752;5139.563137133348;5184.7407629618365;5187.922181167285;5231.659942103751;5315.202719592054;4847.660663753539;5048.7728052237735;5238.947610523884;5267.341582302212;5309.309789727311;5533.750981205815;5732.31212254528;5765.279340082489;5768.134779783024;5774.451427114417;6134.589663226396;6188.566800323979;6206.944819380716;6253.1558239637525;6289;5044.6973181608855;6210.1616281801835;6249.187605611241;6272.65500397478;6276.152719382871;5959.582829201958;5964.403559644001;5964.701764911824;5986.910196347058;5994.730237139252;5001.8499445016705;5404.459554044643;5550.722463876871;6193.783217587363;6332.8416789579705;6042.305134909541;6052.727627256784;6100.786472473442;6111.022236442237;6128.509719222451;6175;6205.565610407319;6205.937940620626;6214.130021796952;6219.720112594944;5859.76944841588;6125.234345706786;6154.599951003171;6164.8767024659255;6182.6599537024995;5576.665400076008;6259.405356491126;6273.780417685359;6294.842628934313;6327.114204011442;4941.937483441077;4973;4975.875603109951;4979.178435558165;4985.4266759323145;4375.190589740908;4390.209762242763;4400.9119817604105;4429.977850110793;4443.644508439317;5877.882442351129;5968.582199246011;5996.13056106862;5997.1304160896425;6020.217371741645;4643.6517261205545;5200.401953775288;6254.655993920287;6273.341299163613;6287.717052732638;4920.6801557898625;5716.371199168088;5821.359650438449;5856.941430585724;5916.319623243382;5340.973295133499;5422.674639521641;5426.620136590401;5448.210009548593;5466.890662186814;6321.96839015802;6334.778282760266;6335.968320158462;6345.0482427636225;6359.87280254383;6115.514161902822;6218.657973811484;6220.533459990609;6228.252609686685;6230.034344676583;6116.366205733216;6148.78479253224;6156.29202641702;6168.691565421801;6202.5658203925295;6103.358992651509;6106.06632622643;6106.572539922163;6114.358196334042;6128.63228206309;6181.876362472815;6210.223722034746;6249.531285153723;6251.437370636664;6259.1550140730415;5498.505134537911;5973.701314934149;6092.268927728701;6168.19813424252;6216.378362163929;6075.453209211192;6118.80192544137;6122.081687746695;6129.908051379144;6138.848241798255;6053.273607167168;6054.515638748978;6081.939180608314;6121.051237058263;6156.538259630635;6207.472364849038;6212.844678883064;6213.409726076015;6224.253089629274;6246.968765156145;5752.798652047159;5767.0196066669605;5814.825555233349;6068.029115341524;6076.453119219114 22:03:52 INFO - PROCESS | 1903 | |1;ext.html;424.63057140287947;430.1204037742818;441.7879417879417;475.1305111645689;476.3141076849337;38641.22717545647;40759.59240407595;41022.79488602553;41100;41241;41791;42198.73403797884;42283.78858105705;42348.57651423485;42366.57633423665;8534.78663033426;8837.072107428723;9388.483633400177;9626.133647971681;9638.518074096302;11341.943290283538;11641.47613357401;11659.358735269558;11823.645290641252;11840.171188016842;11569.53721851125;11630.825537616942;11638.767224655501;11643.592474263403;11652.126090543201;8643.567821608895;11117.27737697051;11273.887261127365;11479.713007174803;11621.302721836717;11442;11533.653990380302;11533.942330288295;11537.826932596017;11566.132540059529;31814.840925795455;39775.80112099421;39856;39898.813154290045;40279;3336.466165413522;3418.675225853565;3471.9826400868087;3846.076941534012;4110.630043296118;25396.873015634803;30441.543376849364;30820.845895770603;30997.535036974463;31196;3183.856726447292;3218.903432897017;3219.307848812493;3251.3497300539752;3292.1769557610596;426726;434451;435500;435723.99999999686;436132;61591;61962.070568941504;62021;62171.68914155491;62274.68862655749;63151;63405;63446.0483092754;63475;63508.68245658742;14899.031562948374;15424.383024679111;15445.382184712485;15452.150131742863;15465.845341546665;10794.028537431668;11072.667819965414;11555.191136620524;11751.236169648946;11775.23460975034;14522.782158267635;17939.282428702965;17972.640547189243;18003.549911252325;18124.09696981824;4825.5898248649255;5794.0439827428845;5855.824325270249;6031.728572214261;6032.4570788629235;715.6529083394549;977.7506735782472;1020.6325722739808;1035.3477309295247;1036.3574583757968;29326;29364;29648;30464.84767576192;30704.232394189876;931.4923366765129;1001.5543083327995;1018.9286749927583;1030.644427672452;1098.6978580890159;13685.657858553417;15560.766588500956;15618.141002244985;15678;15835.835437752401;560822.3917760851;562890;564744;564887;568019;59153.11270330948;59387;59456.40543594509;59474.70262648746;59522;1314.0801438992744;1344.8587898270541;1365.481117175467;1385.6466601016675;1390.874821266091;28838.711612883602;29684.257893552833;29690.40619187561;30397.69602304037;30485;51891.70270743262;51988;52128;52246;52354;525171;525503.3724831351;527692;528176;530592;20379.69430458574;24900.501989960463;25963.740362596887;41288.58711412847;41665.37501937413;42293.577064230194;42585.574144258164;42598;42919.92700182521;43768.562314376446;26814.731852681223;35809.925702228975;35859.282814344086;36808.81595592005;36953;28394.43211135807;34033.97898063062;35592;36153.096172594844;36278.09304767296;35062.47406288857;35488;35552.46671299984;35673.28653426969;35701.10747231236;29918;31001;32069;32222.19444513812;32277.51583726293;22557.323280301618;24218;24407.755922441258;24413.26760197197;24790.628140578265 22:03:52 INFO - PROCESS | 1903 | |2;jquery.html;291.29506594042414;341.24584667883977;341.91452136965756;377.35849056603774;389.8460108257237;15283.694326113486;19543.609127817454;20451.181952721872;20514.28200013;20567.897160514214;17968.191431385585;18250.543736406595;18361.173747181376;18462.169202385892;18548.629027419476;5596.58025648076;5916.230889984296;5917.082852157922;5917.437843404891;5922.792702255422;5036.34527511423;5789.39211382805;6407.231132264134;6482.902756458657;6550.050242714803;6486.448651864597;6511.869762604745;6521.93478065218;6522.902156467633;6533.803985880431;6248.500119990413;6451.677416129175;6465.577572933947;6481.254655714606;6503;4940.160172770602;5209.29674493946;5643.125315576092;5647.774089036434;5650.830475085754;12724.0456965727;13008.674783130402;13009.739805203842;13012.34938253093;17699;2072.171131547378;2304.216566367443;2708.783297336228;2831.2497188245134;2850.415664788734;8073.475224110414;9424;9624;9995.550200240934;10121.291509594332;1778.9555261118576;1798.5611510791314;1816.5458635341165;1824.9361272355536;1861.3485280151838;98644.50677746565;110217;113274.43362783133;113584;114398;11032.393218372907;11065.280756750786;11164.106871450265;11178.944105279585;11312.321260724382;13711.245881476416;13875.236861972486;13882.652933676738;13884.58346249614;13945.511907083406;7822.569758663214;7951.165127661512;8019.959900200462;8100.675972961016;8144.348452123816;7771.145174030852;7775.611219439005;7785.1436342002335;7910.367170626336;7912.4856884302335;4278.572142785696;4372.825086996548;4454.554544545519;4455.331700244924;4566.876402472866;1613.895096818703;1892.4795681187784;1927.6241132979214;1933.5456167800555;1936.6707659697886;788.5938741548107;816.098211476322;850.5534594337898;896.2964073202505;911.3757076402685;19636.018199089987;20565.69151462729;20716.482087947923;20725.89637051805;20893.582128357346;857.9013413457408;869.4305230074185;869.4870026684287;895.7357579514058;947.6114792935001;10175.491225438847;10179.491025448846;10795.730106747236;10850.186236032172;10852.968967948033;533022;537138;543121;550559;556105;118193;119503.80496194927;123555.38222308831;124124;127957.3602132002;909.3543584055395;921.9259562609619;955.5986485675894;956.5265193729149;956.583886009588;17854.821451785316;17921.910390447963;18221.908890455463;18373.989430581445;18539.536511587317;50943;52172.217416739535;52676;54030.729846352086;54042.729786350814;59152.40847591641;59587.70206148941;71967;73866.26133738593;75418;5825.475707186373;6182.814515564541;7352.823548232257;8094.300196958705;8202.38482113832;10544.630937917136;10747.570097196338;11149.885011498785;12209.267443953391;12257.325847078497;10316.896831031594;10578.471076446302;10661.161981686431;10754.247202695737;10849.620263291063;8882.555872206234;10097.394156350643;10172.643957461454;10333.328333658288;10703.571857125939;9490.525473726148;10530.42082685429;10660.253782235479;11004.229703920648;11122.221444499131;6082.665453399928;7812.257835505618;7948.046234451903;8151.184881511801;8181.754547363588;10067.295289329677;10400.635977741045;10470.267081304235;10793.784124317626;10874.401907894826 22:03:52 INFO - PROCESS | 1903 | |3;mootools.html;1270.580708366239;1451.7024010077935;1644.3833562414095;1796.85625149988;1828.9268429262822;7526.924730752691;7798.688052477908;8034.517928924269;8488.23605875471;8517.190866867646;7989.440739148262;8309.044459887114;8315.75052748417;8404;8420.578971051438;2287.3023727762984;2334.299710086967;2334.9533009339802;2337.859728416292;2348.154664320843;2327.208749025331;2341.063574570177;2364.2788949370433;2370.727366352866;2377.8454400463916;2290.3701482092392;2310.6187479066016;2315.872627005514;2327.7555856635063;2329.336139200328;2301.9309420717404;2303.608386574286;2322.454223257533;2334.8248881333884;2337.9649305260436;2323.628219484874;2325.813934885205;2350.8001819845335;2360.834741568091;2368.62102063669;8365;8427.115152908918;8473.661053557851;8481.787955301104;8500.192481714228;4990.65065445419;5126.205438157054;5146.562542183919;5154.664946778447;5172.5344718975475;3309.4539400998806;3371.3762953853616;3555.9111022224392;3563.429851223792;3566.5006899034156;1029.840374741916;1032.8724124194223;1043.7234132954773;1062.6440142552253;1063.787242551493;10778.4610769462;10819.404932728698;10908.50911708967;10917.39954302513;10936.179786515964;3621.855125794991;4601.7699115044115;4679.017406344706;4687.671862969628;4771.880702982453;9401;9491.90508094924;9494.098060684224;9497.5726092326;9508.477033763209;5091.618128640367;5200.63595548308;5209.088409528317;5211.009908117446;5211.2443695663915;4234.661227101825;4393.626541743987;4551.8634440966825;4701.623870090384;4711.976440117777;3783.6027217142355;3810.2379524094736;3877.4765406670303;3898.0644645285493;3915.275674000319;1089.4171618184234;1280.3278278903465;1288.0211039609965;1298.3313593499356;1307.627326212044;708.8972099045712;818.9467684600481;899.3120262998812;901.0898992018109;928.5078908178676;4725.9527404726205;4862.586680132157;5490.313710786152;5726.885462290731;5879.853003674943;712.1063065852322;801.0427539090847;831.2850948184556;883.6907082521067;885.7608445719619;3361.907380101428;3433.7939723616664;3568.8572457101945;3576.8390422431053;3887.9805600971813;9843.803123937481;10144.949275253724;10148.949255253676;10150.695479135638;10165.695029149138;9497.620095196253;9997.950010250048;10017.649382271586;10116.443595602312;10155.593776249016;5523.861903452445;6496.447801936793;7704.576248306397;7731.961340193263;7759.806004849811;5616.6068375213345;5630.7466164023535;5719.542436604978;5745.511631511368;5938.376470470711;9232.95383523105;9352.298577606844;9388.155066044088;9395.154436100784;9421.764455888657;9203.539823008956;9494.715158545254;9586;9603.519824008912;9613.855792163264;6670.899936500859;6860.211075726355;7054.400375967895;7165.2834716530015;7295.088113985752;7848.60756962161;8069.112397636138;8081.515109093473;8096.352291816758;8106.432549721463;6940.722371105099;7080.504364694628;7196;7272.163701174432;7318.34134927859;6044.939550604614;6811.489138314547;6975.563629124611;7054.753083642048;7099.432045436456;6650.567713098633;6675.299093595103;6735.292794256533;6742.831429214113;6744.831379215559;4842.806287748451;4873.658843881036;4876.609871210366;4907.656464047625;4959.404871415453;4809.807607695653;4844.515548445268;4856.611471082234;4907.828226012072;4918.483559226373 22:03:52 INFO - PROCESS | 1903 | |4;prototype.html;297.579193262807;333.423177902229;354.97160227181826;366.7157952586746;371.22414154417265;5308.70802105884;5390.703511306886;5501.862453438661;5540.088669409048;5596.63621864579;4315.546867578906;4581.541845815417;5236.2931004314405;5278.287431196787;5436.456354364562;4257.701960862741;4261.595148460907;4272.978635106821;4278.700490965633;4283.37891005804;3551.3607550640872;4118.4028315894175;4227.471566054243;4285.614294713475;4303.33298338758;4206.789660516977;4212.873613791591;4220.472440944882;4223.915521689564;4227.365895115727;3800.63893930079;3959.841606335743;4193.35003074524;4228.450301460791;4230.217409779185;3430.9313813723834;3451.8101504417245;3699.8705045323286;3732.7760334380023;3765.8870233893026;4427.2695005324085;4523.705959112647;4882;5007.549320561167;5304.814331498379;4063.674906007512;4259.424977628011;4619.653525985531;4632.054537094428;4643.349931009661;5030.396352437694;5302.787888484456;5702.629329093637;5718.685472299022;5727.513161381287;2995.7603391728608;3079.461094308487;3147.064555298475;3753.60587138352;3925.1757131002523;4953.306537084775;5510.724463776795;5558.471945165203;5850.917580247626;6113.174721412597;3938.8030598469963;3950.7036972227197;3951.5060617422823;3955.782431966212;3979.46277252573;3794.886153415402;3954.703397245218;3956.5449973252867;3977.244323578511;3981.8986443962763;3869.620183587074;4577.06170235101;4577.908441831211;4582.006827030253;4653;4642.489326174118;5376.946230537723;5703.9429605704245;5758.424157584208;5775.797847075416;1137.8919002694731;1180.6363649984282;1268.3594784633701;1280.3726174174587;1281.084024922185;817.6198067898456;843.8017065989487;858.789596548837;866.6316815353575;866.9176428239307;4317.784110794448;4400.317950717644;5032.232272250248;5091.5672167865405;5178.922316165261;4985.077760614298;5069.847904562869;5304.647314934667;5345.705986170798;5661.179129026351;4504.373978946914;4599.563041511051;4615.653825963066;5133.61287001546;5206;2610.856402897859;2768.4739899419046;2803.6074949507083;2878.7258066127943;2948.837813920255;5291.947080529146;5856.765729370863;5857.355690874;5871.266091738533;5881.529757501859;4979.751012449436;5175.510366985287;5242.554382877422;5299.376808479828;5477.260569823103;5613.663180209201;5768.23124900042;5805.7387417566315;5903.498202652822;5906.3126277543615;4788;5071.721055341992;5308.203769434615;5356.57147428197;5761.8559536011935;4257.95742042588;5027.64806463544;5905.0808487242675;5931.495822855106;5963.7912673056235;5274.498922602346;5863.973804584266;6021.67523144925;6112.235970503687;6227.844303892439;3344.9665503344654;3836.257076190537;3945.743526670757;3950.486436763202;3954.9406758898062;5068.6451948363265;5675.290588676416;5933.910991335047;5945.464908158287;5992.670403127696;4727.905441891212;4854.00985595904;5601.467860553241;5722.341930677859;5737.168110623853;5727.914081288867;5780.5953583248765;5828.038373668297;5834.883302334015;5873.794417195378;5349.277847490539;5609.4390560943575;5714.514266287412;5739.426057394394;5762.250907382014;5489.1217405214975;5777.2200752897825;5787.94212057874;5804.506616937439;5827.8834423310445;4471.7987690553355;4708.199606066908;5691.288588926384;5717.313922329347;5737.709015471485 22:03:52 INFO - PROCESS | 1903 | |5;yui.html;600.5285850607273;681.5944513014755;713.0979311171366;782.8199514111752;787.2245837849717;14714.77927831082;15616.84383156168;15663.92168039161;15683;15684.372625094997;13331.733365332688;14211.076280041816;14291.71416571668;14343.64140896478;14414.639634009129;824.9463784853996;857.5197889182047;867.5878957495171;867.6702852915914;870.751835726818;852.8166444214493;856.7858035491128;857.952812595307;862.8576284912982;865.2558799432483;900.9144131307548;911.1844898815556;916.2120576304372;916.2990312410977;918.5820451694462;888.8844450221494;897.6050537763396;902;907.2107266677966;910.41733290694;672.8889733194017;681.0295329155933;683.5386114372828;683.9692213850388;686.0224180542707;14369.137851728825;14590.343434545472;15165.772513412308;15299.541013769605;15513.146776927264;14153.363098660586;14189.929050354785;14290.676670835046;14351.856481435261;14588.124712517178;11803.468843902045;11827.881721182765;11859;11884.524619015228;12441.3779311035;3701.3892707703467;3703.8888833335045;3718.2749363874054;3755.455458958436;3878.8448462061483;107052;108175;113333.43333283364;113937.99999999917;116426.41786791012;9344.953275233716;10626.362418254776;10841.674749757361;11126;11248.931351521593;22257.220997265016;22543.43641408945;22612.660810087862;22874.970626321854;22956;6450.612963222128;7669.501482403737;7775.57234352116;7804.3756499479905;7987.800304992305;4219.282721937278;4231.153769246163;4281.807318670667;4320.243957307459;4408.9118217636105;3116.1586371679973;3595.658412450813;3610.422332426799;3617.113807117273;3621.257642183346;582.7435928191667;604.9504110368507;644.3105876711874;648.4034688086971;667.0128210248859;497.72625056218936;567.2512283785446;575.3383608849773;613.650219374952;616.1576885113568;8827.911720882708;9103.863442048374;9108.681196158102;9218.170364667078;9288.164065234161;493.53114541186017;562.8930503204379;572.7508533787816;621.5586933277333;636.7771280051945;4212.53662097169;4348.586884245991;4384.276594361894;4392.868213953587;4495.25828238337;82749;91678;99260.50369748105;99473.50263248637;99968;74769;81084;82178.58910705408;89122.55438722765;92322.23033309012;78647.60676196583;79817;80981;81753;84038.15961840426;8551.615177316911;8661.437006594551;8661.480311181349;8741.344399170215;8883.955580222317;49471;51523.742381287855;51626;52267;54083;52235;54201.45798541964;54384.91230175295;64021.03968440384;69144.96282555665;11041.44792760375;11073.501692423699;11296.378699171624;11452;11508.712282193012;11981.460834262305;12041.879581204075;12252.326122063372;12312.938435307766;12751.553695620609;8991.235744961752;11505.022073123544;11692.766144676889;11727.472263748323;11871.52513899469;9585.808283834423;11788.49098395988;11859.942945619192;11931.76136477283;12327.753444930871;11520.711982200177;11736.354500502213;11811.291322520676;12130.818037729618;12165.574204902787;9616.95191524038;9670.38899497146;9813.754656133367;9833.655822046194;10218.489075546342;8107.513549187068;9850.162736167505;9989.001099889952;10085.142762864945;10246.6413675521 22:03:52 INFO - PROCESS | 1903 | __end_tp_report 22:03:52 INFO - PROCESS | 1903 | __start_cc_report 22:03:52 INFO - PROCESS | 1903 | _x_x_mozilla_cycle_collect,371 22:03:52 INFO - PROCESS | 1903 | __end_cc_report 22:03:52 INFO - PROCESS | 1903 | __startTimestamp1482127432870__endTimestamp 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | ------- Summary: start ------- 22:03:52 INFO - PROCESS | 1903 | Number of tests: 6 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#0] dojo.html Cycles:165 Average:5724.05 Median:5973.70 stddev:631.97 (10.6%) stddev-sans-first:607.00 22:03:52 INFO - PROCESS | 1903 | Values: 3398.5 3402.3 3435.5 3442.3 3446.3 5686.0 5761.6 5786.6 5803.7 5806.0 5705.7 5720.6 5754.6 5766.7 5785.3 5222.5 5256.1 5259.2 5335.9 5338.6 5190.6 5276.5 5324.4 5343.0 5387.5 5304.4 5335.6 5370.4 5377.5 5393.6 5139.6 5184.7 5187.9 5231.7 5315.2 4847.7 5048.8 5238.9 5267.3 5309.3 5533.8 5732.3 5765.3 5768.1 5774.5 6134.6 6188.6 6206.9 6253.2 6289.0 5044.7 6210.2 6249.2 6272.7 6276.2 5959.6 5964.4 5964.7 5986.9 5994.7 5001.8 5404.5 5550.7 6193.8 6332.8 6042.3 6052.7 6100.8 6111.0 6128.5 6175.0 6205.6 6205.9 6214.1 6219.7 5859.8 6125.2 6154.6 6164.9 6182.7 5576.7 6259.4 6273.8 6294.8 6327.1 4941.9 4973.0 4975.9 4979.2 4985.4 4375.2 4390.2 4400.9 4430.0 4443.6 5877.9 5968.6 5996.1 5997.1 6020.2 4643.7 5200.4 6254.7 6273.3 6287.7 4920.7 5716.4 5821.4 5856.9 5916.3 5341.0 5422.7 5426.6 5448.2 5466.9 6322.0 6334.8 6336.0 6345.0 6359.9 6115.5 6218.7 6220.5 6228.3 6230.0 6116.4 6148.8 6156.3 6168.7 6202.6 6103.4 6106.1 6106.6 6114.4 6128.6 6181.9 6210.2 6249.5 6251.4 6259.2 5498.5 5973.7 6092.3 6168.2 6216.4 6075.5 6118.8 6122.1 6129.9 6138.8 6053.3 6054.5 6081.9 6121.1 6156.5 6207.5 6212.8 6213.4 6224.3 6247.0 5752.8 5767.0 5814.8 6068.0 6076.5 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#1] ext.html Cycles:180 Average:64908.22 Median:19267.23 stddev:136226.48 (707.0%) stddev-sans-first:136522.60 22:03:52 INFO - PROCESS | 1903 | Values: 424.6 430.1 441.8 475.1 476.3 38641.2 40759.6 41022.8 41100.0 41241.0 41791.0 42198.7 42283.8 42348.6 42366.6 8534.8 8837.1 9388.5 9626.1 9638.5 11341.9 11641.5 11659.4 11823.6 11840.2 11569.5 11630.8 11638.8 11643.6 11652.1 8643.6 11117.3 11273.9 11479.7 11621.3 11442.0 11533.7 11533.9 11537.8 11566.1 31814.8 39775.8 39856.0 39898.8 40279.0 3336.5 3418.7 3472.0 3846.1 4110.6 25396.9 30441.5 30820.8 30997.5 31196.0 3183.9 3218.9 3219.3 3251.3 3292.2 426726.0 434451.0 435500.0 435724.0 436132.0 61591.0 61962.1 62021.0 62171.7 62274.7 63151.0 63405.0 63446.0 63475.0 63508.7 14899.0 15424.4 15445.4 15452.2 15465.8 10794.0 11072.7 11555.2 11751.2 11775.2 14522.8 17939.3 17972.6 18003.5 18124.1 4825.6 5794.0 5855.8 6031.7 6032.5 715.7 977.8 1020.6 1035.3 1036.4 29326.0 29364.0 29648.0 30464.8 30704.2 931.5 1001.6 1018.9 1030.6 1098.7 13685.7 15560.8 15618.1 15678.0 15835.8 560822.4 562890.0 564744.0 564887.0 568019.0 59153.1 59387.0 59456.4 59474.7 59522.0 1314.1 1344.9 1365.5 1385.6 1390.9 28838.7 29684.3 29690.4 30397.7 30485.0 51891.7 51988.0 52128.0 52246.0 52354.0 525171.0 525503.4 527692.0 528176.0 530592.0 20379.7 24900.5 25963.7 41288.6 41665.4 42293.6 42585.6 42598.0 42919.9 43768.6 26814.7 35809.9 35859.3 36808.8 36953.0 28394.4 34034.0 35592.0 36153.1 36278.1 35062.5 35488.0 35552.5 35673.3 35701.1 29918.0 31001.0 32069.0 32222.2 32277.5 22557.3 24218.0 24407.8 24413.3 24790.6 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#2] jquery.html Cycles:180 Average:32231.83 Median:359.64 stddev:90999.38 (25303.2%) stddev-sans-first:91223.05 22:03:52 INFO - PROCESS | 1903 | Values: 291.3 341.2 341.9 377.4 389.8 15283.7 19543.6 20451.2 20514.3 20567.9 17968.2 18250.5 18361.2 18462.2 18548.6 5596.6 5916.2 5917.1 5917.4 5922.8 5036.3 5789.4 6407.2 6482.9 6550.1 6486.4 6511.9 6521.9 6522.9 6533.8 6248.5 6451.7 6465.6 6481.3 6503.0 4940.2 5209.3 5643.1 5647.8 5650.8 12724.0 13008.7 13009.7 13012.3 17699.0 2072.2 2304.2 2708.8 2831.2 2850.4 8073.5 9424.0 9624.0 9995.6 10121.3 1779.0 1798.6 1816.5 1824.9 1861.3 98644.5 110217.0 113274.4 113584.0 114398.0 11032.4 11065.3 11164.1 11178.9 11312.3 13711.2 13875.2 13882.7 13884.6 13945.5 7822.6 7951.2 8020.0 8100.7 8144.3 7771.1 7775.6 7785.1 7910.4 7912.5 4278.6 4372.8 4454.6 4455.3 4566.9 1613.9 1892.5 1927.6 1933.5 1936.7 788.6 816.1 850.6 896.3 911.4 19636.0 20565.7 20716.5 20725.9 20893.6 857.9 869.4 869.5 895.7 947.6 10175.5 10179.5 10795.7 10850.2 10853.0 533022.0 537138.0 543121.0 550559.0 556105.0 118193.0 119503.8 123555.4 124124.0 127957.4 909.4 921.9 955.6 956.5 956.6 17854.8 17921.9 18221.9 18374.0 18539.5 50943.0 52172.2 52676.0 54030.7 54042.7 59152.4 59587.7 71967.0 73866.3 75418.0 5825.5 6182.8 7352.8 8094.3 8202.4 10544.6 10747.6 11149.9 12209.3 12257.3 10316.9 10578.5 10661.2 10754.2 10849.6 8882.6 10097.4 10172.6 10333.3 10703.6 9490.5 10530.4 10660.3 11004.2 11122.2 6082.7 7812.3 7948.0 8151.2 8181.8 10067.3 10400.6 10470.3 10793.8 10874.4 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#3] mootools.html Cycles:180 Average:5418.98 Median:5108.91 stddev:2983.48 (58.4%) stddev-sans-first:2975.56 22:03:52 INFO - PROCESS | 1903 | Values: 1270.6 1451.7 1644.4 1796.9 1828.9 7526.9 7798.7 8034.5 8488.2 8517.2 7989.4 8309.0 8315.8 8404.0 8420.6 2287.3 2334.3 2335.0 2337.9 2348.2 2327.2 2341.1 2364.3 2370.7 2377.8 2290.4 2310.6 2315.9 2327.8 2329.3 2301.9 2303.6 2322.5 2334.8 2338.0 2323.6 2325.8 2350.8 2360.8 2368.6 8365.0 8427.1 8473.7 8481.8 8500.2 4990.7 5126.2 5146.6 5154.7 5172.5 3309.5 3371.4 3555.9 3563.4 3566.5 1029.8 1032.9 1043.7 1062.6 1063.8 10778.5 10819.4 10908.5 10917.4 10936.2 3621.9 4601.8 4679.0 4687.7 4771.9 9401.0 9491.9 9494.1 9497.6 9508.5 5091.6 5200.6 5209.1 5211.0 5211.2 4234.7 4393.6 4551.9 4701.6 4712.0 3783.6 3810.2 3877.5 3898.1 3915.3 1089.4 1280.3 1288.0 1298.3 1307.6 708.9 818.9 899.3 901.1 928.5 4726.0 4862.6 5490.3 5726.9 5879.9 712.1 801.0 831.3 883.7 885.8 3361.9 3433.8 3568.9 3576.8 3888.0 9843.8 10144.9 10148.9 10150.7 10165.7 9497.6 9998.0 10017.6 10116.4 10155.6 5523.9 6496.4 7704.6 7732.0 7759.8 5616.6 5630.7 5719.5 5745.5 5938.4 9233.0 9352.3 9388.2 9395.2 9421.8 9203.5 9494.7 9586.0 9603.5 9613.9 6670.9 6860.2 7054.4 7165.3 7295.1 7848.6 8069.1 8081.5 8096.4 8106.4 6940.7 7080.5 7196.0 7272.2 7318.3 6044.9 6811.5 6975.6 7054.8 7099.4 6650.6 6675.3 6735.3 6742.8 6744.8 4842.8 4873.7 4876.6 4907.7 4959.4 4809.8 4844.5 4856.6 4907.8 4918.5 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#4] prototype.html Cycles:180 Average:4528.97 Median:5017.60 stddev:1401.27 (27.9%) stddev-sans-first:1368.74 22:03:52 INFO - PROCESS | 1903 | Values: 297.6 333.4 355.0 366.7 371.2 5308.7 5390.7 5501.9 5540.1 5596.6 4315.5 4581.5 5236.3 5278.3 5436.5 4257.7 4261.6 4273.0 4278.7 4283.4 3551.4 4118.4 4227.5 4285.6 4303.3 4206.8 4212.9 4220.5 4223.9 4227.4 3800.6 3959.8 4193.4 4228.5 4230.2 3430.9 3451.8 3699.9 3732.8 3765.9 4427.3 4523.7 4882.0 5007.5 5304.8 4063.7 4259.4 4619.7 4632.1 4643.3 5030.4 5302.8 5702.6 5718.7 5727.5 2995.8 3079.5 3147.1 3753.6 3925.2 4953.3 5510.7 5558.5 5850.9 6113.2 3938.8 3950.7 3951.5 3955.8 3979.5 3794.9 3954.7 3956.5 3977.2 3981.9 3869.6 4577.1 4577.9 4582.0 4653.0 4642.5 5376.9 5703.9 5758.4 5775.8 1137.9 1180.6 1268.4 1280.4 1281.1 817.6 843.8 858.8 866.6 866.9 4317.8 4400.3 5032.2 5091.6 5178.9 4985.1 5069.8 5304.6 5345.7 5661.2 4504.4 4599.6 4615.7 5133.6 5206.0 2610.9 2768.5 2803.6 2878.7 2948.8 5291.9 5856.8 5857.4 5871.3 5881.5 4979.8 5175.5 5242.6 5299.4 5477.3 5613.7 5768.2 5805.7 5903.5 5906.3 4788.0 5071.7 5308.2 5356.6 5761.9 4258.0 5027.6 5905.1 5931.5 5963.8 5274.5 5864.0 6021.7 6112.2 6227.8 3345.0 3836.3 3945.7 3950.5 3954.9 5068.6 5675.3 5933.9 5945.5 5992.7 4727.9 4854.0 5601.5 5722.3 5737.2 5727.9 5780.6 5828.0 5834.9 5873.8 5349.3 5609.4 5714.5 5739.4 5762.3 5489.1 5777.2 5787.9 5804.5 5827.9 4471.8 4708.2 5691.3 5717.3 5737.7 22:03:52 INFO - PROCESS | 1903 | 22:03:52 INFO - PROCESS | 1903 | [#5] yui.html Cycles:180 Average:19767.28 Median:52251.00 stddev:28980.23 (55.5%) stddev-sans-first:29025.79 22:03:52 INFO - PROCESS | 1903 | Values: 600.5 681.6 713.1 782.8 787.2 14714.8 15616.8 15663.9 15683.0 15684.4 13331.7 14211.1 14291.7 14343.6 14414.6 824.9 857.5 867.6 867.7 870.8 852.8 856.8 858.0 862.9 865.3 900.9 911.2 916.2 916.3 918.6 888.9 897.6 902.0 907.2 910.4 672.9 681.0 683.5 684.0 686.0 14369.1 14590.3 15165.8 15299.5 15513.1 14153.4 14189.9 14290.7 14351.9 14588.1 11803.5 11827.9 11859.0 11884.5 12441.4 3701.4 3703.9 3718.3 3755.5 3878.8 107052.0 108175.0 113333.4 113938.0 116426.4 9345.0 10626.4 10841.7 11126.0 11248.9 22257.2 22543.4 22612.7 22875.0 22956.0 6450.6 7669.5 7775.6 7804.4 7987.8 4219.3 4231.2 4281.8 4320.2 4408.9 3116.2 3595.7 3610.4 3617.1 3621.3 582.7 605.0 644.3 648.4 667.0 497.7 567.3 575.3 613.7 616.2 8827.9 9103.9 9108.7 9218.2 9288.2 493.5 562.9 572.8 621.6 636.8 4212.5 4348.6 4384.3 4392.9 4495.3 82749.0 91678.0 99260.5 99473.5 99968.0 74769.0 81084.0 82178.6 89122.6 92322.2 78647.6 79817.0 80981.0 81753.0 84038.2 8551.6 8661.4 8661.5 8741.3 8884.0 49471.0 51523.7 51626.0 52267.0 54083.0 52235.0 54201.5 54384.9 64021.0 69145.0 11041.4 11073.5 11296.4 11452.0 11508.7 11981.5 12041.9 12252.3 12312.9 12751.6 8991.2 11505.0 11692.8 11727.5 11871.5 9585.8 11788.5 11859.9 11931.8 12327.8 11520.7 11736.4 11811.3 12130.8 12165.6 9617.0 9670.4 9813.8 9833.7 10218.5 8107.5 9850.2 9989.0 10085.1 10246.6 22:03:52 INFO - PROCESS | 1903 | -------- Summary: end -------- 22:03:52 INFO - PROCESS | 1903 | 22:03:53 INFO - TEST-INFO | 1903: exit 0 22:03:53 INFO - TEST-OK | dromaeo_css | took 1116311ms 22:03:53 INFO - TEST-START | kraken 22:03:53 INFO - operating with platform_type : mac_ 22:03:53 INFO - Initialising browser for kraken test... 22:03:53 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpIIqHTS/profile http://localhost:49364/getInfo.html) 22:03:56 INFO - PROCESS | 1910 | 2016-12-18 22:03:56.145 plugin-container[1911:11501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 22:03:56 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:03:56 INFO - PROCESS | 1910 | 2016-12-18 22:03:56.147 plugin-container[1911:11501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:03:56 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:03:56 INFO - PROCESS | 1910 | 2016-12-18 22:03:56.147 plugin-container[1911:11501] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:03:56 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 22:03:56 INFO - PROCESS | 1910 | colorDepth:24 22:03:56 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 22:03:56 INFO - PROCESS | 1910 | __metrics 22:03:56 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 22:03:56 INFO - TEST-INFO | 1910: exit 0 22:03:56 INFO - Browser initialized. 22:03:56 INFO - Running cycle 1/1 for kraken test... 22:03:56 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpIIqHTS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:03:59 INFO - PROCESS | 1912 | 2016-12-18 22:03:59.615 plugin-container[1913:11620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 22:03:59 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:03:59 INFO - PROCESS | 1912 | 2016-12-18 22:03:59.616 plugin-container[1913:11620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 22:03:59 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:03:59 INFO - PROCESS | 1912 | 2016-12-18 22:03:59.616 plugin-container[1913:11620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:04:09 INFO - PROCESS | 1912 | RSS: Main: 236691456 22:04:09 INFO - PROCESS | 1912 | 22:04:12 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49364/tests/kraken/driver.html?testName=audio-beat-detection) 22:04:13 INFO - PROCESS | 1912 | RSS: Main: 249327616 22:04:13 INFO - PROCESS | 1912 | 22:04:16 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49364/tests/kraken/driver.html?testName=audio-dft) 22:04:16 INFO - PROCESS | 1912 | RSS: Main: 256921600 22:04:16 INFO - PROCESS | 1912 | 22:04:20 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49364/tests/kraken/driver.html?testName=audio-fft) 22:04:20 INFO - PROCESS | 1912 | RSS: Main: 259760128 22:04:20 INFO - PROCESS | 1912 | 22:04:22 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49364/tests/kraken/driver.html?testName=audio-oscillator) 22:04:23 INFO - PROCESS | 1912 | RSS: Main: 260853760 22:04:23 INFO - PROCESS | 1912 | 22:04:25 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49364/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:04:26 INFO - PROCESS | 1912 | RSS: Main: 261173248 22:04:26 INFO - PROCESS | 1912 | 22:04:29 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49364/tests/kraken/driver.html?testName=imaging-darkroom) 22:04:29 INFO - PROCESS | 1912 | RSS: Main: 267874304 22:04:29 INFO - PROCESS | 1912 | 22:04:32 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49364/tests/kraken/driver.html?testName=imaging-desaturate) 22:04:33 INFO - PROCESS | 1912 | RSS: Main: 267321344 22:04:33 INFO - PROCESS | 1912 | 22:04:36 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49364/tests/kraken/driver.html?testName=json-parse-financial) 22:04:37 INFO - PROCESS | 1912 | RSS: Main: 267145216 22:04:37 INFO - PROCESS | 1912 | 22:04:38 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49364/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:04:39 INFO - PROCESS | 1912 | RSS: Main: 266440704 22:04:39 INFO - PROCESS | 1912 | 22:04:41 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:04:42 INFO - PROCESS | 1912 | RSS: Main: 266387456 22:04:42 INFO - PROCESS | 1912 | 22:04:44 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:04:45 INFO - PROCESS | 1912 | RSS: Main: 266539008 22:04:45 INFO - PROCESS | 1912 | 22:04:48 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:04:48 INFO - PROCESS | 1912 | RSS: Main: 266809344 22:04:48 INFO - PROCESS | 1912 | 22:04:51 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:04:52 INFO - PROCESS | 1912 | RSS: Main: 266747904 22:04:52 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:49364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49364/tests/kraken/driver.html?testName=ai-astar) 22:04:54 INFO - PROCESS | 1912 | RSS: Main: 268959744 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | __start_tp_report 22:04:54 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 22:04:54 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 22:04:54 INFO - PROCESS | 1912 | |i|pagename|runs| 22:04:54 INFO - PROCESS | 1912 | |0;ai-astar;124;124;120;118;119;118;117;155;174;145 22:04:54 INFO - PROCESS | 1912 | |1;audio-beat-detection;152;147;117;150;113;116;115;115;133;114 22:04:54 INFO - PROCESS | 1912 | |2;audio-dft;207;229;197;218;177;280;276;194;209;197 22:04:54 INFO - PROCESS | 1912 | |3;audio-fft;80;71;51;81;84;82;83;59;72;59 22:04:54 INFO - PROCESS | 1912 | |4;audio-oscillator;90;72;84;73;70;68;91;57;73;112 22:04:54 INFO - PROCESS | 1912 | |5;imaging-gaussian-blur;109;111;141;172;113;113;111;113;109;115 22:04:54 INFO - PROCESS | 1912 | |6;imaging-darkroom;96;96;106;96;96;119;97;95;96;94 22:04:54 INFO - PROCESS | 1912 | |7;imaging-desaturate;94;103;92;146;91;95;95;146;152;93 22:04:54 INFO - PROCESS | 1912 | |8;json-parse-financial;57;59;57;59;58;59;59;59;59;61 22:04:54 INFO - PROCESS | 1912 | |9;json-stringify-tinderbox;69;76;78;74;94;104;106;108;108;72 22:04:54 INFO - PROCESS | 1912 | |10;stanford-crypto-aes;93;91;107;110;117;113;90;91;92;91 22:04:54 INFO - PROCESS | 1912 | |11;stanford-crypto-ccm;145;158;157;146;141;141;141;143;187;151 22:04:54 INFO - PROCESS | 1912 | |12;stanford-crypto-pbkdf2;176;215;242;194;174;172;174;176;171;196 22:04:54 INFO - PROCESS | 1912 | |13;stanford-crypto-sha256-iterative;70;68;67;69;66;69;68;67;70;82 22:04:54 INFO - PROCESS | 1912 | __end_tp_report 22:04:54 INFO - PROCESS | 1912 | __start_cc_report 22:04:54 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,937 22:04:54 INFO - PROCESS | 1912 | __end_cc_report 22:04:54 INFO - PROCESS | 1912 | __startTimestamp1482127494519__endTimestamp 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | ------- Summary: start ------- 22:04:54 INFO - PROCESS | 1912 | Number of tests: 14 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#0] ai-astar Cycles:10 Average:131.40 Median:124.00 stddev:19.77 (15.9%) stddev-sans-first:20.78 22:04:54 INFO - PROCESS | 1912 | Values: 124.0 124.0 120.0 118.0 119.0 118.0 117.0 155.0 174.0 145.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:125.00 stddev:16.54 (13.2%) stddev-sans-first:14.92 22:04:54 INFO - PROCESS | 1912 | Values: 152.0 147.0 117.0 150.0 113.0 116.0 115.0 115.0 133.0 114.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#2] audio-dft Cycles:10 Average:218.40 Median:213.50 stddev:34.43 (16.1%) stddev-sans-first:36.27 22:04:54 INFO - PROCESS | 1912 | Values: 207.0 229.0 197.0 218.0 177.0 280.0 276.0 194.0 209.0 197.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#3] audio-fft Cycles:10 Average:72.20 Median:80.50 stddev:11.97 (14.9%) stddev-sans-first:12.36 22:04:54 INFO - PROCESS | 1912 | Values: 80.0 71.0 51.0 81.0 84.0 82.0 83.0 59.0 72.0 59.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:73.00 stddev:15.58 (21.3%) stddev-sans-first:16.01 22:04:54 INFO - PROCESS | 1912 | Values: 90.0 72.0 84.0 73.0 70.0 68.0 91.0 57.0 73.0 112.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#5] imaging-gaussian-blur Cycles:10 Average:120.70 Median:113.00 stddev:20.32 (18.0%) stddev-sans-first:21.11 22:04:54 INFO - PROCESS | 1912 | Values: 109.0 111.0 141.0 172.0 113.0 113.0 111.0 113.0 109.0 115.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:96.00 stddev:7.74 (8.1%) stddev-sans-first:8.13 22:04:54 INFO - PROCESS | 1912 | Values: 96.0 96.0 106.0 96.0 96.0 119.0 97.0 95.0 96.0 94.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#7] imaging-desaturate Cycles:10 Average:110.70 Median:92.50 stddev:25.99 (28.1%) stddev-sans-first:26.86 22:04:54 INFO - PROCESS | 1912 | Values: 94.0 103.0 92.0 146.0 91.0 95.0 95.0 146.0 152.0 93.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:1.16 (2.0%) stddev-sans-first:1.05 22:04:54 INFO - PROCESS | 1912 | Values: 57.0 59.0 57.0 59.0 58.0 59.0 59.0 59.0 59.0 61.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#9] json-stringify-tinderbox Cycles:10 Average:88.90 Median:73.00 stddev:16.55 (22.7%) stddev-sans-first:15.91 22:04:54 INFO - PROCESS | 1912 | Values: 69.0 76.0 78.0 74.0 94.0 104.0 106.0 108.0 108.0 72.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#10] stanford-crypto-aes Cycles:10 Average:99.50 Median:91.00 stddev:10.86 (11.9%) stddev-sans-first:11.26 22:04:54 INFO - PROCESS | 1912 | Values: 93.0 91.0 107.0 110.0 117.0 113.0 90.0 91.0 92.0 91.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:148.50 stddev:14.17 (9.5%) stddev-sans-first:14.86 22:04:54 INFO - PROCESS | 1912 | Values: 145.0 158.0 157.0 146.0 141.0 141.0 141.0 143.0 187.0 151.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:185.00 stddev:23.48 (12.7%) stddev-sans-first:24.42 22:04:54 INFO - PROCESS | 1912 | Values: 176.0 215.0 242.0 194.0 174.0 172.0 174.0 176.0 171.0 196.0 22:04:54 INFO - PROCESS | 1912 | 22:04:54 INFO - PROCESS | 1912 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.00 stddev:4.55 (6.6%) stddev-sans-first:4.82 22:04:54 INFO - PROCESS | 1912 | Values: 70.0 68.0 67.0 69.0 66.0 69.0 68.0 67.0 70.0 82.0 22:04:54 INFO - PROCESS | 1912 | -------- Summary: end -------- 22:04:54 INFO - PROCESS | 1912 | 22:04:55 INFO - TEST-INFO | 1912: exit 0 22:04:55 INFO - TEST-OK | kraken | took 61963ms 22:04:55 INFO - SUITE-END | took 1178s 22:04:55 INFO - Completed test suite (00:19:38) 22:04:55 INFO - javascript benchmark 22:04:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7107.607334958287, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.5072164536127, 3402.319536092781, 3435.5018522314267, 3442.311537692459, 3446.2590543033243, 5685.971570142154, 5761.625494342865, 5786.623869448488, 5803.709814509279, 5806.042003069494, 5705.686187259709, 5720.599558030939, 5754.597178197528, 5766.682832444214, 5785.305763308398, 5222.477752224785, 5256.080185967459, 5259.211118332261, 5335.946640533583, 5338.572914166877, 5190.636655434102, 5276.498732620395, 5324.414314425429, 5342.973285133569, 5387.488188622075, 5304.389995150568, 5335.626506144571, 5370.355557333126, 5377.542908852753, 5393.622446428752, 5139.563137133348, 5184.7407629618365, 5187.922181167285, 5231.659942103751, 5315.202719592054, 4847.660663753539, 5048.7728052237735, 5238.947610523884, 5267.341582302212, 5309.309789727311, 5533.750981205815, 5732.31212254528, 5765.279340082489, 5768.134779783024, 5774.451427114417, 6134.589663226396, 6188.566800323979, 6206.944819380716, 6253.1558239637525, 6289.0, 5044.6973181608855, 6210.1616281801835, 6249.187605611241, 6272.65500397478, 6276.152719382871, 5959.582829201958, 5964.403559644001, 5964.701764911824, 5986.910196347058, 5994.730237139252, 5001.8499445016705, 5404.459554044643, 5550.722463876871, 6193.783217587363, 6332.8416789579705, 6042.305134909541, 6052.727627256784, 6100.786472473442, 6111.022236442237, 6128.509719222451, 6175.0, 6205.565610407319, 6205.937940620626, 6214.130021796952, 6219.720112594944, 5859.76944841588, 6125.234345706786, 6154.599951003171, 6164.8767024659255, 6182.6599537024995, 5576.665400076008, 6259.405356491126, 6273.780417685359, 6294.842628934313, 6327.114204011442, 4941.937483441077, 4973.0, 4975.875603109951, 4979.178435558165, 4985.4266759323145, 4375.190589740908, 4390.209762242763, 4400.9119817604105, 4429.977850110793, 4443.644508439317, 5877.882442351129, 5968.582199246011, 5996.13056106862, 5997.1304160896425, 6020.217371741645, 4643.6517261205545, 5200.401953775288, 6254.655993920287, 6273.341299163613, 6287.717052732638, 4920.6801557898625, 5716.371199168088, 5821.359650438449, 5856.941430585724, 5916.319623243382, 5340.973295133499, 5422.674639521641, 5426.620136590401, 5448.210009548593, 5466.890662186814, 6321.96839015802, 6334.778282760266, 6335.968320158462, 6345.0482427636225, 6359.87280254383, 6115.514161902822, 6218.657973811484, 6220.533459990609, 6228.252609686685, 6230.034344676583, 6116.366205733216, 6148.78479253224, 6156.29202641702, 6168.691565421801, 6202.5658203925295, 6103.358992651509, 6106.06632622643, 6106.572539922163, 6114.358196334042, 6128.63228206309, 6181.876362472815, 6210.223722034746, 6249.531285153723, 6251.437370636664, 6259.1550140730415, 5498.505134537911, 5973.701314934149, 6092.268927728701, 6168.19813424252, 6216.378362163929, 6075.453209211192, 6118.80192544137, 6122.081687746695, 6129.908051379144, 6138.848241798255, 6053.273607167168, 6054.515638748978, 6081.939180608314, 6121.051237058263, 6156.538259630635, 6207.472364849038, 6212.844678883064, 6213.409726076015, 6224.253089629274, 6246.968765156145, 5752.798652047159, 5767.0196066669605, 5814.825555233349, 6068.029115341524, 6076.453119219114], "value": 5686.827736970681, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.63057140287947, 430.1204037742818, 441.7879417879417, 475.1305111645689, 476.3141076849337, 38641.22717545647, 40759.59240407595, 41022.79488602553, 41100.0, 41241.0, 41791.0, 42198.73403797884, 42283.78858105705, 42348.57651423485, 42366.57633423665, 8534.78663033426, 8837.072107428723, 9388.483633400177, 9626.133647971681, 9638.518074096302, 11341.943290283538, 11641.47613357401, 11659.358735269558, 11823.645290641252, 11840.171188016842, 11569.53721851125, 11630.825537616942, 11638.767224655501, 11643.592474263403, 11652.126090543201, 8643.567821608895, 11117.27737697051, 11273.887261127365, 11479.713007174803, 11621.302721836717, 11442.0, 11533.653990380302, 11533.942330288295, 11537.826932596017, 11566.132540059529, 31814.840925795455, 39775.80112099421, 39856.0, 39898.813154290045, 40279.0, 3336.466165413522, 3418.675225853565, 3471.9826400868087, 3846.076941534012, 4110.630043296118, 25396.873015634803, 30441.543376849364, 30820.845895770603, 30997.535036974463, 31196.0, 3183.856726447292, 3218.903432897017, 3219.307848812493, 3251.3497300539752, 3292.1769557610596, 426726.0, 434451.0, 435500.0, 435723.99999999686, 436132.0, 61591.0, 61962.070568941504, 62021.0, 62171.68914155491, 62274.68862655749, 63151.0, 63405.0, 63446.0483092754, 63475.0, 63508.68245658742, 14899.031562948374, 15424.383024679111, 15445.382184712485, 15452.150131742863, 15465.845341546665, 10794.028537431668, 11072.667819965414, 11555.191136620524, 11751.236169648946, 11775.23460975034, 14522.782158267635, 17939.282428702965, 17972.640547189243, 18003.549911252325, 18124.09696981824, 4825.5898248649255, 5794.0439827428845, 5855.824325270249, 6031.728572214261, 6032.4570788629235, 715.6529083394549, 977.7506735782472, 1020.6325722739808, 1035.3477309295247, 1036.3574583757968, 29326.0, 29364.0, 29648.0, 30464.84767576192, 30704.232394189876, 931.4923366765129, 1001.5543083327995, 1018.9286749927583, 1030.644427672452, 1098.6978580890159, 13685.657858553417, 15560.766588500956, 15618.141002244985, 15678.0, 15835.835437752401, 560822.3917760851, 562890.0, 564744.0, 564887.0, 568019.0, 59153.11270330948, 59387.0, 59456.40543594509, 59474.70262648746, 59522.0, 1314.0801438992744, 1344.8587898270541, 1365.481117175467, 1385.6466601016675, 1390.874821266091, 28838.711612883602, 29684.257893552833, 29690.40619187561, 30397.69602304037, 30485.0, 51891.70270743262, 51988.0, 52128.0, 52246.0, 52354.0, 525171.0, 525503.3724831351, 527692.0, 528176.0, 530592.0, 20379.69430458574, 24900.501989960463, 25963.740362596887, 41288.58711412847, 41665.37501937413, 42293.577064230194, 42585.574144258164, 42598.0, 42919.92700182521, 43768.562314376446, 26814.731852681223, 35809.925702228975, 35859.282814344086, 36808.81595592005, 36953.0, 28394.43211135807, 34033.97898063062, 35592.0, 36153.096172594844, 36278.09304767296, 35062.47406288857, 35488.0, 35552.46671299984, 35673.28653426969, 35701.10747231236, 29918.0, 31001.0, 32069.0, 32222.19444513812, 32277.51583726293, 22557.323280301618, 24218.0, 24407.755922441258, 24413.26760197197, 24790.628140578265], "value": 19595.996147308037, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.29506594042414, 341.24584667883977, 341.91452136965756, 377.35849056603774, 389.8460108257237, 15283.694326113486, 19543.609127817454, 20451.181952721872, 20514.28200013, 20567.897160514214, 17968.191431385585, 18250.543736406595, 18361.173747181376, 18462.169202385892, 18548.629027419476, 5596.58025648076, 5916.230889984296, 5917.082852157922, 5917.437843404891, 5922.792702255422, 5036.34527511423, 5789.39211382805, 6407.231132264134, 6482.902756458657, 6550.050242714803, 6486.448651864597, 6511.869762604745, 6521.93478065218, 6522.902156467633, 6533.803985880431, 6248.500119990413, 6451.677416129175, 6465.577572933947, 6481.254655714606, 6503.0, 4940.160172770602, 5209.29674493946, 5643.125315576092, 5647.774089036434, 5650.830475085754, 12724.0456965727, 13008.674783130402, 13009.739805203842, 13012.34938253093, 17699.0, 2072.171131547378, 2304.216566367443, 2708.783297336228, 2831.2497188245134, 2850.415664788734, 8073.475224110414, 9424.0, 9624.0, 9995.550200240934, 10121.291509594332, 1778.9555261118576, 1798.5611510791314, 1816.5458635341165, 1824.9361272355536, 1861.3485280151838, 98644.50677746565, 110217.0, 113274.43362783133, 113584.0, 114398.0, 11032.393218372907, 11065.280756750786, 11164.106871450265, 11178.944105279585, 11312.321260724382, 13711.245881476416, 13875.236861972486, 13882.652933676738, 13884.58346249614, 13945.511907083406, 7822.569758663214, 7951.165127661512, 8019.959900200462, 8100.675972961016, 8144.348452123816, 7771.145174030852, 7775.611219439005, 7785.1436342002335, 7910.367170626336, 7912.4856884302335, 4278.572142785696, 4372.825086996548, 4454.554544545519, 4455.331700244924, 4566.876402472866, 1613.895096818703, 1892.4795681187784, 1927.6241132979214, 1933.5456167800555, 1936.6707659697886, 788.5938741548107, 816.098211476322, 850.5534594337898, 896.2964073202505, 911.3757076402685, 19636.018199089987, 20565.69151462729, 20716.482087947923, 20725.89637051805, 20893.582128357346, 857.9013413457408, 869.4305230074185, 869.4870026684287, 895.7357579514058, 947.6114792935001, 10175.491225438847, 10179.491025448846, 10795.730106747236, 10850.186236032172, 10852.968967948033, 533022.0, 537138.0, 543121.0, 550559.0, 556105.0, 118193.0, 119503.80496194927, 123555.38222308831, 124124.0, 127957.3602132002, 909.3543584055395, 921.9259562609619, 955.5986485675894, 956.5265193729149, 956.583886009588, 17854.821451785316, 17921.910390447963, 18221.908890455463, 18373.989430581445, 18539.536511587317, 50943.0, 52172.217416739535, 52676.0, 54030.729846352086, 54042.729786350814, 59152.40847591641, 59587.70206148941, 71967.0, 73866.26133738593, 75418.0, 5825.475707186373, 6182.814515564541, 7352.823548232257, 8094.300196958705, 8202.38482113832, 10544.630937917136, 10747.570097196338, 11149.885011498785, 12209.267443953391, 12257.325847078497, 10316.896831031594, 10578.471076446302, 10661.161981686431, 10754.247202695737, 10849.620263291063, 8882.555872206234, 10097.394156350643, 10172.643957461454, 10333.328333658288, 10703.571857125939, 9490.525473726148, 10530.42082685429, 10660.253782235479, 11004.229703920648, 11122.221444499131, 6082.665453399928, 7812.257835505618, 7948.046234451903, 8151.184881511801, 8181.754547363588, 10067.295289329677, 10400.635977741045, 10470.267081304235, 10793.784124317626, 10874.401907894826], "value": 9038.443918034049, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.580708366239, 1451.7024010077935, 1644.3833562414095, 1796.85625149988, 1828.9268429262822, 7526.924730752691, 7798.688052477908, 8034.517928924269, 8488.23605875471, 8517.190866867646, 7989.440739148262, 8309.044459887114, 8315.75052748417, 8404.0, 8420.578971051438, 2287.3023727762984, 2334.299710086967, 2334.9533009339802, 2337.859728416292, 2348.154664320843, 2327.208749025331, 2341.063574570177, 2364.2788949370433, 2370.727366352866, 2377.8454400463916, 2290.3701482092392, 2310.6187479066016, 2315.872627005514, 2327.7555856635063, 2329.336139200328, 2301.9309420717404, 2303.608386574286, 2322.454223257533, 2334.8248881333884, 2337.9649305260436, 2323.628219484874, 2325.813934885205, 2350.8001819845335, 2360.834741568091, 2368.62102063669, 8365.0, 8427.115152908918, 8473.661053557851, 8481.787955301104, 8500.192481714228, 4990.65065445419, 5126.205438157054, 5146.562542183919, 5154.664946778447, 5172.5344718975475, 3309.4539400998806, 3371.3762953853616, 3555.9111022224392, 3563.429851223792, 3566.5006899034156, 1029.840374741916, 1032.8724124194223, 1043.7234132954773, 1062.6440142552253, 1063.787242551493, 10778.4610769462, 10819.404932728698, 10908.50911708967, 10917.39954302513, 10936.179786515964, 3621.855125794991, 4601.7699115044115, 4679.017406344706, 4687.671862969628, 4771.880702982453, 9401.0, 9491.90508094924, 9494.098060684224, 9497.5726092326, 9508.477033763209, 5091.618128640367, 5200.63595548308, 5209.088409528317, 5211.009908117446, 5211.2443695663915, 4234.661227101825, 4393.626541743987, 4551.8634440966825, 4701.623870090384, 4711.976440117777, 3783.6027217142355, 3810.2379524094736, 3877.4765406670303, 3898.0644645285493, 3915.275674000319, 1089.4171618184234, 1280.3278278903465, 1288.0211039609965, 1298.3313593499356, 1307.627326212044, 708.8972099045712, 818.9467684600481, 899.3120262998812, 901.0898992018109, 928.5078908178676, 4725.9527404726205, 4862.586680132157, 5490.313710786152, 5726.885462290731, 5879.853003674943, 712.1063065852322, 801.0427539090847, 831.2850948184556, 883.6907082521067, 885.7608445719619, 3361.907380101428, 3433.7939723616664, 3568.8572457101945, 3576.8390422431053, 3887.9805600971813, 9843.803123937481, 10144.949275253724, 10148.949255253676, 10150.695479135638, 10165.695029149138, 9497.620095196253, 9997.950010250048, 10017.649382271586, 10116.443595602312, 10155.593776249016, 5523.861903452445, 6496.447801936793, 7704.576248306397, 7731.961340193263, 7759.806004849811, 5616.6068375213345, 5630.7466164023535, 5719.542436604978, 5745.511631511368, 5938.376470470711, 9232.95383523105, 9352.298577606844, 9388.155066044088, 9395.154436100784, 9421.764455888657, 9203.539823008956, 9494.715158545254, 9586.0, 9603.519824008912, 9613.855792163264, 6670.899936500859, 6860.211075726355, 7054.400375967895, 7165.2834716530015, 7295.088113985752, 7848.60756962161, 8069.112397636138, 8081.515109093473, 8096.352291816758, 8106.432549721463, 6940.722371105099, 7080.504364694628, 7196.0, 7272.163701174432, 7318.34134927859, 6044.939550604614, 6811.489138314547, 6975.563629124611, 7054.753083642048, 7099.432045436456, 6650.567713098633, 6675.299093595103, 6735.292794256533, 6742.831429214113, 6744.831379215559, 4842.806287748451, 4873.658843881036, 4876.609871210366, 4907.656464047625, 4959.404871415453, 4809.807607695653, 4844.515548445268, 4856.611471082234, 4907.828226012072, 4918.483559226373], "value": 4384.069823873675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.579193262807, 333.423177902229, 354.97160227181826, 366.7157952586746, 371.22414154417265, 5308.70802105884, 5390.703511306886, 5501.862453438661, 5540.088669409048, 5596.63621864579, 4315.546867578906, 4581.541845815417, 5236.2931004314405, 5278.287431196787, 5436.456354364562, 4257.701960862741, 4261.595148460907, 4272.978635106821, 4278.700490965633, 4283.37891005804, 3551.3607550640872, 4118.4028315894175, 4227.471566054243, 4285.614294713475, 4303.33298338758, 4206.789660516977, 4212.873613791591, 4220.472440944882, 4223.915521689564, 4227.365895115727, 3800.63893930079, 3959.841606335743, 4193.35003074524, 4228.450301460791, 4230.217409779185, 3430.9313813723834, 3451.8101504417245, 3699.8705045323286, 3732.7760334380023, 3765.8870233893026, 4427.2695005324085, 4523.705959112647, 4882.0, 5007.549320561167, 5304.814331498379, 4063.674906007512, 4259.424977628011, 4619.653525985531, 4632.054537094428, 4643.349931009661, 5030.396352437694, 5302.787888484456, 5702.629329093637, 5718.685472299022, 5727.513161381287, 2995.7603391728608, 3079.461094308487, 3147.064555298475, 3753.60587138352, 3925.1757131002523, 4953.306537084775, 5510.724463776795, 5558.471945165203, 5850.917580247626, 6113.174721412597, 3938.8030598469963, 3950.7036972227197, 3951.5060617422823, 3955.782431966212, 3979.46277252573, 3794.886153415402, 3954.703397245218, 3956.5449973252867, 3977.244323578511, 3981.8986443962763, 3869.620183587074, 4577.06170235101, 4577.908441831211, 4582.006827030253, 4653.0, 4642.489326174118, 5376.946230537723, 5703.9429605704245, 5758.424157584208, 5775.797847075416, 1137.8919002694731, 1180.6363649984282, 1268.3594784633701, 1280.3726174174587, 1281.084024922185, 817.6198067898456, 843.8017065989487, 858.789596548837, 866.6316815353575, 866.9176428239307, 4317.784110794448, 4400.317950717644, 5032.232272250248, 5091.5672167865405, 5178.922316165261, 4985.077760614298, 5069.847904562869, 5304.647314934667, 5345.705986170798, 5661.179129026351, 4504.373978946914, 4599.563041511051, 4615.653825963066, 5133.61287001546, 5206.0, 2610.856402897859, 2768.4739899419046, 2803.6074949507083, 2878.7258066127943, 2948.837813920255, 5291.947080529146, 5856.765729370863, 5857.355690874, 5871.266091738533, 5881.529757501859, 4979.751012449436, 5175.510366985287, 5242.554382877422, 5299.376808479828, 5477.260569823103, 5613.663180209201, 5768.23124900042, 5805.7387417566315, 5903.498202652822, 5906.3126277543615, 4788.0, 5071.721055341992, 5308.203769434615, 5356.57147428197, 5761.8559536011935, 4257.95742042588, 5027.64806463544, 5905.0808487242675, 5931.495822855106, 5963.7912673056235, 5274.498922602346, 5863.973804584266, 6021.67523144925, 6112.235970503687, 6227.844303892439, 3344.9665503344654, 3836.257076190537, 3945.743526670757, 3950.486436763202, 3954.9406758898062, 5068.6451948363265, 5675.290588676416, 5933.910991335047, 5945.464908158287, 5992.670403127696, 4727.905441891212, 4854.00985595904, 5601.467860553241, 5722.341930677859, 5737.168110623853, 5727.914081288867, 5780.5953583248765, 5828.038373668297, 5834.883302334015, 5873.794417195378, 5349.277847490539, 5609.4390560943575, 5714.514266287412, 5739.426057394394, 5762.250907382014, 5489.1217405214975, 5777.2200752897825, 5787.94212057874, 5804.506616937439, 5827.8834423310445, 4471.7987690553355, 4708.199606066908, 5691.288588926384, 5717.313922329347, 5737.709015471485], "value": 4088.520876073384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.5285850607273, 681.5944513014755, 713.0979311171366, 782.8199514111752, 787.2245837849717, 14714.77927831082, 15616.84383156168, 15663.92168039161, 15683.0, 15684.372625094997, 13331.733365332688, 14211.076280041816, 14291.71416571668, 14343.64140896478, 14414.639634009129, 824.9463784853996, 857.5197889182047, 867.5878957495171, 867.6702852915914, 870.751835726818, 852.8166444214493, 856.7858035491128, 857.952812595307, 862.8576284912982, 865.2558799432483, 900.9144131307548, 911.1844898815556, 916.2120576304372, 916.2990312410977, 918.5820451694462, 888.8844450221494, 897.6050537763396, 902.0, 907.2107266677966, 910.41733290694, 672.8889733194017, 681.0295329155933, 683.5386114372828, 683.9692213850388, 686.0224180542707, 14369.137851728825, 14590.343434545472, 15165.772513412308, 15299.541013769605, 15513.146776927264, 14153.363098660586, 14189.929050354785, 14290.676670835046, 14351.856481435261, 14588.124712517178, 11803.468843902045, 11827.881721182765, 11859.0, 11884.524619015228, 12441.3779311035, 3701.3892707703467, 3703.8888833335045, 3718.2749363874054, 3755.455458958436, 3878.8448462061483, 107052.0, 108175.0, 113333.43333283364, 113937.99999999917, 116426.41786791012, 9344.953275233716, 10626.362418254776, 10841.674749757361, 11126.0, 11248.931351521593, 22257.220997265016, 22543.43641408945, 22612.660810087862, 22874.970626321854, 22956.0, 6450.612963222128, 7669.501482403737, 7775.57234352116, 7804.3756499479905, 7987.800304992305, 4219.282721937278, 4231.153769246163, 4281.807318670667, 4320.243957307459, 4408.9118217636105, 3116.1586371679973, 3595.658412450813, 3610.422332426799, 3617.113807117273, 3621.257642183346, 582.7435928191667, 604.9504110368507, 644.3105876711874, 648.4034688086971, 667.0128210248859, 497.72625056218936, 567.2512283785446, 575.3383608849773, 613.650219374952, 616.1576885113568, 8827.911720882708, 9103.863442048374, 9108.681196158102, 9218.170364667078, 9288.164065234161, 493.53114541186017, 562.8930503204379, 572.7508533787816, 621.5586933277333, 636.7771280051945, 4212.53662097169, 4348.586884245991, 4384.276594361894, 4392.868213953587, 4495.25828238337, 82749.0, 91678.0, 99260.50369748105, 99473.50263248637, 99968.0, 74769.0, 81084.0, 82178.58910705408, 89122.55438722765, 92322.23033309012, 78647.60676196583, 79817.0, 80981.0, 81753.0, 84038.15961840426, 8551.615177316911, 8661.437006594551, 8661.480311181349, 8741.344399170215, 8883.955580222317, 49471.0, 51523.742381287855, 51626.0, 52267.0, 54083.0, 52235.0, 54201.45798541964, 54384.91230175295, 64021.03968440384, 69144.96282555665, 11041.44792760375, 11073.501692423699, 11296.378699171624, 11452.0, 11508.712282193012, 11981.460834262305, 12041.879581204075, 12252.326122063372, 12312.938435307766, 12751.553695620609, 8991.235744961752, 11505.022073123544, 11692.766144676889, 11727.472263748323, 11871.52513899469, 9585.808283834423, 11788.49098395988, 11859.942945619192, 11931.76136477283, 12327.753444930871, 11520.711982200177, 11736.354500502213, 11811.291322520676, 12130.818037729618, 12165.574204902787, 9616.95191524038, 9670.38899497146, 9813.754656133367, 9833.655822046194, 10218.489075546342, 8107.513549187068, 9850.162736167505, 9989.001099889952, 10085.142762864945, 10246.6413675521], "value": 7140.3538524944315, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1615.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 120.0, 118.0, 119.0, 118.0, 117.0, 155.0, 174.0, 145.0], "value": 131.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 117.0, 150.0, 113.0, 116.0, 115.0, 115.0, 133.0, 114.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 229.0, 197.0, 218.0, 177.0, 280.0, 276.0, 194.0, 209.0, 197.0], "value": 218.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 51.0, 81.0, 84.0, 82.0, 83.0, 59.0, 72.0, 59.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 84.0, 73.0, 70.0, 68.0, 91.0, 57.0, 73.0, 112.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 141.0, 172.0, 113.0, 113.0, 111.0, 113.0, 109.0, 115.0], "value": 120.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 106.0, 96.0, 96.0, 119.0, 97.0, 95.0, 96.0, 94.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 103.0, 92.0, 146.0, 91.0, 95.0, 95.0, 146.0, 152.0, 93.0], "value": 110.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 61.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 78.0, 74.0, 94.0, 104.0, 106.0, 108.0, 108.0, 72.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 107.0, 110.0, 117.0, 113.0, 90.0, 91.0, 92.0, 91.0], "value": 99.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 158.0, 157.0, 146.0, 141.0, 141.0, 141.0, 143.0, 187.0, 151.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 215.0, 242.0, 194.0, 174.0, 172.0, 174.0, 176.0, 171.0, 196.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 69.0, 66.0, 69.0, 68.0, 67.0, 70.0, 82.0], "value": 69.6, "unit": "score"}]}]} 22:04:55 INFO - Return code: 0 22:04:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:04:55 ERROR - # TBPL SUCCESS # 22:04:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:04:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:04:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:04:55 INFO - 2016-12-18 22:04:55.842 screenresolution[1917:12006] starting screenresolution argv=screenresolution get 22:04:55 INFO - 2016-12-18 22:04:55.845 screenresolution[1917:12006] Display 0: 1600x1200x32@60 22:04:55 INFO - 2016-12-18 22:04:55.851 screenresolution[1918:12009] starting screenresolution argv=screenresolution list 22:04:55 INFO - Available Modes on Display 0 22:04:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:04:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:04:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:04:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:04:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:04:56 INFO - Intel Iris: 22:04:56 INFO - Chipset Model: Intel Iris 22:04:56 INFO - Type: GPU 22:04:56 INFO - Bus: Built-In 22:04:56 INFO - VRAM (Dynamic, Max): 1536 MB 22:04:56 INFO - Vendor: Intel (0x8086) 22:04:56 INFO - Device ID: 0x0a2e 22:04:56 INFO - Revision ID: 0x0009 22:04:56 INFO - Displays: 22:04:56 INFO - Display: 22:04:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:04:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:04:56 INFO - Display Serial Number: 1600x1200 60 22:04:56 INFO - Main Display: Yes 22:04:56 INFO - Mirror: Off 22:04:56 INFO - Online: Yes 22:04:56 INFO - Rotation: Supported 22:04:56 INFO - Return code: 0 22:04:56 INFO - Running post-action listener: _resource_record_post_action 22:04:56 INFO - [mozharness: 2016-12-19 06:04:56.094796Z] Finished run-tests step (success) 22:04:56 INFO - Running post-run listener: _resource_record_post_run 22:04:56 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 3130880; Write bytes: 553043968; Read time: 25; Write time: 1692 22:04:56 INFO - TinderboxPrint: CPU usage
54.6% 22:04:56 INFO - TinderboxPrint: I/O read bytes / time
3,130,880 / 25 22:04:56 INFO - TinderboxPrint: I/O write bytes / time
553,043,968 / 1,692 22:04:56 INFO - TinderboxPrint: CPU idle
2,184.0 (45.4%) 22:04:56 INFO - TinderboxPrint: CPU system
971.8 (20.2%) 22:04:56 INFO - TinderboxPrint: CPU user
1,649.8 (34.3%) 22:04:56 INFO - TinderboxPrint: Swap in / out
253,362,176 / 0 22:04:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249496064; Write bytes: 243419136; Read time: 18204; Write time: 504 22:04:56 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 2225664; Write bytes: 300142592; Read time: 22; Write time: 1154 22:04:56 INFO - Running post-run listener: _upload_blobber_files 22:04:56 INFO - Blob upload gear active. 22:04:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:04:56 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 22:04:56 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'] 22:04:56 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 22:04:56 INFO - (blobuploader) - INFO - Open directory for files ... 22:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:04:58 INFO - (blobuploader) - INFO - Done attempting. 22:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:04:59 INFO - (blobuploader) - INFO - Done attempting. 22:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:05:00 INFO - (blobuploader) - INFO - Done attempting. 22:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:05:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:05:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:05:03 INFO - (blobuploader) - INFO - Done attempting. 22:05:03 INFO - (blobuploader) - INFO - Iteration through files over. 22:05:03 INFO - Return code: 0 22:05:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:05:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:05:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a288ff6ac6645d2d6737af1495a0b4a561a8f9f71484c5d2bceadafaa5a81463088cb5e20643ddea71613b65c847ee43fc781cc16de3921fb67c36b92942ed9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86384590fa956ece848b3b16bf12ffb4571092f9e7af6e72ac755d7787a79eee84408b48d7938dd6958d05b18fce683dec59a1d1e758e7fd390248df271d727a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611d45a73aa4bbfeeb5121ca2019cb45c8f73ab7af4fb7c86d8b08ae2946629dcf5358e732e02d474e7f95986c1aa4e5b91c66737bce99bec3a277f402cb3f78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcd47d08bc3e587e871cd188a12a4028ba063993a0636ac958c868114b8fdd50bc318750d3e0ff5bb2d90e9011d64467a710d4e4db8163615b86958f37e29ba"} 22:05:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:05:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:05:03 INFO - Contents: 22:05:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a288ff6ac6645d2d6737af1495a0b4a561a8f9f71484c5d2bceadafaa5a81463088cb5e20643ddea71613b65c847ee43fc781cc16de3921fb67c36b92942ed9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86384590fa956ece848b3b16bf12ffb4571092f9e7af6e72ac755d7787a79eee84408b48d7938dd6958d05b18fce683dec59a1d1e758e7fd390248df271d727a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611d45a73aa4bbfeeb5121ca2019cb45c8f73ab7af4fb7c86d8b08ae2946629dcf5358e732e02d474e7f95986c1aa4e5b91c66737bce99bec3a277f402cb3f78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcd47d08bc3e587e871cd188a12a4028ba063993a0636ac958c868114b8fdd50bc318750d3e0ff5bb2d90e9011d64467a710d4e4db8163615b86958f37e29ba"} 22:05:03 INFO - Running post-run listener: copy_logs_to_upload_dir 22:05:03 INFO - Copying logs to upload dir... 22:05:03 INFO - mkdir: /builds/slave/test/build/upload/logs 22:05:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.519277 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-18 22:05:03.115288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 22:05:03.119648) ========= 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.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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/a288ff6ac6645d2d6737af1495a0b4a561a8f9f71484c5d2bceadafaa5a81463088cb5e20643ddea71613b65c847ee43fc781cc16de3921fb67c36b92942ed9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86384590fa956ece848b3b16bf12ffb4571092f9e7af6e72ac755d7787a79eee84408b48d7938dd6958d05b18fce683dec59a1d1e758e7fd390248df271d727a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611d45a73aa4bbfeeb5121ca2019cb45c8f73ab7af4fb7c86d8b08ae2946629dcf5358e732e02d474e7f95986c1aa4e5b91c66737bce99bec3a277f402cb3f78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcd47d08bc3e587e871cd188a12a4028ba063993a0636ac958c868114b8fdd50bc318750d3e0ff5bb2d90e9011d64467a710d4e4db8163615b86958f37e29ba"} build_url:https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/L9IEY3p1Sd2_7YRVlhgUoA/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/a288ff6ac6645d2d6737af1495a0b4a561a8f9f71484c5d2bceadafaa5a81463088cb5e20643ddea71613b65c847ee43fc781cc16de3921fb67c36b92942ed9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86384590fa956ece848b3b16bf12ffb4571092f9e7af6e72ac755d7787a79eee84408b48d7938dd6958d05b18fce683dec59a1d1e758e7fd390248df271d727a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611d45a73aa4bbfeeb5121ca2019cb45c8f73ab7af4fb7c86d8b08ae2946629dcf5358e732e02d474e7f95986c1aa4e5b91c66737bce99bec3a277f402cb3f78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcd47d08bc3e587e871cd188a12a4028ba063993a0636ac958c868114b8fdd50bc318750d3e0ff5bb2d90e9011d64467a710d4e4db8163615b86958f37e29ba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 22:05:03.155109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 22:05:03.155424) ========= 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.P434JF1BTQ/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.nvukjDpfRR/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.005347 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 22:05:03.203734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 22:05:03.204048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 22:05:03.204356) ========= ========= Total master_lag: 0.19 =========