builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0017 starttime: 1489543213.49 results: success (0) buildid: 20170314171936 builduid: 16b832b493dc48b2846c80a4b6d8bd81 revision: 8dd496fd015a2b6e99573070279d9d1593836ea9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.493278) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.493736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.494026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.525298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.525650) ========= 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.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-14 19:00:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.3M=0s 2017-03-14 19:00:13 (23.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155828 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.706063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.706402) ========= 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.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.028268 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:13.757897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 19:00:13.758485) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8dd496fd015a2b6e99573070279d9d1593836ea9 --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 8dd496fd015a2b6e99573070279d9d1593836ea9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-14 19:00:13,849 truncating revision to first 12 chars 2017-03-14 19:00:13,849 Setting DEBUG logging. 2017-03-14 19:00:13,849 attempt 1/10 2017-03-14 19:00:13,849 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8dd496fd015a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 19:00:15,111 unpacking tar archive at: mozilla-central-8dd496fd015a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.557327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 19:00:15.340754) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:15.341106) ========= script_repo_revision: 8dd496fd015a2b6e99573070279d9d1593836ea9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:15.341535) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:15.341832) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 19:00:15.361733) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-14 19:00:15.362252) ========= /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.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:00:15 INFO - MultiFileLogger online at 20170314 19:00:15 in /builds/slave/test 19:00:15 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 19:00:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:00:15 INFO - {'append_to_log': False, 19:00:15 INFO - 'base_work_dir': '/builds/slave/test', 19:00:15 INFO - 'blob_upload_branch': 'Firefox', 19:00:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:00:15 INFO - 'branch': 'Firefox', 19:00:15 INFO - 'buildbot_json_path': 'buildprops.json', 19:00:15 INFO - 'config_files': ('talos/mac_config.py',), 19:00:15 INFO - 'default_actions': ('clobber', 19:00:15 INFO - 'read-buildbot-config', 19:00:15 INFO - 'download-and-extract', 19:00:15 INFO - 'populate-webroot', 19:00:15 INFO - 'create-virtualenv', 19:00:15 INFO - 'install', 19:00:15 INFO - 'run-tests'), 19:00:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:00:15 INFO - 'download_minidump_stackwalk': True, 19:00:15 INFO - 'download_symbols': 'ondemand', 19:00:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:00:15 INFO - 'tooltool.py': '/tools/tooltool.py', 19:00:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:00:15 INFO - '/tools/misc-python/virtualenv.py')}, 19:00:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:00:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:00:15 INFO - 'gecko_profile': False, 19:00:15 INFO - 'gecko_profile_interval': 0, 19:00:15 INFO - 'installer_path': 'installer.exe', 19:00:15 INFO - 'log_level': 'info', 19:00:15 INFO - 'log_name': 'talos', 19:00:15 INFO - 'log_to_console': True, 19:00:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:00:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:00:15 INFO - 'opt_config_files': (), 19:00:15 INFO - 'pip_index': False, 19:00:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:15 INFO - 'cmd': ('bash', 19:00:15 INFO - '-c', 19:00:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:15 INFO - 'enabled': True, 19:00:15 INFO - 'halt_on_failure': False, 19:00:15 INFO - 'name': 'check_screen_resolution'},), 19:00:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:15 INFO - 'cmd': ('bash', 19:00:15 INFO - '-c', 19:00:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:15 INFO - 'enabled': True, 19:00:15 INFO - 'halt_on_failure': False, 19:00:15 INFO - 'name': 'check_screen_resolution'},), 19:00:15 INFO - 'run_cmd_checks_enabled': True, 19:00:15 INFO - 'suite': 'dromaeojs-e10s', 19:00:15 INFO - 'system_bits': '32', 19:00:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:00:15 INFO - 'title': 't-yosemite-r7-0017', 19:00:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:00:15 INFO - 'use_talos_json': True, 19:00:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:00:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:00:15 INFO - 'work_dir': 'build'} 19:00:15 INFO - [mozharness: 2017-03-15 02:00:15.569461Z] Running clobber step. 19:00:15 INFO - Running pre-action listener: _resource_record_pre_action 19:00:15 INFO - Running main action method: clobber 19:00:15 INFO - rmtree: /builds/slave/test/build 19:00:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:00:18 INFO - Running post-action listener: _resource_record_post_action 19:00:18 INFO - [mozharness: 2017-03-15 02:00:18.086722Z] Finished clobber step (success) 19:00:18 INFO - [mozharness: 2017-03-15 02:00:18.086927Z] Running read-buildbot-config step. 19:00:18 INFO - Running pre-action listener: _resource_record_pre_action 19:00:18 INFO - Running main action method: read_buildbot_config 19:00:18 INFO - Using buildbot properties: 19:00:18 INFO - { 19:00:18 INFO - "project": "", 19:00:18 INFO - "product": "firefox", 19:00:18 INFO - "script_repo_revision": "production", 19:00:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:00:18 INFO - "repository": "", 19:00:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:00:18 INFO - "buildid": "20170314171936", 19:00:18 INFO - "pgo_build": "False", 19:00:18 INFO - "basedir": "/builds/slave/test", 19:00:18 INFO - "buildnumber": 120, 19:00:18 INFO - "slavename": "t-yosemite-r7-0017", 19:00:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:00:18 INFO - "platform": "yosemite_r7", 19:00:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:00:18 INFO - "branch": "mozilla-central", 19:00:18 INFO - "repo_path": "mozilla-central", 19:00:18 INFO - "revision": "8dd496fd015a2b6e99573070279d9d1593836ea9", 19:00:18 INFO - "stage_platform": "macosx64", 19:00:18 INFO - "builduid": "16b832b493dc48b2846c80a4b6d8bd81", 19:00:18 INFO - "slavebuilddir": "test" 19:00:18 INFO - } 19:00:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:00:18 INFO - Running post-action listener: _resource_record_post_action 19:00:18 INFO - [mozharness: 2017-03-15 02:00:18.090257Z] Finished read-buildbot-config step (success) 19:00:18 INFO - [mozharness: 2017-03-15 02:00:18.090357Z] Running download-and-extract step. 19:00:18 INFO - Running pre-action listener: _resource_record_pre_action 19:00:18 INFO - Running main action method: download_and_extract 19:00:18 INFO - mkdir: /builds/slave/test/build/tests 19:00:18 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')]} 19:00:18 INFO - trying https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:00:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:00:19 INFO - Downloaded 1199 bytes. 19:00:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:00:19 INFO - Using the following test package requirements: 19:00:19 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:00:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:00:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:00:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'jsshell-mac.zip'], 19:00:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:00:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:00:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:00:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:00:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:00:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:00:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:00:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:00:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:00:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:00:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:00:21 INFO - Expected file size: 52470569 19:00:21 INFO - Obtained file size: 52470569 19:00:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:00:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:00:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:00:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:00:40 INFO - Expected file size: 52470569 19:00:40 INFO - Obtained file size: 52470569 19:00:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:00:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:00:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:00:56 INFO - Expected file size: 14404452 19:00:56 INFO - Obtained file size: 14404452 19:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:56 INFO - trying https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:00:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:00:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:00:59 INFO - Downloaded 75086159 bytes. 19:00:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:00:59 INFO - mkdir: /builds/slave/test/properties 19:00:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:59 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:59 INFO - Contents: 19:00:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:00:59 INFO - Running post-action listener: _resource_record_post_action 19:00:59 INFO - Running post-action listener: set_extra_try_arguments 19:00:59 INFO - [mozharness: 2017-03-15 02:00:59.748957Z] Finished download-and-extract step (success) 19:00:59 INFO - [mozharness: 2017-03-15 02:00:59.749126Z] Running populate-webroot step. 19:00:59 INFO - Running pre-action listener: _resource_record_pre_action 19:00:59 INFO - Running main action method: populate_webroot 19:00:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:59 INFO - '--tppagecycles', 19:00:59 INFO - '7'], 19:00:59 INFO - 'tests': ['tsvgm']}}, 19:00:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['damp', 'tps']}, 19:00:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'tests': ['damp', 'tps']}, 19:00:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['dromaeo_dom']}, 19:00:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['basic_compositor_video']}, 19:00:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:00:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tsvgx', 19:00:59 INFO - 'tsvgr_opacity', 19:00:59 INFO - 'tart', 19:00:59 INFO - 'tscrollx', 19:00:59 INFO - 'cart', 19:00:59 INFO - 'tsvg_static']}, 19:00:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:59 INFO - 'tsvgr_opacity', 19:00:59 INFO - 'tart', 19:00:59 INFO - 'tscrollx', 19:00:59 INFO - 'cart', 19:00:59 INFO - 'tsvg_static']}, 19:00:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tp5o']}, 19:00:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:00:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s', 19:00:59 INFO - '--xperf_path', 19:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:59 INFO - 'tests': ['tp5n']}, 19:00:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:00:59 INFO - 'talos_options': ['--xperf_path', 19:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:59 INFO - 'tests': ['tp5n']}}, 19:00:59 INFO - 'talos.zip': {'path': '', 19:00:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:59 INFO - Running post-action listener: _resource_record_post_action 19:00:59 INFO - [mozharness: 2017-03-15 02:00:59.767374Z] Finished populate-webroot step (success) 19:00:59 INFO - [mozharness: 2017-03-15 02:00:59.767500Z] Running create-virtualenv step. 19:00:59 INFO - Running pre-action listener: _resource_record_pre_action 19:00:59 INFO - Running main action method: create_virtualenv 19:00:59 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:59 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 19:00:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:59 INFO - Using real prefix '/tools/python27' 19:00:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 19:01:03 INFO - Installing pip.................done. 19:01:03 INFO - Return code: 0 19:01:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:01:03 INFO - Reading from file tmpfile_stdout 19:01:03 INFO - Output received: 19:01:03 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) 19:01:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:01:03 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')]} 19:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:03 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')]} 19:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:03 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:03 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 19:01:03 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 19:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:03 INFO - 'HOME': '/Users/cltbld', 19:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:03 INFO - 'LOGNAME': 'cltbld', 19:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:03 INFO - 'MOZ_NO_REMOTE': '1', 19:01:03 INFO - 'NO_EM_RESTART': '1', 19:01:03 INFO - 'PAGER': '/bin/cat', 19:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:03 INFO - 'PWD': '/builds/slave/test', 19:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:03 INFO - 'SHELL': '/bin/bash', 19:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:03 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:03 INFO - 'USER': 'cltbld', 19:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:03 INFO - 'XPC_FLAGS': '0x0', 19:01:03 INFO - 'XPC_SERVICE_NAME': '0', 19:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:04 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 19:01:04 INFO - Cleaning up... 19:01:04 INFO - Return code: 0 19:01:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:04 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:04 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 19:01:04 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 19:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:04 INFO - 'HOME': '/Users/cltbld', 19:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:04 INFO - 'LOGNAME': 'cltbld', 19:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:04 INFO - 'MOZ_NO_REMOTE': '1', 19:01:04 INFO - 'NO_EM_RESTART': '1', 19:01:04 INFO - 'PAGER': '/bin/cat', 19:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:04 INFO - 'PWD': '/builds/slave/test', 19:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:04 INFO - 'SHELL': '/bin/bash', 19:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:04 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:04 INFO - 'USER': 'cltbld', 19:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:04 INFO - 'XPC_FLAGS': '0x0', 19:01:04 INFO - 'XPC_SERVICE_NAME': '0', 19:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:04 INFO - Downloading/unpacking psutil>=3.1.1 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:07 INFO - Installing collected packages: psutil 19:01:07 INFO - Running setup.py install for psutil 19:01:07 INFO - building 'psutil._psutil_osx' extension 19:01:07 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 19:01:08 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 19:01:08 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 19:01:08 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 19:01:08 INFO - building 'psutil._psutil_posix' extension 19:01:08 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 19:01:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:01:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:01:08 INFO - ^ 19:01:08 INFO - 1 warning generated. 19:01:08 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 19:01:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:08 INFO - Successfully installed psutil 19:01:08 INFO - Cleaning up... 19:01:09 INFO - Return code: 0 19:01:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:09 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:09 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 19:01:09 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 19:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:09 INFO - 'HOME': '/Users/cltbld', 19:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:09 INFO - 'LOGNAME': 'cltbld', 19:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:09 INFO - 'MOZ_NO_REMOTE': '1', 19:01:09 INFO - 'NO_EM_RESTART': '1', 19:01:09 INFO - 'PAGER': '/bin/cat', 19:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:09 INFO - 'PWD': '/builds/slave/test', 19:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:09 INFO - 'SHELL': '/bin/bash', 19:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:09 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:09 INFO - 'USER': 'cltbld', 19:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:09 INFO - 'XPC_FLAGS': '0x0', 19:01:09 INFO - 'XPC_SERVICE_NAME': '0', 19:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01:09 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 19:01:09 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 19:01:09 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 19:01:09 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 19:01:09 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 19:01:09 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 19:01:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:01:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:01:13 INFO - Installing collected packages: mozsystemmonitor 19:01:13 INFO - Running setup.py install for mozsystemmonitor 19:01:13 INFO - Successfully installed mozsystemmonitor 19:01:13 INFO - Cleaning up... 19:01:13 INFO - Return code: 0 19:01:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:13 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:13 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 19:01:13 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 19:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:13 INFO - 'HOME': '/Users/cltbld', 19:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:13 INFO - 'LOGNAME': 'cltbld', 19:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:13 INFO - 'MOZ_NO_REMOTE': '1', 19:01:13 INFO - 'NO_EM_RESTART': '1', 19:01:13 INFO - 'PAGER': '/bin/cat', 19:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:13 INFO - 'PWD': '/builds/slave/test', 19:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:13 INFO - 'SHELL': '/bin/bash', 19:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:13 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:13 INFO - 'USER': 'cltbld', 19:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:13 INFO - 'XPC_FLAGS': '0x0', 19:01:13 INFO - 'XPC_SERVICE_NAME': '0', 19:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:13 INFO - Downloading/unpacking jsonschema==2.5.1 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:01:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:01:16 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 19:01:16 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 19:01:16 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 19:01:16 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 19:01:16 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 19:01:16 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 19:01:16 INFO - Downloading functools32-3.2.3-2.tar.gz 19:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:01:16 INFO - warning: no files found matching '*.txt' 19:01:16 INFO - no previously-included directories found matching 'build' 19:01:16 INFO - no previously-included directories found matching 'dist' 19:01:16 INFO - no previously-included directories found matching '.git*' 19:01:16 INFO - Installing collected packages: jsonschema, functools32 19:01:16 INFO - Running setup.py install for functools32 19:01:16 INFO - warning: no files found matching '*.txt' 19:01:16 INFO - no previously-included directories found matching 'build' 19:01:16 INFO - no previously-included directories found matching 'dist' 19:01:16 INFO - no previously-included directories found matching '.git*' 19:01:16 INFO - Successfully installed jsonschema functools32 19:01:16 INFO - Cleaning up... 19:01:16 INFO - Return code: 0 19:01:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:16 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:16 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 19:01:16 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 19:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:16 INFO - 'HOME': '/Users/cltbld', 19:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:16 INFO - 'LOGNAME': 'cltbld', 19:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:16 INFO - 'MOZ_NO_REMOTE': '1', 19:01:16 INFO - 'NO_EM_RESTART': '1', 19:01:16 INFO - 'PAGER': '/bin/cat', 19:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:16 INFO - 'PWD': '/builds/slave/test', 19:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:16 INFO - 'SHELL': '/bin/bash', 19:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:16 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:16 INFO - 'USER': 'cltbld', 19:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:16 INFO - 'XPC_FLAGS': '0x0', 19:01:16 INFO - 'XPC_SERVICE_NAME': '0', 19:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:01:17 INFO - Cleaning up... 19:01:17 INFO - Return code: 0 19:01:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:01:17 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')]} 19:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:17 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')]} 19:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:17 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:17 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 19:01:17 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 19:01:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:17 INFO - 'HOME': '/Users/cltbld', 19:01:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:17 INFO - 'LOGNAME': 'cltbld', 19:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:17 INFO - 'MOZ_NO_REMOTE': '1', 19:01:17 INFO - 'NO_EM_RESTART': '1', 19:01:17 INFO - 'PAGER': '/bin/cat', 19:01:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:17 INFO - 'PWD': '/builds/slave/test', 19:01:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:17 INFO - 'SHELL': '/bin/bash', 19:01:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:17 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:17 INFO - 'USER': 'cltbld', 19:01:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:17 INFO - 'XPC_FLAGS': '0x0', 19:01:17 INFO - 'XPC_SERVICE_NAME': '0', 19:01:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:17 INFO - Downloading/unpacking blobuploader==1.2.4 19:01:17 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 19:01:17 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 19:01:17 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 19:01:17 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 19:01:17 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 19:01:17 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 19:01:20 INFO - Downloading blobuploader-1.2.4.tar.gz 19:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:01:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:01:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:20 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 19:01:21 INFO - Downloading docopt-0.6.1.tar.gz 19:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:01:21 INFO - Installing collected packages: blobuploader, requests, docopt 19:01:21 INFO - Running setup.py install for blobuploader 19:01:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:01:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:01:21 INFO - Running setup.py install for requests 19:01:22 INFO - Running setup.py install for docopt 19:01:22 INFO - Successfully installed blobuploader requests docopt 19:01:22 INFO - Cleaning up... 19:01:22 INFO - Return code: 0 19:01:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:22 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')]} 19:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:22 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')]} 19:01:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:22 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:22 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 19:01:22 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 19:01:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:22 INFO - 'HOME': '/Users/cltbld', 19:01:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:22 INFO - 'LOGNAME': 'cltbld', 19:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:22 INFO - 'MOZ_NO_REMOTE': '1', 19:01:22 INFO - 'NO_EM_RESTART': '1', 19:01:22 INFO - 'PAGER': '/bin/cat', 19:01:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:22 INFO - 'PWD': '/builds/slave/test', 19:01:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:22 INFO - 'SHELL': '/bin/bash', 19:01:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:22 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:22 INFO - 'USER': 'cltbld', 19:01:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:22 INFO - 'XPC_FLAGS': '0x0', 19:01:22 INFO - 'XPC_SERVICE_NAME': '0', 19:01:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-CTByni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-p6xJ_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-__64BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:22 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Iligq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-KWhJAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-BiYWLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-cb9qUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-nQ0vAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-N_0TqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-7slSB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-epFHjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ry_8IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:23 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-wFbZDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:24 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Uw_Av2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:24 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-719rhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:24 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-IMmLpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:24 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-FSJRrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:01:24 INFO - Running setup.py install for manifestparser 19:01:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:01:24 INFO - Running setup.py install for mozcrash 19:01:24 INFO - Running setup.py install for mozdebug 19:01:24 INFO - Running setup.py install for mozdevice 19:01:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Running setup.py install for mozfile 19:01:25 INFO - Running setup.py install for mozhttpd 19:01:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Running setup.py install for mozinfo 19:01:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Running setup.py install for mozInstall 19:01:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Running setup.py install for mozleak 19:01:25 INFO - Running setup.py install for mozlog 19:01:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:01:25 INFO - Running setup.py install for moznetwork 19:01:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:01:26 INFO - Running setup.py install for mozprocess 19:01:26 INFO - Running setup.py install for mozprofile 19:01:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:01:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:01:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:01:26 INFO - Running setup.py install for mozrunner 19:01:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:01:26 INFO - Running setup.py install for mozscreenshot 19:01:26 INFO - Running setup.py install for moztest 19:01:26 INFO - Running setup.py install for mozversion 19:01:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:01:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:01:27 INFO - Cleaning up... 19:01:27 INFO - Return code: 0 19:01:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:27 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:27 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 19:01:27 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 19:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:27 INFO - 'HOME': '/Users/cltbld', 19:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:27 INFO - 'LOGNAME': 'cltbld', 19:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:27 INFO - 'MOZ_NO_REMOTE': '1', 19:01:27 INFO - 'NO_EM_RESTART': '1', 19:01:27 INFO - 'PAGER': '/bin/cat', 19:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:27 INFO - 'PWD': '/builds/slave/test', 19:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:27 INFO - 'SHELL': '/bin/bash', 19:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:27 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:27 INFO - 'USER': 'cltbld', 19:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:27 INFO - 'XPC_FLAGS': '0x0', 19:01:27 INFO - 'XPC_SERVICE_NAME': '0', 19:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-PAmHak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ZRZUHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-5NVdeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-REy4PT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ZW7kiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-nZ31Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:27 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)) 19:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:27 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-3oTg4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-vGRzV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Ril3fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-riIvmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-erWY1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-3gHO6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-tVD7Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-UCovbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-mQGIdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:28 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)) 19:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:28 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Q8BNaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:29 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-6REXco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:31 INFO - Downloading blessings-1.6.tar.gz 19:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:01:31 INFO - Installing collected packages: blessings 19:01:31 INFO - Running setup.py install for blessings 19:01:32 INFO - Successfully installed blessings 19:01:32 INFO - Cleaning up... 19:01:32 INFO - Return code: 0 19:01:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:32 INFO - Reading from file tmpfile_stdout 19:01:32 INFO - Current package versions: 19:01:32 INFO - blessings == 1.6 19:01:32 INFO - blobuploader == 1.2.4 19:01:32 INFO - docopt == 0.6.1 19:01:32 INFO - functools32 == 3.2.3-2 19:01:32 INFO - jsonschema == 2.5.1 19:01:32 INFO - manifestparser == 1.1 19:01:32 INFO - mozInstall == 1.12 19:01:32 INFO - mozcrash == 1.0 19:01:32 INFO - mozdebug == 0.1 19:01:32 INFO - mozdevice == 0.49 19:01:32 INFO - mozfile == 1.2 19:01:32 INFO - mozhttpd == 0.7 19:01:32 INFO - mozinfo == 0.9 19:01:32 INFO - mozleak == 0.1 19:01:32 INFO - mozlog == 3.4 19:01:32 INFO - moznetwork == 0.27 19:01:32 INFO - mozprocess == 0.25 19:01:32 INFO - mozprofile == 0.28 19:01:32 INFO - mozrunner == 6.13 19:01:32 INFO - mozscreenshot == 0.1 19:01:32 INFO - mozsystemmonitor == 0.3 19:01:32 INFO - moztest == 0.8 19:01:32 INFO - mozversion == 1.4 19:01:32 INFO - psutil == 3.1.1 19:01:32 INFO - requests == 1.2.3 19:01:32 INFO - wsgiref == 0.1.2 19:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:32 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:32 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 19:01:32 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 19:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:32 INFO - 'HOME': '/Users/cltbld', 19:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:32 INFO - 'LOGNAME': 'cltbld', 19:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:32 INFO - 'MOZ_NO_REMOTE': '1', 19:01:32 INFO - 'NO_EM_RESTART': '1', 19:01:32 INFO - 'PAGER': '/bin/cat', 19:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:32 INFO - 'PWD': '/builds/slave/test', 19:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:32 INFO - 'SHELL': '/bin/bash', 19:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:32 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:32 INFO - 'USER': 'cltbld', 19:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:32 INFO - 'XPC_FLAGS': '0x0', 19:01:32 INFO - 'XPC_SERVICE_NAME': '0', 19:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 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)) 19:01:32 INFO - Cleaning up... 19:01:32 INFO - Return code: 0 19:01:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:32 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 0x10f6d5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6d8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5d945ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, '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.nIib8PgHbB/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.3ScvOPpK41/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 19:01:32 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 19:01:32 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 19:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:32 INFO - 'HOME': '/Users/cltbld', 19:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:32 INFO - 'LOGNAME': 'cltbld', 19:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:32 INFO - 'MOZ_NO_REMOTE': '1', 19:01:32 INFO - 'NO_EM_RESTART': '1', 19:01:32 INFO - 'PAGER': '/bin/cat', 19:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:32 INFO - 'PWD': '/builds/slave/test', 19:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:32 INFO - 'SHELL': '/bin/bash', 19:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:32 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:32 INFO - 'USER': 'cltbld', 19:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:32 INFO - 'XPC_FLAGS': '0x0', 19:01:32 INFO - 'XPC_SERVICE_NAME': '0', 19:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:01:32 INFO - Cleaning up... 19:01:32 INFO - Return code: 0 19:01:32 INFO - Running post-action listener: _resource_record_post_action 19:01:32 INFO - Running post-action listener: _start_resource_monitoring 19:01:32 INFO - Starting resource monitoring. 19:01:32 INFO - [mozharness: 2017-03-15 02:01:32.975027Z] Finished create-virtualenv step (success) 19:01:32 INFO - [mozharness: 2017-03-15 02:01:32.976056Z] Running install step. 19:01:32 INFO - Running pre-action listener: _resource_record_pre_action 19:01:32 INFO - Running main action method: install 19:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:33 INFO - Reading from file tmpfile_stdout 19:01:33 INFO - Detecting whether we're running mozinstall >=1.0... 19:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:01:33 INFO - Reading from file tmpfile_stdout 19:01:33 INFO - Output received: 19:01:33 INFO - Usage: mozinstall [options] installer 19:01:33 INFO - Options: 19:01:33 INFO - -h, --help show this help message and exit 19:01:33 INFO - -d DEST, --destination=DEST 19:01:33 INFO - Directory to install application into. [default: 19:01:33 INFO - "/builds/slave/test"] 19:01:33 INFO - --app=APP Application being installed. [default: firefox] 19:01:33 INFO - mkdir: /builds/slave/test/build/application 19:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:01:53 INFO - Reading from file tmpfile_stdout 19:01:53 INFO - Output received: 19:01:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:01:53 INFO - Running post-action listener: _resource_record_post_action 19:01:53 INFO - [mozharness: 2017-03-15 02:01:53.597971Z] Finished install step (success) 19:01:53 INFO - [mozharness: 2017-03-15 02:01:53.598100Z] Running run-tests step. 19:01:53 INFO - Running pre-action listener: _resource_record_pre_action 19:01:53 INFO - Running main action method: run_tests 19:01:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:01:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:01:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:01:53 INFO - 2017-03-14 19:01:53.612 screenresolution[1622:7284] starting screenresolution argv=screenresolution get 19:01:53 INFO - 2017-03-14 19:01:53.615 screenresolution[1622:7284] Display 0: 1600x1200x32@50 19:01:53 INFO - 2017-03-14 19:01:53.621 screenresolution[1623:7287] starting screenresolution argv=screenresolution list 19:01:53 INFO - Available Modes on Display 0 19:01:53 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 19:01:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:01:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:01:53 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 19:01:53 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 19:01:53 INFO - Graphics/Displays: 19:01:53 INFO - Intel Iris: 19:01:53 INFO - Chipset Model: Intel Iris 19:01:53 INFO - Type: GPU 19:01:53 INFO - Bus: Built-In 19:01:53 INFO - VRAM (Dynamic, Max): 1536 MB 19:01:53 INFO - Vendor: Intel (0x8086) 19:01:53 INFO - Device ID: 0x0a2e 19:01:53 INFO - Revision ID: 0x0009 19:01:53 INFO - Displays: 19:01:53 INFO - Display: 19:01:53 INFO - Resolution: 1600 x 1200 @ 50 Hz 19:01:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:01:53 INFO - Display Serial Number: 1600x1200 50 19:01:53 INFO - Main Display: Yes 19:01:53 INFO - Mirror: Off 19:01:53 INFO - Online: Yes 19:01:53 INFO - Rotation: Supported 19:01:53 INFO - Return code: 0 19:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:01:53 INFO - Python 2.7.3 19:01:53 INFO - Return code: 0 19:01:53 INFO - grabbing minidump binary from tooltool 19:01: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')]} 19:01:53 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 0x10f4b3d30>, '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 0x10f3dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:01:53 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 19:01:53 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 19:01:53 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 19:01:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:01:53 INFO - Return code: 0 19:01:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:01:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:01:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:01:53 INFO - ENV: RUST_BACKTRACE is now 1 19:01:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:01:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:01:53 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/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 19:01:53 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/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 19:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nIib8PgHbB/Render', 19:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:53 INFO - 'HOME': '/Users/cltbld', 19:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:53 INFO - 'LOGNAME': 'cltbld', 19:01:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:01:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:53 INFO - 'MOZ_NO_REMOTE': '1', 19:01:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:01:53 INFO - 'NO_EM_RESTART': '1', 19:01:53 INFO - 'PAGER': '/bin/cat', 19:01:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:53 INFO - 'PWD': '/builds/slave/test', 19:01:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:53 INFO - 'RUST_BACKTRACE': '1', 19:01:53 INFO - 'SHELL': '/bin/bash', 19:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ScvOPpK41/Listeners', 19:01:53 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 19:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:53 INFO - 'USER': 'cltbld', 19:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:53 INFO - 'XPC_FLAGS': '0x0', 19:01:53 INFO - 'XPC_SERVICE_NAME': '0', 19:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:53 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/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 19:01:54 INFO - mozversion application_buildid: 20170314171936 19:01:54 INFO - mozversion application_changeset: 8dd496fd015a2b6e99573070279d9d1593836ea9 19:01:54 INFO - mozversion application_display_name: Nightly 19:01:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:01:54 INFO - mozversion application_name: Firefox 19:01:54 INFO - mozversion application_remotingname: firefox 19:01:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:01:54 INFO - mozversion application_vendor: Mozilla 19:01:54 INFO - mozversion application_version: 55.0a1 19:01:54 INFO - mozversion platform_buildid: 20170314171936 19:01:54 INFO - mozversion platform_changeset: 8dd496fd015a2b6e99573070279d9d1593836ea9 19:01:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:01:54 INFO - mozversion platform_version: 55.0a1 19:01:54 INFO - using testdate: 1489543314 19:01:54 INFO - actual date: 1489543314 19:01:54 INFO - starting webserver on 'localhost:49248' 19:01:54 INFO - SUITE-START | Running 2 tests 19:01:54 INFO - TEST-START | dromaeo_css 19:01:54 INFO - operating with platform_type : mac_ 19:01:54 INFO - Initialising browser for dromaeo_css test... 19:01:54 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmprUpaeS/profile http://localhost:49248/getInfo.html) 19:01:57 INFO - PID 1633 | 2017-03-14 19:01:57.365 plugin-container[1636:7475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 19:01:57 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:01:57 INFO - PID 1633 | 2017-03-14 19:01:57.369 plugin-container[1636:7475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 19:01:57 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:01:57 INFO - PID 1633 | 2017-03-14 19:01:57.369 plugin-container[1636:7475] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:01:57 INFO - PID 1633 | __metrics Screen width/height:1600/1200 19:01:57 INFO - PID 1633 | colorDepth:24 19:01:57 INFO - PID 1633 | Browser inner width/height: 1024/689 19:01:57 INFO - PID 1633 | __metrics 19:01:57 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:01:58 INFO - TEST-INFO | 1633: exit 0 19:01:58 INFO - Browser initialized. 19:01:58 INFO - Running cycle 1/1 for dromaeo_css test... 19:01:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmprUpaeS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:00 INFO - PID 1637 | 2017-03-14 19:02:00.775 plugin-container[1638:7606] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 19:02:00 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:00 INFO - PID 1637 | 2017-03-14 19:02:00.776 plugin-container[1638:7606] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 19:02:00 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:00 INFO - PID 1637 | 2017-03-14 19:02:00.776 plugin-container[1638:7606] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:02:10 INFO - PID 1637 | RSS: Main: 227155968 19:02:10 INFO - PID 1637 | 19:05:14 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 19:05:15 INFO - PID 1637 | RSS: Main: 251150336 19:05:15 INFO - PID 1637 | 19:06:58 INFO - PID 1637 | ************************* 19:06:58 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:06:58 INFO - PID 1637 | 19:06:58 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:06:58 INFO - PID 1637 | Full stack: 19:06:58 INFO - PID 1637 | ************************* 19:08:17 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 19:08:18 INFO - PID 1637 | RSS: Main: 259923968 19:08:18 INFO - PID 1637 | 19:11:20 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 19:11:21 INFO - PID 1637 | RSS: Main: 271470592 19:11:21 INFO - PID 1637 | 19:14:22 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 19:14:23 INFO - PID 1637 | RSS: Main: 264736768 19:14:23 INFO - PID 1637 | 19:17:26 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 19:17:27 INFO - PID 1637 | RSS: Main: 274956288 19:17:27 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 19:20:30 INFO - PID 1637 | RSS: Main: 268800000 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | __start_tp_report 19:20:30 INFO - PID 1637 | _x_x_mozilla_page_load 19:20:30 INFO - PID 1637 | _x_x_mozilla_page_load_details 19:20:30 INFO - PID 1637 | |i|pagename|runs| 19:20:30 INFO - PID 1637 | |0;dojo.html;3440.913977150571;3454.1709989602487;3455.2398472336076;3491.773034752741;3496.353174662688;5774.162746401769;5943.3462319144855;5965.671888046156;6019.187409699689;6019.277686677599;5864.560157988147;5946.137810017545;5968.880622387561;6034.517238620911;6070.484008859241;4488.506264310931;5303.867403314909;5375.381831089436;5422.078246698071;5458.072127738294;5327.387350454689;5396.946030539703;5464.617476776627;5470.152126420411;5483.314585676791;4652.767016740567;5292.179712144585;5405.108157154064;5407.945920540784;5435.510804027636;4703.459102203256;5217.817376391846;5386.622936394454;5418.241446197536;5420.024395608789;5076.111680455905;5332.253484512172;5360.3299587551555;5364.785408583691;5399;4456.643468522543;4497.550244975509;5765.4811067004175;5771.682557459338;5836.5914385993;6222.160008398853;6276.968615156895;6292.024736165901;6302.086197501337;6304.464120549759;6299.244090709099;6374.107624932467;6383.39357761012;6390.776322828725;6418.903716444257;5171;6079.787207447718;6088.908666370008;6089.117078023751;6095.481884039907;6226.968865155645;6232.127502149703;6386.936130638727;6459.063435801782;6462.967685161544;5253.106971814801;6015.819525414245;6112.5946701277735;6152.2924863640355;6245.906311405332;5667.404922483162;5896.528277737771;6271.43557079865;6279.120923070683;6308.337624549356;6267.780627678009;6278.717457714414;6284.057391391237;6294.527910406738;6318.7472501100365;6111.500579230221;6163.38366163389;6284.685765711788;6298.118263443122;6324.2410910691015;5053.898922021613;5081.491850814963;5082.415522214918;5117.692938423706;5135.947130838169;4540.296254080623;4568.56598623137;4578.725276483355;4591.127685739699;4611.838585649486;6134.263888333339;6137.048757442603;6137.84655383619;6146.661933593696;6150.04674275488;6194.101855231056;6294.748210071546;6303.149074874833;6371.299157092716;6371.39471750183;5999.070144127667;6104.298005729398;6122.825106970096;6128.571000030044;6137.8673257933415;4817.98822247332;5641.830745077655;5657.632253903483;5676.858078548069;5694.886102277931;6512.674366281667;6544.3782840630065;6578.440832529289;6593.703283352164;6594.472442204612;6174.8147555573405;6262.5929314594405;6285.1515045470105;6285.842853928689;6327.145835312358;6531.804045878631;6559;6561.901571476527;6563.278039415756;6569.408753212234;6269.592476489013;6277.591956522811;6282.780102696383;6290.1508296381235;6304.5586808922935;6110.467630727307;6183.567150299616;6278.089676996901;6307.085472606538;6332.208473940757;6227.688615569113;6290.528210384331;6306.65313407767;6323.083152942706;6326.335734747785;5221.295125158055;6158.568900177123;6168.136460895388;6204.689765511616;6288.434040936338;6105.44311200641;6119.776756597957;6216.502679785516;6238.6880655966115;6247.687615619292;6280.874382512233;6289.7484100635465;6299;6307.211598550181;6313.715882785377;6043.033114701627;6147.969260153671;6172.722227499863;6188.504919606331;6197.659128747782 19:20:30 INFO - PID 1637 | |1;ext.html;718.0665135324081;783.7844592736998;801.5511313664347;942.7171848445469;943.6461327002374;36840.44739328912;37307.81346093266;37620.999999999935;37640.43539346905;37673.24653506932;31822.363552728988;35743;36740;37037.81481092598;37089;7667.539947603148;7838.451308408414;10553.155747540179;10855.40295283759;10885.292455990375;10999.285046471994;11142.832857507103;11144.164187685916;11156.497957591888;11169.609063682772;9575.95212023939;11106.777864442707;11146;11310.547578096866;11315.886841131565;11207;11256;11259.54961801527;11261.605843795469;11265.774684506265;10975;11002.944985275022;11038.17213708975;11068.003879650789;11075.833862492069;38234;38499.23001539954;38519.229615407814;38523.65167219162;38551.42172867409;3800.3539398302355;3828.8659896903473;3829.9617003830163;3832.9616703833162;3849.788261645608;27739.583906241423;28080.719192808217;28236.15291541257;28596.999105031224;29058.854705726546;3128.1553980425156;3160.8261545614982;3191.6170059592996;3205.6313523944814;3264.379767844102;242778;247637;252088.99999999817;345521;345824.2708786465;46672.5332746675;46917;47060.5293947063;47106;47271;48909.02181956341;48939.776505587644;49130;49391;49510;14425.062370945854;14549.78175327371;14565.927170364292;14567.490137845125;14569.344379502947;10285.382877027403;11410.42947852604;12008.039356851606;12032.977196938358;12164.5134194633;13962.650933726534;13989.860101398855;13994.230317332644;13997.580072597839;14008.369623366973;6106.938930610637;6149.66176860268;6159.81520554384;6170.382961703794;6196.256449226032;1024.5031159887449;1101.1466113761899;1154.809456439695;1168.6377223060708;1187.8752730963297;26049.353090567336;26111.608325875128;26291.605625915625;26515.33711657224;26580.202593922215;1236.8948639365574;1258.4840215511779;1263.911526193176;1294.501616877509;1301.4403806363166;12943.741125177632;13376.929845612522;13627.591172264847;13646;13906.721865562835;411147;418498;418671;419245;420015;55537;55739.44260557424;55742.72128639331;55901.7204913981;55938.440615593325;1384.0657556149558;1455.316001479303;1457;1459.6059064052647;1471.0805746408494;22001.339959801233;24288.757112428648;24805.379865503506;25885;25903.740962590135;46661;46692.53307466882;46723.76638116788;46769.2984605238;46801.53198468108;353538;354285;355126;356064;356255.21872390475;25973.480530389665;26252.343691407103;31428.842855785573;31962.52056219205;33638;39997.60002399939;40406.39390409087;40593.797031014656;40751.77744667665;40787;28873.566896496148;33682.494762579074;35174.47238291479;35904.64095359013;36236.63763362333;34239;34333.48499772555;34409;35668.64331356757;35723.64276357307;34869.476957845145;35371.64628353684;35684.821575891954;35759.64240357563;35779;27176.864115679295;29810.552841706958;30856;31029.913953011535;31070.37859242848;23344.71604061793;23536.058557658187;23795.167169148997;23850.64224036606;23884.40288992789 19:20:30 INFO - PID 1637 | |2;jquery.html;400.35742633073914;404.381296616177;417.83495519269877;431.63311185492324;434.18156774480093;11440.885591144106;14972.026818256832;15609.297581608826;17215.82784172158;20898.791012089874;21371.893140534314;21380.57238855222;21472.033758480866;21662.566748665016;21747.6737848932;5206.557442617363;5487.862803429906;5844.532437404998;6290.339514350996;6775.796726098225;6529.249136349309;6676.332366763309;6680.933190668104;6689.531732778707;7141.464390170758;7463.888041679379;7514.323710866049;7533.547987120763;7535.660895259726;7542.69829206831;7390.5196162249285;7501.774946751579;7610.619469026554;7626;7670.539767613961;7155.67799449026;7369.4472914531625;7395.8151046224275;7464.776056718253;7468.141163766128;25393.74606253932;25919.870400648066;26372.86813565939;26479.602805957926;26793.0622428216;2759.4067275535654;2865.2120666816586;2887.104997450797;2888.494513460157;2913.504704200291;15482.148481833494;15885.444009459614;15968.600784980352;16278.43025494102;16373.42693005739;1759.8240175982298;2009.5277609761695;2014.21445636202;2018.495376155961;2034.8982550872397;138350.30824845913;145596;148060;148761;149618.7557186643;20857.582848342947;21907.890460547915;22326.33021009372;22414.775852241593;22579.87100644961;34110.88336749507;34440.82779586086;34496.310073798886;34561.827190863885;34566.82716586401;10200.285979981478;10489.370637761758;10615.25693201484;10651.680449586525;10792.56829726802;8450.19723126302;8589.828203436022;8599.26906212979;8769.649214031368;8769.649214031495;10302.227332950059;10332.948335258427;10376.948115259376;10408.47957602117;10433.765532684893;3697.8520859165333;4158.459400277945;4384.517703052662;4422.204003279377;4437.092050418351;1058.5025038232172;1062;1065.8827528971806;1068.8183008888507;1108.9944550277357;21371.572568548854;21520;21853.235136770138;22304.665430018562;22426.663600046013;892.4823602310644;1084.5444913136503;1119.1885882734953;1124.2242852431796;1128.249713940237;9111;11457.942710286396;11651.00966417847;11681.76636467266;11730.941345293219;545934;643006;660113.6994314998;665059;665316;131046;131858.34070829584;132873;135259;139369;1240.4727990603958;1281.4425724809737;1309.6791286134958;1325.6950901292757;1375.5185685010167;18056.729149063038;20055.799442005395;20390.490237744176;20641.69037464469;20681.27615533449;53507.46492535181;62687.6865615669;63554;64341;64669.67665161644;90141;90210;91047.08952910386;92065.61901571616;92133;11016.779664406507;11480.655580332603;11688.47401866935;11749.47127379253;11836.171467997501;12717.491300347883;12881.484740610646;13172.143810652276;13221.272829994443;13310.534131305358;11097.11223102166;11244.943775281072;11377.886221137682;11466.484008219484;11518.712032199262;9036.864447033167;9335;9629.422234665943;11076.833847492455;11132.332060076422;11522.76954460923;11671.007964323127;11676.649700508999;11777.293362398283;11860.22908510944;11400;11442.256253343507;11651.941740291244;11684.88315116838;11795.882041179479;9719.324157551164;11131;11235.438228088726;11299.548018079513;11424.428778560872 19:20:30 INFO - PID 1637 | |3;mootools.html;1499.572621802785;1509.3585226278822;1525.534711912867;1558.4311726219933;1634.7057529644667;9465.526723663823;10664.146868250531;10897.291676041052;11179.664610061702;11190.552377904894;10764.300320479164;10955.068819150381;10975.5061022254;10995.395253261067;11086.891310868908;2295.529416469628;2335.871527066011;2344.531093781242;2351.1888398502488;2351.3651314145254;2285.874276914769;2310.8266879983985;2320.8027317678016;2324.883755812211;2331.40549159964;2277.6925115109416;2302.7121609798774;2316.837821352506;2325.6744055832196;2329.440934175794;2270.6821045053707;2278.134308962592;2278.1912421090606;2288.7711228877147;2290.1640901071155;2270.080617349976;2304.0783686525356;2326.197461875667;2327.837051406402;2330.242671131889;11001.119910407147;11067.612633557786;11121.777564448746;11126.721831954184;11147.996680298731;5558.86102847428;5773.711314434261;5783.306003279591;5783.652980821122;5797.768089276424;3358.8656453742065;3433.8111403872776;3570.1431656402538;3673.485712000322;3675.72432067596;1081.086481922774;1127.4757237884342;1128.9565885746824;1230.5508489401393;1243.434237421971;13987.230702311368;14209.431622735181;14573.417063317454;14950.252487375588;15057.17185554794;4612.884677883079;4731.976340118277;4747.667663263607;4763.285507173951;4766.713997160182;11513.769724605629;11543.942280288546;11594.130440217017;11603.477843497063;11615.535378584931;5603.551715862721;5634.943650563442;5678.5741069419955;5692.31692196939;5700.771969121189;5040.722760248148;5285.392179899284;5298.523132918056;5303.15149576066;5314.335708036496;3768.510093687803;3876.496055512822;3918.2163567286657;3965.861194858166;3969.3053715599654;962.4225464721111;981.9983616710915;1054.5939813171983;1066.3814987307344;1068.3643232276784;905.3164860530258;912.5893347993297;928.475411392561;943.5942544705705;952.7903861150536;6279.874402511924;6424.1327420798525;6498.480121590262;6508.9023664645065;6625.635590042594;868.6178081644057;924.3852837862746;941.4022095969109;953.5661274120258;972.1930797438109;3466.4107101792756;3554.289142171576;3640.5995340512522;3678.8528458861883;3679.0802299425145;11068.667939961813;11279.041281491165;11389.886101139049;11440;11481.770364592661;11228.101751859831;11236.325820450798;11448.02691771192;11495.540178392937;11519.19365644397;8788.428752131218;8801;8908.599113039929;8910.599023044108;8915.554222288989;5835.620684655484;5934.376890426443;6010.038393857137;6049.0926361046195;6064.272287325549;10298.588056477656;10422.166226701695;10424.374537527772;10433.426161560883;10476.371417714656;8534.871976920227;10335.328203666737;11232.83150752723;11762.82355764647;11864.347460889734;8809.955950220208;8822.33832462555;8934.910650893407;9036.59932710431;9081.68214112503;9917.702468925943;9924.1068303853;10022.749431264278;10023.097921187129;10066.194704423482;8840.602172902107;8872.467651940904;8875.556222188736;8926.419782714103;8982.910170898207;7640.617969101411;8623.180797824196;8762.824743505222;8820.823583528421;8866.335024873031;7668.463207575416;7823.804404889924;7878.960605196938;7908.169642187488;7955.5226686399;5847.210626565359;5872.911906321494;5954.10688396744;6022.819315420545;6080.300765412034;5734.3692193857805;5841.299044114733;6003.189569408074;6034.818955431344;6182.567220294536 19:20:30 INFO - PID 1637 | |4;prototype.html;350.4322996745272;370.7478914338252;379.33616171699526;380.5471488928175;401.16957897152895;6009.969950150243;6033.306169790475;6202.224721909762;6204.193454850869;6269.811905642838;5280.419153893069;5358.678479291236;5718.713289509868;6024.1566180734735;6052.939470605293;4682.742449165295;4764.571188593026;4959.256111583255;4974.37820272466;4991.575716064139;4311.650150667878;4953.628477864157;4976.975115124419;5009.12340340439;5013.849584512471;4972.03045406146;4988.800447982077;4995.850124496271;5031.974840125794;5034.949650503503;4171.853985110507;4797.9760101199445;4932.728699921503;4936.950630493721;4944.950550494521;4542.977285113587;4544.295634176675;4552.658550608718;4559.316102584606;4580.38164847748;4902.534259245402;5337.572994160458;5393.865153371158;5966.463018328328;5994.291626886316;4818.927716084261;4970;5527.972360138214;5791.971040144815;5808.273965754281;6516.478681705452;6525.347465253437;6540.247871494791;6549.738010479575;6587.143671322745;4399.956000439986;4459.888502787424;4577.725336479822;4649.302604609302;5097.923531146998;6550.508711846583;6711.993201019837;6751.527393082535;6872;6980.930190698079;5326.414094449608;5332.960072785847;5338.706371149624;5345.545628621533;5356.437574054668;4503.279475283939;5331.280277162611;5375.6023433906685;5443.2107344434835;5520.227168196456;5510.283663123768;5795.768169273182;5823.737931793079;5825.825225243249;5856.765729370777;6575.967120164464;6594.868102638016;6596.406323430914;6603.438707709898;6605.70274337656;1709.5042437693178;1745.816689247636;1828.4057681253646;1837.6875931091747;1858.7583614130076;1102.4818335382517;1307.1568838099372;1326.8460946725313;1354.3160703844496;1368.5346982026158;5292.973535132377;5326.973365133227;5343.225232341367;5904.143899134601;6206.410391012847;5935.436133567304;6718;6729.880087772336;6757.695903684346;6764.898526522106;5622.943770562324;5885.911106445321;6001.06983417571;6191.876162476725;6192.566520343623;3540.3804334241404;3557.3952428087287;3559.561700235347;3559.715222782223;3566.982165089158;5785.595008349375;6508;6873.347032031949;7015.964920175366;7079.469039822034;5816.5928385013485;6869.553479023847;6883.827904302432;6935.965320173367;6987.615681137485;6063.181470501514;6815.045893574904;6917.443575195541;7052.33002864727;7056.364927156478;6719.361660642231;6747.561408508432;6756.69594868242;6760.966195168992;6771.593704377753;4875.244911831809;6307.716152773044;6491.707873145814;6674.599524028574;6679.899801503078;6646.302138275605;6881.793546193622;6953.721851125898;7000.054992575937;7037.612931288828;5110.361204849394;5228.268042473981;5253.842384728464;5255.08649412785;5258.605604579749;5604.607677462702;6671.432928201158;6698.933010670026;6699.497537684596;6777.728890844508;6305.148804911279;6358.968205158944;6511.186101737283;6533.640649764308;6549.574277671936;4568.611668008257;6094.847628809138;6451.6774161290805;6455.160829092188;6481.157449531719;6381.904271436025;6403.167588213689;6416.871662566629;6437.227532695919;6485.837854053686;5836.883262334814;6026;6238.157848690369;6444.355564443518;6610.669466526559;6163.445289923749;6416.165898433173;6438.58149220318;6440.32376600469;6457.547971642128 19:20:30 INFO - PID 1637 | |5;yui.html;660.3627499463017;722.1045903080183;761.6161414771764;787.2953706432743;829.4484168028262;11321.377324247163;15694.450694225705;15993.920030399846;17067;18317.542061448472;16793.83206167938;17025.063621500813;17364.958102513858;17420.39028633998;17596.64806703865;965.5510187762688;991.8413053911376;993.0466751918167;996.6511720897701;996.8155891160159;967.8693376394167;988.5255077562774;999.6551189839494;1001.2440607341447;1007.1992765751208;875.5315905990296;1003.3127307794184;1009.4599389326712;1030.917526597874;1031.9845202321972;1005.6027868991744;1015.0610685116275;1016.9694909152737;1018.5060245780865;1019.7909428567126;746.8580969615756;754.766022533011;758.9886151707728;761.3338328962158;763.2291385700482;16204.270807813558;16569.4200702976;16619;16709.164541772985;16895.746563801553;14369.353379097965;14775.187364694937;14779.113253204734;14874.256287185597;14879.256037198096;11681.766364672743;11691.824622630667;11723.941380293129;11968.222065565711;12011.331444759258;3690.8195324036983;3780.8676696315497;3784.4967027497823;3791.4312853071983;3914.5302563692253;87996;99828.00171998332;102493;110885.44557277243;111121;10608.257421980541;11232.775344493064;11309.773804523864;11318.877068675438;11419.429028548706;23840.761592384202;24642;24872.626910596355;24970.750292497207;25045.24864254075;7889.8816517752275;7961.84553239777;8071.2735853772265;8105.432619716563;8118.851237205698;3373.7132343751055;4083.284211367641;4109.773962432035;4162.334026555797;4190.937135942963;2869.26833657416;3582.5342705448124;3608.7654302470255;3613.9277214456088;3620.022593899685;634.7968650031945;641.6599202422724;644.6067898581862;649.7271146118642;655.4035827397046;503.14967704579146;558.5699011760921;581.764385423904;595.2440400691098;610.8106486989047;10003.749906252257;10060.69817905464;10208.744781380525;10561.419121948367;10585.629502967358;634.7048622390611;634.9301427095359;665.6638397609177;679.6465837764433;691.3432239372617;4728.645351598644;4902.828401005948;4905.215165573562;4913.938777646502;4935.851924442272;98575;100162;100450;102013;103486;81568;82075;83696;86378.56810716032;86382.56808715916;47687.523124769;60915.69542152261;69516;70040.64979675069;70789;9317.301202409712;9320.62097539066;9352.766180845534;9386.765330866783;9401.153896149106;45042.77478612586;45084.32373514334;49490;50768.73078173075;51277.7436112832;61027;65215;65444.691106178565;65590.67204663946;66787;11132.279132696993;13325.200487970753;13362.265075420557;13536;14191.787123192953;11833.644990650295;14568.125912445286;14682.41270349174;14947.626309342353;15329;13224.47102115943;13245.602631921058;13597.592072237849;13685.726285474433;13746.381412836647;13306.534271300457;13444.327783610976;13739.862601374258;14041.578752637437;14176.645583860485;12931.935340323238;13206.537771177962;13359.666008349868;13822.65443363924;14098.78851817203;9218.632982175737;9927.602895884083;10737.624183153552;11231.775364492501;11699.941500292443;7772.183920688246;9658.179054780141;10398.480075996018;10452.94773526158;11389.715257118638 19:20:30 INFO - PID 1637 | __end_tp_report 19:20:30 INFO - PID 1637 | __start_cc_report 19:20:30 INFO - PID 1637 | _x_x_mozilla_cycle_collect,419 19:20:30 INFO - PID 1637 | __end_cc_report 19:20:30 INFO - PID 1637 | __startTimestamp1489544430358__endTimestamp 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | ------- Summary: start ------- 19:20:30 INFO - PID 1637 | Number of tests: 6 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:5851.93 Median:6137.85 stddev:676.75 (11.0%) stddev-sans-first:651.86 19:20:30 INFO - PID 1637 | Values: 3440.9 3454.2 3455.2 3491.8 3496.4 5774.2 5943.3 5965.7 6019.2 6019.3 5864.6 5946.1 5968.9 6034.5 6070.5 4488.5 5303.9 5375.4 5422.1 5458.1 5327.4 5396.9 5464.6 5470.2 5483.3 4652.8 5292.2 5405.1 5407.9 5435.5 4703.5 5217.8 5386.6 5418.2 5420.0 5076.1 5332.3 5360.3 5364.8 5399.0 4456.6 4497.6 5765.5 5771.7 5836.6 6222.2 6277.0 6292.0 6302.1 6304.5 6299.2 6374.1 6383.4 6390.8 6418.9 5171.0 6079.8 6088.9 6089.1 6095.5 6227.0 6232.1 6386.9 6459.1 6463.0 5253.1 6015.8 6112.6 6152.3 6245.9 5667.4 5896.5 6271.4 6279.1 6308.3 6267.8 6278.7 6284.1 6294.5 6318.7 6111.5 6163.4 6284.7 6298.1 6324.2 5053.9 5081.5 5082.4 5117.7 5135.9 4540.3 4568.6 4578.7 4591.1 4611.8 6134.3 6137.0 6137.8 6146.7 6150.0 6194.1 6294.7 6303.1 6371.3 6371.4 5999.1 6104.3 6122.8 6128.6 6137.9 4818.0 5641.8 5657.6 5676.9 5694.9 6512.7 6544.4 6578.4 6593.7 6594.5 6174.8 6262.6 6285.2 6285.8 6327.1 6531.8 6559.0 6561.9 6563.3 6569.4 6269.6 6277.6 6282.8 6290.2 6304.6 6110.5 6183.6 6278.1 6307.1 6332.2 6227.7 6290.5 6306.7 6323.1 6326.3 5221.3 6158.6 6168.1 6204.7 6288.4 6105.4 6119.8 6216.5 6238.7 6247.7 6280.9 6289.7 6299.0 6307.2 6313.7 6043.0 6148.0 6172.7 6188.5 6197.7 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:50069.70 Median:17491.59 stddev:94529.35 (540.4%) stddev-sans-first:94721.90 19:20:30 INFO - PID 1637 | Values: 718.1 783.8 801.6 942.7 943.6 36840.4 37307.8 37621.0 37640.4 37673.2 31822.4 35743.0 36740.0 37037.8 37089.0 7667.5 7838.5 10553.2 10855.4 10885.3 10999.3 11142.8 11144.2 11156.5 11169.6 9576.0 11106.8 11146.0 11310.5 11315.9 11207.0 11256.0 11259.5 11261.6 11265.8 10975.0 11002.9 11038.2 11068.0 11075.8 38234.0 38499.2 38519.2 38523.7 38551.4 3800.4 3828.9 3830.0 3833.0 3849.8 27739.6 28080.7 28236.2 28597.0 29058.9 3128.2 3160.8 3191.6 3205.6 3264.4 242778.0 247637.0 252089.0 345521.0 345824.3 46672.5 46917.0 47060.5 47106.0 47271.0 48909.0 48939.8 49130.0 49391.0 49510.0 14425.1 14549.8 14565.9 14567.5 14569.3 10285.4 11410.4 12008.0 12033.0 12164.5 13962.7 13989.9 13994.2 13997.6 14008.4 6106.9 6149.7 6159.8 6170.4 6196.3 1024.5 1101.1 1154.8 1168.6 1187.9 26049.4 26111.6 26291.6 26515.3 26580.2 1236.9 1258.5 1263.9 1294.5 1301.4 12943.7 13376.9 13627.6 13646.0 13906.7 411147.0 418498.0 418671.0 419245.0 420015.0 55537.0 55739.4 55742.7 55901.7 55938.4 1384.1 1455.3 1457.0 1459.6 1471.1 22001.3 24288.8 24805.4 25885.0 25903.7 46661.0 46692.5 46723.8 46769.3 46801.5 353538.0 354285.0 355126.0 356064.0 356255.2 25973.5 26252.3 31428.8 31962.5 33638.0 39997.6 40406.4 40593.8 40751.8 40787.0 28873.6 33682.5 35174.5 35904.6 36236.6 34239.0 34333.5 34409.0 35668.6 35723.6 34869.5 35371.6 35684.8 35759.6 35779.0 27176.9 29810.6 30856.0 31029.9 31070.4 23344.7 23536.1 23795.2 23850.6 23884.4 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:39331.72 Median:21371.73 stddev:106764.87 (499.6%) stddev-sans-first:107024.35 19:20:30 INFO - PID 1637 | Values: 400.4 404.4 417.8 431.6 434.2 11440.9 14972.0 15609.3 17215.8 20898.8 21371.9 21380.6 21472.0 21662.6 21747.7 5206.6 5487.9 5844.5 6290.3 6775.8 6529.2 6676.3 6680.9 6689.5 7141.5 7463.9 7514.3 7533.5 7535.7 7542.7 7390.5 7501.8 7610.6 7626.0 7670.5 7155.7 7369.4 7395.8 7464.8 7468.1 25393.7 25919.9 26372.9 26479.6 26793.1 2759.4 2865.2 2887.1 2888.5 2913.5 15482.1 15885.4 15968.6 16278.4 16373.4 1759.8 2009.5 2014.2 2018.5 2034.9 138350.3 145596.0 148060.0 148761.0 149618.8 20857.6 21907.9 22326.3 22414.8 22579.9 34110.9 34440.8 34496.3 34561.8 34566.8 10200.3 10489.4 10615.3 10651.7 10792.6 8450.2 8589.8 8599.3 8769.6 8769.6 10302.2 10332.9 10376.9 10408.5 10433.8 3697.9 4158.5 4384.5 4422.2 4437.1 1058.5 1062.0 1065.9 1068.8 1109.0 21371.6 21520.0 21853.2 22304.7 22426.7 892.5 1084.5 1119.2 1124.2 1128.2 9111.0 11457.9 11651.0 11681.8 11730.9 545934.0 643006.0 660113.7 665059.0 665316.0 131046.0 131858.3 132873.0 135259.0 139369.0 1240.5 1281.4 1309.7 1325.7 1375.5 18056.7 20055.8 20390.5 20641.7 20681.3 53507.5 62687.7 63554.0 64341.0 64669.7 90141.0 90210.0 91047.1 92065.6 92133.0 11016.8 11480.7 11688.5 11749.5 11836.2 12717.5 12881.5 13172.1 13221.3 13310.5 11097.1 11244.9 11377.9 11466.5 11518.7 9036.9 9335.0 9629.4 11076.8 11132.3 11522.8 11671.0 11676.6 11777.3 11860.2 11400.0 11442.3 11651.9 11684.9 11795.9 9719.3 11131.0 11235.4 11299.5 11424.4 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6381.78 Median:3674.61 stddev:3849.85 (104.8%) stddev-sans-first:3843.17 19:20:30 INFO - PID 1637 | Values: 1499.6 1509.4 1525.5 1558.4 1634.7 9465.5 10664.1 10897.3 11179.7 11190.6 10764.3 10955.1 10975.5 10995.4 11086.9 2295.5 2335.9 2344.5 2351.2 2351.4 2285.9 2310.8 2320.8 2324.9 2331.4 2277.7 2302.7 2316.8 2325.7 2329.4 2270.7 2278.1 2278.2 2288.8 2290.2 2270.1 2304.1 2326.2 2327.8 2330.2 11001.1 11067.6 11121.8 11126.7 11148.0 5558.9 5773.7 5783.3 5783.7 5797.8 3358.9 3433.8 3570.1 3673.5 3675.7 1081.1 1127.5 1129.0 1230.6 1243.4 13987.2 14209.4 14573.4 14950.3 15057.2 4612.9 4732.0 4747.7 4763.3 4766.7 11513.8 11543.9 11594.1 11603.5 11615.5 5603.6 5634.9 5678.6 5692.3 5700.8 5040.7 5285.4 5298.5 5303.2 5314.3 3768.5 3876.5 3918.2 3965.9 3969.3 962.4 982.0 1054.6 1066.4 1068.4 905.3 912.6 928.5 943.6 952.8 6279.9 6424.1 6498.5 6508.9 6625.6 868.6 924.4 941.4 953.6 972.2 3466.4 3554.3 3640.6 3678.9 3679.1 11068.7 11279.0 11389.9 11440.0 11481.8 11228.1 11236.3 11448.0 11495.5 11519.2 8788.4 8801.0 8908.6 8910.6 8915.6 5835.6 5934.4 6010.0 6049.1 6064.3 10298.6 10422.2 10424.4 10433.4 10476.4 8534.9 10335.3 11232.8 11762.8 11864.3 8810.0 8822.3 8934.9 9036.6 9081.7 9917.7 9924.1 10022.7 10023.1 10066.2 8840.6 8872.5 8875.6 8926.4 8982.9 7640.6 8623.2 8762.8 8820.8 8866.3 7668.5 7823.8 7879.0 7908.2 7955.5 5847.2 5872.9 5954.1 6022.8 6080.3 5734.4 5841.3 6003.2 6034.8 6182.6 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5446.31 Median:5871.34 stddev:1553.86 (26.5%) stddev-sans-first:1510.41 19:20:30 INFO - PID 1637 | Values: 350.4 370.7 379.3 380.5 401.2 6010.0 6033.3 6202.2 6204.2 6269.8 5280.4 5358.7 5718.7 6024.2 6052.9 4682.7 4764.6 4959.3 4974.4 4991.6 4311.7 4953.6 4977.0 5009.1 5013.8 4972.0 4988.8 4995.9 5032.0 5034.9 4171.9 4798.0 4932.7 4937.0 4945.0 4543.0 4544.3 4552.7 4559.3 4580.4 4902.5 5337.6 5393.9 5966.5 5994.3 4818.9 4970.0 5528.0 5792.0 5808.3 6516.5 6525.3 6540.2 6549.7 6587.1 4400.0 4459.9 4577.7 4649.3 5097.9 6550.5 6712.0 6751.5 6872.0 6980.9 5326.4 5333.0 5338.7 5345.5 5356.4 4503.3 5331.3 5375.6 5443.2 5520.2 5510.3 5795.8 5823.7 5825.8 5856.8 6576.0 6594.9 6596.4 6603.4 6605.7 1709.5 1745.8 1828.4 1837.7 1858.8 1102.5 1307.2 1326.8 1354.3 1368.5 5293.0 5327.0 5343.2 5904.1 6206.4 5935.4 6718.0 6729.9 6757.7 6764.9 5622.9 5885.9 6001.1 6191.9 6192.6 3540.4 3557.4 3559.6 3559.7 3567.0 5785.6 6508.0 6873.3 7016.0 7079.5 5816.6 6869.6 6883.8 6936.0 6987.6 6063.2 6815.0 6917.4 7052.3 7056.4 6719.4 6747.6 6756.7 6761.0 6771.6 4875.2 6307.7 6491.7 6674.6 6679.9 6646.3 6881.8 6953.7 7000.1 7037.6 5110.4 5228.3 5253.8 5255.1 5258.6 5604.6 6671.4 6698.9 6699.5 6777.7 6305.1 6359.0 6511.2 6533.6 6549.6 4568.6 6094.8 6451.7 6455.2 6481.2 6381.9 6403.2 6416.9 6437.2 6485.8 5836.9 6026.0 6238.2 6444.4 6610.7 6163.4 6416.2 6438.6 6440.3 6457.5 19:20:30 INFO - PID 1637 | 19:20:30 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19889.95 Median:3478.12 stddev:27800.44 (799.3%) stddev-sans-first:27840.93 19:20:30 INFO - PID 1637 | Values: 660.4 722.1 761.6 787.3 829.4 11321.4 15694.5 15993.9 17067.0 18317.5 16793.8 17025.1 17365.0 17420.4 17596.6 965.6 991.8 993.0 996.7 996.8 967.9 988.5 999.7 1001.2 1007.2 875.5 1003.3 1009.5 1030.9 1032.0 1005.6 1015.1 1017.0 1018.5 1019.8 746.9 754.8 759.0 761.3 763.2 16204.3 16569.4 16619.0 16709.2 16895.7 14369.4 14775.2 14779.1 14874.3 14879.3 11681.8 11691.8 11723.9 11968.2 12011.3 3690.8 3780.9 3784.5 3791.4 3914.5 87996.0 99828.0 102493.0 110885.4 111121.0 10608.3 11232.8 11309.8 11318.9 11419.4 23840.8 24642.0 24872.6 24970.8 25045.2 7889.9 7961.8 8071.3 8105.4 8118.9 3373.7 4083.3 4109.8 4162.3 4190.9 2869.3 3582.5 3608.8 3613.9 3620.0 634.8 641.7 644.6 649.7 655.4 503.1 558.6 581.8 595.2 610.8 10003.7 10060.7 10208.7 10561.4 10585.6 634.7 634.9 665.7 679.6 691.3 4728.6 4902.8 4905.2 4913.9 4935.9 98575.0 100162.0 100450.0 102013.0 103486.0 81568.0 82075.0 83696.0 86378.6 86382.6 47687.5 60915.7 69516.0 70040.6 70789.0 9317.3 9320.6 9352.8 9386.8 9401.2 45042.8 45084.3 49490.0 50768.7 51277.7 61027.0 65215.0 65444.7 65590.7 66787.0 11132.3 13325.2 13362.3 13536.0 14191.8 11833.6 14568.1 14682.4 14947.6 15329.0 13224.5 13245.6 13597.6 13685.7 13746.4 13306.5 13444.3 13739.9 14041.6 14176.6 12931.9 13206.5 13359.7 13822.7 14098.8 9218.6 9927.6 10737.6 11231.8 11699.9 7772.2 9658.2 10398.5 10452.9 11389.7 19:20:30 INFO - PID 1637 | -------- Summary: end -------- 19:20:30 INFO - PID 1637 | 19:20:31 INFO - TEST-INFO | 1637: exit 0 19:20:31 INFO - TEST-OK | dromaeo_css | took 1116946ms 19:20:31 INFO - TEST-START | kraken 19:20:31 INFO - operating with platform_type : mac_ 19:20:31 INFO - Initialising browser for kraken test... 19:20:31 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpFVUXLU/profile http://localhost:49248/getInfo.html) 19:20:33 INFO - PID 1649 | 2017-03-14 19:20:33.865 plugin-container[1650:9855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 19:20:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:33 INFO - PID 1649 | 2017-03-14 19:20:33.868 plugin-container[1650:9855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 19:20:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:33 INFO - PID 1649 | 2017-03-14 19:20:33.868 plugin-container[1650:9855] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:20:33 INFO - PID 1649 | __metrics Screen width/height:1600/1200 19:20:33 INFO - PID 1649 | colorDepth:24 19:20:33 INFO - PID 1649 | Browser inner width/height: 1024/689 19:20:33 INFO - PID 1649 | __metrics 19:20:34 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 19:20:34 INFO - TEST-INFO | 1649: exit 0 19:20:34 INFO - Browser initialized. 19:20:34 INFO - Running cycle 1/1 for kraken test... 19:20:34 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpFVUXLU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:37 INFO - PID 1651 | 2017-03-14 19:20:37.342 plugin-container[1652:9976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 19:20:37 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:37 INFO - PID 1651 | 2017-03-14 19:20:37.342 plugin-container[1652:9976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 19:20:37 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:20:37 INFO - PID 1651 | 2017-03-14 19:20:37.342 plugin-container[1652:9976] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:20:46 INFO - PID 1651 | RSS: Main: 226918400 19:20:46 INFO - PID 1651 | 19:20:50 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 19:20:50 INFO - PID 1651 | RSS: Main: 241463296 19:20:50 INFO - PID 1651 | 19:20:53 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 19:20:54 INFO - PID 1651 | RSS: Main: 250007552 19:20:54 INFO - PID 1651 | 19:20:57 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 19:20:58 INFO - PID 1651 | RSS: Main: 252493824 19:20:58 INFO - PID 1651 | 19:21:00 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 19:21:00 INFO - PID 1651 | RSS: Main: 254816256 19:21:00 INFO - PID 1651 | 19:21:02 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:03 INFO - PID 1651 | RSS: Main: 255234048 19:21:03 INFO - PID 1651 | 19:21:06 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:07 INFO - PID 1651 | RSS: Main: 261050368 19:21:07 INFO - PID 1651 | 19:21:10 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:11 INFO - PID 1651 | RSS: Main: 261857280 19:21:11 INFO - PID 1651 | 19:21:14 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 19:21:14 INFO - PID 1651 | RSS: Main: 261836800 19:21:14 INFO - PID 1651 | 19:21:16 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:21:17 INFO - PID 1651 | RSS: Main: 261591040 19:21:17 INFO - PID 1651 | 19:21:19 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:21:19 INFO - PID 1651 | RSS: Main: 261410816 19:21:19 INFO - PID 1651 | 19:21:21 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:21:22 INFO - PID 1651 | RSS: Main: 261492736 19:21:22 INFO - PID 1651 | 19:21:25 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:21:26 INFO - PID 1651 | RSS: Main: 261914624 19:21:26 INFO - PID 1651 | 19:21:29 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:21:30 INFO - PID 1651 | RSS: Main: 261718016 19:21:30 INFO - PID 1651 | 19:21:31 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 19:21:32 INFO - PID 1651 | RSS: Main: 263487488 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | __start_tp_report 19:21:32 INFO - PID 1651 | _x_x_mozilla_page_load 19:21:32 INFO - PID 1651 | _x_x_mozilla_page_load_details 19:21:32 INFO - PID 1651 | |i|pagename|runs| 19:21:32 INFO - PID 1651 | |0;ai-astar;161;180;132;145;164;118;118;120;128;151 19:21:32 INFO - PID 1651 | |1;audio-beat-detection;146;115;126;193;120;117;117;106;173;108 19:21:32 INFO - PID 1651 | |2;audio-dft;194;205;211;227;222;200;195;196;200;193 19:21:32 INFO - PID 1651 | |3;audio-fft;85;63;60;88;58;61;57;52;87;84 19:21:32 INFO - PID 1651 | |4;audio-oscillator;101;106;124;71;74;69;87;73;72;85 19:21:32 INFO - PID 1651 | |5;imaging-gaussian-blur;90;104;108;89;91;84;136;88;89;108 19:21:32 INFO - PID 1651 | |6;imaging-darkroom;132;97;105;95;154;152;127;153;131;96 19:21:32 INFO - PID 1651 | |7;imaging-desaturate;90;113;109;88;126;129;91;116;88;76 19:21:32 INFO - PID 1651 | |8;json-parse-financial;68;66;67;80;107;106;79;68;66;68 19:21:32 INFO - PID 1651 | |9;json-stringify-tinderbox;56;61;61;60;69;58;56;58;58;58 19:21:32 INFO - PID 1651 | |10;stanford-crypto-aes;84;85;90;90;82;84;86;86;83;85 19:21:32 INFO - PID 1651 | |11;stanford-crypto-ccm;144;167;148;149;165;158;158;154;190;131 19:21:32 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;186;185;188;180;185;186;189;183;187;187 19:21:32 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;68;69;70;69;67;69;69;68;68;70 19:21:32 INFO - PID 1651 | __end_tp_report 19:21:32 INFO - PID 1651 | __start_cc_report 19:21:32 INFO - PID 1651 | _x_x_mozilla_cycle_collect,999 19:21:32 INFO - PID 1651 | __end_cc_report 19:21:32 INFO - PID 1651 | __startTimestamp1489544492102__endTimestamp 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | ------- Summary: start ------- 19:21:32 INFO - PID 1651 | Number of tests: 14 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:141.70 Median:148.00 stddev:21.88 (14.8%) stddev-sans-first:22.07 19:21:32 INFO - PID 1651 | Values: 161.0 180.0 132.0 145.0 164.0 118.0 118.0 120.0 128.0 151.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:123.00 stddev:29.38 (23.9%) stddev-sans-first:30.73 19:21:32 INFO - PID 1651 | Values: 146.0 115.0 126.0 193.0 120.0 117.0 117.0 106.0 173.0 108.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:204.30 Median:202.50 stddev:12.02 (5.9%) stddev-sans-first:12.16 19:21:32 INFO - PID 1651 | Values: 194.0 205.0 211.0 227.0 222.0 200.0 195.0 196.0 200.0 193.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:69.50 Median:73.50 stddev:14.52 (19.8%) stddev-sans-first:14.28 19:21:32 INFO - PID 1651 | Values: 85.0 63.0 60.0 88.0 58.0 61.0 57.0 52.0 87.0 84.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:72.50 stddev:18.54 (25.6%) stddev-sans-first:18.88 19:21:32 INFO - PID 1651 | Values: 101.0 106.0 124.0 71.0 74.0 69.0 87.0 73.0 72.0 85.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:88.50 stddev:15.80 (17.9%) stddev-sans-first:16.44 19:21:32 INFO - PID 1651 | Values: 90.0 104.0 108.0 89.0 91.0 84.0 136.0 88.0 89.0 108.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:153.50 stddev:24.41 (15.9%) stddev-sans-first:25.72 19:21:32 INFO - PID 1651 | Values: 132.0 97.0 105.0 95.0 154.0 152.0 127.0 153.0 131.0 96.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:102.60 Median:82.00 stddev:18.26 (22.3%) stddev-sans-first:18.79 19:21:32 INFO - PID 1651 | Values: 90.0 113.0 109.0 88.0 126.0 129.0 91.0 116.0 88.0 76.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:77.50 Median:68.00 stddev:16.11 (23.7%) stddev-sans-first:16.72 19:21:32 INFO - PID 1651 | Values: 68.0 66.0 67.0 80.0 107.0 106.0 79.0 68.0 66.0 68.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:3.78 (6.4%) stddev-sans-first:3.79 19:21:32 INFO - PID 1651 | Values: 56.0 61.0 61.0 60.0 69.0 58.0 56.0 58.0 58.0 58.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.50 stddev:2.68 (3.1%) stddev-sans-first:2.78 19:21:32 INFO - PID 1651 | Values: 84.0 85.0 90.0 90.0 82.0 84.0 86.0 86.0 83.0 85.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:158.00 stddev:15.81 (10.0%) stddev-sans-first:16.12 19:21:32 INFO - PID 1651 | Values: 144.0 167.0 148.0 149.0 165.0 158.0 158.0 154.0 190.0 131.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:186.50 stddev:2.59 (1.4%) stddev-sans-first:2.74 19:21:32 INFO - PID 1651 | Values: 186.0 185.0 188.0 180.0 185.0 186.0 189.0 183.0 187.0 187.0 19:21:32 INFO - PID 1651 | 19:21:32 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 19:21:32 INFO - PID 1651 | Values: 68.0 69.0 70.0 69.0 67.0 69.0 69.0 68.0 68.0 70.0 19:21:32 INFO - PID 1651 | -------- Summary: end -------- 19:21:32 INFO - PID 1651 | 19:21:32 INFO - TEST-INFO | 1651: exit 0 19:21:33 INFO - TEST-OK | kraken | took 61857ms 19:21:33 INFO - SUITE-END | took 1178s 19:21:33 INFO - Completed test suite (00:19:39) 19:21:33 INFO - javascript benchmark 19:21:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7849.685979550516, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3440.913977150571, 3454.1709989602487, 3455.2398472336076, 3491.773034752741, 3496.353174662688, 5774.162746401769, 5943.3462319144855, 5965.671888046156, 6019.187409699689, 6019.277686677599, 5864.560157988147, 5946.137810017545, 5968.880622387561, 6034.517238620911, 6070.484008859241, 4488.506264310931, 5303.867403314909, 5375.381831089436, 5422.078246698071, 5458.072127738294, 5327.387350454689, 5396.946030539703, 5464.617476776627, 5470.152126420411, 5483.314585676791, 4652.767016740567, 5292.179712144585, 5405.108157154064, 5407.945920540784, 5435.510804027636, 4703.459102203256, 5217.817376391846, 5386.622936394454, 5418.241446197536, 5420.024395608789, 5076.111680455905, 5332.253484512172, 5360.3299587551555, 5364.785408583691, 5399.0, 4456.643468522543, 4497.550244975509, 5765.4811067004175, 5771.682557459338, 5836.5914385993, 6222.160008398853, 6276.968615156895, 6292.024736165901, 6302.086197501337, 6304.464120549759, 6299.244090709099, 6374.107624932467, 6383.39357761012, 6390.776322828725, 6418.903716444257, 5171.0, 6079.787207447718, 6088.908666370008, 6089.117078023751, 6095.481884039907, 6226.968865155645, 6232.127502149703, 6386.936130638727, 6459.063435801782, 6462.967685161544, 5253.106971814801, 6015.819525414245, 6112.5946701277735, 6152.2924863640355, 6245.906311405332, 5667.404922483162, 5896.528277737771, 6271.43557079865, 6279.120923070683, 6308.337624549356, 6267.780627678009, 6278.717457714414, 6284.057391391237, 6294.527910406738, 6318.7472501100365, 6111.500579230221, 6163.38366163389, 6284.685765711788, 6298.118263443122, 6324.2410910691015, 5053.898922021613, 5081.491850814963, 5082.415522214918, 5117.692938423706, 5135.947130838169, 4540.296254080623, 4568.56598623137, 4578.725276483355, 4591.127685739699, 4611.838585649486, 6134.263888333339, 6137.048757442603, 6137.84655383619, 6146.661933593696, 6150.04674275488, 6194.101855231056, 6294.748210071546, 6303.149074874833, 6371.299157092716, 6371.39471750183, 5999.070144127667, 6104.298005729398, 6122.825106970096, 6128.571000030044, 6137.8673257933415, 4817.98822247332, 5641.830745077655, 5657.632253903483, 5676.858078548069, 5694.886102277931, 6512.674366281667, 6544.3782840630065, 6578.440832529289, 6593.703283352164, 6594.472442204612, 6174.8147555573405, 6262.5929314594405, 6285.1515045470105, 6285.842853928689, 6327.145835312358, 6531.804045878631, 6559.0, 6561.901571476527, 6563.278039415756, 6569.408753212234, 6269.592476489013, 6277.591956522811, 6282.780102696383, 6290.1508296381235, 6304.5586808922935, 6110.467630727307, 6183.567150299616, 6278.089676996901, 6307.085472606538, 6332.208473940757, 6227.688615569113, 6290.528210384331, 6306.65313407767, 6323.083152942706, 6326.335734747785, 5221.295125158055, 6158.568900177123, 6168.136460895388, 6204.689765511616, 6288.434040936338, 6105.44311200641, 6119.776756597957, 6216.502679785516, 6238.6880655966115, 6247.687615619292, 6280.874382512233, 6289.7484100635465, 6299.0, 6307.211598550181, 6313.715882785377, 6043.033114701627, 6147.969260153671, 6172.722227499863, 6188.504919606331, 6197.659128747782], "value": 5810.211772506604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.0665135324081, 783.7844592736998, 801.5511313664347, 942.7171848445469, 943.6461327002374, 36840.44739328912, 37307.81346093266, 37620.999999999935, 37640.43539346905, 37673.24653506932, 31822.363552728988, 35743.0, 36740.0, 37037.81481092598, 37089.0, 7667.539947603148, 7838.451308408414, 10553.155747540179, 10855.40295283759, 10885.292455990375, 10999.285046471994, 11142.832857507103, 11144.164187685916, 11156.497957591888, 11169.609063682772, 9575.95212023939, 11106.777864442707, 11146.0, 11310.547578096866, 11315.886841131565, 11207.0, 11256.0, 11259.54961801527, 11261.605843795469, 11265.774684506265, 10975.0, 11002.944985275022, 11038.17213708975, 11068.003879650789, 11075.833862492069, 38234.0, 38499.23001539954, 38519.229615407814, 38523.65167219162, 38551.42172867409, 3800.3539398302355, 3828.8659896903473, 3829.9617003830163, 3832.9616703833162, 3849.788261645608, 27739.583906241423, 28080.719192808217, 28236.15291541257, 28596.999105031224, 29058.854705726546, 3128.1553980425156, 3160.8261545614982, 3191.6170059592996, 3205.6313523944814, 3264.379767844102, 242778.0, 247637.0, 252088.99999999817, 345521.0, 345824.2708786465, 46672.5332746675, 46917.0, 47060.5293947063, 47106.0, 47271.0, 48909.02181956341, 48939.776505587644, 49130.0, 49391.0, 49510.0, 14425.062370945854, 14549.78175327371, 14565.927170364292, 14567.490137845125, 14569.344379502947, 10285.382877027403, 11410.42947852604, 12008.039356851606, 12032.977196938358, 12164.5134194633, 13962.650933726534, 13989.860101398855, 13994.230317332644, 13997.580072597839, 14008.369623366973, 6106.938930610637, 6149.66176860268, 6159.81520554384, 6170.382961703794, 6196.256449226032, 1024.5031159887449, 1101.1466113761899, 1154.809456439695, 1168.6377223060708, 1187.8752730963297, 26049.353090567336, 26111.608325875128, 26291.605625915625, 26515.33711657224, 26580.202593922215, 1236.8948639365574, 1258.4840215511779, 1263.911526193176, 1294.501616877509, 1301.4403806363166, 12943.741125177632, 13376.929845612522, 13627.591172264847, 13646.0, 13906.721865562835, 411147.0, 418498.0, 418671.0, 419245.0, 420015.0, 55537.0, 55739.44260557424, 55742.72128639331, 55901.7204913981, 55938.440615593325, 1384.0657556149558, 1455.316001479303, 1457.0, 1459.6059064052647, 1471.0805746408494, 22001.339959801233, 24288.757112428648, 24805.379865503506, 25885.0, 25903.740962590135, 46661.0, 46692.53307466882, 46723.76638116788, 46769.2984605238, 46801.53198468108, 353538.0, 354285.0, 355126.0, 356064.0, 356255.21872390475, 25973.480530389665, 26252.343691407103, 31428.842855785573, 31962.52056219205, 33638.0, 39997.60002399939, 40406.39390409087, 40593.797031014656, 40751.77744667665, 40787.0, 28873.566896496148, 33682.494762579074, 35174.47238291479, 35904.64095359013, 36236.63763362333, 34239.0, 34333.48499772555, 34409.0, 35668.64331356757, 35723.64276357307, 34869.476957845145, 35371.64628353684, 35684.821575891954, 35759.64240357563, 35779.0, 27176.864115679295, 29810.552841706958, 30856.0, 31029.913953011535, 31070.37859242848, 23344.71604061793, 23536.058557658187, 23795.167169148997, 23850.64224036606, 23884.40288992789], "value": 18617.120275413698, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.35742633073914, 404.381296616177, 417.83495519269877, 431.63311185492324, 434.18156774480093, 11440.885591144106, 14972.026818256832, 15609.297581608826, 17215.82784172158, 20898.791012089874, 21371.893140534314, 21380.57238855222, 21472.033758480866, 21662.566748665016, 21747.6737848932, 5206.557442617363, 5487.862803429906, 5844.532437404998, 6290.339514350996, 6775.796726098225, 6529.249136349309, 6676.332366763309, 6680.933190668104, 6689.531732778707, 7141.464390170758, 7463.888041679379, 7514.323710866049, 7533.547987120763, 7535.660895259726, 7542.69829206831, 7390.5196162249285, 7501.774946751579, 7610.619469026554, 7626.0, 7670.539767613961, 7155.67799449026, 7369.4472914531625, 7395.8151046224275, 7464.776056718253, 7468.141163766128, 25393.74606253932, 25919.870400648066, 26372.86813565939, 26479.602805957926, 26793.0622428216, 2759.4067275535654, 2865.2120666816586, 2887.104997450797, 2888.494513460157, 2913.504704200291, 15482.148481833494, 15885.444009459614, 15968.600784980352, 16278.43025494102, 16373.42693005739, 1759.8240175982298, 2009.5277609761695, 2014.21445636202, 2018.495376155961, 2034.8982550872397, 138350.30824845913, 145596.0, 148060.0, 148761.0, 149618.7557186643, 20857.582848342947, 21907.890460547915, 22326.33021009372, 22414.775852241593, 22579.87100644961, 34110.88336749507, 34440.82779586086, 34496.310073798886, 34561.827190863885, 34566.82716586401, 10200.285979981478, 10489.370637761758, 10615.25693201484, 10651.680449586525, 10792.56829726802, 8450.19723126302, 8589.828203436022, 8599.26906212979, 8769.649214031368, 8769.649214031495, 10302.227332950059, 10332.948335258427, 10376.948115259376, 10408.47957602117, 10433.765532684893, 3697.8520859165333, 4158.459400277945, 4384.517703052662, 4422.204003279377, 4437.092050418351, 1058.5025038232172, 1062.0, 1065.8827528971806, 1068.8183008888507, 1108.9944550277357, 21371.572568548854, 21520.0, 21853.235136770138, 22304.665430018562, 22426.663600046013, 892.4823602310644, 1084.5444913136503, 1119.1885882734953, 1124.2242852431796, 1128.249713940237, 9111.0, 11457.942710286396, 11651.00966417847, 11681.76636467266, 11730.941345293219, 545934.0, 643006.0, 660113.6994314998, 665059.0, 665316.0, 131046.0, 131858.34070829584, 132873.0, 135259.0, 139369.0, 1240.4727990603958, 1281.4425724809737, 1309.6791286134958, 1325.6950901292757, 1375.5185685010167, 18056.729149063038, 20055.799442005395, 20390.490237744176, 20641.69037464469, 20681.27615533449, 53507.46492535181, 62687.6865615669, 63554.0, 64341.0, 64669.67665161644, 90141.0, 90210.0, 91047.08952910386, 92065.61901571616, 92133.0, 11016.779664406507, 11480.655580332603, 11688.47401866935, 11749.47127379253, 11836.171467997501, 12717.491300347883, 12881.484740610646, 13172.143810652276, 13221.272829994443, 13310.534131305358, 11097.11223102166, 11244.943775281072, 11377.886221137682, 11466.484008219484, 11518.712032199262, 9036.864447033167, 9335.0, 9629.422234665943, 11076.833847492455, 11132.332060076422, 11522.76954460923, 11671.007964323127, 11676.649700508999, 11777.293362398283, 11860.22908510944, 11400.0, 11442.256253343507, 11651.941740291244, 11684.88315116838, 11795.882041179479, 9719.324157551164, 11131.0, 11235.438228088726, 11299.548018079513, 11424.428778560872], "value": 11624.730813796981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.572621802785, 1509.3585226278822, 1525.534711912867, 1558.4311726219933, 1634.7057529644667, 9465.526723663823, 10664.146868250531, 10897.291676041052, 11179.664610061702, 11190.552377904894, 10764.300320479164, 10955.068819150381, 10975.5061022254, 10995.395253261067, 11086.891310868908, 2295.529416469628, 2335.871527066011, 2344.531093781242, 2351.1888398502488, 2351.3651314145254, 2285.874276914769, 2310.8266879983985, 2320.8027317678016, 2324.883755812211, 2331.40549159964, 2277.6925115109416, 2302.7121609798774, 2316.837821352506, 2325.6744055832196, 2329.440934175794, 2270.6821045053707, 2278.134308962592, 2278.1912421090606, 2288.7711228877147, 2290.1640901071155, 2270.080617349976, 2304.0783686525356, 2326.197461875667, 2327.837051406402, 2330.242671131889, 11001.119910407147, 11067.612633557786, 11121.777564448746, 11126.721831954184, 11147.996680298731, 5558.86102847428, 5773.711314434261, 5783.306003279591, 5783.652980821122, 5797.768089276424, 3358.8656453742065, 3433.8111403872776, 3570.1431656402538, 3673.485712000322, 3675.72432067596, 1081.086481922774, 1127.4757237884342, 1128.9565885746824, 1230.5508489401393, 1243.434237421971, 13987.230702311368, 14209.431622735181, 14573.417063317454, 14950.252487375588, 15057.17185554794, 4612.884677883079, 4731.976340118277, 4747.667663263607, 4763.285507173951, 4766.713997160182, 11513.769724605629, 11543.942280288546, 11594.130440217017, 11603.477843497063, 11615.535378584931, 5603.551715862721, 5634.943650563442, 5678.5741069419955, 5692.31692196939, 5700.771969121189, 5040.722760248148, 5285.392179899284, 5298.523132918056, 5303.15149576066, 5314.335708036496, 3768.510093687803, 3876.496055512822, 3918.2163567286657, 3965.861194858166, 3969.3053715599654, 962.4225464721111, 981.9983616710915, 1054.5939813171983, 1066.3814987307344, 1068.3643232276784, 905.3164860530258, 912.5893347993297, 928.475411392561, 943.5942544705705, 952.7903861150536, 6279.874402511924, 6424.1327420798525, 6498.480121590262, 6508.9023664645065, 6625.635590042594, 868.6178081644057, 924.3852837862746, 941.4022095969109, 953.5661274120258, 972.1930797438109, 3466.4107101792756, 3554.289142171576, 3640.5995340512522, 3678.8528458861883, 3679.0802299425145, 11068.667939961813, 11279.041281491165, 11389.886101139049, 11440.0, 11481.770364592661, 11228.101751859831, 11236.325820450798, 11448.02691771192, 11495.540178392937, 11519.19365644397, 8788.428752131218, 8801.0, 8908.599113039929, 8910.599023044108, 8915.554222288989, 5835.620684655484, 5934.376890426443, 6010.038393857137, 6049.0926361046195, 6064.272287325549, 10298.588056477656, 10422.166226701695, 10424.374537527772, 10433.426161560883, 10476.371417714656, 8534.871976920227, 10335.328203666737, 11232.83150752723, 11762.82355764647, 11864.347460889734, 8809.955950220208, 8822.33832462555, 8934.910650893407, 9036.59932710431, 9081.68214112503, 9917.702468925943, 9924.1068303853, 10022.749431264278, 10023.097921187129, 10066.194704423482, 8840.602172902107, 8872.467651940904, 8875.556222188736, 8926.419782714103, 8982.910170898207, 7640.617969101411, 8623.180797824196, 8762.824743505222, 8820.823583528421, 8866.335024873031, 7668.463207575416, 7823.804404889924, 7878.960605196938, 7908.169642187488, 7955.5226686399, 5847.210626565359, 5872.911906321494, 5954.10688396744, 6022.819315420545, 6080.300765412034, 5734.3692193857805, 5841.299044114733, 6003.189569408074, 6034.818955431344, 6182.567220294536], "value": 4928.343785955169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.4322996745272, 370.7478914338252, 379.33616171699526, 380.5471488928175, 401.16957897152895, 6009.969950150243, 6033.306169790475, 6202.224721909762, 6204.193454850869, 6269.811905642838, 5280.419153893069, 5358.678479291236, 5718.713289509868, 6024.1566180734735, 6052.939470605293, 4682.742449165295, 4764.571188593026, 4959.256111583255, 4974.37820272466, 4991.575716064139, 4311.650150667878, 4953.628477864157, 4976.975115124419, 5009.12340340439, 5013.849584512471, 4972.03045406146, 4988.800447982077, 4995.850124496271, 5031.974840125794, 5034.949650503503, 4171.853985110507, 4797.9760101199445, 4932.728699921503, 4936.950630493721, 4944.950550494521, 4542.977285113587, 4544.295634176675, 4552.658550608718, 4559.316102584606, 4580.38164847748, 4902.534259245402, 5337.572994160458, 5393.865153371158, 5966.463018328328, 5994.291626886316, 4818.927716084261, 4970.0, 5527.972360138214, 5791.971040144815, 5808.273965754281, 6516.478681705452, 6525.347465253437, 6540.247871494791, 6549.738010479575, 6587.143671322745, 4399.956000439986, 4459.888502787424, 4577.725336479822, 4649.302604609302, 5097.923531146998, 6550.508711846583, 6711.993201019837, 6751.527393082535, 6872.0, 6980.930190698079, 5326.414094449608, 5332.960072785847, 5338.706371149624, 5345.545628621533, 5356.437574054668, 4503.279475283939, 5331.280277162611, 5375.6023433906685, 5443.2107344434835, 5520.227168196456, 5510.283663123768, 5795.768169273182, 5823.737931793079, 5825.825225243249, 5856.765729370777, 6575.967120164464, 6594.868102638016, 6596.406323430914, 6603.438707709898, 6605.70274337656, 1709.5042437693178, 1745.816689247636, 1828.4057681253646, 1837.6875931091747, 1858.7583614130076, 1102.4818335382517, 1307.1568838099372, 1326.8460946725313, 1354.3160703844496, 1368.5346982026158, 5292.973535132377, 5326.973365133227, 5343.225232341367, 5904.143899134601, 6206.410391012847, 5935.436133567304, 6718.0, 6729.880087772336, 6757.695903684346, 6764.898526522106, 5622.943770562324, 5885.911106445321, 6001.06983417571, 6191.876162476725, 6192.566520343623, 3540.3804334241404, 3557.3952428087287, 3559.561700235347, 3559.715222782223, 3566.982165089158, 5785.595008349375, 6508.0, 6873.347032031949, 7015.964920175366, 7079.469039822034, 5816.5928385013485, 6869.553479023847, 6883.827904302432, 6935.965320173367, 6987.615681137485, 6063.181470501514, 6815.045893574904, 6917.443575195541, 7052.33002864727, 7056.364927156478, 6719.361660642231, 6747.561408508432, 6756.69594868242, 6760.966195168992, 6771.593704377753, 4875.244911831809, 6307.716152773044, 6491.707873145814, 6674.599524028574, 6679.899801503078, 6646.302138275605, 6881.793546193622, 6953.721851125898, 7000.054992575937, 7037.612931288828, 5110.361204849394, 5228.268042473981, 5253.842384728464, 5255.08649412785, 5258.605604579749, 5604.607677462702, 6671.432928201158, 6698.933010670026, 6699.497537684596, 6777.728890844508, 6305.148804911279, 6358.968205158944, 6511.186101737283, 6533.640649764308, 6549.574277671936, 4568.611668008257, 6094.847628809138, 6451.6774161290805, 6455.160829092188, 6481.157449531719, 6381.904271436025, 6403.167588213689, 6416.871662566629, 6437.227532695919, 6485.837854053686, 5836.883262334814, 6026.0, 6238.157848690369, 6444.355564443518, 6610.669466526559, 6163.445289923749, 6416.165898433173, 6438.58149220318, 6440.32376600469, 6457.547971642128], "value": 4970.339904539066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.3627499463017, 722.1045903080183, 761.6161414771764, 787.2953706432743, 829.4484168028262, 11321.377324247163, 15694.450694225705, 15993.920030399846, 17067.0, 18317.542061448472, 16793.83206167938, 17025.063621500813, 17364.958102513858, 17420.39028633998, 17596.64806703865, 965.5510187762688, 991.8413053911376, 993.0466751918167, 996.6511720897701, 996.8155891160159, 967.8693376394167, 988.5255077562774, 999.6551189839494, 1001.2440607341447, 1007.1992765751208, 875.5315905990296, 1003.3127307794184, 1009.4599389326712, 1030.917526597874, 1031.9845202321972, 1005.6027868991744, 1015.0610685116275, 1016.9694909152737, 1018.5060245780865, 1019.7909428567126, 746.8580969615756, 754.766022533011, 758.9886151707728, 761.3338328962158, 763.2291385700482, 16204.270807813558, 16569.4200702976, 16619.0, 16709.164541772985, 16895.746563801553, 14369.353379097965, 14775.187364694937, 14779.113253204734, 14874.256287185597, 14879.256037198096, 11681.766364672743, 11691.824622630667, 11723.941380293129, 11968.222065565711, 12011.331444759258, 3690.8195324036983, 3780.8676696315497, 3784.4967027497823, 3791.4312853071983, 3914.5302563692253, 87996.0, 99828.00171998332, 102493.0, 110885.44557277243, 111121.0, 10608.257421980541, 11232.775344493064, 11309.773804523864, 11318.877068675438, 11419.429028548706, 23840.761592384202, 24642.0, 24872.626910596355, 24970.750292497207, 25045.24864254075, 7889.8816517752275, 7961.84553239777, 8071.2735853772265, 8105.432619716563, 8118.851237205698, 3373.7132343751055, 4083.284211367641, 4109.773962432035, 4162.334026555797, 4190.937135942963, 2869.26833657416, 3582.5342705448124, 3608.7654302470255, 3613.9277214456088, 3620.022593899685, 634.7968650031945, 641.6599202422724, 644.6067898581862, 649.7271146118642, 655.4035827397046, 503.14967704579146, 558.5699011760921, 581.764385423904, 595.2440400691098, 610.8106486989047, 10003.749906252257, 10060.69817905464, 10208.744781380525, 10561.419121948367, 10585.629502967358, 634.7048622390611, 634.9301427095359, 665.6638397609177, 679.6465837764433, 691.3432239372617, 4728.645351598644, 4902.828401005948, 4905.215165573562, 4913.938777646502, 4935.851924442272, 98575.0, 100162.0, 100450.0, 102013.0, 103486.0, 81568.0, 82075.0, 83696.0, 86378.56810716032, 86382.56808715916, 47687.523124769, 60915.69542152261, 69516.0, 70040.64979675069, 70789.0, 9317.301202409712, 9320.62097539066, 9352.766180845534, 9386.765330866783, 9401.153896149106, 45042.77478612586, 45084.32373514334, 49490.0, 50768.73078173075, 51277.7436112832, 61027.0, 65215.0, 65444.691106178565, 65590.67204663946, 66787.0, 11132.279132696993, 13325.200487970753, 13362.265075420557, 13536.0, 14191.787123192953, 11833.644990650295, 14568.125912445286, 14682.41270349174, 14947.626309342353, 15329.0, 13224.47102115943, 13245.602631921058, 13597.592072237849, 13685.726285474433, 13746.381412836647, 13306.534271300457, 13444.327783610976, 13739.862601374258, 14041.578752637437, 14176.645583860485, 12931.935340323238, 13206.537771177962, 13359.666008349868, 13822.65443363924, 14098.78851817203, 9218.632982175737, 9927.602895884083, 10737.624183153552, 11231.775364492501, 11699.941500292443, 7772.183920688246, 9658.179054780141, 10398.480075996018, 10452.94773526158, 11389.715257118638], "value": 7594.568327504093, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1592.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 180.0, 132.0, 145.0, 164.0, 118.0, 118.0, 120.0, 128.0, 151.0], "value": 141.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 126.0, 193.0, 120.0, 117.0, 117.0, 106.0, 173.0, 108.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 205.0, 211.0, 227.0, 222.0, 200.0, 195.0, 196.0, 200.0, 193.0], "value": 204.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 63.0, 60.0, 88.0, 58.0, 61.0, 57.0, 52.0, 87.0, 84.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 106.0, 124.0, 71.0, 74.0, 69.0, 87.0, 73.0, 72.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 104.0, 108.0, 89.0, 91.0, 84.0, 136.0, 88.0, 89.0, 108.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 97.0, 105.0, 95.0, 154.0, 152.0, 127.0, 153.0, 131.0, 96.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 113.0, 109.0, 88.0, 126.0, 129.0, 91.0, 116.0, 88.0, 76.0], "value": 102.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 80.0, 107.0, 106.0, 79.0, 68.0, 66.0, 68.0], "value": 77.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 61.0, 60.0, 69.0, 58.0, 56.0, 58.0, 58.0, 58.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 90.0, 90.0, 82.0, 84.0, 86.0, 86.0, 83.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 167.0, 148.0, 149.0, 165.0, 158.0, 158.0, 154.0, 190.0, 131.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 188.0, 180.0, 185.0, 186.0, 189.0, 183.0, 187.0, 187.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 69.0, 67.0, 69.0, 69.0, 68.0, 68.0, 70.0], "value": 68.7, "unit": "score"}]}]} 19:21:33 INFO - Return code: 0 19:21:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:21:33 ERROR - # TBPL SUCCESS # 19:21:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:21:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:21:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:21:33 INFO - 2017-03-14 19:21:33.434 screenresolution[1656:10353] starting screenresolution argv=screenresolution get 19:21:33 INFO - 2017-03-14 19:21:33.437 screenresolution[1656:10353] Display 0: 1600x1200x32@50 19:21:33 INFO - 2017-03-14 19:21:33.443 screenresolution[1657:10356] starting screenresolution argv=screenresolution list 19:21:33 INFO - Available Modes on Display 0 19:21:33 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 19:21:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:21:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:21:33 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 19:21:33 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 19:21:33 INFO - Graphics/Displays: 19:21:33 INFO - Intel Iris: 19:21:33 INFO - Chipset Model: Intel Iris 19:21:33 INFO - Type: GPU 19:21:33 INFO - Bus: Built-In 19:21:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:21:33 INFO - Vendor: Intel (0x8086) 19:21:33 INFO - Device ID: 0x0a2e 19:21:33 INFO - Revision ID: 0x0009 19:21:33 INFO - Displays: 19:21:33 INFO - Display: 19:21:33 INFO - Resolution: 1600 x 1200 @ 50 Hz 19:21:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:21:33 INFO - Display Serial Number: 1600x1200 50 19:21:33 INFO - Main Display: Yes 19:21:33 INFO - Mirror: Off 19:21:33 INFO - Online: Yes 19:21:33 INFO - Rotation: Supported 19:21:33 INFO - Return code: 0 19:21:33 INFO - Running post-action listener: _resource_record_post_action 19:21:33 INFO - [mozharness: 2017-03-15 02:21:33.680851Z] Finished run-tests step (success) 19:21:33 INFO - Running post-run listener: _resource_record_post_run 19:21:33 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 41388544; Write bytes: 517178368; Read time: 406; Write time: 1761 19:21:33 INFO - TinderboxPrint: CPU usage
54.1% 19:21:33 INFO - TinderboxPrint: I/O read bytes / time
41,388,544 / 406 19:21:33 INFO - TinderboxPrint: I/O write bytes / time
517,178,368 / 1,761 19:21:33 INFO - TinderboxPrint: CPU idle
2,202.8 (45.9%) 19:21:33 INFO - TinderboxPrint: CPU system
958.2 (20.0%) 19:21:33 INFO - TinderboxPrint: CPU user
1,640.7 (34.2%) 19:21:33 INFO - TinderboxPrint: Swap in / out
264,966,144 / 0 19:21:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203815424; Write bytes: 202892800; Read time: 15553; Write time: 440 19:21:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 39504384; Write bytes: 303496704; Read time: 385; Write time: 1287 19:21:34 INFO - Running post-run listener: _upload_blobber_files 19:21:34 INFO - Blob upload gear active. 19:21:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:21:34 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 19:21:34 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'] 19:21:34 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 19:21:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:35 INFO - (blobuploader) - INFO - Done attempting. 19:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:36 INFO - (blobuploader) - INFO - Done attempting. 19:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:37 INFO - (blobuploader) - INFO - Done attempting. 19:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:21:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:39 INFO - (blobuploader) - INFO - Done attempting. 19:21:39 INFO - (blobuploader) - INFO - Iteration through files over. 19:21:39 INFO - Return code: 0 19:21:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:21:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:21:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39730b6c321f9494f2c9653519a6cbe1c11649600c95b1a2bbcb8be95598ede6e9985c5c646b1080b064291053f9fd1e58257444923c0735e91a9b61b3186e1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa6646ec9040bcfa0f76f1aaab882c520b82673db6d208104e3ed1cf07c14ea9236ff21883bec8e0e8ae71b90d80200f0fe7352dd1522c976dc2859bf804eb9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb374b7f7665cbdc41192c70a4cd60d0f4f645497a9163b9b0f10178243e77773642079a3c7b2077472fec372d4f07a6bc0de5be650a7b515ab6d56526f75d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8d2ffca401b650d3d2138d9813f0d7d25e2a70a82ab69b49e46342557d1ce86e9795e3b1111a7c07f8fec1dae61416e86c78916555c0c29960553d18e3036f6"} 19:21:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:21:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:21:39 INFO - Contents: 19:21:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39730b6c321f9494f2c9653519a6cbe1c11649600c95b1a2bbcb8be95598ede6e9985c5c646b1080b064291053f9fd1e58257444923c0735e91a9b61b3186e1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa6646ec9040bcfa0f76f1aaab882c520b82673db6d208104e3ed1cf07c14ea9236ff21883bec8e0e8ae71b90d80200f0fe7352dd1522c976dc2859bf804eb9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb374b7f7665cbdc41192c70a4cd60d0f4f645497a9163b9b0f10178243e77773642079a3c7b2077472fec372d4f07a6bc0de5be650a7b515ab6d56526f75d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8d2ffca401b650d3d2138d9813f0d7d25e2a70a82ab69b49e46342557d1ce86e9795e3b1111a7c07f8fec1dae61416e86c78916555c0c29960553d18e3036f6"} 19:21:39 INFO - Running post-run listener: copy_logs_to_upload_dir 19:21:39 INFO - Copying logs to upload dir... 19:21:39 INFO - mkdir: /builds/slave/test/build/upload/logs 19:21:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.197707 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-14 19:21:39.594608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 19:21:39.598170) ========= 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.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/39730b6c321f9494f2c9653519a6cbe1c11649600c95b1a2bbcb8be95598ede6e9985c5c646b1080b064291053f9fd1e58257444923c0735e91a9b61b3186e1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa6646ec9040bcfa0f76f1aaab882c520b82673db6d208104e3ed1cf07c14ea9236ff21883bec8e0e8ae71b90d80200f0fe7352dd1522c976dc2859bf804eb9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb374b7f7665cbdc41192c70a4cd60d0f4f645497a9163b9b0f10178243e77773642079a3c7b2077472fec372d4f07a6bc0de5be650a7b515ab6d56526f75d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8d2ffca401b650d3d2138d9813f0d7d25e2a70a82ab69b49e46342557d1ce86e9795e3b1111a7c07f8fec1dae61416e86c78916555c0c29960553d18e3036f6"} build_url:https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010723 build_url: 'https://queue.taskcluster.net/v1/task/Req_UzsvRAexDvcn_uHc3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39730b6c321f9494f2c9653519a6cbe1c11649600c95b1a2bbcb8be95598ede6e9985c5c646b1080b064291053f9fd1e58257444923c0735e91a9b61b3186e1a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa6646ec9040bcfa0f76f1aaab882c520b82673db6d208104e3ed1cf07c14ea9236ff21883bec8e0e8ae71b90d80200f0fe7352dd1522c976dc2859bf804eb9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb374b7f7665cbdc41192c70a4cd60d0f4f645497a9163b9b0f10178243e77773642079a3c7b2077472fec372d4f07a6bc0de5be650a7b515ab6d56526f75d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8d2ffca401b650d3d2138d9813f0d7d25e2a70a82ab69b49e46342557d1ce86e9795e3b1111a7c07f8fec1dae61416e86c78916555c0c29960553d18e3036f6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 19:21:39.636105) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:21:39.636471) ========= 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.nIib8PgHbB/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.3ScvOPpK41/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005190 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 19:21:39.685534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 19:21:39.685894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 19:21:39.686220) ========= ========= Total master_lag: 0.18 =========