builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1486603667.03 results: success (0) buildid: 20170208162021 builduid: 5c0705c268f24c61924ed2150060c653 revision: f505911eb333d5ae8c2bf5c44f7b85add6450b53 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.029259) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.029683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.029955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.055134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.055757) ========= 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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-02-08 17:27:47-- 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% 9.90M=0.001s 2017-02-08 17:27:47 (9.90 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119241 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.200436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.200802) ========= 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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.020286 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:47.251685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:27:47.252275) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f505911eb333d5ae8c2bf5c44f7b85add6450b53 --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 f505911eb333d5ae8c2bf5c44f7b85add6450b53 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-02-08 17:27:47,330 truncating revision to first 12 chars 2017-02-08 17:27:47,330 Setting DEBUG logging. 2017-02-08 17:27:47,330 attempt 1/10 2017-02-08 17:27:47,330 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f505911eb333?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 17:27:48,365 unpacking tar archive at: mozilla-central-f505911eb333/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288847 ========= master_lag: 0.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:27:49.172501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:49.172970) ========= script_repo_revision: f505911eb333d5ae8c2bf5c44f7b85add6450b53 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:49.173475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:49.173791) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:27:49.196600) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-08 17:27:49.197000) ========= /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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:27:49 INFO - MultiFileLogger online at 20170208 17:27:49 in /builds/slave/test 17:27:49 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 17:27:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:27:49 INFO - {'append_to_log': False, 17:27:49 INFO - 'base_work_dir': '/builds/slave/test', 17:27:49 INFO - 'blob_upload_branch': 'Firefox', 17:27:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:27:49 INFO - 'branch': 'Firefox', 17:27:49 INFO - 'buildbot_json_path': 'buildprops.json', 17:27:49 INFO - 'config_files': ('talos/mac_config.py',), 17:27:49 INFO - 'default_actions': ('clobber', 17:27:49 INFO - 'read-buildbot-config', 17:27:49 INFO - 'download-and-extract', 17:27:49 INFO - 'populate-webroot', 17:27:49 INFO - 'create-virtualenv', 17:27:49 INFO - 'install', 17:27:49 INFO - 'run-tests'), 17:27:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:27:49 INFO - 'download_minidump_stackwalk': True, 17:27:49 INFO - 'download_symbols': 'ondemand', 17:27:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:27:49 INFO - 'tooltool.py': '/tools/tooltool.py', 17:27:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:27:49 INFO - '/tools/misc-python/virtualenv.py')}, 17:27:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:27:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:27:49 INFO - 'installer_path': 'installer.exe', 17:27:49 INFO - 'log_level': 'info', 17:27:49 INFO - 'log_name': 'talos', 17:27:49 INFO - 'log_to_console': True, 17:27:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:27:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:27:49 INFO - 'opt_config_files': (), 17:27:49 INFO - 'pip_index': False, 17:27:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:27:49 INFO - 'cmd': ('bash', 17:27:49 INFO - '-c', 17:27:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:27:49 INFO - 'enabled': True, 17:27:49 INFO - 'halt_on_failure': False, 17:27:49 INFO - 'name': 'check_screen_resolution'},), 17:27:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:27:49 INFO - 'cmd': ('bash', 17:27:49 INFO - '-c', 17:27:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:27:49 INFO - 'enabled': True, 17:27:49 INFO - 'halt_on_failure': False, 17:27:49 INFO - 'name': 'check_screen_resolution'},), 17:27:49 INFO - 'run_cmd_checks_enabled': True, 17:27:49 INFO - 'sps_profile': False, 17:27:49 INFO - 'sps_profile_interval': 0, 17:27:49 INFO - 'suite': 'dromaeojs-e10s', 17:27:49 INFO - 'system_bits': '32', 17:27:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:27:49 INFO - 'title': 't-yosemite-r7-0014', 17:27:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:27:49 INFO - 'use_talos_json': True, 17:27:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:27:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:27:49 INFO - 'work_dir': 'build'} 17:27:49 INFO - [mozharness: 2017-02-09 01:27:49.399885Z] Running clobber step. 17:27:49 INFO - Running pre-action listener: _resource_record_pre_action 17:27:49 INFO - Running main action method: clobber 17:27:49 INFO - rmtree: /builds/slave/test/build 17:27:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:27:50 INFO - Running post-action listener: _resource_record_post_action 17:27:50 INFO - [mozharness: 2017-02-09 01:27:50.485640Z] Finished clobber step (success) 17:27:50 INFO - [mozharness: 2017-02-09 01:27:50.485764Z] Running read-buildbot-config step. 17:27:50 INFO - Running pre-action listener: _resource_record_pre_action 17:27:50 INFO - Running main action method: read_buildbot_config 17:27:50 INFO - Using buildbot properties: 17:27:50 INFO - { 17:27:50 INFO - "project": "", 17:27:50 INFO - "product": "firefox", 17:27:50 INFO - "script_repo_revision": "production", 17:27:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:27:50 INFO - "repository": "", 17:27:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:27:50 INFO - "buildid": "20170208162021", 17:27:50 INFO - "pgo_build": "False", 17:27:50 INFO - "basedir": "/builds/slave/test", 17:27:50 INFO - "buildnumber": 66, 17:27:50 INFO - "slavename": "t-yosemite-r7-0014", 17:27:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:27:50 INFO - "platform": "yosemite_r7", 17:27:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:27:50 INFO - "branch": "mozilla-central", 17:27:50 INFO - "repo_path": "mozilla-central", 17:27:50 INFO - "revision": "f505911eb333d5ae8c2bf5c44f7b85add6450b53", 17:27:50 INFO - "stage_platform": "macosx64", 17:27:50 INFO - "builduid": "5c0705c268f24c61924ed2150060c653", 17:27:50 INFO - "slavebuilddir": "test" 17:27:50 INFO - } 17:27:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 17:27:50 INFO - Running post-action listener: _resource_record_post_action 17:27:50 INFO - [mozharness: 2017-02-09 01:27:50.489251Z] Finished read-buildbot-config step (success) 17:27:50 INFO - [mozharness: 2017-02-09 01:27:50.489429Z] Running download-and-extract step. 17:27:50 INFO - Running pre-action listener: _resource_record_pre_action 17:27:50 INFO - Running main action method: download_and_extract 17:27:50 INFO - mkdir: /builds/slave/test/build/tests 17:27:50 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')]} 17:27:50 INFO - trying https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 17:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 17:27:51 INFO - Downloaded 1199 bytes. 17:27:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:27:51 INFO - Using the following test package requirements: 17:27:51 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:27:51 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 17:27:51 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 17:27:51 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'jsshell-mac.zip'], 17:27:51 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 17:27:51 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:27:51 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 17:27:51 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 17:27:51 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 17:27:51 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:27:51 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 17:27:51 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:27:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:27:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:27:51 INFO - Fetch https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:27:52 INFO - Expected file size: 34938722 17:27:52 INFO - Obtained file size: 34938722 17:27:59 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:27:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:27:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:27:59 INFO - Fetch https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:28:00 INFO - Expected file size: 34938722 17:28:00 INFO - Obtained file size: 34938722 17:28:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 17:28:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:28:07 INFO - Fetch https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 17:28:09 INFO - Expected file size: 14403454 17:28:09 INFO - Obtained file size: 14403454 17:28: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')]} 17:28:09 INFO - trying https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:28:09 INFO - Downloading https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 17:28:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 17:28:11 INFO - Downloaded 70305105 bytes. 17:28:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:28:11 INFO - mkdir: /builds/slave/test/properties 17:28:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:28:11 INFO - Writing to file /builds/slave/test/properties/build_url 17:28:11 INFO - Contents: 17:28:11 INFO - build_url:https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:28:11 INFO - Running post-action listener: _resource_record_post_action 17:28:11 INFO - Running post-action listener: set_extra_try_arguments 17:28:11 INFO - [mozharness: 2017-02-09 01:28:11.999899Z] Finished download-and-extract step (success) 17:28:12 INFO - [mozharness: 2017-02-09 01:28:12.000048Z] Running populate-webroot step. 17:28:12 INFO - Running pre-action listener: _resource_record_pre_action 17:28:12 INFO - Running main action method: populate_webroot 17:28:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:28:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:28:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:28:12 INFO - '--tppagecycles', 17:28:12 INFO - '7'], 17:28:12 INFO - 'tests': ['tsvgm']}}, 17:28:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:28:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:28:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:28:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:28:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:28:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:28:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['damp', 'tps']}, 17:28:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'tests': ['damp', 'tps']}, 17:28:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['dromaeo_dom']}, 17:28:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:28:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['basic_compositor_video']}, 17:28:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:28:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'other-e10s': {'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['a11yr', 17:28:12 INFO - 'ts_paint', 17:28:12 INFO - 'tpaint', 17:28:12 INFO - 'sessionrestore', 17:28:12 INFO - 'sessionrestore_no_auto_restore', 17:28:12 INFO - 'tabpaint']}, 17:28:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['tsvgx', 17:28:12 INFO - 'tsvgr_opacity', 17:28:12 INFO - 'tart', 17:28:12 INFO - 'tscrollx', 17:28:12 INFO - 'cart', 17:28:12 INFO - 'tsvg_static']}, 17:28:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:28:12 INFO - 'tsvgr_opacity', 17:28:12 INFO - 'tart', 17:28:12 INFO - 'tscrollx', 17:28:12 INFO - 'cart', 17:28:12 INFO - 'tsvg_static']}, 17:28:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'talos_options': ['--disable-e10s'], 17:28:12 INFO - 'tests': ['tp5o']}, 17:28:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:28:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'talos_options': ['--disable-e10s', 17:28:12 INFO - '--xperf_path', 17:28:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:28:12 INFO - 'tests': ['tp5n']}, 17:28:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:28:12 INFO - 'talos_options': ['--xperf_path', 17:28:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:28:12 INFO - 'tests': ['tp5n']}}, 17:28:12 INFO - 'talos.zip': {'path': '', 17:28:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:28:12 INFO - Running post-action listener: _resource_record_post_action 17:28:12 INFO - [mozharness: 2017-02-09 01:28:12.018677Z] Finished populate-webroot step (success) 17:28:12 INFO - [mozharness: 2017-02-09 01:28:12.018764Z] Running create-virtualenv step. 17:28:12 INFO - Running pre-action listener: _resource_record_pre_action 17:28:12 INFO - Running main action method: create_virtualenv 17:28:12 INFO - Creating virtualenv /builds/slave/test/build/venv 17:28: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 17:28:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:28:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:28:12 INFO - Using real prefix '/tools/python27' 17:28:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:28:12 INFO - Installing distribute.............................................................................................................................................................................................done. 17:28:15 INFO - Installing pip.................done. 17:28:15 INFO - Return code: 0 17:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:28:16 INFO - Reading from file tmpfile_stdout 17:28:16 INFO - Output received: 17:28: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) 17:28:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:28: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')]} 17:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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')]} 17:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28: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 17:28: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 17:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:16 INFO - 'HOME': '/Users/cltbld', 17:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:16 INFO - 'LOGNAME': 'cltbld', 17:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:16 INFO - 'MOZ_NO_REMOTE': '1', 17:28:16 INFO - 'NO_EM_RESTART': '1', 17:28:16 INFO - 'PAGER': '/bin/cat', 17:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:16 INFO - 'PWD': '/builds/slave/test', 17:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:16 INFO - 'SHELL': '/bin/bash', 17:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:16 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:16 INFO - 'USER': 'cltbld', 17:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:16 INFO - 'XPC_FLAGS': '0x0', 17:28:16 INFO - 'XPC_SERVICE_NAME': '0', 17:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28: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 17:28:16 INFO - Cleaning up... 17:28:16 INFO - Return code: 0 17:28:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:28: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')]} 17:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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')]} 17:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28: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 17:28: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 17:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:16 INFO - 'HOME': '/Users/cltbld', 17:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:16 INFO - 'LOGNAME': 'cltbld', 17:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:16 INFO - 'MOZ_NO_REMOTE': '1', 17:28:16 INFO - 'NO_EM_RESTART': '1', 17:28:16 INFO - 'PAGER': '/bin/cat', 17:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:16 INFO - 'PWD': '/builds/slave/test', 17:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:16 INFO - 'SHELL': '/bin/bash', 17:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:16 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:16 INFO - 'USER': 'cltbld', 17:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:16 INFO - 'XPC_FLAGS': '0x0', 17:28:16 INFO - 'XPC_SERVICE_NAME': '0', 17:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:16 INFO - Downloading/unpacking psutil>=3.1.1 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:28:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:28:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:28:19 INFO - Installing collected packages: psutil 17:28:19 INFO - Running setup.py install for psutil 17:28:20 INFO - building 'psutil._psutil_osx' extension 17:28: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 17:28: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 17:28: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 17:28:20 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 17:28:20 INFO - building 'psutil._psutil_posix' extension 17:28:20 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 17:28:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:28:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:28:20 INFO - ^ 17:28:20 INFO - 1 warning generated. 17:28:20 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 17:28:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:28:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:28:20 INFO - Successfully installed psutil 17:28:20 INFO - Cleaning up... 17:28:20 INFO - Return code: 0 17:28:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:20 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:20 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 17:28:20 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 17:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:20 INFO - 'HOME': '/Users/cltbld', 17:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:20 INFO - 'LOGNAME': 'cltbld', 17:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:20 INFO - 'MOZ_NO_REMOTE': '1', 17:28:20 INFO - 'NO_EM_RESTART': '1', 17:28:20 INFO - 'PAGER': '/bin/cat', 17:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:20 INFO - 'PWD': '/builds/slave/test', 17:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:20 INFO - 'SHELL': '/bin/bash', 17:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:20 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:20 INFO - 'USER': 'cltbld', 17:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:20 INFO - 'XPC_FLAGS': '0x0', 17:28:20 INFO - 'XPC_SERVICE_NAME': '0', 17:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:28:21 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 17:28:21 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 17:28:21 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 17:28:21 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 17:28:21 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 17:28:21 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 17:28:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:28:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:28:23 INFO - Installing collected packages: mozsystemmonitor 17:28:23 INFO - Running setup.py install for mozsystemmonitor 17:28:23 INFO - Successfully installed mozsystemmonitor 17:28:23 INFO - Cleaning up... 17:28:24 INFO - Return code: 0 17:28:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:28:24 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')]} 17:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:24 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')]} 17:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:24 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:24 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 17:28:24 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 17:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:24 INFO - 'HOME': '/Users/cltbld', 17:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:24 INFO - 'LOGNAME': 'cltbld', 17:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:24 INFO - 'MOZ_NO_REMOTE': '1', 17:28:24 INFO - 'NO_EM_RESTART': '1', 17:28:24 INFO - 'PAGER': '/bin/cat', 17:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:24 INFO - 'PWD': '/builds/slave/test', 17:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:24 INFO - 'SHELL': '/bin/bash', 17:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:24 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:24 INFO - 'USER': 'cltbld', 17:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:24 INFO - 'XPC_FLAGS': '0x0', 17:28:24 INFO - 'XPC_SERVICE_NAME': '0', 17:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:24 INFO - Downloading/unpacking jsonschema==2.5.1 17:28:24 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 17:28:24 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 17:28:24 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 17:28:24 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 17:28:24 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 17:28:24 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 17:28:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:28:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28:27 INFO - Downloading functools32-3.2.3-2.tar.gz 17:28:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:28:27 INFO - warning: no files found matching '*.txt' 17:28:27 INFO - no previously-included directories found matching 'build' 17:28:27 INFO - no previously-included directories found matching 'dist' 17:28:27 INFO - no previously-included directories found matching '.git*' 17:28:27 INFO - Installing collected packages: jsonschema, functools32 17:28:27 INFO - Running setup.py install for functools32 17:28:27 INFO - warning: no files found matching '*.txt' 17:28:27 INFO - no previously-included directories found matching 'build' 17:28:27 INFO - no previously-included directories found matching 'dist' 17:28:27 INFO - no previously-included directories found matching '.git*' 17:28:27 INFO - Successfully installed jsonschema functools32 17:28:27 INFO - Cleaning up... 17:28:27 INFO - Return code: 0 17:28:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:28:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:27 INFO - 'HOME': '/Users/cltbld', 17:28:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:27 INFO - 'LOGNAME': 'cltbld', 17:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:27 INFO - 'MOZ_NO_REMOTE': '1', 17:28:27 INFO - 'NO_EM_RESTART': '1', 17:28:27 INFO - 'PAGER': '/bin/cat', 17:28:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:27 INFO - 'PWD': '/builds/slave/test', 17:28:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:27 INFO - 'SHELL': '/bin/bash', 17:28:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:27 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:27 INFO - 'USER': 'cltbld', 17:28:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:27 INFO - 'XPC_FLAGS': '0x0', 17:28:27 INFO - 'XPC_SERVICE_NAME': '0', 17:28:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:28:28 INFO - Cleaning up... 17:28:28 INFO - Return code: 0 17:28:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:28:28 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')]} 17:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:28 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')]} 17:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:28 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:28 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 17:28:28 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 17:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:28 INFO - 'HOME': '/Users/cltbld', 17:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:28 INFO - 'LOGNAME': 'cltbld', 17:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:28 INFO - 'MOZ_NO_REMOTE': '1', 17:28:28 INFO - 'NO_EM_RESTART': '1', 17:28:28 INFO - 'PAGER': '/bin/cat', 17:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:28 INFO - 'PWD': '/builds/slave/test', 17:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:28 INFO - 'SHELL': '/bin/bash', 17:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:28 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:28 INFO - 'USER': 'cltbld', 17:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:28 INFO - 'XPC_FLAGS': '0x0', 17:28:28 INFO - 'XPC_SERVICE_NAME': '0', 17:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:28 INFO - Downloading/unpacking blobuploader==1.2.4 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28:30 INFO - Downloading blobuploader-1.2.4.tar.gz 17:28:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:28:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:28:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:28:31 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 17:28:31 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 17:28:31 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 17:28:31 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 17:28:31 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 17:28:31 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 17:28:32 INFO - Downloading docopt-0.6.1.tar.gz 17:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:28:32 INFO - Installing collected packages: blobuploader, requests, docopt 17:28:32 INFO - Running setup.py install for blobuploader 17:28:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:28:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:28:32 INFO - Running setup.py install for requests 17:28:32 INFO - Running setup.py install for docopt 17:28:32 INFO - Successfully installed blobuploader requests docopt 17:28:32 INFO - Cleaning up... 17:28:33 INFO - Return code: 0 17:28:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:33 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')]} 17:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:33 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')]} 17:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:33 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:33 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 17:28:33 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 17:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:33 INFO - 'HOME': '/Users/cltbld', 17:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:33 INFO - 'LOGNAME': 'cltbld', 17:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:33 INFO - 'MOZ_NO_REMOTE': '1', 17:28:33 INFO - 'NO_EM_RESTART': '1', 17:28:33 INFO - 'PAGER': '/bin/cat', 17:28:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:33 INFO - 'PWD': '/builds/slave/test', 17:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:33 INFO - 'SHELL': '/bin/bash', 17:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:33 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:33 INFO - 'USER': 'cltbld', 17:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:33 INFO - 'XPC_FLAGS': '0x0', 17:28:33 INFO - 'XPC_SERVICE_NAME': '0', 17:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-_hXsMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-iREpfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-l5GoE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-__Qlye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-TuE8bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ZskVqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:28:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-TbXcwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-MFzRcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-vJtHi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UbYPnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-f9WrVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-jEHJhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-6C_Egs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-RIqwDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-qU5qH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-m9u30V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XLnbgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:28:35 INFO - Running setup.py install for manifestparser 17:28:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:28:35 INFO - Running setup.py install for mozcrash 17:28:35 INFO - Running setup.py install for mozdebug 17:28:35 INFO - Running setup.py install for mozdevice 17:28:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:28:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:28:35 INFO - Running setup.py install for mozfile 17:28:35 INFO - Running setup.py install for mozhttpd 17:28:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:28:35 INFO - Running setup.py install for mozinfo 17:28:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Running setup.py install for mozInstall 17:28:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Running setup.py install for mozleak 17:28:36 INFO - Running setup.py install for mozlog 17:28:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Running setup.py install for moznetwork 17:28:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:28:36 INFO - Running setup.py install for mozprocess 17:28:36 INFO - Running setup.py install for mozprofile 17:28:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:28:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:28:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:28:37 INFO - Running setup.py install for mozrunner 17:28:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:28:37 INFO - Running setup.py install for mozscreenshot 17:28:37 INFO - Running setup.py install for moztest 17:28:37 INFO - Running setup.py install for mozversion 17:28:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:28:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:28:37 INFO - Cleaning up... 17:28:37 INFO - Return code: 0 17:28:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:37 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:37 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 17:28:37 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 17:28:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:37 INFO - 'HOME': '/Users/cltbld', 17:28:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:37 INFO - 'LOGNAME': 'cltbld', 17:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:37 INFO - 'MOZ_NO_REMOTE': '1', 17:28:37 INFO - 'NO_EM_RESTART': '1', 17:28:37 INFO - 'PAGER': '/bin/cat', 17:28:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:37 INFO - 'PWD': '/builds/slave/test', 17:28:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:37 INFO - 'SHELL': '/bin/bash', 17:28:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:37 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:37 INFO - 'USER': 'cltbld', 17:28:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:37 INFO - 'XPC_FLAGS': '0x0', 17:28:37 INFO - 'XPC_SERVICE_NAME': '0', 17:28:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:28:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-VuXwRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-u7MM6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-iWWxDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-auR1f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-cZBLYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-7S2ZYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-HPDp2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-FKssnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-96x0gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:28:38 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)) 17:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:28:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-bTxBFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-l96ki4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-iL1pbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-qYWlRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-TKhSrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-WKCuDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-IBNrQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:39 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)) 17:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:39 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-uOGb2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:39 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)) 17:28:39 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)) 17:28:39 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)) 17:28:39 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)) 17:28:39 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)) 17:28:39 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)) 17:28:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:42 INFO - Downloading blessings-1.6.tar.gz 17:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:28:42 INFO - Installing collected packages: blessings 17:28:42 INFO - Running setup.py install for blessings 17:28:42 INFO - Successfully installed blessings 17:28:42 INFO - Cleaning up... 17:28:42 INFO - Return code: 0 17:28:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:28:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:43 INFO - Reading from file tmpfile_stdout 17:28:43 INFO - Current package versions: 17:28:43 INFO - blessings == 1.6 17:28:43 INFO - blobuploader == 1.2.4 17:28:43 INFO - docopt == 0.6.1 17:28:43 INFO - functools32 == 3.2.3-2 17:28:43 INFO - jsonschema == 2.5.1 17:28:43 INFO - manifestparser == 1.1 17:28:43 INFO - mozInstall == 1.12 17:28:43 INFO - mozcrash == 1.0 17:28:43 INFO - mozdebug == 0.1 17:28:43 INFO - mozdevice == 0.49 17:28:43 INFO - mozfile == 1.2 17:28:43 INFO - mozhttpd == 0.7 17:28:43 INFO - mozinfo == 0.9 17:28:43 INFO - mozleak == 0.1 17:28:43 INFO - mozlog == 3.4 17:28:43 INFO - moznetwork == 0.27 17:28:43 INFO - mozprocess == 0.24 17:28:43 INFO - mozprofile == 0.28 17:28:43 INFO - mozrunner == 6.13 17:28:43 INFO - mozscreenshot == 0.1 17:28:43 INFO - mozsystemmonitor == 0.3 17:28:43 INFO - moztest == 0.8 17:28:43 INFO - mozversion == 1.4 17:28:43 INFO - psutil == 3.1.1 17:28:43 INFO - requests == 1.2.3 17:28:43 INFO - wsgiref == 0.1.2 17:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:43 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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28:43 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 17:28:43 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 17:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:43 INFO - 'HOME': '/Users/cltbld', 17:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:43 INFO - 'LOGNAME': 'cltbld', 17:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:43 INFO - 'MOZ_NO_REMOTE': '1', 17:28:43 INFO - 'NO_EM_RESTART': '1', 17:28:43 INFO - 'PAGER': '/bin/cat', 17:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:43 INFO - 'PWD': '/builds/slave/test', 17:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:43 INFO - 'SHELL': '/bin/bash', 17:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:43 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:43 INFO - 'USER': 'cltbld', 17:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:43 INFO - 'XPC_FLAGS': '0x0', 17:28:43 INFO - 'XPC_SERVICE_NAME': '0', 17:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 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)) 17:28:43 INFO - Cleaning up... 17:28:43 INFO - Return code: 0 17:28:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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')]} 17:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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 0x102936b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102939768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ebcb8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, '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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:28: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 17:28: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 17:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:44 INFO - 'HOME': '/Users/cltbld', 17:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:44 INFO - 'LOGNAME': 'cltbld', 17:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:44 INFO - 'MOZ_NO_REMOTE': '1', 17:28:44 INFO - 'NO_EM_RESTART': '1', 17:28:44 INFO - 'PAGER': '/bin/cat', 17:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:44 INFO - 'PWD': '/builds/slave/test', 17:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:44 INFO - 'SHELL': '/bin/bash', 17:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:28:44 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:44 INFO - 'USER': 'cltbld', 17:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:44 INFO - 'XPC_FLAGS': '0x0', 17:28:44 INFO - 'XPC_SERVICE_NAME': '0', 17:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:28:45 INFO - Cleaning up... 17:28:45 INFO - Return code: 0 17:28:45 INFO - Running post-action listener: _resource_record_post_action 17:28:45 INFO - Running post-action listener: _start_resource_monitoring 17:28:45 INFO - Starting resource monitoring. 17:28:45 INFO - [mozharness: 2017-02-09 01:28:45.072644Z] Finished create-virtualenv step (success) 17:28:45 INFO - [mozharness: 2017-02-09 01:28:45.073635Z] Running install step. 17:28:45 INFO - Running pre-action listener: _resource_record_pre_action 17:28:45 INFO - Running main action method: install 17:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:45 INFO - Reading from file tmpfile_stdout 17:28:45 INFO - Detecting whether we're running mozinstall >=1.0... 17:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:28:45 INFO - Reading from file tmpfile_stdout 17:28:45 INFO - Output received: 17:28:45 INFO - Usage: mozinstall [options] installer 17:28:45 INFO - Options: 17:28:45 INFO - -h, --help show this help message and exit 17:28:45 INFO - -d DEST, --destination=DEST 17:28:45 INFO - Directory to install application into. [default: 17:28:45 INFO - "/builds/slave/test"] 17:28:45 INFO - --app=APP Application being installed. [default: firefox] 17:28:45 INFO - mkdir: /builds/slave/test/build/application 17:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:29:07 INFO - Reading from file tmpfile_stdout 17:29:07 INFO - Output received: 17:29:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:29:07 INFO - Running post-action listener: _resource_record_post_action 17:29:07 INFO - [mozharness: 2017-02-09 01:29:07.593599Z] Finished install step (success) 17:29:07 INFO - [mozharness: 2017-02-09 01:29:07.593725Z] Running run-tests step. 17:29:07 INFO - Running pre-action listener: _resource_record_pre_action 17:29:07 INFO - Running main action method: run_tests 17:29:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:29:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:29:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:29:07 INFO - 2017-02-08 17:29:07.608 screenresolution[1972:16345] starting screenresolution argv=screenresolution get 17:29:07 INFO - 2017-02-08 17:29:07.610 screenresolution[1972:16345] Display 0: 1600x1200x32@60 17:29:07 INFO - 2017-02-08 17:29:07.616 screenresolution[1973:16347] starting screenresolution argv=screenresolution list 17:29:07 INFO - Available Modes on Display 0 17:29:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:29:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:29:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:29:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:29:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:29:07 INFO - Intel Iris: 17:29:07 INFO - Chipset Model: Intel Iris 17:29:07 INFO - Type: GPU 17:29:07 INFO - Bus: Built-In 17:29:07 INFO - VRAM (Dynamic, Max): 1536 MB 17:29:07 INFO - Vendor: Intel (0x8086) 17:29:07 INFO - Device ID: 0x0a2e 17:29:07 INFO - Revision ID: 0x0009 17:29:07 INFO - Displays: 17:29:07 INFO - Display: 17:29:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:29:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:29:07 INFO - Display Serial Number: 1600x1200 60 17:29:07 INFO - Main Display: Yes 17:29:07 INFO - Mirror: Off 17:29:07 INFO - Online: Yes 17:29:07 INFO - Rotation: Supported 17:29:07 INFO - Return code: 0 17:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:29:07 INFO - Python 2.7.3 17:29:07 INFO - Return code: 0 17:29:07 INFO - grabbing minidump binary from tooltool 17:29:07 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')]} 17:29:07 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 0x102714d30>, '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 0x10263bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102709be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:29:07 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 17:29:07 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 17:29:07 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 17:29:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:29:07 INFO - Return code: 0 17:29:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:29:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:29:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:29:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:29:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 17:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 17:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZHAJ9EGbd8/Render', 17:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:29:07 INFO - 'HOME': '/Users/cltbld', 17:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:29:07 INFO - 'LOGNAME': 'cltbld', 17:29:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:29:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:07 INFO - 'MOZ_NO_REMOTE': '1', 17:29:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:29:07 INFO - 'NO_EM_RESTART': '1', 17:29:07 INFO - 'PAGER': '/bin/cat', 17:29:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:07 INFO - 'PWD': '/builds/slave/test', 17:29:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:29:07 INFO - 'SHELL': '/bin/bash', 17:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0MpsSAi0R/Listeners', 17:29:07 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:29:07 INFO - 'USER': 'cltbld', 17:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:07 INFO - 'XPC_FLAGS': '0x0', 17:29:07 INFO - 'XPC_SERVICE_NAME': '0', 17:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:29:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 17:29:08 INFO - mozversion application_buildid: 20170208162021 17:29:08 INFO - mozversion application_changeset: f505911eb333d5ae8c2bf5c44f7b85add6450b53 17:29:08 INFO - mozversion application_display_name: Nightly 17:29:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:29:08 INFO - mozversion application_name: Firefox 17:29:08 INFO - mozversion application_remotingname: firefox 17:29:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:29:08 INFO - mozversion application_vendor: Mozilla 17:29:08 INFO - mozversion application_version: 54.0a1 17:29:08 INFO - mozversion platform_buildid: 20170208162021 17:29:08 INFO - mozversion platform_changeset: f505911eb333d5ae8c2bf5c44f7b85add6450b53 17:29:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:29:08 INFO - mozversion platform_version: 54.0a1 17:29:08 INFO - using testdate: 1486603748 17:29:08 INFO - actual date: 1486603748 17:29:08 INFO - starting webserver on 'localhost:52763' 17:29:08 INFO - SUITE-START | Running 2 tests 17:29:08 INFO - TEST-START | dromaeo_css 17:29:08 INFO - operating with platform_type : mac_ 17:29:08 INFO - Initialising browser for dromaeo_css test... 17:29:08 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp7R2Nht/profile http://localhost:52763/getInfo.html) 17:29:10 INFO - PROCESS | 1983 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:29:11 INFO - PROCESS | 1983 | 2017-02-08 17:29:11.002 plugin-container[1985:16511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 17:29:11 INFO - PROCESS | 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:29:11 INFO - PROCESS | 1983 | 2017-02-08 17:29:11.005 plugin-container[1985:16511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 17:29:11 INFO - PROCESS | 1983 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:29:11 INFO - PROCESS | 1983 | 2017-02-08 17:29:11.005 plugin-container[1985:16511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:29:11 INFO - PROCESS | 1983 | __metrics Screen width/height:1600/1200 17:29:11 INFO - PROCESS | 1983 | colorDepth:24 17:29:11 INFO - PROCESS | 1983 | Browser inner width/height: 1024/689 17:29:11 INFO - PROCESS | 1983 | __metrics 17:29:11 ERROR - PROCESS | 1983 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 17:29:11 INFO - TEST-INFO | 1983: exit 0 17:29:11 INFO - Browser initialized. 17:29:11 INFO - Running cycle 1/1 for dromaeo_css test... 17:29:11 INFO - TEST-INFO | started process 1986 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp7R2Nht/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:14 INFO - PROCESS | 1986 | 2017-02-08 17:29:14.221 plugin-container[1987:16633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 17:29:14 INFO - PROCESS | 1986 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:29:14 INFO - PROCESS | 1986 | 2017-02-08 17:29:14.221 plugin-container[1987:16633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 17:29:14 INFO - PROCESS | 1986 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:29:14 INFO - PROCESS | 1986 | 2017-02-08 17:29:14.221 plugin-container[1987:16633] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:29:24 INFO - PROCESS | 1986 | RSS: Main: 233848832 17:29:24 INFO - PROCESS | 1986 | 17:32:27 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52763/tests/dromaeo/cssquery-ext.html) 17:32:28 INFO - PROCESS | 1986 | RSS: Main: 258940928 17:32:28 INFO - PROCESS | 1986 | 17:34:12 INFO - PROCESS | 1986 | ************************* 17:34:12 INFO - PROCESS | 1986 | A coding exception was thrown and uncaught in a Task. 17:34:12 INFO - PROCESS | 1986 | 17:34:12 ERROR - PROCESS | 1986 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:34:12 INFO - PROCESS | 1986 | Full stack: 17:34:12 INFO - PROCESS | 1986 | ************************* 17:35:31 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-ext.html (next: http://localhost:52763/tests/dromaeo/cssquery-jquery.html) 17:35:31 INFO - PROCESS | 1986 | RSS: Main: 265334784 17:35:31 INFO - PROCESS | 1986 | 17:38:33 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52763/tests/dromaeo/cssquery-mootools.html) 17:38:34 INFO - PROCESS | 1986 | RSS: Main: 279384064 17:38:34 INFO - PROCESS | 1986 | 17:41:36 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52763/tests/dromaeo/cssquery-prototype.html) 17:41:37 INFO - PROCESS | 1986 | RSS: Main: 272420864 17:41:37 INFO - PROCESS | 1986 | 17:44:39 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52763/tests/dromaeo/cssquery-yui.html) 17:44:40 INFO - PROCESS | 1986 | RSS: Main: 277495808 17:44:40 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:52763/tests/dromaeo/cssquery-yui.html (next: http://localhost:52763/tests/dromaeo/cssquery-dojo.html) 17:47:43 INFO - PROCESS | 1986 | RSS: Main: 279465984 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | __start_tp_report 17:47:43 INFO - PROCESS | 1986 | _x_x_mozilla_page_load 17:47:43 INFO - PROCESS | 1986 | _x_x_mozilla_page_load_details 17:47:43 INFO - PROCESS | 1986 | |i|pagename|runs| 17:47:43 INFO - PROCESS | 1986 | |0;dojo.html;3076.3693442844215;3335.7664963452567;3352.1284466038824;3394.304167645633;3405.42107841667;5596.048671725806;5609.719514024303;5643.4074422185695;5647.604667673259;5671.943280567193;4495.865124046284;5656.971715141429;5662.405447428023;5696.544276457884;5711.314642242936;4734.834280800155;5049.722265275427;5144.176931690921;5195.220716892459;5338.199270109476;4895.55939965403;5215.035218484573;5225.529702326789;5249.448807875176;5263.105272103751;4492.191405547001;5099.311592934943;5138.640295179339;5177.094008548489;5212.50481204285;5164.948350516484;5199.714015729133;5200.3759548854005;5202.505761952609;5251.264822924793;4655.813767449298;4877.219644856806;4980.850574482772;5126.154184559543;5137.74311284438;5340.118880384732;5493.533049690781;5559.110542313251;5615.157726341041;5652.547796176296;6179.752809887599;6188.690565471754;6192;6202.131701561785;6232.3144454110015;6196.938030619726;6214.689265536705;6215.660364236121;6223.719932603044;6261.812145635638;5577.581681373913;5584.441555844383;5645.661260324311;5645.661260324394;5649.830505084854;6200.503959683215;6229.283632382243;6246.312905580383;6252.468540174136;6257.061440783918;5815.79644712433;5818.069108942634;5856.765729370863;5924.7037648118285;6005.279366476051;6110.419510146529;6144.569880108439;6202.689865506707;6213.9067913980425;6299.622022678654;5854.941450585525;5934.525237980951;6190.969045154836;6192.59748116371;6220.906686399708;6026.21659184312;6111.014442317784;6138.32709113608;6141.324454310023;6203.410675985774;4956.826511072095;4959.950400495949;4973.5523802857915;4979.651424400257;5012.573931215888;3828.502294701671;4431.7148027072435;4450.688451808407;4463.24124898768;4480.910381792346;5573.832785016456;5596.916046259309;5666.150077488327;5673;5703.201551782754;6179.5056395488255;6188.381161883775;6189.690515474298;6235.875282494416;6270.216222972129;5747.281589801275;5805.419458054161;5810.477057064884;5812.0119579671555;5832.9708351457975;5243.213517972258;5298.947010529845;5339.439358867263;5400.054990376747;5421.349438067457;6252.968735156477;6253.937460625427;6270.7491700331475;6272.874542509216;6296.716647750771;6245.656488892972;6248.218972628421;6262.686865656608;6269;6287.056941458818;6146.279041868284;6185.350538193605;6218;6262.248530176408;6281;5208.739563021758;6016.819495415145;6037.245344331958;6146.6004709693725;6147.815565533041;6020.578559500968;6123.84690382744;6179.752809887734;6185.6907154641185;6189.102580125947;5818.854528636818;5888.205092312654;6023.27720673522;6084.239470066242;6110.938890611214;6103.640733310809;6124.625058545733;6140.62695141751;6149.385061493815;6154.046122850965;5862.355140934408;5920.585559011;5928.614640048383;5969.552283578836;5999.130126131599;5216.113260745607;5824.301083869963;5889.057750759858;6061.909071363845;6086.939130608815;4941.838667912965;5106.387233532;5879.265091863517;5946.970265148647;5999.460048595647 17:47:43 INFO - PROCESS | 1986 | |1;ext.html;482.45723560993883;485.905248476547;486.1807853766402;517.6247220764945;521.2259794205605;38641;41144.17711645765;41283.58716412835;41311.79344103279;41398.58601413985;41644.37533437001;41971;42093.36859947096;42125.57874421255;42137.78931105348;9179.632814687404;9395.027044320701;9416.81166376672;9430.905690943106;10696.572137114545;9791.461469619167;10440.895591044069;10529.526171322308;10901.12790976724;10911.945440272788;9033.457992520436;10863.83704244437;10872.891271087306;10900.34597924127;10907;10819.83770243447;10890.183236257313;10908.072813810875;10927.945360273148;10929.016388525071;10771.676849694522;10819.026287634071;10851.186161037953;10863.076638485738;10919.071878890225;36122;36243.456348154796;36496.452553211726;36575;36662.450063249074;3318.386098571772;3744.4383342498572;3779.6976241900584;3790.9810450947843;3801.885943421702;27840.443191136063;28496.857515712498;28626;28694.282642933886;28694.856525717445;3103.0841568377314;3137.294108825519;3154.274516861135;3204.8878289259765;3246.902592922216;436381;439819;440419;443323;443380;40256.59743402529;47874.28188577174;56054.719726401105;56305;56405.15392269037;58842.41157588455;59306.703466482395;59712;62914.68542657349;63276.36723632797;14968.70062598742;15021.774673379909;15131.470398536;15137.318820652878;15144.16707081099;11383.772324553462;11408.258463199865;11512.539498420138;11525.711857203636;11540.422978851024;14503.347349369304;17826.5543361417;18004.36984705529;18057.91652500828;18092;6594;7723.845523089507;7795.922040779633;7810.726624568113;7820.452568320276;737.8966944627756;814.5112932240727;822.083377034614;830.588858515039;843.4053991935671;29845.70154298429;29922.401551969273;29978.95073672411;30270.84864575663;30745.92389266365;824.9257566819015;1005.4419797012728;1020.7244044108049;1051.9316244444087;1138.7153211697075;12376.938115309365;15494.612634684223;15617.765733514007;15745.921270393575;15908.522744317688;582020;586811;587269;588038;588924;40292.589759358285;42544.361834572504;45320.3201951971;45663;45738.31392529115;1368.7125703602155;1378.4899587152618;1402.425005747659;1403.9508617198348;1404.501402002295;28402.85798570994;29126.56310155392;29213.561796572645;29940.251493712833;30219.244518886324;50139.74930125326;50201.49798501968;50517.242241367145;50559.94400559914;50587.988240235725;478373;482261;483101;483901;484539;19653.8034619652;23119.4220144491;26014.479710406064;26085.608715869657;26954.056608018625;46254.53745462637;46549;46584.53415465938;46959.826004350165;47809.80475488141;31507.36985260328;37247.44128838124;37615.05962350963;38920.026999325244;38936.80531597324;33164.50253246252;37216.25567488581;37379.81310093432;37853.81073094727;38607.61392386153;30240.09279721612;36427;36437.817810910776;36477.63522364743;36579.634203657624;30540.014899031492;31719.841400792848;33460.330793384484;33613.495797562566;33615;22911.65632515547;23693.644595330738;24658.02735781169;24677.62983555284;25401.74598253994 17:47:43 INFO - PROCESS | 1986 | |2;jquery.html;382.56005593567374;384.204696278703;389.2681758294406;398.42825545342447;422.3158483257124;18919.52701182471;20182.596348073028;20193.39419817406;20300.89849550752;20506.589868202627;20751.481212969644;20798.584028319423;20986.475338116554;21067;21269.787302126973;5480.150576660614;5869.647821130746;5922.466977972003;5930.140129681194;5950.404959504037;5659.575531835108;5754.942450575482;5832.912506312408;5868.911966320508;5884.4703976642095;5817.825465236049;5852.912206316909;5871.970640146793;5877.559183061266;5884.558658100638;5581.441855814386;5723.198752174699;5859.296884373881;5880.911786323209;5895.911561326605;5002.674826136289;5085.821996230115;5120.564751996085;5122.667026643257;5124.436312005676;24857.25428237156;25005.7497125143;25033;25237.36906577332;25390.492190156278;2633.8814753336146;2670.719574444675;2693.932651683704;2695.9865200674067;2697.0695110186953;14944.701105977929;15268.847311526853;15429.691406171925;15642;15726.449574264845;1790.5556383137248;1810.2697998259357;1848.260695721722;1851.0282101896478;1853.2494339792406;119206;123762.38118809438;124392;124653;125730;9008.909910900808;11019.787823339426;11371.886281137247;11582.189246752647;11673.88326116745;12506.49974001048;14337.283135843374;14457.638559036108;14462.27688615565;14503.347349369304;8146.959265203636;8388.119247478928;8391.412601117985;8451.492910425393;8483.363747718946;8161.877571836427;8182.140875208136;8209.302209312274;8354.874676879852;8686.478811271343;7105.4827860209525;7222.241664625244;7239.167495738057;7239.674214660247;7243.420526357879;1793.1841012339355;1920.7310976463307;1924.3553409607657;1926.9325573604856;1927.5855691026497;749.318120510333;861.3582880753753;871.4292138649195;882.7798087809165;894.8702438146432;18884.82376696874;19588.510287242647;20603.690944635844;21937.48380280266;21957.773512475946;887.7558671365426;889.3241136736127;913.2100732866156;916.2532535983153;926.2775035472342;10534.841977370346;11562.826557601642;11567.710807229887;11584.710382240512;11663.825042624367;668030;671409;675526;680217;698057;114719;116490.41754791171;118920;119829;121531;1000.5747557288123;1013.0629168019552;1032.7779527401494;1033.3180101133366;1038.3925403638955;15304.387824487341;17704.849184802948;18417.447476575726;19021.23915043438;19093.236270549023;49567.7694503741;51211.487885120674;51473;52007;53436.73281633722;57556.71221643865;72267;72404.63797680978;73099;81164.59417702873;7371.8157046074275;8012.479188852706;8417.831643366975;8834.867476987978;8922.107789221285;11049.613263535737;12686;12951.287679177736;13118.540851070167;13478.056536042383;7890.171531989058;8022.676258417529;8156.347492200728;8361.247487726134;11062.723431914266;7712.267334603203;7765.301122898966;8049.27556519916;8294.626741796514;8325.95837020811;8087.757367278991;8139.1453897339925;8340.749777506684;8604;8661.913380866363;12307.69230769238;12951.740965180832;13067.934660326637;13405.66485837862;13419.597412077654;11080.889191107986;11490.425478726198;11589.6523104307;11867.169298149047;12162.756744864877 17:47:43 INFO - PROCESS | 1986 | |3;mootools.html;1101.7410908436516;1166.0496695193413;1184.4788293151014;1188.9583864564743;1201.0871737479515;7491.98858154149;7730.7680769577;8091.231333023368;8466.153384661531;8505.659773609048;8012.91987080129;8237.876431853527;8280.213379728932;8380.45527040742;8407.57962101894;2224.63293556563;2260.2993072147606;2264.9581192651317;2268.25147701259;2268.5236100418933;1959.3632069577372;2032.542677897476;2236.6197746383073;2276.4195130241847;2277.2485079923586;2181.3674034529968;2234.3743751749453;2246.3148739634485;2257.6500642400456;2269.614165591853;2232.586971410294;2234;2254.4701995031237;2255.4023183856293;2256.571251462217;2210.635245184543;2214.2250212425683;2281.6691579721014;2284.9543009139725;2287.656851472276;8448.535330556817;8499.405041647087;8526.701565445179;8542.829143417097;8569.657213711444;5059.595232381401;5200.089984252779;5208.973955130238;5210.192420174879;5223.843284701465;3396.1339858335964;3418.7607117338184;3429.5713035870517;3438.793672379665;3485.257371314343;1159.339176669299;1182.8817118288102;1188.2395267029108;1193.2065176657513;1224.65097447228;9470.57382417786;9539.236861051098;11036.337819730841;11088.002079812855;11110.555577776799;3977.7016723745833;4350.303951367766;4623.86128416148;4704.88237794051;4706.670533062721;7240.275972402717;7902.723404680809;9613.423194608346;9632.711018669452;9635.710928672152;4417.536158703354;4590.196715574761;4993.775280112403;5206.245094461282;5220.112580861262;4098.508179018478;4624.329472226508;4736.052789442126;4757;4805.231163013901;3588.3540962626457;3627.165751877083;3654.8172591370326;3673.559172899269;3676.6690997810324;1045.163868904873;1059.327327147267;1145.8166693329194;1154.2266681323533;1155.8786327435666;699.2972063076576;723.9457040721967;765.5253742679463;779.9415043871733;811.5982588618672;4868.488808675038;5054.444011158769;5327.067763141435;5376.086065368897;5605.075162598126;824.5217773691364;931;945.6548359848606;949.914507694297;1004.8141093897506;3486;3709.4435834625024;3711.4247291669835;3799.1072098056934;3808.3716186829415;9796.51017449139;10123.341982771244;10129;10131.898681013243;10138.695839124837;10087.142592879687;10096.545655445523;10122.544485498023;10127.64553240633;10127.734033245844;7483.438742094474;7830.138684744674;7978.082520510099;7987.520748755093;7995.40034497415;5357.678539287499;5549.667019978814;5557.277553917965;5619.943800561942;5678.914816277842;8322;9052.275817934418;9121.635134594542;9194.080591940752;9270.397424167408;9148.908510914805;9352;9366.67216647414;9391.671291504765;9404.52977351116;7014.5440546364325;7192.100987376578;7263.019492368674;7291.854162916606;7293.343599076109;7819.804504887424;7850.214978502333;7908.683652654059;7919.604019798871;7928.643211055632;7139.607321597362;7168.605726685082;7212.531185473137;7252.746153884589;7319.158296795937;5420.457954204548;7065.434765218667;7074.929250707427;7077.5399599025895;7092.751753688596;6196.1015652731;6347.238331400262;6348.936510634834;6367.968160159355;6368.77709280173;4384.188925048876;4781.497942716104;4939.975300123477;4967.875803104806;4995.875103122451;4863.319135321129;4866.245731911559;4928.6057115431395;4987.850364489072;5018.222175562793 17:47:43 INFO - PROCESS | 1986 | |4;prototype.html;348.8308170537289;351.97926014557794;358.28700885238374;363.3260302139531;367.5350681388046;4321.654267658588;5100.744962751857;5305.734713264341;5495.334762640121;5579.721013949306;4150.896227594304;5140.357455318085;5377.865553361168;5382.788872503685;5396.406395296524;3536.3104194682046;4249.256380133472;4261.936070958923;4263.829446822139;4282.614564689177;3781.3760729479604;3892.5718171001167;4139.275626765319;4301.010767523471;4323.502797178317;3390.643982381839;3497.3769672745443;3710.888673339791;3749.787625301072;4156.418101465797;3266.80399176049;3453.1021934297137;4070.104576993057;4182.4771903511755;4194.433751443577;3425.811580363079;3712.1647629283466;3746.3256613809504;3751.4747935289083;3760.668385600945;4584.541545845422;4806.086843499724;5158.871028224287;5242.55438287746;5252.369715634149;4584.770761461914;4604.963883126304;4608.585227329557;4625.028743963771;4676.672632915697;5660;5673.404292549264;5703.77184912603;5747.7408171523;5778.508826749731;3355.6476569960046;3740.7942563159017;3845.1540661054523;3848.3842585186235;3861.4787003754413;5384.892302153974;5461.989531936604;5607.467290607386;5722.856928576777;5767.798127065533;3869.187470631199;3878.7866667333005;3882.611738826095;3888.3972984187494;3902.5902280260166;3929.5677475477673;3933.350997085449;3945.6646185074014;3954.347532657137;3962.148138150254;4463.285874260103;4608.6082682971655;4612.331211974245;4624.421947256593;4653.6509761768;4811.3745213122775;5030.019146266516;5698.230738850285;5720.599558030981;5729.3984131665575;1109.1348747976592;1167.7897978363806;1182.3969775414428;1226.7791797476361;1314.5464814638933;762.1616222155584;776.8252143267832;813.894193754808;888.2938064238946;888.3071204460402;4342.739435633872;5040.395152581714;5115.897682046339;5122.564582010571;5140.583925554984;4938.59250113723;5197.58419326453;5390.760125171232;5640.774369025193;5676;4285.849995250152;4649.279361698875;4688.718676879398;4996.650234483552;5115.590752739772;2879.337752316979;2947.3073827650487;2947.469455497988;2962.674105848355;2969.5100308449296;5535.865147644723;5563.607648711093;5883.294004719461;5889.937232639089;5921.851953701106;4707.9285114550175;5046.84859454217;5359.651622644516;5681.403452637496;5761.905238004766;4883.194272944925;5258.845512045517;5746.798862039808;5798.710064496674;5854.912176317273;4866.90266194667;4880.707157570557;5553.615089067315;5666.660000399989;5704.344000440003;4817.759112044314;5153.6758790579115;5227.869303267449;5340.839774806762;5646.548276137982;5143.6399452037995;5437.4562543745305;5723.484886360247;5765.94234057654;5959;3531.876384326536;3567.464880267981;3792.867249646249;3808.6572208501366;3829.5596006459614;5158.38099428056;5875.471207591167;5891.469767720754;5897.7935772247765;5938.406159384027;5343.65266257692;5677.205191273308;5711.086226203787;5763.423657634203;5824.359320474659;5717.4557321991315;5815.70279739509;5839.722508221882;5848.444397782204;5852.853678658068;5055.757700749843;5376.56987440996;5486.369067557282;5569.220309156803;5607.2149899014985;5701.971490142689;5721.997300944636;5743.712814359349;5745.8563535911935;5761.654300741969;5601.719914004365;5603.495685388335;5632.5775566831835;5665.801696940587;5708.65748055118 17:47:43 INFO - PROCESS | 1986 | |5;yui.html;670.7518218259245;682.2324884504931;683.6650041479677;780.8867714181443;804.2882049386292;13958.232297223649;14995.775063374058;15007.249637518122;15034.548963531099;15066.3220155093;13741.519046833362;13860.653483662914;13861.168329900212;13895.652608684788;13956.860431395682;858.0647094666812;873.3188113271626;876.750126214026;877.4296707140378;878.4904755241944;814.267159556398;850.8808766772656;852.2713080316303;865.2429124516049;869.2307308032405;908.4912449028532;910.1171863292595;931.4271722890408;935.7567032571546;937.1706040154478;927.8654595083675;931.0503286647615;931.8369285375067;935.242453612576;935.3873213045466;688.8760023195823;692.1279188222825;692.4771797293062;692.7713854428077;693.9132608423947;14170.787438188436;14760.188189649565;15103.169325687082;15130.319135638923;15161.772573411408;13492.325383730775;13495.219184661786;13808.17150970945;13835.584932451942;13861.722765544633;11150.149220956782;11445.542178312857;11493.080553555696;11568.363739994296;11742.58900938463;3073.92315192122;3231.111444352798;3408.880689175867;3564.8395822188063;3634.254977729559;67652.66173669149;73849.63075184589;94660.52669736676;96238.51880740622;96893.51553242188;8996.280297576179;10383.532741026673;10392.636257730943;10495.212859035602;10908.454577271104;21326.893365533382;21588.89205553962;21611;21657.241996530047;21723.891380542995;6351.618902865843;6445.484361251182;7353.1911489736085;7366.563520113634;7456.217097204824;4050.8987275317763;4137.275976704125;4154.646855017297;4163.854265100767;4165.895852603649;3464.34177506273;3556.751027428107;3562.323158599858;3579.6420357963993;3611.656892595199;641.3906788550895;647.97732079377;649.1463725201345;656.671664167916;657.2999755260632;529.5075579710906;657.1489920552896;676.5230512488683;681.3220845258999;698.6735113546882;8338.708145214889;8914;9008.369414140947;9272.30163869112;9635.759106022406;664.4817042706696;672.8048865828953;675.9763408280686;681.3731367142241;682.4403988729194;4155.314373128461;4610.654200934943;4616.815327386867;4844.733539655353;4860.149473842134;95014;96116.55825162628;96641.516792417;98142.50928745311;100429;83638;85286;85827;85840;87358.56320718482;73934.6303268491;74876.25123748802;75002.62498687471;75080;75532;9283.76790580241;9320.300977426856;9347.672831450867;9369.858095912328;9423.858642120236;38129;39676.60323396729;51278;51349.74325128351;51625.96748065093;65236.67381663061;66059.66970165119;67172.664136679;68462.63074738577;68655.65672171608;11187.496562654864;11266.324020558794;11356;11368.94315528417;11883.346415947206;12352.75294494123;12505.187162834387;12537.87462125367;12556.623301300975;13151.60545183646;11459.770804583695;11718.121140914294;11735.706607334885;11773.17587768848;11865.169438139248;9868.555914983699;11781.705457363634;11896.940515297369;11931.164818462625;12334.876651233373;11497.712557186138;11790.174687772116;11920.867517585815;11951.820722689341;12207.389630518615;7301.452391070585;9489.620415183315;9527.666531671359;9646.565904534173;9758.170555502862;9431;9546.904530954602;9554.426734395958;9573.090556397132;9709.6116155353 17:47:43 INFO - PROCESS | 1986 | __end_tp_report 17:47:43 INFO - PROCESS | 1986 | __start_cc_report 17:47:43 INFO - PROCESS | 1986 | _x_x_mozilla_cycle_collect,404 17:47:43 INFO - PROCESS | 1986 | __end_cc_report 17:47:43 INFO - PROCESS | 1986 | __startTimestamp1486604863326__endTimestamp 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | ------- Summary: start ------- 17:47:43 INFO - PROCESS | 1986 | Number of tests: 6 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#0] dojo.html Cycles:165 Average:5650.97 Median:5832.97 stddev:661.03 (11.3%) stddev-sans-first:631.45 17:47:43 INFO - PROCESS | 1986 | Values: 3076.4 3335.8 3352.1 3394.3 3405.4 5596.0 5609.7 5643.4 5647.6 5671.9 4495.9 5657.0 5662.4 5696.5 5711.3 4734.8 5049.7 5144.2 5195.2 5338.2 4895.6 5215.0 5225.5 5249.4 5263.1 4492.2 5099.3 5138.6 5177.1 5212.5 5164.9 5199.7 5200.4 5202.5 5251.3 4655.8 4877.2 4980.9 5126.2 5137.7 5340.1 5493.5 5559.1 5615.2 5652.5 6179.8 6188.7 6192.0 6202.1 6232.3 6196.9 6214.7 6215.7 6223.7 6261.8 5577.6 5584.4 5645.7 5645.7 5649.8 6200.5 6229.3 6246.3 6252.5 6257.1 5815.8 5818.1 5856.8 5924.7 6005.3 6110.4 6144.6 6202.7 6213.9 6299.6 5854.9 5934.5 6191.0 6192.6 6220.9 6026.2 6111.0 6138.3 6141.3 6203.4 4956.8 4960.0 4973.6 4979.7 5012.6 3828.5 4431.7 4450.7 4463.2 4480.9 5573.8 5596.9 5666.2 5673.0 5703.2 6179.5 6188.4 6189.7 6235.9 6270.2 5747.3 5805.4 5810.5 5812.0 5833.0 5243.2 5298.9 5339.4 5400.1 5421.3 6253.0 6253.9 6270.7 6272.9 6296.7 6245.7 6248.2 6262.7 6269.0 6287.1 6146.3 6185.4 6218.0 6262.2 6281.0 5208.7 6016.8 6037.2 6146.6 6147.8 6020.6 6123.8 6179.8 6185.7 6189.1 5818.9 5888.2 6023.3 6084.2 6110.9 6103.6 6124.6 6140.6 6149.4 6154.0 5862.4 5920.6 5928.6 5969.6 5999.1 5216.1 5824.3 5889.1 6061.9 6086.9 4941.8 5106.4 5879.3 5947.0 5999.5 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#1] ext.html Cycles:180 Average:63604.28 Median:36457.73 stddev:135114.16 (370.6%) stddev-sans-first:135410.07 17:47:43 INFO - PROCESS | 1986 | Values: 482.5 485.9 486.2 517.6 521.2 38641.0 41144.2 41283.6 41311.8 41398.6 41644.4 41971.0 42093.4 42125.6 42137.8 9179.6 9395.0 9416.8 9430.9 10696.6 9791.5 10440.9 10529.5 10901.1 10911.9 9033.5 10863.8 10872.9 10900.3 10907.0 10819.8 10890.2 10908.1 10927.9 10929.0 10771.7 10819.0 10851.2 10863.1 10919.1 36122.0 36243.5 36496.5 36575.0 36662.5 3318.4 3744.4 3779.7 3791.0 3801.9 27840.4 28496.9 28626.0 28694.3 28694.9 3103.1 3137.3 3154.3 3204.9 3246.9 436381.0 439819.0 440419.0 443323.0 443380.0 40256.6 47874.3 56054.7 56305.0 56405.2 58842.4 59306.7 59712.0 62914.7 63276.4 14968.7 15021.8 15131.5 15137.3 15144.2 11383.8 11408.3 11512.5 11525.7 11540.4 14503.3 17826.6 18004.4 18057.9 18092.0 6594.0 7723.8 7795.9 7810.7 7820.5 737.9 814.5 822.1 830.6 843.4 29845.7 29922.4 29979.0 30270.8 30745.9 824.9 1005.4 1020.7 1051.9 1138.7 12376.9 15494.6 15617.8 15745.9 15908.5 582020.0 586811.0 587269.0 588038.0 588924.0 40292.6 42544.4 45320.3 45663.0 45738.3 1368.7 1378.5 1402.4 1404.0 1404.5 28402.9 29126.6 29213.6 29940.3 30219.2 50139.7 50201.5 50517.2 50559.9 50588.0 478373.0 482261.0 483101.0 483901.0 484539.0 19653.8 23119.4 26014.5 26085.6 26954.1 46254.5 46549.0 46584.5 46959.8 47809.8 31507.4 37247.4 37615.1 38920.0 38936.8 33164.5 37216.3 37379.8 37853.8 38607.6 30240.1 36427.0 36437.8 36477.6 36579.6 30540.0 31719.8 33460.3 33613.5 33615.0 22911.7 23693.6 24658.0 24677.6 25401.7 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#2] jquery.html Cycles:180 Average:37008.16 Median:383.38 stddev:112421.26 (29323.5%) stddev-sans-first:112702.99 17:47:43 INFO - PROCESS | 1986 | Values: 382.6 384.2 389.3 398.4 422.3 18919.5 20182.6 20193.4 20300.9 20506.6 20751.5 20798.6 20986.5 21067.0 21269.8 5480.2 5869.6 5922.5 5930.1 5950.4 5659.6 5754.9 5832.9 5868.9 5884.5 5817.8 5852.9 5872.0 5877.6 5884.6 5581.4 5723.2 5859.3 5880.9 5895.9 5002.7 5085.8 5120.6 5122.7 5124.4 24857.3 25005.7 25033.0 25237.4 25390.5 2633.9 2670.7 2693.9 2696.0 2697.1 14944.7 15268.8 15429.7 15642.0 15726.4 1790.6 1810.3 1848.3 1851.0 1853.2 119206.0 123762.4 124392.0 124653.0 125730.0 9008.9 11019.8 11371.9 11582.2 11673.9 12506.5 14337.3 14457.6 14462.3 14503.3 8147.0 8388.1 8391.4 8451.5 8483.4 8161.9 8182.1 8209.3 8354.9 8686.5 7105.5 7222.2 7239.2 7239.7 7243.4 1793.2 1920.7 1924.4 1926.9 1927.6 749.3 861.4 871.4 882.8 894.9 18884.8 19588.5 20603.7 21937.5 21957.8 887.8 889.3 913.2 916.3 926.3 10534.8 11562.8 11567.7 11584.7 11663.8 668030.0 671409.0 675526.0 680217.0 698057.0 114719.0 116490.4 118920.0 119829.0 121531.0 1000.6 1013.1 1032.8 1033.3 1038.4 15304.4 17704.8 18417.4 19021.2 19093.2 49567.8 51211.5 51473.0 52007.0 53436.7 57556.7 72267.0 72404.6 73099.0 81164.6 7371.8 8012.5 8417.8 8834.9 8922.1 11049.6 12686.0 12951.3 13118.5 13478.1 7890.2 8022.7 8156.3 8361.2 11062.7 7712.3 7765.3 8049.3 8294.6 8326.0 8087.8 8139.1 8340.7 8604.0 8661.9 12307.7 12951.7 13067.9 13405.7 13419.6 11080.9 11490.4 11589.7 11867.2 12162.8 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#3] mootools.html Cycles:180 Average:5350.45 Median:5007.05 stddev:2974.30 (59.4%) stddev-sans-first:2965.50 17:47:43 INFO - PROCESS | 1986 | Values: 1101.7 1166.0 1184.5 1189.0 1201.1 7492.0 7730.8 8091.2 8466.2 8505.7 8012.9 8237.9 8280.2 8380.5 8407.6 2224.6 2260.3 2265.0 2268.3 2268.5 1959.4 2032.5 2236.6 2276.4 2277.2 2181.4 2234.4 2246.3 2257.7 2269.6 2232.6 2234.0 2254.5 2255.4 2256.6 2210.6 2214.2 2281.7 2285.0 2287.7 8448.5 8499.4 8526.7 8542.8 8569.7 5059.6 5200.1 5209.0 5210.2 5223.8 3396.1 3418.8 3429.6 3438.8 3485.3 1159.3 1182.9 1188.2 1193.2 1224.7 9470.6 9539.2 11036.3 11088.0 11110.6 3977.7 4350.3 4623.9 4704.9 4706.7 7240.3 7902.7 9613.4 9632.7 9635.7 4417.5 4590.2 4993.8 5206.2 5220.1 4098.5 4624.3 4736.1 4757.0 4805.2 3588.4 3627.2 3654.8 3673.6 3676.7 1045.2 1059.3 1145.8 1154.2 1155.9 699.3 723.9 765.5 779.9 811.6 4868.5 5054.4 5327.1 5376.1 5605.1 824.5 931.0 945.7 949.9 1004.8 3486.0 3709.4 3711.4 3799.1 3808.4 9796.5 10123.3 10129.0 10131.9 10138.7 10087.1 10096.5 10122.5 10127.6 10127.7 7483.4 7830.1 7978.1 7987.5 7995.4 5357.7 5549.7 5557.3 5619.9 5678.9 8322.0 9052.3 9121.6 9194.1 9270.4 9148.9 9352.0 9366.7 9391.7 9404.5 7014.5 7192.1 7263.0 7291.9 7293.3 7819.8 7850.2 7908.7 7919.6 7928.6 7139.6 7168.6 7212.5 7252.7 7319.2 5420.5 7065.4 7074.9 7077.5 7092.8 6196.1 6347.2 6348.9 6368.0 6368.8 4384.2 4781.5 4940.0 4967.9 4995.9 4863.3 4866.2 4928.6 4987.9 5018.2 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#4] prototype.html Cycles:180 Average:4528.75 Median:5108.17 stddev:1395.24 (27.3%) stddev-sans-first:1363.43 17:47:43 INFO - PROCESS | 1986 | Values: 348.8 352.0 358.3 363.3 367.5 4321.7 5100.7 5305.7 5495.3 5579.7 4150.9 5140.4 5377.9 5382.8 5396.4 3536.3 4249.3 4261.9 4263.8 4282.6 3781.4 3892.6 4139.3 4301.0 4323.5 3390.6 3497.4 3710.9 3749.8 4156.4 3266.8 3453.1 4070.1 4182.5 4194.4 3425.8 3712.2 3746.3 3751.5 3760.7 4584.5 4806.1 5158.9 5242.6 5252.4 4584.8 4605.0 4608.6 4625.0 4676.7 5660.0 5673.4 5703.8 5747.7 5778.5 3355.6 3740.8 3845.2 3848.4 3861.5 5384.9 5462.0 5607.5 5722.9 5767.8 3869.2 3878.8 3882.6 3888.4 3902.6 3929.6 3933.4 3945.7 3954.3 3962.1 4463.3 4608.6 4612.3 4624.4 4653.7 4811.4 5030.0 5698.2 5720.6 5729.4 1109.1 1167.8 1182.4 1226.8 1314.5 762.2 776.8 813.9 888.3 888.3 4342.7 5040.4 5115.9 5122.6 5140.6 4938.6 5197.6 5390.8 5640.8 5676.0 4285.8 4649.3 4688.7 4996.7 5115.6 2879.3 2947.3 2947.5 2962.7 2969.5 5535.9 5563.6 5883.3 5889.9 5921.9 4707.9 5046.8 5359.7 5681.4 5761.9 4883.2 5258.8 5746.8 5798.7 5854.9 4866.9 4880.7 5553.6 5666.7 5704.3 4817.8 5153.7 5227.9 5340.8 5646.5 5143.6 5437.5 5723.5 5765.9 5959.0 3531.9 3567.5 3792.9 3808.7 3829.6 5158.4 5875.5 5891.5 5897.8 5938.4 5343.7 5677.2 5711.1 5763.4 5824.4 5717.5 5815.7 5839.7 5848.4 5852.9 5055.8 5376.6 5486.4 5569.2 5607.2 5702.0 5722.0 5743.7 5745.9 5761.7 5601.7 5603.5 5632.6 5665.8 5708.7 17:47:43 INFO - PROCESS | 1986 | 17:47:43 INFO - PROCESS | 1986 | [#5] yui.html Cycles:180 Average:18988.72 Median:32946.67 stddev:27121.74 (82.3%) stddev-sans-first:27162.95 17:47:43 INFO - PROCESS | 1986 | Values: 670.8 682.2 683.7 780.9 804.3 13958.2 14995.8 15007.2 15034.5 15066.3 13741.5 13860.7 13861.2 13895.7 13956.9 858.1 873.3 876.8 877.4 878.5 814.3 850.9 852.3 865.2 869.2 908.5 910.1 931.4 935.8 937.2 927.9 931.1 931.8 935.2 935.4 688.9 692.1 692.5 692.8 693.9 14170.8 14760.2 15103.2 15130.3 15161.8 13492.3 13495.2 13808.2 13835.6 13861.7 11150.1 11445.5 11493.1 11568.4 11742.6 3073.9 3231.1 3408.9 3564.8 3634.3 67652.7 73849.6 94660.5 96238.5 96893.5 8996.3 10383.5 10392.6 10495.2 10908.5 21326.9 21588.9 21611.0 21657.2 21723.9 6351.6 6445.5 7353.2 7366.6 7456.2 4050.9 4137.3 4154.6 4163.9 4165.9 3464.3 3556.8 3562.3 3579.6 3611.7 641.4 648.0 649.1 656.7 657.3 529.5 657.1 676.5 681.3 698.7 8338.7 8914.0 9008.4 9272.3 9635.8 664.5 672.8 676.0 681.4 682.4 4155.3 4610.7 4616.8 4844.7 4860.1 95014.0 96116.6 96641.5 98142.5 100429.0 83638.0 85286.0 85827.0 85840.0 87358.6 73934.6 74876.3 75002.6 75080.0 75532.0 9283.8 9320.3 9347.7 9369.9 9423.9 38129.0 39676.6 51278.0 51349.7 51626.0 65236.7 66059.7 67172.7 68462.6 68655.7 11187.5 11266.3 11356.0 11368.9 11883.3 12352.8 12505.2 12537.9 12556.6 13151.6 11459.8 11718.1 11735.7 11773.2 11865.2 9868.6 11781.7 11896.9 11931.2 12334.9 11497.7 11790.2 11920.9 11951.8 12207.4 7301.5 9489.6 9527.7 9646.6 9758.2 9431.0 9546.9 9554.4 9573.1 9709.6 17:47:43 INFO - PROCESS | 1986 | -------- Summary: end -------- 17:47:43 INFO - PROCESS | 1986 | 17:47:44 INFO - TEST-INFO | 1986: exit 0 17:47:44 INFO - TEST-OK | dromaeo_css | took 1115836ms 17:47:44 INFO - TEST-START | kraken 17:47:44 INFO - operating with platform_type : mac_ 17:47:44 INFO - Initialising browser for kraken test... 17:47:44 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpiTh7AR/profile http://localhost:52763/getInfo.html) 17:47:46 INFO - PROCESS | 1993 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:47:46 INFO - PROCESS | 1993 | 2017-02-08 17:47:46.804 plugin-container[1994:18553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 17:47:46 INFO - PROCESS | 1993 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:47:46 INFO - PROCESS | 1993 | 2017-02-08 17:47:46.807 plugin-container[1994:18553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 17:47:46 INFO - PROCESS | 1993 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:47:46 INFO - PROCESS | 1993 | 2017-02-08 17:47:46.807 plugin-container[1994:18553] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:47:46 INFO - PROCESS | 1993 | __metrics Screen width/height:1600/1200 17:47:46 INFO - PROCESS | 1993 | colorDepth:24 17:47:46 INFO - PROCESS | 1993 | Browser inner width/height: 1024/689 17:47:46 INFO - PROCESS | 1993 | __metrics 17:47:46 ERROR - PROCESS | 1993 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 17:47:47 INFO - TEST-INFO | 1993: exit 0 17:47:47 INFO - Browser initialized. 17:47:47 INFO - Running cycle 1/1 for kraken test... 17:47:47 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpiTh7AR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:47:50 INFO - PROCESS | 1995 | 2017-02-08 17:47:50.047 plugin-container[1996:18670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 17:47:50 INFO - PROCESS | 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:47:50 INFO - PROCESS | 1995 | 2017-02-08 17:47:50.047 plugin-container[1996:18670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 17:47:50 INFO - PROCESS | 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:47:50 INFO - PROCESS | 1995 | 2017-02-08 17:47:50.047 plugin-container[1996:18670] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:47:59 INFO - PROCESS | 1995 | RSS: Main: 234434560 17:47:59 INFO - PROCESS | 1995 | 17:48:03 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52763/tests/kraken/driver.html?testName=audio-beat-detection) 17:48:04 INFO - PROCESS | 1995 | RSS: Main: 247775232 17:48:04 INFO - PROCESS | 1995 | 17:48:06 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52763/tests/kraken/driver.html?testName=audio-dft) 17:48:07 INFO - PROCESS | 1995 | RSS: Main: 256929792 17:48:07 INFO - PROCESS | 1995 | 17:48:11 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52763/tests/kraken/driver.html?testName=audio-fft) 17:48:11 INFO - PROCESS | 1995 | RSS: Main: 258650112 17:48:11 INFO - PROCESS | 1995 | 17:48:13 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52763/tests/kraken/driver.html?testName=audio-oscillator) 17:48:14 INFO - PROCESS | 1995 | RSS: Main: 260894720 17:48:14 INFO - PROCESS | 1995 | 17:48:16 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52763/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:48:17 INFO - PROCESS | 1995 | RSS: Main: 261246976 17:48:17 INFO - PROCESS | 1995 | 17:48:20 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52763/tests/kraken/driver.html?testName=imaging-darkroom) 17:48:21 INFO - PROCESS | 1995 | RSS: Main: 266809344 17:48:21 INFO - PROCESS | 1995 | 17:48:24 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52763/tests/kraken/driver.html?testName=imaging-desaturate) 17:48:25 INFO - PROCESS | 1995 | RSS: Main: 266469376 17:48:25 INFO - PROCESS | 1995 | 17:48:28 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52763/tests/kraken/driver.html?testName=json-parse-financial) 17:48:28 INFO - PROCESS | 1995 | RSS: Main: 265625600 17:48:28 INFO - PROCESS | 1995 | 17:48:30 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52763/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:48:31 INFO - PROCESS | 1995 | RSS: Main: 265555968 17:48:31 INFO - PROCESS | 1995 | 17:48:32 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:48:33 INFO - PROCESS | 1995 | RSS: Main: 265613312 17:48:33 INFO - PROCESS | 1995 | 17:48:35 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:48:36 INFO - PROCESS | 1995 | RSS: Main: 265981952 17:48:36 INFO - PROCESS | 1995 | 17:48:39 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:48:40 INFO - PROCESS | 1995 | RSS: Main: 266301440 17:48:40 INFO - PROCESS | 1995 | 17:48:43 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:48:44 INFO - PROCESS | 1995 | RSS: Main: 266080256 17:48:44 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:52763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52763/tests/kraken/driver.html?testName=ai-astar) 17:48:46 INFO - PROCESS | 1995 | RSS: Main: 269414400 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | __start_tp_report 17:48:46 INFO - PROCESS | 1995 | _x_x_mozilla_page_load 17:48:46 INFO - PROCESS | 1995 | _x_x_mozilla_page_load_details 17:48:46 INFO - PROCESS | 1995 | |i|pagename|runs| 17:48:46 INFO - PROCESS | 1995 | |0;ai-astar;155;103;177;173;163;119;118;119;123;99 17:48:46 INFO - PROCESS | 1995 | |1;audio-beat-detection;150;131;103;181;146;124;116;117;140;117 17:48:46 INFO - PROCESS | 1995 | |2;audio-dft;213;267;274;200;188;278;248;194;195;195 17:48:46 INFO - PROCESS | 1995 | |3;audio-fft;85;76;86;109;55;79;53;75;92;84 17:48:46 INFO - PROCESS | 1995 | |4;audio-oscillator;83;71;86;72;77;104;116;79;71;84 17:48:46 INFO - PROCESS | 1995 | |5;imaging-gaussian-blur;136;135;92;134;76;135;136;90;136;128 17:48:46 INFO - PROCESS | 1995 | |6;imaging-darkroom;153;119;156;152;98;97;99;98;122;113 17:48:46 INFO - PROCESS | 1995 | |7;imaging-desaturate;98;133;92;95;128;76;121;114;78;141 17:48:46 INFO - PROCESS | 1995 | |8;json-parse-financial;66;65;59;61;61;53;54;65;89;74 17:48:46 INFO - PROCESS | 1995 | |9;json-stringify-tinderbox;70;69;74;76;86;75;71;71;75;72 17:48:46 INFO - PROCESS | 1995 | |10;stanford-crypto-aes;91;90;81;104;109;109;81;82;114;111 17:48:46 INFO - PROCESS | 1995 | |11;stanford-crypto-ccm;129;162;147;145;143;163;148;153;209;173 17:48:46 INFO - PROCESS | 1995 | |12;stanford-crypto-pbkdf2;222;241;188;186;177;174;162;179;178;209 17:48:46 INFO - PROCESS | 1995 | |13;stanford-crypto-sha256-iterative;61;82;105;68;66;83;83;86;83;61 17:48:46 INFO - PROCESS | 1995 | __end_tp_report 17:48:46 INFO - PROCESS | 1995 | __start_cc_report 17:48:46 INFO - PROCESS | 1995 | _x_x_mozilla_cycle_collect,970 17:48:46 INFO - PROCESS | 1995 | __end_cc_report 17:48:46 INFO - PROCESS | 1995 | __startTimestamp1486604926330__endTimestamp 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | ------- Summary: start ------- 17:48:46 INFO - PROCESS | 1995 | Number of tests: 14 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#0] ai-astar Cycles:10 Average:134.90 Median:159.00 stddev:29.17 (18.3%) stddev-sans-first:30.02 17:48:46 INFO - PROCESS | 1995 | Values: 155.0 103.0 177.0 173.0 163.0 119.0 118.0 119.0 123.0 99.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:135.50 stddev:22.59 (16.7%) stddev-sans-first:23.06 17:48:46 INFO - PROCESS | 1995 | Values: 150.0 131.0 103.0 181.0 146.0 124.0 116.0 117.0 140.0 117.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#2] audio-dft Cycles:10 Average:225.20 Median:230.50 stddev:37.12 (16.1%) stddev-sans-first:39.11 17:48:46 INFO - PROCESS | 1995 | Values: 213.0 267.0 274.0 200.0 188.0 278.0 248.0 194.0 195.0 195.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#3] audio-fft Cycles:10 Average:79.40 Median:81.50 stddev:16.51 (20.3%) stddev-sans-first:17.39 17:48:46 INFO - PROCESS | 1995 | Values: 85.0 76.0 86.0 109.0 55.0 79.0 53.0 75.0 92.0 84.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:78.00 stddev:14.85 (19.0%) stddev-sans-first:15.74 17:48:46 INFO - PROCESS | 1995 | Values: 83.0 71.0 86.0 72.0 77.0 104.0 116.0 79.0 71.0 84.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#5] imaging-gaussian-blur Cycles:10 Average:119.80 Median:136.00 stddev:23.80 (17.5%) stddev-sans-first:24.51 17:48:46 INFO - PROCESS | 1995 | Values: 136.0 135.0 92.0 134.0 76.0 135.0 136.0 90.0 136.0 128.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#6] imaging-darkroom Cycles:10 Average:120.70 Median:126.50 stddev:24.49 (19.4%) stddev-sans-first:23.01 17:48:46 INFO - PROCESS | 1995 | Values: 153.0 119.0 156.0 152.0 98.0 97.0 99.0 98.0 122.0 113.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#7] imaging-desaturate Cycles:10 Average:107.60 Median:77.00 stddev:23.01 (29.9%) stddev-sans-first:24.15 17:48:46 INFO - PROCESS | 1995 | Values: 98.0 133.0 92.0 95.0 128.0 76.0 121.0 114.0 78.0 141.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:10.49 (16.1%) stddev-sans-first:11.11 17:48:46 INFO - PROCESS | 1995 | Values: 66.0 65.0 59.0 61.0 61.0 53.0 54.0 65.0 89.0 74.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:74.50 stddev:4.86 (6.5%) stddev-sans-first:4.95 17:48:46 INFO - PROCESS | 1995 | Values: 70.0 69.0 74.0 76.0 86.0 75.0 71.0 71.0 75.0 72.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:81.00 stddev:13.51 (16.7%) stddev-sans-first:14.15 17:48:46 INFO - PROCESS | 1995 | Values: 91.0 90.0 81.0 104.0 109.0 109.0 81.0 82.0 114.0 111.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#11] stanford-crypto-ccm Cycles:10 Average:157.20 Median:157.50 stddev:21.96 (13.9%) stddev-sans-first:20.79 17:48:46 INFO - PROCESS | 1995 | Values: 129.0 162.0 147.0 145.0 143.0 163.0 148.0 153.0 209.0 173.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:187.00 stddev:24.62 (13.2%) stddev-sans-first:23.53 17:48:46 INFO - PROCESS | 1995 | Values: 222.0 241.0 188.0 186.0 177.0 174.0 162.0 179.0 178.0 209.0 17:48:46 INFO - PROCESS | 1995 | 17:48:46 INFO - PROCESS | 1995 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.80 Median:82.50 stddev:13.77 (16.7%) stddev-sans-first:13.19 17:48:46 INFO - PROCESS | 1995 | Values: 61.0 82.0 105.0 68.0 66.0 83.0 83.0 86.0 83.0 61.0 17:48:46 INFO - PROCESS | 1995 | -------- Summary: end -------- 17:48:46 INFO - PROCESS | 1995 | 17:48:47 INFO - TEST-INFO | 1995: exit 0 17:48:47 INFO - TEST-OK | kraken | took 63185ms 17:48:47 INFO - SUITE-END | took 1179s 17:48:47 INFO - Completed test suite (00:19:39) 17:48:47 INFO - javascript benchmark 17:48:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7119.141074183393, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.3693442844215, 3335.7664963452567, 3352.1284466038824, 3394.304167645633, 3405.42107841667, 5596.048671725806, 5609.719514024303, 5643.4074422185695, 5647.604667673259, 5671.943280567193, 4495.865124046284, 5656.971715141429, 5662.405447428023, 5696.544276457884, 5711.314642242936, 4734.834280800155, 5049.722265275427, 5144.176931690921, 5195.220716892459, 5338.199270109476, 4895.55939965403, 5215.035218484573, 5225.529702326789, 5249.448807875176, 5263.105272103751, 4492.191405547001, 5099.311592934943, 5138.640295179339, 5177.094008548489, 5212.50481204285, 5164.948350516484, 5199.714015729133, 5200.3759548854005, 5202.505761952609, 5251.264822924793, 4655.813767449298, 4877.219644856806, 4980.850574482772, 5126.154184559543, 5137.74311284438, 5340.118880384732, 5493.533049690781, 5559.110542313251, 5615.157726341041, 5652.547796176296, 6179.752809887599, 6188.690565471754, 6192.0, 6202.131701561785, 6232.3144454110015, 6196.938030619726, 6214.689265536705, 6215.660364236121, 6223.719932603044, 6261.812145635638, 5577.581681373913, 5584.441555844383, 5645.661260324311, 5645.661260324394, 5649.830505084854, 6200.503959683215, 6229.283632382243, 6246.312905580383, 6252.468540174136, 6257.061440783918, 5815.79644712433, 5818.069108942634, 5856.765729370863, 5924.7037648118285, 6005.279366476051, 6110.419510146529, 6144.569880108439, 6202.689865506707, 6213.9067913980425, 6299.622022678654, 5854.941450585525, 5934.525237980951, 6190.969045154836, 6192.59748116371, 6220.906686399708, 6026.21659184312, 6111.014442317784, 6138.32709113608, 6141.324454310023, 6203.410675985774, 4956.826511072095, 4959.950400495949, 4973.5523802857915, 4979.651424400257, 5012.573931215888, 3828.502294701671, 4431.7148027072435, 4450.688451808407, 4463.24124898768, 4480.910381792346, 5573.832785016456, 5596.916046259309, 5666.150077488327, 5673.0, 5703.201551782754, 6179.5056395488255, 6188.381161883775, 6189.690515474298, 6235.875282494416, 6270.216222972129, 5747.281589801275, 5805.419458054161, 5810.477057064884, 5812.0119579671555, 5832.9708351457975, 5243.213517972258, 5298.947010529845, 5339.439358867263, 5400.054990376747, 5421.349438067457, 6252.968735156477, 6253.937460625427, 6270.7491700331475, 6272.874542509216, 6296.716647750771, 6245.656488892972, 6248.218972628421, 6262.686865656608, 6269.0, 6287.056941458818, 6146.279041868284, 6185.350538193605, 6218.0, 6262.248530176408, 6281.0, 5208.739563021758, 6016.819495415145, 6037.245344331958, 6146.6004709693725, 6147.815565533041, 6020.578559500968, 6123.84690382744, 6179.752809887734, 6185.6907154641185, 6189.102580125947, 5818.854528636818, 5888.205092312654, 6023.27720673522, 6084.239470066242, 6110.938890611214, 6103.640733310809, 6124.625058545733, 6140.62695141751, 6149.385061493815, 6154.046122850965, 5862.355140934408, 5920.585559011, 5928.614640048383, 5969.552283578836, 5999.130126131599, 5216.113260745607, 5824.301083869963, 5889.057750759858, 6061.909071363845, 6086.939130608815, 4941.838667912965, 5106.387233532, 5879.265091863517, 5946.970265148647, 5999.460048595647], "value": 5608.685141041042, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.45723560993883, 485.905248476547, 486.1807853766402, 517.6247220764945, 521.2259794205605, 38641.0, 41144.17711645765, 41283.58716412835, 41311.79344103279, 41398.58601413985, 41644.37533437001, 41971.0, 42093.36859947096, 42125.57874421255, 42137.78931105348, 9179.632814687404, 9395.027044320701, 9416.81166376672, 9430.905690943106, 10696.572137114545, 9791.461469619167, 10440.895591044069, 10529.526171322308, 10901.12790976724, 10911.945440272788, 9033.457992520436, 10863.83704244437, 10872.891271087306, 10900.34597924127, 10907.0, 10819.83770243447, 10890.183236257313, 10908.072813810875, 10927.945360273148, 10929.016388525071, 10771.676849694522, 10819.026287634071, 10851.186161037953, 10863.076638485738, 10919.071878890225, 36122.0, 36243.456348154796, 36496.452553211726, 36575.0, 36662.450063249074, 3318.386098571772, 3744.4383342498572, 3779.6976241900584, 3790.9810450947843, 3801.885943421702, 27840.443191136063, 28496.857515712498, 28626.0, 28694.282642933886, 28694.856525717445, 3103.0841568377314, 3137.294108825519, 3154.274516861135, 3204.8878289259765, 3246.902592922216, 436381.0, 439819.0, 440419.0, 443323.0, 443380.0, 40256.59743402529, 47874.28188577174, 56054.719726401105, 56305.0, 56405.15392269037, 58842.41157588455, 59306.703466482395, 59712.0, 62914.68542657349, 63276.36723632797, 14968.70062598742, 15021.774673379909, 15131.470398536, 15137.318820652878, 15144.16707081099, 11383.772324553462, 11408.258463199865, 11512.539498420138, 11525.711857203636, 11540.422978851024, 14503.347349369304, 17826.5543361417, 18004.36984705529, 18057.91652500828, 18092.0, 6594.0, 7723.845523089507, 7795.922040779633, 7810.726624568113, 7820.452568320276, 737.8966944627756, 814.5112932240727, 822.083377034614, 830.588858515039, 843.4053991935671, 29845.70154298429, 29922.401551969273, 29978.95073672411, 30270.84864575663, 30745.92389266365, 824.9257566819015, 1005.4419797012728, 1020.7244044108049, 1051.9316244444087, 1138.7153211697075, 12376.938115309365, 15494.612634684223, 15617.765733514007, 15745.921270393575, 15908.522744317688, 582020.0, 586811.0, 587269.0, 588038.0, 588924.0, 40292.589759358285, 42544.361834572504, 45320.3201951971, 45663.0, 45738.31392529115, 1368.7125703602155, 1378.4899587152618, 1402.425005747659, 1403.9508617198348, 1404.501402002295, 28402.85798570994, 29126.56310155392, 29213.561796572645, 29940.251493712833, 30219.244518886324, 50139.74930125326, 50201.49798501968, 50517.242241367145, 50559.94400559914, 50587.988240235725, 478373.0, 482261.0, 483101.0, 483901.0, 484539.0, 19653.8034619652, 23119.4220144491, 26014.479710406064, 26085.608715869657, 26954.056608018625, 46254.53745462637, 46549.0, 46584.53415465938, 46959.826004350165, 47809.80475488141, 31507.36985260328, 37247.44128838124, 37615.05962350963, 38920.026999325244, 38936.80531597324, 33164.50253246252, 37216.25567488581, 37379.81310093432, 37853.81073094727, 38607.61392386153, 30240.09279721612, 36427.0, 36437.817810910776, 36477.63522364743, 36579.634203657624, 30540.014899031492, 31719.841400792848, 33460.330793384484, 33613.495797562566, 33615.0, 22911.65632515547, 23693.644595330738, 24658.02735781169, 24677.62983555284, 25401.74598253994], "value": 19226.95291632354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.56005593567374, 384.204696278703, 389.2681758294406, 398.42825545342447, 422.3158483257124, 18919.52701182471, 20182.596348073028, 20193.39419817406, 20300.89849550752, 20506.589868202627, 20751.481212969644, 20798.584028319423, 20986.475338116554, 21067.0, 21269.787302126973, 5480.150576660614, 5869.647821130746, 5922.466977972003, 5930.140129681194, 5950.404959504037, 5659.575531835108, 5754.942450575482, 5832.912506312408, 5868.911966320508, 5884.4703976642095, 5817.825465236049, 5852.912206316909, 5871.970640146793, 5877.559183061266, 5884.558658100638, 5581.441855814386, 5723.198752174699, 5859.296884373881, 5880.911786323209, 5895.911561326605, 5002.674826136289, 5085.821996230115, 5120.564751996085, 5122.667026643257, 5124.436312005676, 24857.25428237156, 25005.7497125143, 25033.0, 25237.36906577332, 25390.492190156278, 2633.8814753336146, 2670.719574444675, 2693.932651683704, 2695.9865200674067, 2697.0695110186953, 14944.701105977929, 15268.847311526853, 15429.691406171925, 15642.0, 15726.449574264845, 1790.5556383137248, 1810.2697998259357, 1848.260695721722, 1851.0282101896478, 1853.2494339792406, 119206.0, 123762.38118809438, 124392.0, 124653.0, 125730.0, 9008.909910900808, 11019.787823339426, 11371.886281137247, 11582.189246752647, 11673.88326116745, 12506.49974001048, 14337.283135843374, 14457.638559036108, 14462.27688615565, 14503.347349369304, 8146.959265203636, 8388.119247478928, 8391.412601117985, 8451.492910425393, 8483.363747718946, 8161.877571836427, 8182.140875208136, 8209.302209312274, 8354.874676879852, 8686.478811271343, 7105.4827860209525, 7222.241664625244, 7239.167495738057, 7239.674214660247, 7243.420526357879, 1793.1841012339355, 1920.7310976463307, 1924.3553409607657, 1926.9325573604856, 1927.5855691026497, 749.318120510333, 861.3582880753753, 871.4292138649195, 882.7798087809165, 894.8702438146432, 18884.82376696874, 19588.510287242647, 20603.690944635844, 21937.48380280266, 21957.773512475946, 887.7558671365426, 889.3241136736127, 913.2100732866156, 916.2532535983153, 926.2775035472342, 10534.841977370346, 11562.826557601642, 11567.710807229887, 11584.710382240512, 11663.825042624367, 668030.0, 671409.0, 675526.0, 680217.0, 698057.0, 114719.0, 116490.41754791171, 118920.0, 119829.0, 121531.0, 1000.5747557288123, 1013.0629168019552, 1032.7779527401494, 1033.3180101133366, 1038.3925403638955, 15304.387824487341, 17704.849184802948, 18417.447476575726, 19021.23915043438, 19093.236270549023, 49567.7694503741, 51211.487885120674, 51473.0, 52007.0, 53436.73281633722, 57556.71221643865, 72267.0, 72404.63797680978, 73099.0, 81164.59417702873, 7371.8157046074275, 8012.479188852706, 8417.831643366975, 8834.867476987978, 8922.107789221285, 11049.613263535737, 12686.0, 12951.287679177736, 13118.540851070167, 13478.056536042383, 7890.171531989058, 8022.676258417529, 8156.347492200728, 8361.247487726134, 11062.723431914266, 7712.267334603203, 7765.301122898966, 8049.27556519916, 8294.626741796514, 8325.95837020811, 8087.757367278991, 8139.1453897339925, 8340.749777506684, 8604.0, 8661.913380866363, 12307.69230769238, 12951.740965180832, 13067.934660326637, 13405.66485837862, 13419.597412077654, 11080.889191107986, 11490.425478726198, 11589.6523104307, 11867.169298149047, 12162.756744864877], "value": 9676.01173642232, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1101.7410908436516, 1166.0496695193413, 1184.4788293151014, 1188.9583864564743, 1201.0871737479515, 7491.98858154149, 7730.7680769577, 8091.231333023368, 8466.153384661531, 8505.659773609048, 8012.91987080129, 8237.876431853527, 8280.213379728932, 8380.45527040742, 8407.57962101894, 2224.63293556563, 2260.2993072147606, 2264.9581192651317, 2268.25147701259, 2268.5236100418933, 1959.3632069577372, 2032.542677897476, 2236.6197746383073, 2276.4195130241847, 2277.2485079923586, 2181.3674034529968, 2234.3743751749453, 2246.3148739634485, 2257.6500642400456, 2269.614165591853, 2232.586971410294, 2234.0, 2254.4701995031237, 2255.4023183856293, 2256.571251462217, 2210.635245184543, 2214.2250212425683, 2281.6691579721014, 2284.9543009139725, 2287.656851472276, 8448.535330556817, 8499.405041647087, 8526.701565445179, 8542.829143417097, 8569.657213711444, 5059.595232381401, 5200.089984252779, 5208.973955130238, 5210.192420174879, 5223.843284701465, 3396.1339858335964, 3418.7607117338184, 3429.5713035870517, 3438.793672379665, 3485.257371314343, 1159.339176669299, 1182.8817118288102, 1188.2395267029108, 1193.2065176657513, 1224.65097447228, 9470.57382417786, 9539.236861051098, 11036.337819730841, 11088.002079812855, 11110.555577776799, 3977.7016723745833, 4350.303951367766, 4623.86128416148, 4704.88237794051, 4706.670533062721, 7240.275972402717, 7902.723404680809, 9613.423194608346, 9632.711018669452, 9635.710928672152, 4417.536158703354, 4590.196715574761, 4993.775280112403, 5206.245094461282, 5220.112580861262, 4098.508179018478, 4624.329472226508, 4736.052789442126, 4757.0, 4805.231163013901, 3588.3540962626457, 3627.165751877083, 3654.8172591370326, 3673.559172899269, 3676.6690997810324, 1045.163868904873, 1059.327327147267, 1145.8166693329194, 1154.2266681323533, 1155.8786327435666, 699.2972063076576, 723.9457040721967, 765.5253742679463, 779.9415043871733, 811.5982588618672, 4868.488808675038, 5054.444011158769, 5327.067763141435, 5376.086065368897, 5605.075162598126, 824.5217773691364, 931.0, 945.6548359848606, 949.914507694297, 1004.8141093897506, 3486.0, 3709.4435834625024, 3711.4247291669835, 3799.1072098056934, 3808.3716186829415, 9796.51017449139, 10123.341982771244, 10129.0, 10131.898681013243, 10138.695839124837, 10087.142592879687, 10096.545655445523, 10122.544485498023, 10127.64553240633, 10127.734033245844, 7483.438742094474, 7830.138684744674, 7978.082520510099, 7987.520748755093, 7995.40034497415, 5357.678539287499, 5549.667019978814, 5557.277553917965, 5619.943800561942, 5678.914816277842, 8322.0, 9052.275817934418, 9121.635134594542, 9194.080591940752, 9270.397424167408, 9148.908510914805, 9352.0, 9366.67216647414, 9391.671291504765, 9404.52977351116, 7014.5440546364325, 7192.100987376578, 7263.019492368674, 7291.854162916606, 7293.343599076109, 7819.804504887424, 7850.214978502333, 7908.683652654059, 7919.604019798871, 7928.643211055632, 7139.607321597362, 7168.605726685082, 7212.531185473137, 7252.746153884589, 7319.158296795937, 5420.457954204548, 7065.434765218667, 7074.929250707427, 7077.5399599025895, 7092.751753688596, 6196.1015652731, 6347.238331400262, 6348.936510634834, 6367.968160159355, 6368.77709280173, 4384.188925048876, 4781.497942716104, 4939.975300123477, 4967.875803104806, 4995.875103122451, 4863.319135321129, 4866.245731911559, 4928.6057115431395, 4987.850364489072, 5018.222175562793], "value": 4296.057170036298, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.8308170537289, 351.97926014557794, 358.28700885238374, 363.3260302139531, 367.5350681388046, 4321.654267658588, 5100.744962751857, 5305.734713264341, 5495.334762640121, 5579.721013949306, 4150.896227594304, 5140.357455318085, 5377.865553361168, 5382.788872503685, 5396.406395296524, 3536.3104194682046, 4249.256380133472, 4261.936070958923, 4263.829446822139, 4282.614564689177, 3781.3760729479604, 3892.5718171001167, 4139.275626765319, 4301.010767523471, 4323.502797178317, 3390.643982381839, 3497.3769672745443, 3710.888673339791, 3749.787625301072, 4156.418101465797, 3266.80399176049, 3453.1021934297137, 4070.104576993057, 4182.4771903511755, 4194.433751443577, 3425.811580363079, 3712.1647629283466, 3746.3256613809504, 3751.4747935289083, 3760.668385600945, 4584.541545845422, 4806.086843499724, 5158.871028224287, 5242.55438287746, 5252.369715634149, 4584.770761461914, 4604.963883126304, 4608.585227329557, 4625.028743963771, 4676.672632915697, 5660.0, 5673.404292549264, 5703.77184912603, 5747.7408171523, 5778.508826749731, 3355.6476569960046, 3740.7942563159017, 3845.1540661054523, 3848.3842585186235, 3861.4787003754413, 5384.892302153974, 5461.989531936604, 5607.467290607386, 5722.856928576777, 5767.798127065533, 3869.187470631199, 3878.7866667333005, 3882.611738826095, 3888.3972984187494, 3902.5902280260166, 3929.5677475477673, 3933.350997085449, 3945.6646185074014, 3954.347532657137, 3962.148138150254, 4463.285874260103, 4608.6082682971655, 4612.331211974245, 4624.421947256593, 4653.6509761768, 4811.3745213122775, 5030.019146266516, 5698.230738850285, 5720.599558030981, 5729.3984131665575, 1109.1348747976592, 1167.7897978363806, 1182.3969775414428, 1226.7791797476361, 1314.5464814638933, 762.1616222155584, 776.8252143267832, 813.894193754808, 888.2938064238946, 888.3071204460402, 4342.739435633872, 5040.395152581714, 5115.897682046339, 5122.564582010571, 5140.583925554984, 4938.59250113723, 5197.58419326453, 5390.760125171232, 5640.774369025193, 5676.0, 4285.849995250152, 4649.279361698875, 4688.718676879398, 4996.650234483552, 5115.590752739772, 2879.337752316979, 2947.3073827650487, 2947.469455497988, 2962.674105848355, 2969.5100308449296, 5535.865147644723, 5563.607648711093, 5883.294004719461, 5889.937232639089, 5921.851953701106, 4707.9285114550175, 5046.84859454217, 5359.651622644516, 5681.403452637496, 5761.905238004766, 4883.194272944925, 5258.845512045517, 5746.798862039808, 5798.710064496674, 5854.912176317273, 4866.90266194667, 4880.707157570557, 5553.615089067315, 5666.660000399989, 5704.344000440003, 4817.759112044314, 5153.6758790579115, 5227.869303267449, 5340.839774806762, 5646.548276137982, 5143.6399452037995, 5437.4562543745305, 5723.484886360247, 5765.94234057654, 5959.0, 3531.876384326536, 3567.464880267981, 3792.867249646249, 3808.6572208501366, 3829.5596006459614, 5158.38099428056, 5875.471207591167, 5891.469767720754, 5897.7935772247765, 5938.406159384027, 5343.65266257692, 5677.205191273308, 5711.086226203787, 5763.423657634203, 5824.359320474659, 5717.4557321991315, 5815.70279739509, 5839.722508221882, 5848.444397782204, 5852.853678658068, 5055.757700749843, 5376.56987440996, 5486.369067557282, 5569.220309156803, 5607.2149899014985, 5701.971490142689, 5721.997300944636, 5743.712814359349, 5745.8563535911935, 5761.654300741969, 5601.719914004365, 5603.495685388335, 5632.5775566831835, 5665.801696940587, 5708.65748055118], "value": 4088.423911076541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.7518218259245, 682.2324884504931, 683.6650041479677, 780.8867714181443, 804.2882049386292, 13958.232297223649, 14995.775063374058, 15007.249637518122, 15034.548963531099, 15066.3220155093, 13741.519046833362, 13860.653483662914, 13861.168329900212, 13895.652608684788, 13956.860431395682, 858.0647094666812, 873.3188113271626, 876.750126214026, 877.4296707140378, 878.4904755241944, 814.267159556398, 850.8808766772656, 852.2713080316303, 865.2429124516049, 869.2307308032405, 908.4912449028532, 910.1171863292595, 931.4271722890408, 935.7567032571546, 937.1706040154478, 927.8654595083675, 931.0503286647615, 931.8369285375067, 935.242453612576, 935.3873213045466, 688.8760023195823, 692.1279188222825, 692.4771797293062, 692.7713854428077, 693.9132608423947, 14170.787438188436, 14760.188189649565, 15103.169325687082, 15130.319135638923, 15161.772573411408, 13492.325383730775, 13495.219184661786, 13808.17150970945, 13835.584932451942, 13861.722765544633, 11150.149220956782, 11445.542178312857, 11493.080553555696, 11568.363739994296, 11742.58900938463, 3073.92315192122, 3231.111444352798, 3408.880689175867, 3564.8395822188063, 3634.254977729559, 67652.66173669149, 73849.63075184589, 94660.52669736676, 96238.51880740622, 96893.51553242188, 8996.280297576179, 10383.532741026673, 10392.636257730943, 10495.212859035602, 10908.454577271104, 21326.893365533382, 21588.89205553962, 21611.0, 21657.241996530047, 21723.891380542995, 6351.618902865843, 6445.484361251182, 7353.1911489736085, 7366.563520113634, 7456.217097204824, 4050.8987275317763, 4137.275976704125, 4154.646855017297, 4163.854265100767, 4165.895852603649, 3464.34177506273, 3556.751027428107, 3562.323158599858, 3579.6420357963993, 3611.656892595199, 641.3906788550895, 647.97732079377, 649.1463725201345, 656.671664167916, 657.2999755260632, 529.5075579710906, 657.1489920552896, 676.5230512488683, 681.3220845258999, 698.6735113546882, 8338.708145214889, 8914.0, 9008.369414140947, 9272.30163869112, 9635.759106022406, 664.4817042706696, 672.8048865828953, 675.9763408280686, 681.3731367142241, 682.4403988729194, 4155.314373128461, 4610.654200934943, 4616.815327386867, 4844.733539655353, 4860.149473842134, 95014.0, 96116.55825162628, 96641.516792417, 98142.50928745311, 100429.0, 83638.0, 85286.0, 85827.0, 85840.0, 87358.56320718482, 73934.6303268491, 74876.25123748802, 75002.62498687471, 75080.0, 75532.0, 9283.76790580241, 9320.300977426856, 9347.672831450867, 9369.858095912328, 9423.858642120236, 38129.0, 39676.60323396729, 51278.0, 51349.74325128351, 51625.96748065093, 65236.67381663061, 66059.66970165119, 67172.664136679, 68462.63074738577, 68655.65672171608, 11187.496562654864, 11266.324020558794, 11356.0, 11368.94315528417, 11883.346415947206, 12352.75294494123, 12505.187162834387, 12537.87462125367, 12556.623301300975, 13151.60545183646, 11459.770804583695, 11718.121140914294, 11735.706607334885, 11773.17587768848, 11865.169438139248, 9868.555914983699, 11781.705457363634, 11896.940515297369, 11931.164818462625, 12334.876651233373, 11497.712557186138, 11790.174687772116, 11920.867517585815, 11951.820722689341, 12207.389630518615, 7301.452391070585, 9489.620415183315, 9527.666531671359, 9646.565904534173, 9758.170555502862, 9431.0, 9546.904530954602, 9554.426734395958, 9573.090556397132, 9709.6116155353], "value": 7102.7149120202575, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1666.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 103.0, 177.0, 173.0, 163.0, 119.0, 118.0, 119.0, 123.0, 99.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 131.0, 103.0, 181.0, 146.0, 124.0, 116.0, 117.0, 140.0, 117.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 267.0, 274.0, 200.0, 188.0, 278.0, 248.0, 194.0, 195.0, 195.0], "value": 225.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 76.0, 86.0, 109.0, 55.0, 79.0, 53.0, 75.0, 92.0, 84.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 86.0, 72.0, 77.0, 104.0, 116.0, 79.0, 71.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 92.0, 134.0, 76.0, 135.0, 136.0, 90.0, 136.0, 128.0], "value": 119.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 156.0, 152.0, 98.0, 97.0, 99.0, 98.0, 122.0, 113.0], "value": 120.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 133.0, 92.0, 95.0, 128.0, 76.0, 121.0, 114.0, 78.0, 141.0], "value": 107.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 59.0, 61.0, 61.0, 53.0, 54.0, 65.0, 89.0, 74.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 74.0, 76.0, 86.0, 75.0, 71.0, 71.0, 75.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 81.0, 104.0, 109.0, 109.0, 81.0, 82.0, 114.0, 111.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 162.0, 147.0, 145.0, 143.0, 163.0, 148.0, 153.0, 209.0, 173.0], "value": 157.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 241.0, 188.0, 186.0, 177.0, 174.0, 162.0, 179.0, 178.0, 209.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 82.0, 105.0, 68.0, 66.0, 83.0, 83.0, 86.0, 83.0, 61.0], "value": 77.8, "unit": "score"}]}]} 17:48:47 INFO - Return code: 0 17:48:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:48:47 ERROR - # TBPL SUCCESS # 17:48:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:48:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:48:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:48:47 INFO - 2017-02-08 17:48:47.637 screenresolution[2000:19056] starting screenresolution argv=screenresolution get 17:48:47 INFO - 2017-02-08 17:48:47.640 screenresolution[2000:19056] Display 0: 1600x1200x32@60 17:48:47 INFO - 2017-02-08 17:48:47.646 screenresolution[2001:19059] starting screenresolution argv=screenresolution list 17:48:47 INFO - Available Modes on Display 0 17:48:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:48:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:48:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:48:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:48:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:48:47 INFO - Intel Iris: 17:48:47 INFO - Chipset Model: Intel Iris 17:48:47 INFO - Type: GPU 17:48:47 INFO - Bus: Built-In 17:48:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:48:47 INFO - Vendor: Intel (0x8086) 17:48:47 INFO - Device ID: 0x0a2e 17:48:47 INFO - Revision ID: 0x0009 17:48:47 INFO - Displays: 17:48:47 INFO - Display: 17:48:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:48:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:48:47 INFO - Display Serial Number: 1600x1200 60 17:48:47 INFO - Main Display: Yes 17:48:47 INFO - Mirror: Off 17:48:47 INFO - Online: Yes 17:48:47 INFO - Rotation: Supported 17:48:47 INFO - Return code: 0 17:48:47 INFO - Running post-action listener: _resource_record_post_action 17:48:47 INFO - [mozharness: 2017-02-09 01:48:47.885776Z] Finished run-tests step (success) 17:48:47 INFO - Running post-run listener: _resource_record_post_run 17:48:47 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 819200; Write bytes: 557809664; Read time: 11; Write time: 1798 17:48:47 INFO - TinderboxPrint: CPU usage
54.4% 17:48:47 INFO - TinderboxPrint: I/O read bytes / time
819,200 / 11 17:48:47 INFO - TinderboxPrint: I/O write bytes / time
557,809,664 / 1,798 17:48:47 INFO - TinderboxPrint: CPU idle
2,192.8 (45.6%) 17:48:47 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 17:48:47 INFO - TinderboxPrint: CPU user
1,650.8 (34.3%) 17:48:47 INFO - TinderboxPrint: Swap in / out
253,050,880 / 0 17:48:47 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 242133504; Write bytes: 235918848; Read time: 17834; Write time: 480 17:48:47 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 819200; Write bytes: 301394432; Read time: 11; Write time: 1269 17:48:48 INFO - Running post-run listener: _upload_blobber_files 17:48:48 INFO - Blob upload gear active. 17:48:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:48: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 17:48: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'] 17:48: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 17:48:48 INFO - (blobuploader) - INFO - Open directory for files ... 17:48:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:48:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:48:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:49 INFO - (blobuploader) - INFO - Done attempting. 17:48:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:48:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:48:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:51 INFO - (blobuploader) - INFO - Done attempting. 17:48:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:48:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:48:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:52 INFO - (blobuploader) - INFO - Done attempting. 17:48:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:48:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:48:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:55 INFO - (blobuploader) - INFO - Done attempting. 17:48:55 INFO - (blobuploader) - INFO - Iteration through files over. 17:48:55 INFO - Return code: 0 17:48:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:48:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:48:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b173050448513cb39b78249ac8831d12a9b3efe0fd3707edbb8836b1e2d753c036d45bda5ab1af8cd13b9b7218e28dc6b111208e26e8544a09e82f9e5a7e3c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db78da3dfbbab76b0a08dd5f4ee53b3f7b6a36c2713fd0f127771f1071c27c150e03f22b5aaea2676ad802e4d41ff65ff55cb474876c6bf4dc57ff642d70cf4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b9474974f34bc350d6da75bb53b8036501f1f50ea557e7f23a46dd3fd6d99f36835477d52fad30e8ae78b0d41cc4a4083ad0579fa9eb595c61843ad435754f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee34f5112294f5469e29c14af205ab1d7d8970fb9465ede7d5ee3aee46dc7481e04cfe3ffb87602f84cccccf208b115c0267af2ecb5873b3b55db752e63abff4"} 17:48:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:48:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:48:55 INFO - Contents: 17:48:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b173050448513cb39b78249ac8831d12a9b3efe0fd3707edbb8836b1e2d753c036d45bda5ab1af8cd13b9b7218e28dc6b111208e26e8544a09e82f9e5a7e3c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db78da3dfbbab76b0a08dd5f4ee53b3f7b6a36c2713fd0f127771f1071c27c150e03f22b5aaea2676ad802e4d41ff65ff55cb474876c6bf4dc57ff642d70cf4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b9474974f34bc350d6da75bb53b8036501f1f50ea557e7f23a46dd3fd6d99f36835477d52fad30e8ae78b0d41cc4a4083ad0579fa9eb595c61843ad435754f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee34f5112294f5469e29c14af205ab1d7d8970fb9465ede7d5ee3aee46dc7481e04cfe3ffb87602f84cccccf208b115c0267af2ecb5873b3b55db752e63abff4"} 17:48:55 INFO - Running post-run listener: copy_logs_to_upload_dir 17:48:55 INFO - Copying logs to upload dir... 17:48:55 INFO - mkdir: /builds/slave/test/build/upload/logs 17:48:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.024610 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-08 17:48:55.262221) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:48:55.265788) ========= 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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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/b173050448513cb39b78249ac8831d12a9b3efe0fd3707edbb8836b1e2d753c036d45bda5ab1af8cd13b9b7218e28dc6b111208e26e8544a09e82f9e5a7e3c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db78da3dfbbab76b0a08dd5f4ee53b3f7b6a36c2713fd0f127771f1071c27c150e03f22b5aaea2676ad802e4d41ff65ff55cb474876c6bf4dc57ff642d70cf4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b9474974f34bc350d6da75bb53b8036501f1f50ea557e7f23a46dd3fd6d99f36835477d52fad30e8ae78b0d41cc4a4083ad0579fa9eb595c61843ad435754f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee34f5112294f5469e29c14af205ab1d7d8970fb9465ede7d5ee3aee46dc7481e04cfe3ffb87602f84cccccf208b115c0267af2ecb5873b3b55db752e63abff4"} build_url:https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011051 build_url: 'https://queue.taskcluster.net/v1/task/A86S5OApR0GkSKaMweJNtQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b173050448513cb39b78249ac8831d12a9b3efe0fd3707edbb8836b1e2d753c036d45bda5ab1af8cd13b9b7218e28dc6b111208e26e8544a09e82f9e5a7e3c02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db78da3dfbbab76b0a08dd5f4ee53b3f7b6a36c2713fd0f127771f1071c27c150e03f22b5aaea2676ad802e4d41ff65ff55cb474876c6bf4dc57ff642d70cf4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b9474974f34bc350d6da75bb53b8036501f1f50ea557e7f23a46dd3fd6d99f36835477d52fad30e8ae78b0d41cc4a4083ad0579fa9eb595c61843ad435754f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee34f5112294f5469e29c14af205ab1d7d8970fb9465ede7d5ee3aee46dc7481e04cfe3ffb87602f84cccccf208b115c0267af2ecb5873b3b55db752e63abff4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:48:55.300226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:48:55.300578) ========= 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.ZHAJ9EGbd8/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.q0MpsSAi0R/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005334 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:48:55.348969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:48:55.349282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:48:55.349613) ========= ========= Total master_lag: 0.79 =========