builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0145 starttime: 1489664544.94 results: success (0) buildid: 20170316030211 builduid: abdacb50e35145d2bf5bb930f9980450 revision: ff04d410e74b69acfab17ef7e73e7397602d5a68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:24.945266) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:24.945728) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:24.946026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:24.975008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:24.975375) ========= 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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-16 04:42:25-- 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% 11.0M=0.001s 2017-03-16 04:42:25 (11.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.225870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:25.225378) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:25.225691) ========= 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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.028340 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:25.275736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 04:42:25.276052) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff04d410e74b69acfab17ef7e73e7397602d5a68 --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 ff04d410e74b69acfab17ef7e73e7397602d5a68 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-16 04:42:25,379 truncating revision to first 12 chars 2017-03-16 04:42:25,379 Setting DEBUG logging. 2017-03-16 04:42:25,379 attempt 1/10 2017-03-16 04:42:25,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff04d410e74b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 04:42:26,553 unpacking tar archive at: mozilla-central-ff04d410e74b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.444283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 04:42:26.743533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:26.743883) ========= script_repo_revision: ff04d410e74b69acfab17ef7e73e7397602d5a68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:26.744293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:26.744565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 04:42:26.764199) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-16 04:42:26.764492) ========= /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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:42:26 INFO - MultiFileLogger online at 20170316 04:42:26 in /builds/slave/test 04:42:26 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 04:42:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:26 INFO - {'append_to_log': False, 04:42:26 INFO - 'base_work_dir': '/builds/slave/test', 04:42:26 INFO - 'blob_upload_branch': 'Firefox', 04:42:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:26 INFO - 'branch': 'Firefox', 04:42:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:26 INFO - 'config_files': ('talos/mac_config.py',), 04:42:26 INFO - 'default_actions': ('clobber', 04:42:26 INFO - 'read-buildbot-config', 04:42:26 INFO - 'download-and-extract', 04:42:26 INFO - 'populate-webroot', 04:42:26 INFO - 'create-virtualenv', 04:42:26 INFO - 'install', 04:42:26 INFO - 'run-tests'), 04:42:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:26 INFO - 'download_minidump_stackwalk': True, 04:42:26 INFO - 'download_symbols': 'ondemand', 04:42:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:26 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:26 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:26 INFO - 'gecko_profile': False, 04:42:26 INFO - 'gecko_profile_interval': 0, 04:42:26 INFO - 'installer_path': 'installer.exe', 04:42:26 INFO - 'log_level': 'info', 04:42:26 INFO - 'log_name': 'talos', 04:42:26 INFO - 'log_to_console': True, 04:42:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:42:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:42:26 INFO - 'opt_config_files': (), 04:42:26 INFO - 'pip_index': False, 04:42:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:26 INFO - 'cmd': ('bash', 04:42:26 INFO - '-c', 04:42:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:26 INFO - 'enabled': True, 04:42:26 INFO - 'halt_on_failure': False, 04:42:26 INFO - 'name': 'check_screen_resolution'},), 04:42:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:26 INFO - 'cmd': ('bash', 04:42:26 INFO - '-c', 04:42:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:26 INFO - 'enabled': True, 04:42:26 INFO - 'halt_on_failure': False, 04:42:26 INFO - 'name': 'check_screen_resolution'},), 04:42:26 INFO - 'run_cmd_checks_enabled': True, 04:42:26 INFO - 'suite': 'dromaeojs-e10s', 04:42:26 INFO - 'system_bits': '32', 04:42:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:26 INFO - 'title': 't-yosemite-r7-0145', 04:42:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:26 INFO - 'use_talos_json': True, 04:42:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:26 INFO - 'work_dir': 'build'} 04:42:26 INFO - [mozharness: 2017-03-16 11:42:26.980464Z] Running clobber step. 04:42:26 INFO - Running pre-action listener: _resource_record_pre_action 04:42:26 INFO - Running main action method: clobber 04:42:26 INFO - rmtree: /builds/slave/test/build 04:42:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:31 INFO - Running post-action listener: _resource_record_post_action 04:42:31 INFO - [mozharness: 2017-03-16 11:42:31.490986Z] Finished clobber step (success) 04:42:31 INFO - [mozharness: 2017-03-16 11:42:31.491150Z] Running read-buildbot-config step. 04:42:31 INFO - Running pre-action listener: _resource_record_pre_action 04:42:31 INFO - Running main action method: read_buildbot_config 04:42:31 INFO - Using buildbot properties: 04:42:31 INFO - { 04:42:31 INFO - "project": "", 04:42:31 INFO - "product": "firefox", 04:42:31 INFO - "script_repo_revision": "production", 04:42:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:42:31 INFO - "repository": "", 04:42:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:42:31 INFO - "buildid": "20170316030211", 04:42:31 INFO - "stage_platform": "macosx64", 04:42:31 INFO - "basedir": "/builds/slave/test", 04:42:31 INFO - "nightly_build": "True", 04:42:31 INFO - "buildnumber": 126, 04:42:31 INFO - "slavename": "t-yosemite-r7-0145", 04:42:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:42:31 INFO - "platform": "yosemite_r7", 04:42:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:42:31 INFO - "branch": "mozilla-central", 04:42:31 INFO - "repo_path": "mozilla-central", 04:42:31 INFO - "revision": "ff04d410e74b69acfab17ef7e73e7397602d5a68", 04:42:31 INFO - "pgo_build": "False", 04:42:31 INFO - "builduid": "abdacb50e35145d2bf5bb930f9980450", 04:42:31 INFO - "slavebuilddir": "test" 04:42:31 INFO - } 04:42:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:42:31 INFO - Running post-action listener: _resource_record_post_action 04:42:31 INFO - [mozharness: 2017-03-16 11:42:31.494566Z] Finished read-buildbot-config step (success) 04:42:31 INFO - [mozharness: 2017-03-16 11:42:31.494700Z] Running download-and-extract step. 04:42:31 INFO - Running pre-action listener: _resource_record_pre_action 04:42:31 INFO - Running main action method: download_and_extract 04:42:31 INFO - mkdir: /builds/slave/test/build/tests 04:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:31 INFO - trying https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:42:31 INFO - Downloading https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/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 04:42:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/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 04:42:32 INFO - Downloaded 1199 bytes. 04:42:32 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:42:32 INFO - Using the following test package requirements: 04:42:32 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:42:32 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:42:32 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:42:32 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'jsshell-mac.zip'], 04:42:32 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:42:32 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:42:32 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:42:32 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:42:32 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:42:32 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:42:32 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:42:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:42:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:42:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:32 INFO - Fetch https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:42:34 INFO - Expected file size: 52472578 04:42:34 INFO - Obtained file size: 52472578 04:42:50 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 04:42:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:42:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:50 INFO - Fetch https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:42:52 INFO - Expected file size: 52472578 04:42:52 INFO - Obtained file size: 52472578 04:43:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:43:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:43:07 INFO - Fetch https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:43:09 INFO - Expected file size: 14404452 04:43:09 INFO - Obtained file size: 14404452 04:43: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')]} 04:43:09 INFO - trying https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:43:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:43:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/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 04:43:11 INFO - Downloaded 75173232 bytes. 04:43:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:43:11 INFO - mkdir: /builds/slave/test/properties 04:43:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:43:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:43:11 INFO - Contents: 04:43:11 INFO - build_url:https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:43:12 INFO - Running post-action listener: _resource_record_post_action 04:43:12 INFO - Running post-action listener: set_extra_try_arguments 04:43:12 INFO - [mozharness: 2017-03-16 11:43:12.145966Z] Finished download-and-extract step (success) 04:43:12 INFO - [mozharness: 2017-03-16 11:43:12.146120Z] Running populate-webroot step. 04:43:12 INFO - Running pre-action listener: _resource_record_pre_action 04:43:12 INFO - Running main action method: populate_webroot 04:43:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:43:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:43:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:43:12 INFO - '--tppagecycles', 04:43:12 INFO - '7'], 04:43:12 INFO - 'tests': ['tsvgm']}}, 04:43:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:43:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:43:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:43:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:43:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['damp', 'tps']}, 04:43:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'tests': ['damp', 'tps']}, 04:43:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['dromaeo_dom']}, 04:43:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:43:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['basic_compositor_video']}, 04:43:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:43:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['a11yr', 04:43:12 INFO - 'ts_paint', 04:43:12 INFO - 'tpaint', 04:43:12 INFO - 'sessionrestore', 04:43:12 INFO - 'sessionrestore_no_auto_restore', 04:43:12 INFO - 'tabpaint']}, 04:43:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['tsvgx', 04:43:12 INFO - 'tsvgr_opacity', 04:43:12 INFO - 'tart', 04:43:12 INFO - 'tscrollx', 04:43:12 INFO - 'cart', 04:43:12 INFO - 'tsvg_static']}, 04:43:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:43:12 INFO - 'tsvgr_opacity', 04:43:12 INFO - 'tart', 04:43:12 INFO - 'tscrollx', 04:43:12 INFO - 'cart', 04:43:12 INFO - 'tsvg_static']}, 04:43:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'talos_options': ['--disable-e10s'], 04:43:12 INFO - 'tests': ['tp5o']}, 04:43:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:43:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'talos_options': ['--disable-e10s', 04:43:12 INFO - '--xperf_path', 04:43:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:12 INFO - 'tests': ['tp5n']}, 04:43:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:43:12 INFO - 'talos_options': ['--xperf_path', 04:43:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:12 INFO - 'tests': ['tp5n']}}, 04:43:12 INFO - 'talos.zip': {'path': '', 04:43:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:43:12 INFO - Running post-action listener: _resource_record_post_action 04:43:12 INFO - [mozharness: 2017-03-16 11:43:12.164309Z] Finished populate-webroot step (success) 04:43:12 INFO - [mozharness: 2017-03-16 11:43:12.164421Z] Running create-virtualenv step. 04:43:12 INFO - Running pre-action listener: _resource_record_pre_action 04:43:12 INFO - Running main action method: create_virtualenv 04:43:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:43:12 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 04:43:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:43:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:43:12 INFO - Using real prefix '/tools/python27' 04:43:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:43:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:43:15 INFO - Installing pip.................done. 04:43:15 INFO - Return code: 0 04:43:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:43:16 INFO - Reading from file tmpfile_stdout 04:43:16 INFO - Output received: 04:43:16 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) 04:43:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43: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', 'pip>=1.5'] in /builds/slave/test/build 04:43: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 pip>=1.5 04:43:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:16 INFO - 'HOME': '/Users/cltbld', 04:43:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:16 INFO - 'LOGNAME': 'cltbld', 04:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:16 INFO - 'MOZ_NO_REMOTE': '1', 04:43:16 INFO - 'NO_EM_RESTART': '1', 04:43:16 INFO - 'PAGER': '/bin/cat', 04:43:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:16 INFO - 'PWD': '/builds/slave/test', 04:43:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:16 INFO - 'SHELL': '/bin/bash', 04:43:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:16 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:16 INFO - 'USER': 'cltbld', 04:43:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:16 INFO - 'XPC_FLAGS': '0x0', 04:43:16 INFO - 'XPC_SERVICE_NAME': '0', 04:43:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:16 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 04:43:16 INFO - Cleaning up... 04:43:16 INFO - Return code: 0 04:43:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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', '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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:43: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 psutil>=3.1.1 04:43:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:16 INFO - 'HOME': '/Users/cltbld', 04:43:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:16 INFO - 'LOGNAME': 'cltbld', 04:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:16 INFO - 'MOZ_NO_REMOTE': '1', 04:43:16 INFO - 'NO_EM_RESTART': '1', 04:43:16 INFO - 'PAGER': '/bin/cat', 04:43:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:16 INFO - 'PWD': '/builds/slave/test', 04:43:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:16 INFO - 'SHELL': '/bin/bash', 04:43:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:16 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:16 INFO - 'USER': 'cltbld', 04:43:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:16 INFO - 'XPC_FLAGS': '0x0', 04:43:16 INFO - 'XPC_SERVICE_NAME': '0', 04:43:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:16 INFO - Downloading/unpacking psutil>=3.1.1 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:43:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:20 INFO - Installing collected packages: psutil 04:43:20 INFO - Running setup.py install for psutil 04:43:20 INFO - building 'psutil._psutil_osx' extension 04:43:20 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 04:43:20 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 04:43:20 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 04:43:21 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 04:43:21 INFO - building 'psutil._psutil_posix' extension 04:43:21 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 04:43:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:43:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:43:21 INFO - ^ 04:43:21 INFO - 1 warning generated. 04:43:21 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 04:43:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:21 INFO - Successfully installed psutil 04:43:21 INFO - Cleaning up... 04:43:21 INFO - Return code: 0 04:43:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:43:21 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')]} 04:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:21 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')]} 04:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:22 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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:22 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 04:43:22 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 04:43:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:22 INFO - 'HOME': '/Users/cltbld', 04:43:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:22 INFO - 'LOGNAME': 'cltbld', 04:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:22 INFO - 'MOZ_NO_REMOTE': '1', 04:43:22 INFO - 'NO_EM_RESTART': '1', 04:43:22 INFO - 'PAGER': '/bin/cat', 04:43:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:22 INFO - 'PWD': '/builds/slave/test', 04:43:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:22 INFO - 'SHELL': '/bin/bash', 04:43:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:22 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:22 INFO - 'USER': 'cltbld', 04:43:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:22 INFO - 'XPC_FLAGS': '0x0', 04:43:22 INFO - 'XPC_SERVICE_NAME': '0', 04:43:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:43:25 INFO - Installing collected packages: mozsystemmonitor 04:43:25 INFO - Running setup.py install for mozsystemmonitor 04:43:25 INFO - Successfully installed mozsystemmonitor 04:43:25 INFO - Cleaning up... 04:43:25 INFO - Return code: 0 04:43:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:43:25 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')]} 04:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:25 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')]} 04:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:25 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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:25 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 04:43:25 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 04:43:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:25 INFO - 'HOME': '/Users/cltbld', 04:43:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:25 INFO - 'LOGNAME': 'cltbld', 04:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:25 INFO - 'MOZ_NO_REMOTE': '1', 04:43:25 INFO - 'NO_EM_RESTART': '1', 04:43:25 INFO - 'PAGER': '/bin/cat', 04:43:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:25 INFO - 'PWD': '/builds/slave/test', 04:43:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:25 INFO - 'SHELL': '/bin/bash', 04:43:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:25 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:25 INFO - 'USER': 'cltbld', 04:43:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:25 INFO - 'XPC_FLAGS': '0x0', 04:43:25 INFO - 'XPC_SERVICE_NAME': '0', 04:43:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:26 INFO - Downloading/unpacking jsonschema==2.5.1 04:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:43:28 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 04:43:28 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 04:43:28 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 04:43:28 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 04:43:28 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 04:43:28 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 04:43:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:29 INFO - warning: no files found matching '*.txt' 04:43:29 INFO - no previously-included directories found matching 'build' 04:43:29 INFO - no previously-included directories found matching 'dist' 04:43:29 INFO - no previously-included directories found matching '.git*' 04:43:29 INFO - Installing collected packages: jsonschema, functools32 04:43:29 INFO - Running setup.py install for functools32 04:43:29 INFO - warning: no files found matching '*.txt' 04:43:29 INFO - no previously-included directories found matching 'build' 04:43:29 INFO - no previously-included directories found matching 'dist' 04:43:29 INFO - no previously-included directories found matching '.git*' 04:43:29 INFO - Successfully installed jsonschema functools32 04:43:29 INFO - Cleaning up... 04:43:29 INFO - Return code: 0 04:43:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:29 INFO - 'HOME': '/Users/cltbld', 04:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:29 INFO - 'LOGNAME': 'cltbld', 04:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:29 INFO - 'MOZ_NO_REMOTE': '1', 04:43:29 INFO - 'NO_EM_RESTART': '1', 04:43:29 INFO - 'PAGER': '/bin/cat', 04:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:29 INFO - 'PWD': '/builds/slave/test', 04:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:29 INFO - 'SHELL': '/bin/bash', 04:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:29 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:29 INFO - 'USER': 'cltbld', 04:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:29 INFO - 'XPC_FLAGS': '0x0', 04:43:29 INFO - 'XPC_SERVICE_NAME': '0', 04:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:43:29 INFO - Cleaning up... 04:43:29 INFO - Return code: 0 04:43:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:29 INFO - 'HOME': '/Users/cltbld', 04:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:29 INFO - 'LOGNAME': 'cltbld', 04:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:29 INFO - 'MOZ_NO_REMOTE': '1', 04:43:29 INFO - 'NO_EM_RESTART': '1', 04:43:29 INFO - 'PAGER': '/bin/cat', 04:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:29 INFO - 'PWD': '/builds/slave/test', 04:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:29 INFO - 'SHELL': '/bin/bash', 04:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:29 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:29 INFO - 'USER': 'cltbld', 04:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:29 INFO - 'XPC_FLAGS': '0x0', 04:43:29 INFO - 'XPC_SERVICE_NAME': '0', 04:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:30 INFO - Downloading/unpacking blobuploader==1.2.4 04:43:30 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 04:43:30 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 04:43:30 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 04:43:30 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 04:43:30 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 04:43:30 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 04:43:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:43:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:43:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:43:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:33 INFO - Downloading docopt-0.6.1.tar.gz 04:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:43:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:43:33 INFO - Running setup.py install for blobuploader 04:43:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:43:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:43:34 INFO - Running setup.py install for requests 04:43:34 INFO - Running setup.py install for docopt 04:43:34 INFO - Successfully installed blobuploader requests docopt 04:43:34 INFO - Cleaning up... 04:43:34 INFO - Return code: 0 04:43:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:34 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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:34 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 04:43:34 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 04:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:34 INFO - 'HOME': '/Users/cltbld', 04:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:34 INFO - 'LOGNAME': 'cltbld', 04:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:34 INFO - 'MOZ_NO_REMOTE': '1', 04:43:34 INFO - 'NO_EM_RESTART': '1', 04:43:34 INFO - 'PAGER': '/bin/cat', 04:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:34 INFO - 'PWD': '/builds/slave/test', 04:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:34 INFO - 'SHELL': '/bin/bash', 04:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:34 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:34 INFO - 'USER': 'cltbld', 04:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:34 INFO - 'XPC_FLAGS': '0x0', 04:43:34 INFO - 'XPC_SERVICE_NAME': '0', 04:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-4Bmtfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-XRQNjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-nCBxdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-irCHcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-aZgJ2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-lbaSLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-GA_SmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-mXzf1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-oQp2Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:35 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-LGaQnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ow3wfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-zQ2xgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-funm2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-FrsJUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-dpyFFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-3WI1MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:36 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-5Z29IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:43:36 INFO - Running setup.py install for manifestparser 04:43:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:43:36 INFO - Running setup.py install for mozcrash 04:43:37 INFO - Running setup.py install for mozdebug 04:43:37 INFO - Running setup.py install for mozdevice 04:43:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Running setup.py install for mozfile 04:43:37 INFO - Running setup.py install for mozhttpd 04:43:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Running setup.py install for mozinfo 04:43:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Running setup.py install for mozInstall 04:43:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:43:37 INFO - Running setup.py install for mozleak 04:43:38 INFO - Running setup.py install for mozlog 04:43:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Running setup.py install for moznetwork 04:43:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Running setup.py install for mozprocess 04:43:38 INFO - Running setup.py install for mozprofile 04:43:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Running setup.py install for mozrunner 04:43:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:43:38 INFO - Running setup.py install for mozscreenshot 04:43:39 INFO - Running setup.py install for moztest 04:43:39 INFO - Running setup.py install for mozversion 04:43:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:43:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:43:39 INFO - Cleaning up... 04:43:39 INFO - Return code: 0 04:43:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:39 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 04:43:39 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 04:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:39 INFO - 'HOME': '/Users/cltbld', 04:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:39 INFO - 'LOGNAME': 'cltbld', 04:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:39 INFO - 'MOZ_NO_REMOTE': '1', 04:43:39 INFO - 'NO_EM_RESTART': '1', 04:43:39 INFO - 'PAGER': '/bin/cat', 04:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:39 INFO - 'PWD': '/builds/slave/test', 04:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:39 INFO - 'SHELL': '/bin/bash', 04:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:39 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:39 INFO - 'USER': 'cltbld', 04:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:39 INFO - 'XPC_FLAGS': '0x0', 04:43:39 INFO - 'XPC_SERVICE_NAME': '0', 04:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:39 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-9ODr5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:39 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)) 04:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:39 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-uW_nzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:39 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)) 04:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:39 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YbFPKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:39 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)) 04:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:39 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-yHemqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-seUZgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-wrZiWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-wij8rS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-m940j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-odgBbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ChtflP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-hH5V5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Srnu12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:40 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)) 04:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-UWR5xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:41 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)) 04:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-C5RA32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:41 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)) 04:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-uQx69y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:41 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)) 04:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Qdu9BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:41 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)) 04:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-kfNGyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:41 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)) 04:43:41 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)) 04:43:41 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)) 04:43:41 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)) 04:43:41 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)) 04:43:41 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)) 04:43:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:44 INFO - Downloading blessings-1.6.tar.gz 04:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:43:44 INFO - Installing collected packages: blessings 04:43:44 INFO - Running setup.py install for blessings 04:43:44 INFO - Successfully installed blessings 04:43:44 INFO - Cleaning up... 04:43:44 INFO - Return code: 0 04:43:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:43:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:44 INFO - Reading from file tmpfile_stdout 04:43:44 INFO - Current package versions: 04:43:44 INFO - blessings == 1.6 04:43:44 INFO - blobuploader == 1.2.4 04:43:44 INFO - docopt == 0.6.1 04:43:44 INFO - functools32 == 3.2.3-2 04:43:44 INFO - jsonschema == 2.5.1 04:43:44 INFO - manifestparser == 1.1 04:43:44 INFO - mozInstall == 1.12 04:43:44 INFO - mozcrash == 1.0 04:43:44 INFO - mozdebug == 0.1 04:43:44 INFO - mozdevice == 0.49 04:43:44 INFO - mozfile == 1.2 04:43:44 INFO - mozhttpd == 0.7 04:43:44 INFO - mozinfo == 0.9 04:43:44 INFO - mozleak == 0.1 04:43:44 INFO - mozlog == 3.4 04:43:44 INFO - moznetwork == 0.27 04:43:44 INFO - mozprocess == 0.25 04:43:44 INFO - mozprofile == 0.28 04:43:44 INFO - mozrunner == 6.13 04:43:44 INFO - mozscreenshot == 0.1 04:43:44 INFO - mozsystemmonitor == 0.3 04:43:44 INFO - moztest == 0.8 04:43:44 INFO - mozversion == 1.4 04:43:44 INFO - psutil == 3.1.1 04:43:44 INFO - requests == 1.2.3 04:43:44 INFO - wsgiref == 0.1.2 04:43:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:44 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 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:44 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 04:43:44 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 04:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:44 INFO - 'HOME': '/Users/cltbld', 04:43:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:44 INFO - 'LOGNAME': 'cltbld', 04:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:44 INFO - 'MOZ_NO_REMOTE': '1', 04:43:44 INFO - 'NO_EM_RESTART': '1', 04:43:44 INFO - 'PAGER': '/bin/cat', 04:43:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:44 INFO - 'PWD': '/builds/slave/test', 04:43:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:44 INFO - 'SHELL': '/bin/bash', 04:43:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:44 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:44 INFO - 'USER': 'cltbld', 04:43:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:44 INFO - 'XPC_FLAGS': '0x0', 04:43:44 INFO - 'XPC_SERVICE_NAME': '0', 04:43:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 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)) 04:43:44 INFO - Cleaning up... 04:43:44 INFO - Return code: 0 04:43:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10096fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100972768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc148eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, '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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 04:43:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:43:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:45 INFO - 'HOME': '/Users/cltbld', 04:43:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:45 INFO - 'LOGNAME': 'cltbld', 04:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:45 INFO - 'MOZ_NO_REMOTE': '1', 04:43:45 INFO - 'NO_EM_RESTART': '1', 04:43:45 INFO - 'PAGER': '/bin/cat', 04:43:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:45 INFO - 'PWD': '/builds/slave/test', 04:43:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:45 INFO - 'SHELL': '/bin/bash', 04:43:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:43:45 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:43:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:45 INFO - 'USER': 'cltbld', 04:43:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:45 INFO - 'XPC_FLAGS': '0x0', 04:43:45 INFO - 'XPC_SERVICE_NAME': '0', 04:43:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:43:45 INFO - Cleaning up... 04:43:45 INFO - Return code: 0 04:43:45 INFO - Running post-action listener: _resource_record_post_action 04:43:45 INFO - Running post-action listener: _start_resource_monitoring 04:43:45 INFO - Starting resource monitoring. 04:43:45 INFO - [mozharness: 2017-03-16 11:43:45.285019Z] Finished create-virtualenv step (success) 04:43:45 INFO - [mozharness: 2017-03-16 11:43:45.285980Z] Running install step. 04:43:45 INFO - Running pre-action listener: _resource_record_pre_action 04:43:45 INFO - Running main action method: install 04:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:45 INFO - Reading from file tmpfile_stdout 04:43:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:43:45 INFO - Reading from file tmpfile_stdout 04:43:45 INFO - Output received: 04:43:45 INFO - Usage: mozinstall [options] installer 04:43:45 INFO - Options: 04:43:45 INFO - -h, --help show this help message and exit 04:43:45 INFO - -d DEST, --destination=DEST 04:43:45 INFO - Directory to install application into. [default: 04:43:45 INFO - "/builds/slave/test"] 04:43:45 INFO - --app=APP Application being installed. [default: firefox] 04:43:45 INFO - mkdir: /builds/slave/test/build/application 04:43:45 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'] 04:43:45 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 04:44:06 INFO - Reading from file tmpfile_stdout 04:44:06 INFO - Output received: 04:44:06 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:44:06 INFO - Running post-action listener: _resource_record_post_action 04:44:06 INFO - [mozharness: 2017-03-16 11:44:06.604704Z] Finished install step (success) 04:44:06 INFO - [mozharness: 2017-03-16 11:44:06.604826Z] Running run-tests step. 04:44:06 INFO - Running pre-action listener: _resource_record_pre_action 04:44:06 INFO - Running main action method: run_tests 04:44:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:06 INFO - 2017-03-16 04:44:06.619 screenresolution[1621:7359] starting screenresolution argv=screenresolution get 04:44:06 INFO - 2017-03-16 04:44:06.622 screenresolution[1621:7359] Display 0: 1600x1200x32@60 04:44:06 INFO - 2017-03-16 04:44:06.627 screenresolution[1622:7361] starting screenresolution argv=screenresolution list 04:44:06 INFO - Available Modes on Display 0 04:44:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:06 INFO - Intel Iris: 04:44:06 INFO - Chipset Model: Intel Iris 04:44:06 INFO - Type: GPU 04:44:06 INFO - Bus: Built-In 04:44:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:06 INFO - Vendor: Intel (0x8086) 04:44:06 INFO - Device ID: 0x0a2e 04:44:06 INFO - Revision ID: 0x0009 04:44:06 INFO - Displays: 04:44:06 INFO - Display: 04:44:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:06 INFO - Display Serial Number: 1600x1200 60 04:44:06 INFO - Main Display: Yes 04:44:06 INFO - Mirror: Off 04:44:06 INFO - Online: Yes 04:44:06 INFO - Rotation: Supported 04:44:06 INFO - Return code: 0 04:44:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:44:06 INFO - Python 2.7.3 04:44:06 INFO - Return code: 0 04:44:06 INFO - grabbing minidump binary from tooltool 04:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:06 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 0x10074dd30>, '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 0x100674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100742be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:44:06 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 04:44:06 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 04:44:06 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 04:44:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:44:06 INFO - Return code: 0 04:44:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:44:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:44:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:44:06 INFO - ENV: RUST_BACKTRACE is now 1 04:44:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:44:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:44:06 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 04:44:06 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --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 04:44:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OU1FkBa2Q0/Render', 04:44:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:06 INFO - 'HOME': '/Users/cltbld', 04:44:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:06 INFO - 'LOGNAME': 'cltbld', 04:44:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:44:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:06 INFO - 'MOZ_NO_REMOTE': '1', 04:44:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:44:06 INFO - 'NO_EM_RESTART': '1', 04:44:06 INFO - 'PAGER': '/bin/cat', 04:44:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:06 INFO - 'PWD': '/builds/slave/test', 04:44:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:44:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:06 INFO - 'RUST_BACKTRACE': '1', 04:44:06 INFO - 'SHELL': '/bin/bash', 04:44:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJGbQ82NBS/Listeners', 04:44:06 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 04:44:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:06 INFO - 'USER': 'cltbld', 04:44:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:06 INFO - 'XPC_FLAGS': '0x0', 04:44:06 INFO - 'XPC_SERVICE_NAME': '0', 04:44:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:06 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 04:44:07 INFO - mozversion application_buildid: 20170316030211 04:44:07 INFO - mozversion application_changeset: ff04d410e74b69acfab17ef7e73e7397602d5a68 04:44:07 INFO - mozversion application_display_name: Nightly 04:44:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:44:07 INFO - mozversion application_name: Firefox 04:44:07 INFO - mozversion application_remotingname: firefox 04:44:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:44:07 INFO - mozversion application_vendor: Mozilla 04:44:07 INFO - mozversion application_version: 55.0a1 04:44:07 INFO - mozversion platform_buildid: 20170316030211 04:44:07 INFO - mozversion platform_changeset: ff04d410e74b69acfab17ef7e73e7397602d5a68 04:44:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:44:07 INFO - mozversion platform_version: 55.0a1 04:44:07 INFO - using testdate: 1489664647 04:44:07 INFO - actual date: 1489664647 04:44:07 INFO - starting webserver on 'localhost:49241' 04:44:07 INFO - SUITE-START | Running 2 tests 04:44:07 INFO - TEST-START | dromaeo_css 04:44:07 INFO - operating with platform_type : mac_ 04:44:07 INFO - Initialising browser for dromaeo_css test... 04:44:07 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmp5fPkGt/profile http://localhost:49241/getInfo.html) 04:44:10 INFO - PID 1632 | 2017-03-16 04:44:10.617 plugin-container[1636:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 04:44:10 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:10 INFO - PID 1632 | 2017-03-16 04:44:10.617 plugin-container[1636:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 04:44:10 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:10 INFO - PID 1632 | 2017-03-16 04:44:10.617 plugin-container[1636:7561] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:10 INFO - PID 1632 | __metrics Screen width/height:1600/1200 04:44:10 INFO - PID 1632 | colorDepth:24 04:44:10 INFO - PID 1632 | Browser inner width/height: 1024/689 04:44:10 INFO - PID 1632 | __metrics 04:44:10 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 04:44:11 INFO - TEST-INFO | 1632: exit 0 04:44:11 INFO - Browser initialized. 04:44:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:44:11 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmp5fPkGt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:14 INFO - PID 1637 | 2017-03-16 04:44:14.139 plugin-container[1638:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 04:44:14 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:14 INFO - PID 1637 | 2017-03-16 04:44:14.140 plugin-container[1638:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 04:44:14 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:14 INFO - PID 1637 | 2017-03-16 04:44:14.140 plugin-container[1638:7689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:23 INFO - PID 1637 | RSS: Main: 226631680 04:44:23 INFO - PID 1637 | 04:47:27 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:47:29 INFO - PID 1637 | RSS: Main: 250294272 04:47:29 INFO - PID 1637 | 04:49:12 INFO - PID 1637 | ************************* 04:49:12 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 04:49:12 INFO - PID 1637 | 04:49:12 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:49:12 INFO - PID 1637 | Full stack: 04:49:12 INFO - PID 1637 | ************************* 04:50:31 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:50:32 INFO - PID 1637 | RSS: Main: 258347008 04:50:32 INFO - PID 1637 | 04:53:34 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:53:34 INFO - PID 1637 | RSS: Main: 270274560 04:53:34 INFO - PID 1637 | 04:56:36 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:56:37 INFO - PID 1637 | RSS: Main: 262541312 04:56:37 INFO - PID 1637 | 04:59:40 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:59:40 INFO - PID 1637 | RSS: Main: 272764928 04:59:41 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:02:44 INFO - PID 1637 | RSS: Main: 266477568 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | __start_tp_report 05:02:44 INFO - PID 1637 | _x_x_mozilla_page_load 05:02:44 INFO - PID 1637 | _x_x_mozilla_page_load_details 05:02:44 INFO - PID 1637 | |i|pagename|runs| 05:02:44 INFO - PID 1637 | |0;dojo.html;3240.57872476578;3454.3436747018063;3470.6702863228015;3500.929981400372;3517.595476520201;5883.294004719434;5925.200097986771;6045.486133678637;6073.665948372838;6114.144019837227;5991.730372133254;6075.544334174943;6140.140380346744;6140.171076904617;6162.537809664271;4498.842540511083;5403.7027963462;5488.17677348399;5518.641288316247;5583.106702927533;4977.377827771528;5392.91910621341;5453.509184173424;5532.806351777688;5557.888842223153;5372.677639341613;5412.783488660449;5482.068048431776;5516;5528.143137813645;5446.891062178774;5451.536619387317;5457.645253058538;5480.780768769245;5492.862678433071;5387.19192121181;5391.056565101092;5405.378381486101;5407.107827208506;5461.918071228934;4805.255185446262;5844.88310233793;5906.497947674453;5964.821055368346;6097.902377572035;6230.937690623081;6288.779892703734;6360.300366959677;6384.680765961683;6395.232572091379;6449.580777249511;6462.192225971754;6465.612063276219;6480.15757951468;6490.221173459168;6013.639181649115;6170.290416602058;6185;6196.349383314777;6208.627482351073;6378.138951241523;6501.577397469149;6569.737210511622;6593.714225726033;6616.239132499824;6136.6722581676495;6251.562390632612;6277.246730392382;6332.430081292706;6358.141650877073;6299;6364.809055728336;6376.776812811529;6468.385503377266;6474.643894585749;6323.114763933053;6373.649449280241;6385.2656944451055;6389.233292004897;6399.072134540465;5476.479734425223;6215.751369945152;6265.27949285838;6329;6348.111264422984;5189.065968125698;5207.192885102815;5221.947780522146;5235.973820130875;5258.474152584711;4619.9076018479445;4623.69945953512;4634.768261586907;4649.7210167389385;4659.394278743742;5986.491148252448;6092.99465588173;6168.167297414881;6183.3816618337805;6221.253449586078;6296.087067375205;6318.304986451487;6409.615423074537;6441.613503189824;6456.903146452807;6135.96932015346;6146.508279337642;6152.600485810537;6164.383561643889;6169.290531588835;5686.886262274731;5701.686407247558;5713.800016999385;5715.31416230055;5763.682997435098;6432.517561183024;6485.740570377132;6609.570377925419;6631.502637302222;6636.13730215069;6445.097686324012;6461.353864613501;6472.996685513753;6481.805545833632;6612.23959244693;6597.868042639025;6660.400563949268;6665.800025999228;6684.097646817618;6693.732250709917;5246.632735708464;6261.874762504815;6287.088372186099;6343.619382837044;6348.269948955929;4848.7090774553635;5654.3497497788285;5946.702664866652;6396.008618664115;6490.026496025634;5261.3686357637325;6076.8784624308155;6232.968835155795;6263.27972283175;6265.248170219603;6224.346443623354;6230.813075607739;6244.594101383395;6299.779507717208;6319.96840015797;6139.171211886338;6160.414760597736;6248.687565621792;6249.218847644044;6277.905831412624;5178.611604129781;5225.973870130625;6232.065190221322;6307.180066591313;6312.400321969406;6182.938170618237;6186.072089186658;6189.938100619116;6194.907076393948;6197.566170368212 05:02:44 INFO - PID 1637 | |1;ext.html;739.7719785156642;780.5745868501666;807.1202389395557;928.6099838068011;934.6027938126299;36473.81763091184;37360.43959340612;37362;37422.62577374226;37444;36307;36670.633293667124;36681;36754.44868326971;38009;7737.458377913563;7901.130875603679;10691.572337106545;10999.505022273977;11050.060744836699;10929.56281748733;11001.724956876062;11091.500882460268;11096.223264371498;11119.388433636146;11035.172362072837;11143.33140011602;11148.498317575688;11148.498317575728;11149.940755628202;11017.779644407146;11235.887641123607;11244;11280.717982050432;11284.717882052932;10848.077913377372;10861.83707244392;10881.183911206692;10897.346159230392;10905.67282981504;38286.23427531434;38341.041473963094;38426.84719458421;38441.03897402559;38473.03817404559;3807.752496087773;3826.7512611680154;3827.311084004878;3835.5013848199555;3845.9615403845883;27588.862055689595;27620.723792762015;27792.861035694896;28041.298967525974;28068;2690.90581829637;3108.6425061118034;3116.56368108465;3171.3815805917857;3262.331222099464;204018;207942;273665.999999998;320598;322657;46914.5308546917;47159.82100447448;47231.763841180575;47249.763751181024;47265.5273447268;49127;49364.753176234604;49390.813964533205;49521.50478495241;49561.25658115131;13931.721365572632;14046.227457489937;14050.508232211823;14053.227072510905;14067.859321406859;11513.366764828015;11701.356425396685;11749.88250117505;11757.235779674294;11766.705832354088;12715.791999760007;13729.382177802023;13730.51943181984;13754.243516606482;13871.722565548633;5600.943990560124;5687.8009269676195;5777.335606405233;6146;6187.412195841388;1034.8706411698538;1040.8750949886062;1107.2083460325905;1125.2966895690195;1171.718787491013;23350.7664923352;25904.8704756475;26310.079147229753;26491;26512.337191570365;1133.642902485731;1181.8995385392338;1206.2943178240828;1216.4282787090053;1275.5471807508386;13337.933310333387;13635.863641363658;13642.2496762679;13653.863461365458;13770.173789572658;359387;362402;367997;368120;368593;54184;54776.726116369166;54837;54979.45020549823;55000.449995500334;1377.4145987955078;1411.632975426376;1414.3069895751007;1414.936327865228;1440.7046555456106;27933.301667458476;28183.577246340912;29206.70793292125;29457.558136628395;29745;45245.54754452545;45407.864803378856;45644.54355456403;45828.31257531206;45842.541574583825;455900;457083;458530;459481;460574;32942.505862411606;34088.82955585206;34096.48855267223;34118.65881341254;34134;41325.586744132175;41464.585354146075;41519.58480415278;42019.36970945377;42157.367639484815;30593.77624894979;32052.03843884687;32588.83705581457;33866.49200262047;35034.82482587571;30575.318357490552;34972.65027349694;35770.64229357673;36129.458058129676;36229.818850905576;33549.83225083859;34739.826300868335;36319.45520817137;36396.27207455783;36429.45355819612;29715.851420742758;31681.841590791897;31684.36631267408;31840.885569004975;31952;23982;24051.18336540666;24157.15449959243;24433.75566244315;24512.632310515713 05:02:44 INFO - PID 1637 | |2;jquery.html;402.36828179757794;414.84028648970155;418.84712080090765;429.931211006239;434.49598465779684;19725.309614163507;21376.46558836028;22302.996365163566;22309;22318.330450086483;14416.92791536041;20956.790432095673;21426.89286553565;21613;21658;5392.973035134829;6745.831354216135;6765;6770.0860383848285;6778.356056174655;5586.692731899744;6454.128692626506;6657.633830139341;6671.899921501182;6680.532362734611;6670.866582668344;6685.665716714169;6685.997100434925;6694.163229596301;6714.328567143271;7277.235890231529;7628.0846298443985;7632.389408847279;7649.426293028045;7665.731699390551;7088.291170882922;7245.4928155029165;7298.343149116605;7303.488755787097;7453.031105956191;26729.866350668315;26816.73183268162;27586.172414827386;27706.307342316402;27760.444791104266;2805.551111822119;2876.280929767558;2894.536874100155;2894.768418526534;2907.25864904448;13650.658733531642;15913.283902224312;16012.718982481374;17042.23309951055;17230.396936107303;1890.867639265252;1980.227711192636;1984.4046785964154;1995.760508738946;2029.2288930206576;140865;148469;163745;166295;166767.32628715996;22150.88924555367;22640.773592263868;22824.543509130057;23055.42361440944;23701;35474.743030836464;35549.11127221789;35566.28867422689;35588.64411355905;35677.82671512668;10261.281710280358;10489.265751397481;10682.519286631965;10732.570697172026;10858.891411085946;8474.1949514796;8496.686639419124;8591.3556483264;8746.431481953652;8748.69379571712;9050.366474346733;10755.892441075646;10796.94601527003;10861.294015888941;10915.727106822393;3803.8478460861256;4415.977920110379;4525.547445255448;4556.65825063115;4570.908581828411;1064.41989115932;1066.7439814444479;1072.1851392941264;1073.6349641122056;1084.5011294804478;22185.778142218373;22244.55510889773;23319.300420987398;24015;24219;1077.5851297250615;1093.1091160704;1120.4229821641861;1121.8317252412203;1127.4362818590705;11468.36923969173;11571.826422603668;12314.630561083182;12449.875501245053;12519.436625351698;603348;623994;679072.6046369835;696597;714723;127539;157862;159002;159892.40107599008;161224;1307.5815738963631;1325.4830616059744;1357.7556039912713;1358.796180572922;1361.7889227169605;19918.004099794663;20827;20883.746975181537;20946;20987;54958;56063.71968140133;60413.0938035921;62901.37098629138;63222.68388658027;90430.54784726034;91597.54201228952;91600;91992.54003729939;92442.53778731333;11615.419229038684;11797;12016.158868879094;12122.696932576757;12214.389280536116;12160.939195303967;12899.742005160031;13137.014723895554;13173.736525269633;13523.323833808074;10226.846597301195;11303.717407064889;11353.262037967506;11412.144089193509;11621.535138594361;9749.220062395134;11351.716207094889;11391.715207119889;11560.30638161713;11832.82250766222;10549.261551691308;10698.197635177237;10806.189535785004;10822.350658960173;10849.620263291063;11783.265698948984;11925.821112683143;11967.341796201292;12085.154039217523;12106.75786484283;8811.427257228259;10517.737056573647;10625.628103016357;10698.839517407401;10721.98141176587 05:02:44 INFO - PID 1637 | |3;mootools.html;1520.1942970225784;1570.3953977718581;1582.762585612158;1588.52344296711;1645.5803770038642;9378.531073446318;9641.710748677533;10518.789624207511;10924.016838484535;10984.176186785993;10347.120494757955;10745.8388124178;10762.677119686421;10777.730556736085;10785.730356741085;1813.4287699374654;1873.6627407066724;2156.7304086989125;2189.2994241842616;2190.5728382965326;1921.2315073970383;2262.558801033799;2293.8050265727434;2305.5504176685554;2321.0367697405554;1903.7715474143122;2281.6007198740235;2291.175176936302;2307.676925230469;2313.097891822191;2266.01428378655;2275.4311422144465;2278.8290878184202;2278.954420911589;2286.1541229745017;2232.5758105959817;2333.4166458385403;2342.297310806768;2342.976570234293;2345.882705864717;10997.780044399067;11115.333080015225;11128.276662017024;11135.942085501865;11140.665780026613;4559.247724125516;5483.917741233885;5717.685527295997;5731;5731.484166425;3323.5180898769786;3417.9487307690406;3622.619624939396;3632.037510059836;3638.8362523686496;1151.5739176504746;1166.7433164703752;1185.146694380045;1203.205884116479;1208.9395530223542;12521.937390313082;15265.007774494734;15326.310316035693;15407.614809629737;15436.459723909722;3898.7270891037356;4622.39908811859;4720.704682066539;4804.615630749601;4832.492588278251;11517.76964460706;12632.431540580696;12832.294223817593;12834.80747788784;12840.871591283967;5100.821471248489;5498.81775418287;5663.9716801415725;5747.626404283708;5773.24947756789;4466.285394336891;5033.3205017322925;5077.822276220315;5109.233614957711;5147.6396652234735;3968.8610898618986;4025.5370632377067;4076.5719599442223;4090;4134.751914885116;1137.6985098948785;1140.9543618255177;1141.1042331769522;1146.4497041420166;1156.4738044189874;748.3190296829858;763.576215200569;784.3685832904566;788.9605519724106;804.8430556041633;6329.7151628175925;6488.902666460007;6642.535022548375;6672.532922695462;6706.128203333536;952.5094576293214;955.6702937486556;960.1166926427703;982.4007355513274;984.1339621133357;3593.299306635181;4086.4279000939296;4163.91672166555;4173.749575025508;4303.956960430418;13074.411651475517;13101.737965240642;13265.801012984813;13587.456501740018;13610.727785444236;13135;13245.27151006704;13405.329733513481;13421.664458388423;13445.260510672008;9900.257480688977;10003.099721025143;10052.949735251423;10101.949490252502;10103.797924041479;6236.251649802053;6344.936550634435;6348.746050157942;6408.487321014214;6415.8396040097505;11722.70693232676;12074.698132546757;12199.573014944433;12226.266424014588;12238.999999999643;11889.643310700692;11913.94043029814;12271.877281227073;12284.938575307424;12453.315067671365;8422.6209820557;8974.41666291689;9131.954340228256;9205.125513076247;9243.676471323472;8273.296769774637;8745.562721863755;10254.384736915808;10352.016558426938;10368.170546356123;9015.909840901508;9075.54622268897;9116.635334586543;9116.635334586808;9144.497052662167;8908;8916.910830891607;8960.865587016331;9017.729468115705;9041.547922603711;7824.64789084504;7830.2952734254195;7949;7951;7983.2415920487465;5743.253377060955;5795.797147099831;5874.530037597067;5915.704214789157;5933.881322373615;5311.814086506954;5836.212111364912;5946.5540084494705;5959.582829201914;6068.089786532056 05:02:44 INFO - PID 1637 | |4;prototype.html;355.5981740633084;379.51801212460174;382.1859439394092;383.50527819113347;385.33529661334194;5101.67873617504;6187.52499000399;6242.968785156074;6243.469305109065;6365.989426661208;6058.939410605893;6182.659953702545;6185.381461853813;6187.195664563612;6227.5952063115865;4607.654425918053;4751.263554149113;4803.831865884695;4990.305235173002;5044.562299744555;4180.268453020724;4834.395700537433;4995.700257984515;4999.500049994989;5091.134507133796;5051.343325367697;5054.570361519257;5072.568831649296;5077.466865979074;5088.422588997422;4830.396200474941;4938.715933857223;4964.950350496503;5078.238264260353;5164.328637277167;4350.651947844165;4518.2319005769095;4542.591166795004;4546.568076032771;4559.544045595447;5723.713814309268;5840.503557197643;5961.5230781537375;6020.5785595008365;6022.307434644984;5770.422957704238;5844.47399734026;5913.2608423947;5925.955545336852;5929.199558059693;5452.863678408032;5857.941420585825;6399.26408463028;6639;6645.6344901030425;4482.8431004915;4509.075639493896;4667.346571479996;4791.161546729309;5172.301739265189;6686.498512611525;6856.931430685729;7028.543144695578;7087.610181440019;7093.148822141325;4613.653975951817;4624.699394539344;5180.145276029489;5201.427842937273;5208.401033881152;4737.857864264078;5360.731963401814;5388.2725832012175;5423.701696406735;5447.182922561568;5646.520045796071;5846.473817356458;5860.769238459971;5873.412658734178;5885.352611212763;6622.8013159604325;6642.501812364092;6651.7671881484885;6667.33326667339;6674.866502669919;1506.1716056169064;1631.9020858748513;1827;1842.7653472173677;1865.5709186887093;1186.1400484648561;1312.566852938528;1345.5357901524155;1348.2449828096298;1375.9587212383644;4828;5323.707196104174;5687.829365119053;6089.786857460056;6178.691065446799;5934.258217722785;6612.801615951529;6777.864442711217;6853.348931851466;6915.654217289115;5651.632643878134;5692.9146062809095;6137.324894261627;6175.84560385994;6212.006079027425;3115.8753649854207;3146.65386807451;3339.8477525253747;3626.7279954003557;3629.709623230135;5729.455701708331;6092.268927728613;6431.74273029083;6580.815453218371;6824.965875170592;6779.593224406552;6912.585244885323;7097.574145551283;7099.751508697171;7122.92877071233;6775;6778.356056174655;7076.25699301566;7137.2149063602965;7139.678714457758;5549.5837812163445;6759.155105611799;6865.794026179223;6883.862322753416;6890.44876409876;4536.931946020878;6582.605043697394;6786.864262714619;6817.829554261183;6820.795376138723;6217.906731399123;7049.647517623996;7050.647467626701;7155.677994490156;7181.922711593303;4449.421575195204;5201.609879258995;5295.364556253275;5307.734613269244;5317.468253174651;5249.238860365302;6185.659788711664;6607.636579988147;6726.596404215763;6749.190097188369;5682.488576028178;6149.877002460015;6486.578372405778;6555.147830781968;6689.832754181184;6384.361563843579;6514.4788416925585;6653.401193892573;6654.234763002317;6716.496262780409;5584.022796010763;6284;6509.804705858832;6525.934740652533;6531.510136739668;5857.297124345106;6276.9372306276355;6642.269350371552;6679.1984961802955;6712.630805305755;5671.574631902541;6451.290358060516;6514.967425163033;6534.738610455719;6550.8362290943105 05:02:44 INFO - PID 1637 | |5;yui.html;679.7858674517527;698.4691634357887;766.6396793507054;772.5982489105666;814.9633266503008;15362.53912382629;15710.842891571067;15716.214189290547;15799.921000394996;15992;14306.713865722679;14713.264336783144;14796.778048329285;14839.258037098129;14930.776038359434;924.1959495239134;934.7756538430796;941.2422999485411;944.4946953379944;946.2004606107828;802.4904185842004;911.4212475078307;931.7903471718843;934.7990182110844;947.9336446448751;1000.2897942460861;1000.824855650262;1001.8146642871055;1002.4386343647544;1004.3923426327076;979.2410881566772;983.3018556824663;983.7934033852899;986.0238364019641;994.6419289055934;730.0436428278972;730.6748496918873;735.0554537419443;735.5696917303384;744.7840126363349;15989.760153597705;16382;16393.508194754057;16470.341186352533;16566.917165414216;14542.854571454256;14839.406423743038;14857.331420086122;14891.47879824201;14919.030263032868;11703.239289446245;12049.759004819942;12055.397230138458;12091.395430228453;12112.15214934955;3629.2046573136076;3815.561210460805;3832.405977073558;3841.9807900960595;3849.3648547989546;104945.47527262391;106050.46974765154;108443.91556084497;108951;111465;11120.55517779296;11264.605738799266;11334.716632084264;11565.479553420117;11871.287722736663;24649.63025554618;24952.00191992336;24983.87508062448;25008.749912500643;25115.87442062778;7541.283578060075;7779.8833017504785;7815;7920.683172673143;8175.059868115243;3733.141377483194;3803.9980593867067;3879.4568760373572;3969.8809035728973;3982.944519702281;3395.5246265522846;3456.844442000116;3470.219200679827;3472;3474.947875781865;486.5791090706513;491.9532644398776;545.2693390856192;565.8217661436635;586.6069733278714;596.5794115148809;615.3262177915176;632.34552238433;679.0326274181758;679.7790718016664;9871.309008369346;9905.356151850106;10143.442110683836;10399.740006499898;10593.205509586813;502.22155658729554;521.5488602358932;556.7299859568107;564.7430419159273;582.2547139661241;4786.923525977836;4934.235193545006;4942.5304596063315;4949.084419382354;4986.052649996489;105208.47395763126;106632;106735.03491356195;106939.930600693;106967.46516267525;60686.69656651688;75773.6211318951;88696;89720.5513972426;91514;50747;59856.20431387065;67898.66050669715;67992.6600366995;68160.65919670468;10002.949985250027;10076.69769906904;10940.6717798463;11048.944755276172;11132.332060076422;48358.758206208746;48568.271475927184;48586.05655773871;49329;49635.503644964534;45044;60129.398706012376;63030.68484657731;63685.810270356524;63737;12896;13228.743269389393;13317.933410332886;13450.529231476852;13466.64333741611;11787.41062946832;11908.336233037011;11946.34295113777;12348.320842353756;12350.197237179555;10208.387496749921;13065.346732663518;13073.477060917457;13350.666233343856;13695.246761428218;12954.67613309637;13057.28184949799;13639.38622761998;13647.11293765902;13784.586462406143;12813.615591532269;12991.090623656632;13041.369828771403;13160.60518184456;13496.32518374097;10788.460576971276;10886.23796334249;10978.615748449085;11114.610988615359;11782.469788859351;9169.88224003842;9681.354169791011;10333.586656533655;10918.781624367628;10925.744846440888 05:02:44 INFO - PID 1637 | __end_tp_report 05:02:44 INFO - PID 1637 | __start_cc_report 05:02:44 INFO - PID 1637 | _x_x_mozilla_cycle_collect,423 05:02:44 INFO - PID 1637 | __end_cc_report 05:02:44 INFO - PID 1637 | __startTimestamp1489665764319__endTimestamp 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | ------- Summary: start ------- 05:02:44 INFO - PID 1637 | Number of tests: 6 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:5921.60 Median:6182.94 stddev:671.25 (10.9%) stddev-sans-first:639.51 05:02:44 INFO - PID 1637 | Values: 3240.6 3454.3 3470.7 3500.9 3517.6 5883.3 5925.2 6045.5 6073.7 6114.1 5991.7 6075.5 6140.1 6140.2 6162.5 4498.8 5403.7 5488.2 5518.6 5583.1 4977.4 5392.9 5453.5 5532.8 5557.9 5372.7 5412.8 5482.1 5516.0 5528.1 5446.9 5451.5 5457.6 5480.8 5492.9 5387.2 5391.1 5405.4 5407.1 5461.9 4805.3 5844.9 5906.5 5964.8 6097.9 6230.9 6288.8 6360.3 6384.7 6395.2 6449.6 6462.2 6465.6 6480.2 6490.2 6013.6 6170.3 6185.0 6196.3 6208.6 6378.1 6501.6 6569.7 6593.7 6616.2 6136.7 6251.6 6277.2 6332.4 6358.1 6299.0 6364.8 6376.8 6468.4 6474.6 6323.1 6373.6 6385.3 6389.2 6399.1 5476.5 6215.8 6265.3 6329.0 6348.1 5189.1 5207.2 5221.9 5236.0 5258.5 4619.9 4623.7 4634.8 4649.7 4659.4 5986.5 6093.0 6168.2 6183.4 6221.3 6296.1 6318.3 6409.6 6441.6 6456.9 6136.0 6146.5 6152.6 6164.4 6169.3 5686.9 5701.7 5713.8 5715.3 5763.7 6432.5 6485.7 6609.6 6631.5 6636.1 6445.1 6461.4 6473.0 6481.8 6612.2 6597.9 6660.4 6665.8 6684.1 6693.7 5246.6 6261.9 6287.1 6343.6 6348.3 4848.7 5654.3 5946.7 6396.0 6490.0 5261.4 6076.9 6233.0 6263.3 6265.2 6224.3 6230.8 6244.6 6299.8 6320.0 6139.2 6160.4 6248.7 6249.2 6277.9 5178.6 5226.0 6232.1 6307.2 6312.4 6182.9 6186.1 6189.9 6194.9 6197.6 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:51100.47 Median:31896.44 stddev:98654.81 (309.3%) stddev-sans-first:98859.10 05:02:44 INFO - PID 1637 | Values: 739.8 780.6 807.1 928.6 934.6 36473.8 37360.4 37362.0 37422.6 37444.0 36307.0 36670.6 36681.0 36754.4 38009.0 7737.5 7901.1 10691.6 10999.5 11050.1 10929.6 11001.7 11091.5 11096.2 11119.4 11035.2 11143.3 11148.5 11148.5 11149.9 11017.8 11235.9 11244.0 11280.7 11284.7 10848.1 10861.8 10881.2 10897.3 10905.7 38286.2 38341.0 38426.8 38441.0 38473.0 3807.8 3826.8 3827.3 3835.5 3846.0 27588.9 27620.7 27792.9 28041.3 28068.0 2690.9 3108.6 3116.6 3171.4 3262.3 204018.0 207942.0 273666.0 320598.0 322657.0 46914.5 47159.8 47231.8 47249.8 47265.5 49127.0 49364.8 49390.8 49521.5 49561.3 13931.7 14046.2 14050.5 14053.2 14067.9 11513.4 11701.4 11749.9 11757.2 11766.7 12715.8 13729.4 13730.5 13754.2 13871.7 5600.9 5687.8 5777.3 6146.0 6187.4 1034.9 1040.9 1107.2 1125.3 1171.7 23350.8 25904.9 26310.1 26491.0 26512.3 1133.6 1181.9 1206.3 1216.4 1275.5 13337.9 13635.9 13642.2 13653.9 13770.2 359387.0 362402.0 367997.0 368120.0 368593.0 54184.0 54776.7 54837.0 54979.5 55000.4 1377.4 1411.6 1414.3 1414.9 1440.7 27933.3 28183.6 29206.7 29457.6 29745.0 45245.5 45407.9 45644.5 45828.3 45842.5 455900.0 457083.0 458530.0 459481.0 460574.0 32942.5 34088.8 34096.5 34118.7 34134.0 41325.6 41464.6 41519.6 42019.4 42157.4 30593.8 32052.0 32588.8 33866.5 35034.8 30575.3 34972.7 35770.6 36129.5 36229.8 33549.8 34739.8 36319.5 36396.3 36429.5 29715.9 31681.8 31684.4 31840.9 31952.0 23982.0 24051.2 24157.2 24433.8 24512.6 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:41164.47 Median:21401.68 stddev:111839.11 (522.6%) stddev-sans-first:112110.97 05:02:44 INFO - PID 1637 | Values: 402.4 414.8 418.8 429.9 434.5 19725.3 21376.5 22303.0 22309.0 22318.3 14416.9 20956.8 21426.9 21613.0 21658.0 5393.0 6745.8 6765.0 6770.1 6778.4 5586.7 6454.1 6657.6 6671.9 6680.5 6670.9 6685.7 6686.0 6694.2 6714.3 7277.2 7628.1 7632.4 7649.4 7665.7 7088.3 7245.5 7298.3 7303.5 7453.0 26729.9 26816.7 27586.2 27706.3 27760.4 2805.6 2876.3 2894.5 2894.8 2907.3 13650.7 15913.3 16012.7 17042.2 17230.4 1890.9 1980.2 1984.4 1995.8 2029.2 140865.0 148469.0 163745.0 166295.0 166767.3 22150.9 22640.8 22824.5 23055.4 23701.0 35474.7 35549.1 35566.3 35588.6 35677.8 10261.3 10489.3 10682.5 10732.6 10858.9 8474.2 8496.7 8591.4 8746.4 8748.7 9050.4 10755.9 10796.9 10861.3 10915.7 3803.8 4416.0 4525.5 4556.7 4570.9 1064.4 1066.7 1072.2 1073.6 1084.5 22185.8 22244.6 23319.3 24015.0 24219.0 1077.6 1093.1 1120.4 1121.8 1127.4 11468.4 11571.8 12314.6 12449.9 12519.4 603348.0 623994.0 679072.6 696597.0 714723.0 127539.0 157862.0 159002.0 159892.4 161224.0 1307.6 1325.5 1357.8 1358.8 1361.8 19918.0 20827.0 20883.7 20946.0 20987.0 54958.0 56063.7 60413.1 62901.4 63222.7 90430.5 91597.5 91600.0 91992.5 92442.5 11615.4 11797.0 12016.2 12122.7 12214.4 12160.9 12899.7 13137.0 13173.7 13523.3 10226.8 11303.7 11353.3 11412.1 11621.5 9749.2 11351.7 11391.7 11560.3 11832.8 10549.3 10698.2 10806.2 10822.4 10849.6 11783.3 11925.8 11967.3 12085.2 12106.8 8811.4 10517.7 10625.6 10698.8 10722.0 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6623.25 Median:3370.73 stddev:4185.51 (124.2%) stddev-sans-first:4179.69 05:02:44 INFO - PID 1637 | Values: 1520.2 1570.4 1582.8 1588.5 1645.6 9378.5 9641.7 10518.8 10924.0 10984.2 10347.1 10745.8 10762.7 10777.7 10785.7 1813.4 1873.7 2156.7 2189.3 2190.6 1921.2 2262.6 2293.8 2305.6 2321.0 1903.8 2281.6 2291.2 2307.7 2313.1 2266.0 2275.4 2278.8 2279.0 2286.2 2232.6 2333.4 2342.3 2343.0 2345.9 10997.8 11115.3 11128.3 11135.9 11140.7 4559.2 5483.9 5717.7 5731.0 5731.5 3323.5 3417.9 3622.6 3632.0 3638.8 1151.6 1166.7 1185.1 1203.2 1208.9 12521.9 15265.0 15326.3 15407.6 15436.5 3898.7 4622.4 4720.7 4804.6 4832.5 11517.8 12632.4 12832.3 12834.8 12840.9 5100.8 5498.8 5664.0 5747.6 5773.2 4466.3 5033.3 5077.8 5109.2 5147.6 3968.9 4025.5 4076.6 4090.0 4134.8 1137.7 1141.0 1141.1 1146.4 1156.5 748.3 763.6 784.4 789.0 804.8 6329.7 6488.9 6642.5 6672.5 6706.1 952.5 955.7 960.1 982.4 984.1 3593.3 4086.4 4163.9 4173.7 4304.0 13074.4 13101.7 13265.8 13587.5 13610.7 13135.0 13245.3 13405.3 13421.7 13445.3 9900.3 10003.1 10052.9 10101.9 10103.8 6236.3 6344.9 6348.7 6408.5 6415.8 11722.7 12074.7 12199.6 12226.3 12239.0 11889.6 11913.9 12271.9 12284.9 12453.3 8422.6 8974.4 9132.0 9205.1 9243.7 8273.3 8745.6 10254.4 10352.0 10368.2 9015.9 9075.5 9116.6 9116.6 9144.5 8908.0 8916.9 8960.9 9017.7 9041.5 7824.6 7830.3 7949.0 7951.0 7983.2 5743.3 5795.8 5874.5 5915.7 5933.9 5311.8 5836.2 5946.6 5959.6 6068.1 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5481.62 Median:5919.61 stddev:1584.26 (26.8%) stddev-sans-first:1541.27 05:02:44 INFO - PID 1637 | Values: 355.6 379.5 382.2 383.5 385.3 5101.7 6187.5 6243.0 6243.5 6366.0 6058.9 6182.7 6185.4 6187.2 6227.6 4607.7 4751.3 4803.8 4990.3 5044.6 4180.3 4834.4 4995.7 4999.5 5091.1 5051.3 5054.6 5072.6 5077.5 5088.4 4830.4 4938.7 4965.0 5078.2 5164.3 4350.7 4518.2 4542.6 4546.6 4559.5 5723.7 5840.5 5961.5 6020.6 6022.3 5770.4 5844.5 5913.3 5926.0 5929.2 5452.9 5857.9 6399.3 6639.0 6645.6 4482.8 4509.1 4667.3 4791.2 5172.3 6686.5 6856.9 7028.5 7087.6 7093.1 4613.7 4624.7 5180.1 5201.4 5208.4 4737.9 5360.7 5388.3 5423.7 5447.2 5646.5 5846.5 5860.8 5873.4 5885.4 6622.8 6642.5 6651.8 6667.3 6674.9 1506.2 1631.9 1827.0 1842.8 1865.6 1186.1 1312.6 1345.5 1348.2 1376.0 4828.0 5323.7 5687.8 6089.8 6178.7 5934.3 6612.8 6777.9 6853.3 6915.7 5651.6 5692.9 6137.3 6175.8 6212.0 3115.9 3146.7 3339.8 3626.7 3629.7 5729.5 6092.3 6431.7 6580.8 6825.0 6779.6 6912.6 7097.6 7099.8 7122.9 6775.0 6778.4 7076.3 7137.2 7139.7 5549.6 6759.2 6865.8 6883.9 6890.4 4536.9 6582.6 6786.9 6817.8 6820.8 6217.9 7049.6 7050.6 7155.7 7181.9 4449.4 5201.6 5295.4 5307.7 5317.5 5249.2 6185.7 6607.6 6726.6 6749.2 5682.5 6149.9 6486.6 6555.1 6689.8 6384.4 6514.5 6653.4 6654.2 6716.5 5584.0 6284.0 6509.8 6525.9 6531.5 5857.3 6276.9 6642.3 6679.2 6712.6 5671.6 6451.3 6515.0 6534.7 6550.8 05:02:44 INFO - PID 1637 | 05:02:44 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19840.96 Median:14255.70 stddev:28355.26 (198.9%) stddev-sans-first:28398.30 05:02:44 INFO - PID 1637 | Values: 679.8 698.5 766.6 772.6 815.0 15362.5 15710.8 15716.2 15799.9 15992.0 14306.7 14713.3 14796.8 14839.3 14930.8 924.2 934.8 941.2 944.5 946.2 802.5 911.4 931.8 934.8 947.9 1000.3 1000.8 1001.8 1002.4 1004.4 979.2 983.3 983.8 986.0 994.6 730.0 730.7 735.1 735.6 744.8 15989.8 16382.0 16393.5 16470.3 16566.9 14542.9 14839.4 14857.3 14891.5 14919.0 11703.2 12049.8 12055.4 12091.4 12112.2 3629.2 3815.6 3832.4 3842.0 3849.4 104945.5 106050.5 108443.9 108951.0 111465.0 11120.6 11264.6 11334.7 11565.5 11871.3 24649.6 24952.0 24983.9 25008.7 25115.9 7541.3 7779.9 7815.0 7920.7 8175.1 3733.1 3804.0 3879.5 3969.9 3982.9 3395.5 3456.8 3470.2 3472.0 3474.9 486.6 492.0 545.3 565.8 586.6 596.6 615.3 632.3 679.0 679.8 9871.3 9905.4 10143.4 10399.7 10593.2 502.2 521.5 556.7 564.7 582.3 4786.9 4934.2 4942.5 4949.1 4986.1 105208.5 106632.0 106735.0 106939.9 106967.5 60686.7 75773.6 88696.0 89720.6 91514.0 50747.0 59856.2 67898.7 67992.7 68160.7 10002.9 10076.7 10940.7 11048.9 11132.3 48358.8 48568.3 48586.1 49329.0 49635.5 45044.0 60129.4 63030.7 63685.8 63737.0 12896.0 13228.7 13317.9 13450.5 13466.6 11787.4 11908.3 11946.3 12348.3 12350.2 10208.4 13065.3 13073.5 13350.7 13695.2 12954.7 13057.3 13639.4 13647.1 13784.6 12813.6 12991.1 13041.4 13160.6 13496.3 10788.5 10886.2 10978.6 11114.6 11782.5 9169.9 9681.4 10333.6 10918.8 10925.7 05:02:44 INFO - PID 1637 | -------- Summary: end -------- 05:02:44 INFO - PID 1637 | 05:02:45 INFO - TEST-INFO | 1637: exit 0 05:02:45 INFO - TEST-OK | dromaeo_css | took 1117829ms 05:02:45 INFO - TEST-START | kraken 05:02:45 INFO - operating with platform_type : mac_ 05:02:45 INFO - Initialising browser for kraken test... 05:02:45 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmprehv0e/profile http://localhost:49241/getInfo.html) 05:02:47 INFO - PID 1649 | 2017-03-16 05:02:47.998 plugin-container[1651:10175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 05:02:47 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:48 INFO - PID 1649 | 2017-03-16 05:02:47.999 plugin-container[1651:10175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 05:02:48 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:48 INFO - PID 1649 | 2017-03-16 05:02:47.999 plugin-container[1651:10175] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:02:48 INFO - PID 1649 | __metrics Screen width/height:1600/1200 05:02:48 INFO - PID 1649 | colorDepth:24 05:02:48 INFO - PID 1649 | Browser inner width/height: 1024/689 05:02:48 INFO - PID 1649 | __metrics 05:02:48 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:02:49 INFO - TEST-INFO | 1649: exit 0 05:02:49 INFO - Browser initialized. 05:02:49 INFO - Running cycle 1/1 for kraken test... 05:02:49 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmprehv0e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:51 INFO - PID 1652 | 2017-03-16 05:02:51.844 plugin-container[1653:10289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 05:02:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:51 INFO - PID 1652 | 2017-03-16 05:02:51.844 plugin-container[1653:10289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a17, name = 'com.apple.CFPasteboardClient' 05:02:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:51 INFO - PID 1652 | 2017-03-16 05:02:51.844 plugin-container[1653:10289] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:01 INFO - PID 1652 | RSS: Main: 227835904 05:03:01 INFO - PID 1652 | 05:03:04 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:03:05 INFO - PID 1652 | RSS: Main: 241745920 05:03:05 INFO - PID 1652 | 05:03:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:03:08 INFO - PID 1652 | RSS: Main: 250343424 05:03:08 INFO - PID 1652 | 05:03:12 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:03:12 INFO - PID 1652 | RSS: Main: 253001728 05:03:12 INFO - PID 1652 | 05:03:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:03:15 INFO - PID 1652 | RSS: Main: 255299584 05:03:15 INFO - PID 1652 | 05:03:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:03:17 INFO - PID 1652 | RSS: Main: 255938560 05:03:17 INFO - PID 1652 | 05:03:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:03:21 INFO - PID 1652 | RSS: Main: 260780032 05:03:21 INFO - PID 1652 | 05:03:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:03:25 INFO - PID 1652 | RSS: Main: 261373952 05:03:25 INFO - PID 1652 | 05:03:28 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:03:29 INFO - PID 1652 | RSS: Main: 261738496 05:03:29 INFO - PID 1652 | 05:03:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:31 INFO - PID 1652 | RSS: Main: 261582848 05:03:31 INFO - PID 1652 | 05:03:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:33 INFO - PID 1652 | RSS: Main: 261693440 05:03:33 INFO - PID 1652 | 05:03:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:36 INFO - PID 1652 | RSS: Main: 261685248 05:03:36 INFO - PID 1652 | 05:03:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:40 INFO - PID 1652 | RSS: Main: 262045696 05:03:40 INFO - PID 1652 | 05:03:43 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:44 INFO - PID 1652 | RSS: Main: 259358720 05:03:44 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:03:46 INFO - PID 1652 | RSS: Main: 261173248 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | __start_tp_report 05:03:46 INFO - PID 1652 | _x_x_mozilla_page_load 05:03:46 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:03:46 INFO - PID 1652 | |i|pagename|runs| 05:03:46 INFO - PID 1652 | |0;ai-astar;112;116;174;175;154;117;118;121;120;120 05:03:46 INFO - PID 1652 | |1;audio-beat-detection;154;132;139;157;117;118;115;116;136;120 05:03:46 INFO - PID 1652 | |2;audio-dft;202;208;209;219;194;201;199;232;257;192 05:03:46 INFO - PID 1652 | |3;audio-fft;80;64;73;110;83;78;60;65;89;82 05:03:46 INFO - PID 1652 | |4;audio-oscillator;90;70;93;71;70;71;85;72;74;82 05:03:46 INFO - PID 1652 | |5;imaging-gaussian-blur;87;136;137;107;77;136;136;117;91;92 05:03:46 INFO - PID 1652 | |6;imaging-darkroom;99;98;99;100;102;99;100;99;99;102 05:03:46 INFO - PID 1652 | |7;imaging-desaturate;104;90;93;92;91;90;92;90;113;110 05:03:46 INFO - PID 1652 | |8;json-parse-financial;67;67;67;68;67;68;66;68;70;68 05:03:46 INFO - PID 1652 | |9;json-stringify-tinderbox;57;66;59;64;64;59;58;57;58;59 05:03:46 INFO - PID 1652 | |10;stanford-crypto-aes;83;85;83;84;82;82;83;85;83;84 05:03:46 INFO - PID 1652 | |11;stanford-crypto-ccm;142;132;145;150;139;145;144;133;193;173 05:03:46 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;187;192;186;199;164;235;236;214;189;221 05:03:46 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;68;67;70;66;71;76;95;97;92;78 05:03:46 INFO - PID 1652 | __end_tp_report 05:03:46 INFO - PID 1652 | __start_cc_report 05:03:46 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1005 05:03:46 INFO - PID 1652 | __end_cc_report 05:03:46 INFO - PID 1652 | __startTimestamp1489665826285__endTimestamp 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | ------- Summary: start ------- 05:03:46 INFO - PID 1652 | Number of tests: 14 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:132.70 Median:120.50 stddev:24.90 (20.7%) stddev-sans-first:25.25 05:03:46 INFO - PID 1652 | Values: 112.0 116.0 174.0 175.0 154.0 117.0 118.0 121.0 120.0 120.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:134.00 stddev:15.84 (11.8%) stddev-sans-first:14.32 05:03:46 INFO - PID 1652 | Values: 154.0 132.0 139.0 157.0 117.0 118.0 115.0 116.0 136.0 120.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:211.30 Median:208.50 stddev:20.02 (9.6%) stddev-sans-first:20.95 05:03:46 INFO - PID 1652 | Values: 202.0 208.0 209.0 219.0 194.0 201.0 199.0 232.0 257.0 192.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:78.40 Median:79.00 stddev:14.54 (18.4%) stddev-sans-first:15.41 05:03:46 INFO - PID 1652 | Values: 80.0 64.0 73.0 110.0 83.0 78.0 60.0 65.0 89.0 82.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:8.89 (11.4%) stddev-sans-first:8.26 05:03:46 INFO - PID 1652 | Values: 90.0 70.0 93.0 71.0 70.0 71.0 85.0 72.0 74.0 82.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:111.60 Median:107.00 stddev:23.79 (22.2%) stddev-sans-first:23.51 05:03:46 INFO - PID 1652 | Values: 87.0 136.0 137.0 107.0 77.0 136.0 136.0 117.0 91.0 92.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:99.00 stddev:1.34 (1.4%) stddev-sans-first:1.39 05:03:46 INFO - PID 1652 | Values: 99.0 98.0 99.0 100.0 102.0 99.0 100.0 99.0 99.0 102.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:90.50 stddev:8.95 (9.9%) stddev-sans-first:9.07 05:03:46 INFO - PID 1652 | Values: 104.0 90.0 93.0 92.0 91.0 90.0 92.0 90.0 113.0 110.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.12 05:03:46 INFO - PID 1652 | Values: 67.0 67.0 67.0 68.0 67.0 68.0 66.0 68.0 70.0 68.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:59.00 stddev:3.28 (5.6%) stddev-sans-first:3.28 05:03:46 INFO - PID 1652 | Values: 57.0 66.0 59.0 64.0 64.0 59.0 58.0 57.0 58.0 59.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:1.07 (1.3%) stddev-sans-first:1.13 05:03:46 INFO - PID 1652 | Values: 83.0 85.0 83.0 84.0 82.0 82.0 83.0 85.0 83.0 84.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:145.00 stddev:19.03 (13.1%) stddev-sans-first:19.99 05:03:46 INFO - PID 1652 | Values: 142.0 132.0 145.0 150.0 139.0 145.0 144.0 133.0 193.0 173.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.30 Median:206.50 stddev:23.46 (11.4%) stddev-sans-first:24.22 05:03:46 INFO - PID 1652 | Values: 187.0 192.0 186.0 199.0 164.0 235.0 236.0 214.0 189.0 221.0 05:03:46 INFO - PID 1652 | 05:03:46 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:77.00 stddev:12.15 (15.8%) stddev-sans-first:12.33 05:03:46 INFO - PID 1652 | Values: 68.0 67.0 70.0 66.0 71.0 76.0 95.0 97.0 92.0 78.0 05:03:46 INFO - PID 1652 | -------- Summary: end -------- 05:03:46 INFO - PID 1652 | 05:03:47 INFO - TEST-INFO | 1652: exit 0 05:03:47 INFO - TEST-OK | kraken | took 62170ms 05:03:47 INFO - SUITE-END | took 1179s 05:03:47 INFO - Completed test suite (00:19:40) 05:03:47 INFO - javascript benchmark 05:03:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7900.911523087479, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3240.57872476578, 3454.3436747018063, 3470.6702863228015, 3500.929981400372, 3517.595476520201, 5883.294004719434, 5925.200097986771, 6045.486133678637, 6073.665948372838, 6114.144019837227, 5991.730372133254, 6075.544334174943, 6140.140380346744, 6140.171076904617, 6162.537809664271, 4498.842540511083, 5403.7027963462, 5488.17677348399, 5518.641288316247, 5583.106702927533, 4977.377827771528, 5392.91910621341, 5453.509184173424, 5532.806351777688, 5557.888842223153, 5372.677639341613, 5412.783488660449, 5482.068048431776, 5516.0, 5528.143137813645, 5446.891062178774, 5451.536619387317, 5457.645253058538, 5480.780768769245, 5492.862678433071, 5387.19192121181, 5391.056565101092, 5405.378381486101, 5407.107827208506, 5461.918071228934, 4805.255185446262, 5844.88310233793, 5906.497947674453, 5964.821055368346, 6097.902377572035, 6230.937690623081, 6288.779892703734, 6360.300366959677, 6384.680765961683, 6395.232572091379, 6449.580777249511, 6462.192225971754, 6465.612063276219, 6480.15757951468, 6490.221173459168, 6013.639181649115, 6170.290416602058, 6185.0, 6196.349383314777, 6208.627482351073, 6378.138951241523, 6501.577397469149, 6569.737210511622, 6593.714225726033, 6616.239132499824, 6136.6722581676495, 6251.562390632612, 6277.246730392382, 6332.430081292706, 6358.141650877073, 6299.0, 6364.809055728336, 6376.776812811529, 6468.385503377266, 6474.643894585749, 6323.114763933053, 6373.649449280241, 6385.2656944451055, 6389.233292004897, 6399.072134540465, 5476.479734425223, 6215.751369945152, 6265.27949285838, 6329.0, 6348.111264422984, 5189.065968125698, 5207.192885102815, 5221.947780522146, 5235.973820130875, 5258.474152584711, 4619.9076018479445, 4623.69945953512, 4634.768261586907, 4649.7210167389385, 4659.394278743742, 5986.491148252448, 6092.99465588173, 6168.167297414881, 6183.3816618337805, 6221.253449586078, 6296.087067375205, 6318.304986451487, 6409.615423074537, 6441.613503189824, 6456.903146452807, 6135.96932015346, 6146.508279337642, 6152.600485810537, 6164.383561643889, 6169.290531588835, 5686.886262274731, 5701.686407247558, 5713.800016999385, 5715.31416230055, 5763.682997435098, 6432.517561183024, 6485.740570377132, 6609.570377925419, 6631.502637302222, 6636.13730215069, 6445.097686324012, 6461.353864613501, 6472.996685513753, 6481.805545833632, 6612.23959244693, 6597.868042639025, 6660.400563949268, 6665.800025999228, 6684.097646817618, 6693.732250709917, 5246.632735708464, 6261.874762504815, 6287.088372186099, 6343.619382837044, 6348.269948955929, 4848.7090774553635, 5654.3497497788285, 5946.702664866652, 6396.008618664115, 6490.026496025634, 5261.3686357637325, 6076.8784624308155, 6232.968835155795, 6263.27972283175, 6265.248170219603, 6224.346443623354, 6230.813075607739, 6244.594101383395, 6299.779507717208, 6319.96840015797, 6139.171211886338, 6160.414760597736, 6248.687565621792, 6249.218847644044, 6277.905831412624, 5178.611604129781, 5225.973870130625, 6232.065190221322, 6307.180066591313, 6312.400321969406, 6182.938170618237, 6186.072089186658, 6189.938100619116, 6194.907076393948, 6197.566170368212], "value": 5880.859107037196, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.7719785156642, 780.5745868501666, 807.1202389395557, 928.6099838068011, 934.6027938126299, 36473.81763091184, 37360.43959340612, 37362.0, 37422.62577374226, 37444.0, 36307.0, 36670.633293667124, 36681.0, 36754.44868326971, 38009.0, 7737.458377913563, 7901.130875603679, 10691.572337106545, 10999.505022273977, 11050.060744836699, 10929.56281748733, 11001.724956876062, 11091.500882460268, 11096.223264371498, 11119.388433636146, 11035.172362072837, 11143.33140011602, 11148.498317575688, 11148.498317575728, 11149.940755628202, 11017.779644407146, 11235.887641123607, 11244.0, 11280.717982050432, 11284.717882052932, 10848.077913377372, 10861.83707244392, 10881.183911206692, 10897.346159230392, 10905.67282981504, 38286.23427531434, 38341.041473963094, 38426.84719458421, 38441.03897402559, 38473.03817404559, 3807.752496087773, 3826.7512611680154, 3827.311084004878, 3835.5013848199555, 3845.9615403845883, 27588.862055689595, 27620.723792762015, 27792.861035694896, 28041.298967525974, 28068.0, 2690.90581829637, 3108.6425061118034, 3116.56368108465, 3171.3815805917857, 3262.331222099464, 204018.0, 207942.0, 273665.999999998, 320598.0, 322657.0, 46914.5308546917, 47159.82100447448, 47231.763841180575, 47249.763751181024, 47265.5273447268, 49127.0, 49364.753176234604, 49390.813964533205, 49521.50478495241, 49561.25658115131, 13931.721365572632, 14046.227457489937, 14050.508232211823, 14053.227072510905, 14067.859321406859, 11513.366764828015, 11701.356425396685, 11749.88250117505, 11757.235779674294, 11766.705832354088, 12715.791999760007, 13729.382177802023, 13730.51943181984, 13754.243516606482, 13871.722565548633, 5600.943990560124, 5687.8009269676195, 5777.335606405233, 6146.0, 6187.412195841388, 1034.8706411698538, 1040.8750949886062, 1107.2083460325905, 1125.2966895690195, 1171.718787491013, 23350.7664923352, 25904.8704756475, 26310.079147229753, 26491.0, 26512.337191570365, 1133.642902485731, 1181.8995385392338, 1206.2943178240828, 1216.4282787090053, 1275.5471807508386, 13337.933310333387, 13635.863641363658, 13642.2496762679, 13653.863461365458, 13770.173789572658, 359387.0, 362402.0, 367997.0, 368120.0, 368593.0, 54184.0, 54776.726116369166, 54837.0, 54979.45020549823, 55000.449995500334, 1377.4145987955078, 1411.632975426376, 1414.3069895751007, 1414.936327865228, 1440.7046555456106, 27933.301667458476, 28183.577246340912, 29206.70793292125, 29457.558136628395, 29745.0, 45245.54754452545, 45407.864803378856, 45644.54355456403, 45828.31257531206, 45842.541574583825, 455900.0, 457083.0, 458530.0, 459481.0, 460574.0, 32942.505862411606, 34088.82955585206, 34096.48855267223, 34118.65881341254, 34134.0, 41325.586744132175, 41464.585354146075, 41519.58480415278, 42019.36970945377, 42157.367639484815, 30593.77624894979, 32052.03843884687, 32588.83705581457, 33866.49200262047, 35034.82482587571, 30575.318357490552, 34972.65027349694, 35770.64229357673, 36129.458058129676, 36229.818850905576, 33549.83225083859, 34739.826300868335, 36319.45520817137, 36396.27207455783, 36429.45355819612, 29715.851420742758, 31681.841590791897, 31684.36631267408, 31840.885569004975, 31952.0, 23982.0, 24051.18336540666, 24157.15449959243, 24433.75566244315, 24512.632310515713], "value": 18674.857125709026, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.36828179757794, 414.84028648970155, 418.84712080090765, 429.931211006239, 434.49598465779684, 19725.309614163507, 21376.46558836028, 22302.996365163566, 22309.0, 22318.330450086483, 14416.92791536041, 20956.790432095673, 21426.89286553565, 21613.0, 21658.0, 5392.973035134829, 6745.831354216135, 6765.0, 6770.0860383848285, 6778.356056174655, 5586.692731899744, 6454.128692626506, 6657.633830139341, 6671.899921501182, 6680.532362734611, 6670.866582668344, 6685.665716714169, 6685.997100434925, 6694.163229596301, 6714.328567143271, 7277.235890231529, 7628.0846298443985, 7632.389408847279, 7649.426293028045, 7665.731699390551, 7088.291170882922, 7245.4928155029165, 7298.343149116605, 7303.488755787097, 7453.031105956191, 26729.866350668315, 26816.73183268162, 27586.172414827386, 27706.307342316402, 27760.444791104266, 2805.551111822119, 2876.280929767558, 2894.536874100155, 2894.768418526534, 2907.25864904448, 13650.658733531642, 15913.283902224312, 16012.718982481374, 17042.23309951055, 17230.396936107303, 1890.867639265252, 1980.227711192636, 1984.4046785964154, 1995.760508738946, 2029.2288930206576, 140865.0, 148469.0, 163745.0, 166295.0, 166767.32628715996, 22150.88924555367, 22640.773592263868, 22824.543509130057, 23055.42361440944, 23701.0, 35474.743030836464, 35549.11127221789, 35566.28867422689, 35588.64411355905, 35677.82671512668, 10261.281710280358, 10489.265751397481, 10682.519286631965, 10732.570697172026, 10858.891411085946, 8474.1949514796, 8496.686639419124, 8591.3556483264, 8746.431481953652, 8748.69379571712, 9050.366474346733, 10755.892441075646, 10796.94601527003, 10861.294015888941, 10915.727106822393, 3803.8478460861256, 4415.977920110379, 4525.547445255448, 4556.65825063115, 4570.908581828411, 1064.41989115932, 1066.7439814444479, 1072.1851392941264, 1073.6349641122056, 1084.5011294804478, 22185.778142218373, 22244.55510889773, 23319.300420987398, 24015.0, 24219.0, 1077.5851297250615, 1093.1091160704, 1120.4229821641861, 1121.8317252412203, 1127.4362818590705, 11468.36923969173, 11571.826422603668, 12314.630561083182, 12449.875501245053, 12519.436625351698, 603348.0, 623994.0, 679072.6046369835, 696597.0, 714723.0, 127539.0, 157862.0, 159002.0, 159892.40107599008, 161224.0, 1307.5815738963631, 1325.4830616059744, 1357.7556039912713, 1358.796180572922, 1361.7889227169605, 19918.004099794663, 20827.0, 20883.746975181537, 20946.0, 20987.0, 54958.0, 56063.71968140133, 60413.0938035921, 62901.37098629138, 63222.68388658027, 90430.54784726034, 91597.54201228952, 91600.0, 91992.54003729939, 92442.53778731333, 11615.419229038684, 11797.0, 12016.158868879094, 12122.696932576757, 12214.389280536116, 12160.939195303967, 12899.742005160031, 13137.014723895554, 13173.736525269633, 13523.323833808074, 10226.846597301195, 11303.717407064889, 11353.262037967506, 11412.144089193509, 11621.535138594361, 9749.220062395134, 11351.716207094889, 11391.715207119889, 11560.30638161713, 11832.82250766222, 10549.261551691308, 10698.197635177237, 10806.189535785004, 10822.350658960173, 10849.620263291063, 11783.265698948984, 11925.821112683143, 11967.341796201292, 12085.154039217523, 12106.75786484283, 8811.427257228259, 10517.737056573647, 10625.628103016357, 10698.839517407401, 10721.98141176587], "value": 11876.224514703556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.1942970225784, 1570.3953977718581, 1582.762585612158, 1588.52344296711, 1645.5803770038642, 9378.531073446318, 9641.710748677533, 10518.789624207511, 10924.016838484535, 10984.176186785993, 10347.120494757955, 10745.8388124178, 10762.677119686421, 10777.730556736085, 10785.730356741085, 1813.4287699374654, 1873.6627407066724, 2156.7304086989125, 2189.2994241842616, 2190.5728382965326, 1921.2315073970383, 2262.558801033799, 2293.8050265727434, 2305.5504176685554, 2321.0367697405554, 1903.7715474143122, 2281.6007198740235, 2291.175176936302, 2307.676925230469, 2313.097891822191, 2266.01428378655, 2275.4311422144465, 2278.8290878184202, 2278.954420911589, 2286.1541229745017, 2232.5758105959817, 2333.4166458385403, 2342.297310806768, 2342.976570234293, 2345.882705864717, 10997.780044399067, 11115.333080015225, 11128.276662017024, 11135.942085501865, 11140.665780026613, 4559.247724125516, 5483.917741233885, 5717.685527295997, 5731.0, 5731.484166425, 3323.5180898769786, 3417.9487307690406, 3622.619624939396, 3632.037510059836, 3638.8362523686496, 1151.5739176504746, 1166.7433164703752, 1185.146694380045, 1203.205884116479, 1208.9395530223542, 12521.937390313082, 15265.007774494734, 15326.310316035693, 15407.614809629737, 15436.459723909722, 3898.7270891037356, 4622.39908811859, 4720.704682066539, 4804.615630749601, 4832.492588278251, 11517.76964460706, 12632.431540580696, 12832.294223817593, 12834.80747788784, 12840.871591283967, 5100.821471248489, 5498.81775418287, 5663.9716801415725, 5747.626404283708, 5773.24947756789, 4466.285394336891, 5033.3205017322925, 5077.822276220315, 5109.233614957711, 5147.6396652234735, 3968.8610898618986, 4025.5370632377067, 4076.5719599442223, 4090.0, 4134.751914885116, 1137.6985098948785, 1140.9543618255177, 1141.1042331769522, 1146.4497041420166, 1156.4738044189874, 748.3190296829858, 763.576215200569, 784.3685832904566, 788.9605519724106, 804.8430556041633, 6329.7151628175925, 6488.902666460007, 6642.535022548375, 6672.532922695462, 6706.128203333536, 952.5094576293214, 955.6702937486556, 960.1166926427703, 982.4007355513274, 984.1339621133357, 3593.299306635181, 4086.4279000939296, 4163.91672166555, 4173.749575025508, 4303.956960430418, 13074.411651475517, 13101.737965240642, 13265.801012984813, 13587.456501740018, 13610.727785444236, 13135.0, 13245.27151006704, 13405.329733513481, 13421.664458388423, 13445.260510672008, 9900.257480688977, 10003.099721025143, 10052.949735251423, 10101.949490252502, 10103.797924041479, 6236.251649802053, 6344.936550634435, 6348.746050157942, 6408.487321014214, 6415.8396040097505, 11722.70693232676, 12074.698132546757, 12199.573014944433, 12226.266424014588, 12238.999999999643, 11889.643310700692, 11913.94043029814, 12271.877281227073, 12284.938575307424, 12453.315067671365, 8422.6209820557, 8974.41666291689, 9131.954340228256, 9205.125513076247, 9243.676471323472, 8273.296769774637, 8745.562721863755, 10254.384736915808, 10352.016558426938, 10368.170546356123, 9015.909840901508, 9075.54622268897, 9116.635334586543, 9116.635334586808, 9144.497052662167, 8908.0, 8916.910830891607, 8960.865587016331, 9017.729468115705, 9041.547922603711, 7824.64789084504, 7830.2952734254195, 7949.0, 7951.0, 7983.2415920487465, 5743.253377060955, 5795.797147099831, 5874.530037597067, 5915.704214789157, 5933.881322373615, 5311.814086506954, 5836.212111364912, 5946.5540084494705, 5959.582829201914, 6068.089786532056], "value": 5014.6728365534955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.5981740633084, 379.51801212460174, 382.1859439394092, 383.50527819113347, 385.33529661334194, 5101.67873617504, 6187.52499000399, 6242.968785156074, 6243.469305109065, 6365.989426661208, 6058.939410605893, 6182.659953702545, 6185.381461853813, 6187.195664563612, 6227.5952063115865, 4607.654425918053, 4751.263554149113, 4803.831865884695, 4990.305235173002, 5044.562299744555, 4180.268453020724, 4834.395700537433, 4995.700257984515, 4999.500049994989, 5091.134507133796, 5051.343325367697, 5054.570361519257, 5072.568831649296, 5077.466865979074, 5088.422588997422, 4830.396200474941, 4938.715933857223, 4964.950350496503, 5078.238264260353, 5164.328637277167, 4350.651947844165, 4518.2319005769095, 4542.591166795004, 4546.568076032771, 4559.544045595447, 5723.713814309268, 5840.503557197643, 5961.5230781537375, 6020.5785595008365, 6022.307434644984, 5770.422957704238, 5844.47399734026, 5913.2608423947, 5925.955545336852, 5929.199558059693, 5452.863678408032, 5857.941420585825, 6399.26408463028, 6639.0, 6645.6344901030425, 4482.8431004915, 4509.075639493896, 4667.346571479996, 4791.161546729309, 5172.301739265189, 6686.498512611525, 6856.931430685729, 7028.543144695578, 7087.610181440019, 7093.148822141325, 4613.653975951817, 4624.699394539344, 5180.145276029489, 5201.427842937273, 5208.401033881152, 4737.857864264078, 5360.731963401814, 5388.2725832012175, 5423.701696406735, 5447.182922561568, 5646.520045796071, 5846.473817356458, 5860.769238459971, 5873.412658734178, 5885.352611212763, 6622.8013159604325, 6642.501812364092, 6651.7671881484885, 6667.33326667339, 6674.866502669919, 1506.1716056169064, 1631.9020858748513, 1827.0, 1842.7653472173677, 1865.5709186887093, 1186.1400484648561, 1312.566852938528, 1345.5357901524155, 1348.2449828096298, 1375.9587212383644, 4828.0, 5323.707196104174, 5687.829365119053, 6089.786857460056, 6178.691065446799, 5934.258217722785, 6612.801615951529, 6777.864442711217, 6853.348931851466, 6915.654217289115, 5651.632643878134, 5692.9146062809095, 6137.324894261627, 6175.84560385994, 6212.006079027425, 3115.8753649854207, 3146.65386807451, 3339.8477525253747, 3626.7279954003557, 3629.709623230135, 5729.455701708331, 6092.268927728613, 6431.74273029083, 6580.815453218371, 6824.965875170592, 6779.593224406552, 6912.585244885323, 7097.574145551283, 7099.751508697171, 7122.92877071233, 6775.0, 6778.356056174655, 7076.25699301566, 7137.2149063602965, 7139.678714457758, 5549.5837812163445, 6759.155105611799, 6865.794026179223, 6883.862322753416, 6890.44876409876, 4536.931946020878, 6582.605043697394, 6786.864262714619, 6817.829554261183, 6820.795376138723, 6217.906731399123, 7049.647517623996, 7050.647467626701, 7155.677994490156, 7181.922711593303, 4449.421575195204, 5201.609879258995, 5295.364556253275, 5307.734613269244, 5317.468253174651, 5249.238860365302, 6185.659788711664, 6607.636579988147, 6726.596404215763, 6749.190097188369, 5682.488576028178, 6149.877002460015, 6486.578372405778, 6555.147830781968, 6689.832754181184, 6384.361563843579, 6514.4788416925585, 6653.401193892573, 6654.234763002317, 6716.496262780409, 5584.022796010763, 6284.0, 6509.804705858832, 6525.934740652533, 6531.510136739668, 5857.297124345106, 6276.9372306276355, 6642.269350371552, 6679.1984961802955, 6712.630805305755, 5671.574631902541, 6451.290358060516, 6514.967425163033, 6534.738610455719, 6550.8362290943105], "value": 4995.197542139589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7858674517527, 698.4691634357887, 766.6396793507054, 772.5982489105666, 814.9633266503008, 15362.53912382629, 15710.842891571067, 15716.214189290547, 15799.921000394996, 15992.0, 14306.713865722679, 14713.264336783144, 14796.778048329285, 14839.258037098129, 14930.776038359434, 924.1959495239134, 934.7756538430796, 941.2422999485411, 944.4946953379944, 946.2004606107828, 802.4904185842004, 911.4212475078307, 931.7903471718843, 934.7990182110844, 947.9336446448751, 1000.2897942460861, 1000.824855650262, 1001.8146642871055, 1002.4386343647544, 1004.3923426327076, 979.2410881566772, 983.3018556824663, 983.7934033852899, 986.0238364019641, 994.6419289055934, 730.0436428278972, 730.6748496918873, 735.0554537419443, 735.5696917303384, 744.7840126363349, 15989.760153597705, 16382.0, 16393.508194754057, 16470.341186352533, 16566.917165414216, 14542.854571454256, 14839.406423743038, 14857.331420086122, 14891.47879824201, 14919.030263032868, 11703.239289446245, 12049.759004819942, 12055.397230138458, 12091.395430228453, 12112.15214934955, 3629.2046573136076, 3815.561210460805, 3832.405977073558, 3841.9807900960595, 3849.3648547989546, 104945.47527262391, 106050.46974765154, 108443.91556084497, 108951.0, 111465.0, 11120.55517779296, 11264.605738799266, 11334.716632084264, 11565.479553420117, 11871.287722736663, 24649.63025554618, 24952.00191992336, 24983.87508062448, 25008.749912500643, 25115.87442062778, 7541.283578060075, 7779.8833017504785, 7815.0, 7920.683172673143, 8175.059868115243, 3733.141377483194, 3803.9980593867067, 3879.4568760373572, 3969.8809035728973, 3982.944519702281, 3395.5246265522846, 3456.844442000116, 3470.219200679827, 3472.0, 3474.947875781865, 486.5791090706513, 491.9532644398776, 545.2693390856192, 565.8217661436635, 586.6069733278714, 596.5794115148809, 615.3262177915176, 632.34552238433, 679.0326274181758, 679.7790718016664, 9871.309008369346, 9905.356151850106, 10143.442110683836, 10399.740006499898, 10593.205509586813, 502.22155658729554, 521.5488602358932, 556.7299859568107, 564.7430419159273, 582.2547139661241, 4786.923525977836, 4934.235193545006, 4942.5304596063315, 4949.084419382354, 4986.052649996489, 105208.47395763126, 106632.0, 106735.03491356195, 106939.930600693, 106967.46516267525, 60686.69656651688, 75773.6211318951, 88696.0, 89720.5513972426, 91514.0, 50747.0, 59856.20431387065, 67898.66050669715, 67992.6600366995, 68160.65919670468, 10002.949985250027, 10076.69769906904, 10940.6717798463, 11048.944755276172, 11132.332060076422, 48358.758206208746, 48568.271475927184, 48586.05655773871, 49329.0, 49635.503644964534, 45044.0, 60129.398706012376, 63030.68484657731, 63685.810270356524, 63737.0, 12896.0, 13228.743269389393, 13317.933410332886, 13450.529231476852, 13466.64333741611, 11787.41062946832, 11908.336233037011, 11946.34295113777, 12348.320842353756, 12350.197237179555, 10208.387496749921, 13065.346732663518, 13073.477060917457, 13350.666233343856, 13695.246761428218, 12954.67613309637, 13057.28184949799, 13639.38622761998, 13647.11293765902, 13784.586462406143, 12813.615591532269, 12991.090623656632, 13041.369828771403, 13160.60518184456, 13496.32518374097, 10788.460576971276, 10886.23796334249, 10978.615748449085, 11114.610988615359, 11782.469788859351, 9169.88224003842, 9681.354169791011, 10333.586656533655, 10918.781624367628, 10925.744846440888], "value": 7444.845967896513, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 174.0, 175.0, 154.0, 117.0, 118.0, 121.0, 120.0, 120.0], "value": 132.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 132.0, 139.0, 157.0, 117.0, 118.0, 115.0, 116.0, 136.0, 120.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 208.0, 209.0, 219.0, 194.0, 201.0, 199.0, 232.0, 257.0, 192.0], "value": 211.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 64.0, 73.0, 110.0, 83.0, 78.0, 60.0, 65.0, 89.0, 82.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 93.0, 71.0, 70.0, 71.0, 85.0, 72.0, 74.0, 82.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 136.0, 137.0, 107.0, 77.0, 136.0, 136.0, 117.0, 91.0, 92.0], "value": 111.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 99.0, 100.0, 102.0, 99.0, 100.0, 99.0, 99.0, 102.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 90.0, 93.0, 92.0, 91.0, 90.0, 92.0, 90.0, 113.0, 110.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 68.0, 66.0, 68.0, 70.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 66.0, 59.0, 64.0, 64.0, 59.0, 58.0, 57.0, 58.0, 59.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 84.0, 82.0, 82.0, 83.0, 85.0, 83.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 132.0, 145.0, 150.0, 139.0, 145.0, 144.0, 133.0, 193.0, 173.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 192.0, 186.0, 199.0, 164.0, 235.0, 236.0, 214.0, 189.0, 221.0], "value": 202.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 66.0, 71.0, 76.0, 95.0, 97.0, 92.0, 78.0], "value": 78.0, "unit": "score"}]}]} 05:03:47 INFO - Return code: 0 05:03:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:03:47 ERROR - # TBPL SUCCESS # 05:03:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:47 INFO - 2017-03-16 05:03:47.635 screenresolution[1657:10679] starting screenresolution argv=screenresolution get 05:03:47 INFO - 2017-03-16 05:03:47.637 screenresolution[1657:10679] Display 0: 1600x1200x32@60 05:03:47 INFO - 2017-03-16 05:03:47.643 screenresolution[1658:10682] starting screenresolution argv=screenresolution list 05:03:47 INFO - Available Modes on Display 0 05:03:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:47 INFO - Intel Iris: 05:03:47 INFO - Chipset Model: Intel Iris 05:03:47 INFO - Type: GPU 05:03:47 INFO - Bus: Built-In 05:03:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:47 INFO - Vendor: Intel (0x8086) 05:03:47 INFO - Device ID: 0x0a2e 05:03:47 INFO - Revision ID: 0x0009 05:03:47 INFO - Displays: 05:03:47 INFO - Display: 05:03:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:47 INFO - Display Serial Number: 1600x1200 60 05:03:47 INFO - Main Display: Yes 05:03:47 INFO - Mirror: Off 05:03:47 INFO - Online: Yes 05:03:47 INFO - Rotation: Supported 05:03:47 INFO - Return code: 0 05:03:47 INFO - Running post-action listener: _resource_record_post_action 05:03:47 INFO - [mozharness: 2017-03-16 12:03:47.883601Z] Finished run-tests step (success) 05:03:47 INFO - Running post-run listener: _resource_record_post_run 05:03:47 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 45803520; Write bytes: 516224512; Read time: 484; Write time: 1662 05:03:47 INFO - TinderboxPrint: CPU usage
54.0% 05:03:47 INFO - TinderboxPrint: I/O read bytes / time
45,803,520 / 484 05:03:47 INFO - TinderboxPrint: I/O write bytes / time
516,224,512 / 1,662 05:03:47 INFO - TinderboxPrint: CPU idle
2,210.5 (46.0%) 05:03:47 INFO - TinderboxPrint: CPU system
961.9 (20.0%) 05:03:47 INFO - TinderboxPrint: CPU user
1,637.2 (34.0%) 05:03:47 INFO - TinderboxPrint: Swap in / out
250,892,288 / 0 05:03:47 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 208079360; Write bytes: 203028480; Read time: 16457; Write time: 434 05:03:47 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 43603968; Write bytes: 302116352; Read time: 459; Write time: 1197 05:03:48 INFO - Running post-run listener: _upload_blobber_files 05:03:48 INFO - Blob upload gear active. 05:03:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:03:48 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 05:03:48 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'] 05:03:48 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 05:03:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:03:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:49 INFO - (blobuploader) - INFO - Done attempting. 05:03:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:03:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:51 INFO - (blobuploader) - INFO - Done attempting. 05:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:51 INFO - (blobuploader) - INFO - Done attempting. 05:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:03:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:54 INFO - (blobuploader) - INFO - Done attempting. 05:03:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:03:54 INFO - Return code: 0 05:03:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:03:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:03:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7cd9fb61ef84b1504201e181a3186fd485691f80cadc04a78c51953c11308aef8b077155037e5d794a83a455d0d17e85cb0c98682b536cdc816580a0fb3370", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba6f40ad93fe6bea2601715ae2108bc07d9a26556293454ceb4eb3f900fc6b44ee385066112e99d0589885325b05feeee81183e08fe3f83d957587ed65807bdc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9aa6da15ab413c8f5487244d17d82b986912477f16fccea9616543a3d32cf377922c162a620fcb51acdfd4a8274145bd09e16c3d5dfc8fd1dd7fefe437cf44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3ad544117525e3516928d413283a9b94a7124028ef85e70990febadbeebe8a40b65aad3e9c8e295e05c1da4e06c5c5b28c91645f3bd51c7e7a0c2c5616702"} 05:03:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:03:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:03:54 INFO - Contents: 05:03:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7cd9fb61ef84b1504201e181a3186fd485691f80cadc04a78c51953c11308aef8b077155037e5d794a83a455d0d17e85cb0c98682b536cdc816580a0fb3370", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba6f40ad93fe6bea2601715ae2108bc07d9a26556293454ceb4eb3f900fc6b44ee385066112e99d0589885325b05feeee81183e08fe3f83d957587ed65807bdc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9aa6da15ab413c8f5487244d17d82b986912477f16fccea9616543a3d32cf377922c162a620fcb51acdfd4a8274145bd09e16c3d5dfc8fd1dd7fefe437cf44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3ad544117525e3516928d413283a9b94a7124028ef85e70990febadbeebe8a40b65aad3e9c8e295e05c1da4e06c5c5b28c91645f3bd51c7e7a0c2c5616702"} 05:03:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:03:54 INFO - Copying logs to upload dir... 05:03:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:03:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.677952 ========= master_lag: 0.88 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-16 05:03:55.326802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 05:03:55.331779) ========= 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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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/9b7cd9fb61ef84b1504201e181a3186fd485691f80cadc04a78c51953c11308aef8b077155037e5d794a83a455d0d17e85cb0c98682b536cdc816580a0fb3370", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba6f40ad93fe6bea2601715ae2108bc07d9a26556293454ceb4eb3f900fc6b44ee385066112e99d0589885325b05feeee81183e08fe3f83d957587ed65807bdc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9aa6da15ab413c8f5487244d17d82b986912477f16fccea9616543a3d32cf377922c162a620fcb51acdfd4a8274145bd09e16c3d5dfc8fd1dd7fefe437cf44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3ad544117525e3516928d413283a9b94a7124028ef85e70990febadbeebe8a40b65aad3e9c8e295e05c1da4e06c5c5b28c91645f3bd51c7e7a0c2c5616702"} build_url:https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011405 build_url: 'https://queue.taskcluster.net/v1/task/fAHKTxkbQfWGRTPKs5aiKA/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/9b7cd9fb61ef84b1504201e181a3186fd485691f80cadc04a78c51953c11308aef8b077155037e5d794a83a455d0d17e85cb0c98682b536cdc816580a0fb3370", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba6f40ad93fe6bea2601715ae2108bc07d9a26556293454ceb4eb3f900fc6b44ee385066112e99d0589885325b05feeee81183e08fe3f83d957587ed65807bdc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9aa6da15ab413c8f5487244d17d82b986912477f16fccea9616543a3d32cf377922c162a620fcb51acdfd4a8274145bd09e16c3d5dfc8fd1dd7fefe437cf44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3ad544117525e3516928d413283a9b94a7124028ef85e70990febadbeebe8a40b65aad3e9c8e295e05c1da4e06c5c5b28c91645f3bd51c7e7a0c2c5616702"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 05:03:55.447905) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 05:03:55.448207) ========= 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.OU1FkBa2Q0/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.VJGbQ82NBS/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005157 ========= master_lag: 0.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 05:03:55.626662) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 05:03:55.626979) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 05:03:55.627371) ========= ========= Total master_lag: 1.23 =========