builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0215 starttime: 1491435904.38 results: success (0) buildid: 20170405114721 builduid: f7666c6f15404094bd04d4930faa51e6 revision: 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.377040) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.377472) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.377746) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.404287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.404601) ========= 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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-04-05 16:45:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-04-05 16:45:04 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118210 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.546713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.547031) ========= 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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.027351 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:04.749899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 16:45:04.750264) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-04-05 16:45:04,947 truncating revision to first 12 chars 2017-04-05 16:45:04,947 Setting DEBUG logging. 2017-04-05 16:45:04,947 attempt 1/10 2017-04-05 16:45:04,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/46c85c0e2aba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 16:45:05,715 unpacking tar archive at: pine-46c85c0e2aba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.031824 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 16:45:05.909105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:05.909472) ========= script_repo_revision: 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:05.909886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:05.910157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 16:45:05.930203) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-05 16:45:05.930616) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:45:06 INFO - MultiFileLogger online at 20170405 16:45:06 in /builds/slave/test 16:45:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:45:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:45:06 INFO - {'append_to_log': False, 16:45:06 INFO - 'base_work_dir': '/builds/slave/test', 16:45:06 INFO - 'blob_upload_branch': 'Pine', 16:45:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:45:06 INFO - 'branch': 'Pine', 16:45:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:45:06 INFO - 'config_files': ('talos/mac_config.py',), 16:45:06 INFO - 'default_actions': ('clobber', 16:45:06 INFO - 'read-buildbot-config', 16:45:06 INFO - 'download-and-extract', 16:45:06 INFO - 'populate-webroot', 16:45:06 INFO - 'create-virtualenv', 16:45:06 INFO - 'install', 16:45:06 INFO - 'run-tests'), 16:45:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:45:06 INFO - 'download_minidump_stackwalk': True, 16:45:06 INFO - 'download_symbols': 'ondemand', 16:45:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:45:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:45:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:45:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:45:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:45:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:45:06 INFO - 'gecko_profile': False, 16:45:06 INFO - 'gecko_profile_interval': 0, 16:45:06 INFO - 'installer_path': 'installer.exe', 16:45:06 INFO - 'log_level': 'info', 16:45:06 INFO - 'log_name': 'talos', 16:45:06 INFO - 'log_to_console': True, 16:45:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:45:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:45:06 INFO - 'opt_config_files': (), 16:45:06 INFO - 'pip_index': False, 16:45:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:45:06 INFO - 'cmd': ('bash', 16:45:06 INFO - '-c', 16:45:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:45:06 INFO - 'enabled': True, 16:45:06 INFO - 'halt_on_failure': False, 16:45:06 INFO - 'name': 'check_screen_resolution'},), 16:45:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:45:06 INFO - 'cmd': ('bash', 16:45:06 INFO - '-c', 16:45:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:45:06 INFO - 'enabled': True, 16:45:06 INFO - 'halt_on_failure': False, 16:45:06 INFO - 'name': 'check_screen_resolution'},), 16:45:06 INFO - 'run_cmd_checks_enabled': True, 16:45:06 INFO - 'suite': 'dromaeojs-e10s', 16:45:06 INFO - 'system_bits': '32', 16:45:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:45:06 INFO - 'title': 't-yosemite-r7-0215', 16:45:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:45:06 INFO - 'use_talos_json': True, 16:45:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:45:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:45:06 INFO - 'work_dir': 'build'} 16:45:06 INFO - [mozharness: 2017-04-05 23:45:06.128414Z] Running clobber step. 16:45:06 INFO - Running pre-action listener: _resource_record_pre_action 16:45:06 INFO - Running main action method: clobber 16:45:06 INFO - rmtree: /builds/slave/test/build 16:45:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:45:08 INFO - Running post-action listener: _resource_record_post_action 16:45:08 INFO - [mozharness: 2017-04-05 23:45:08.620997Z] Finished clobber step (success) 16:45:08 INFO - [mozharness: 2017-04-05 23:45:08.621131Z] Running read-buildbot-config step. 16:45:08 INFO - Running pre-action listener: _resource_record_pre_action 16:45:08 INFO - Running main action method: read_buildbot_config 16:45:08 INFO - Using buildbot properties: 16:45:08 INFO - { 16:45:08 INFO - "project": "", 16:45:08 INFO - "product": "firefox", 16:45:08 INFO - "script_repo_revision": "default", 16:45:08 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:45:08 INFO - "repository": "", 16:45:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:45:08 INFO - "buildid": "20170405114721", 16:45:08 INFO - "pgo_build": "False", 16:45:08 INFO - "basedir": "/builds/slave/test", 16:45:08 INFO - "buildnumber": 4, 16:45:08 INFO - "slavename": "t-yosemite-r7-0215", 16:45:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:45:08 INFO - "platform": "yosemite_r7", 16:45:08 INFO - "scheduler": "tests-pine-macosx64-talos", 16:45:08 INFO - "branch": "pine", 16:45:08 INFO - "repo_path": "projects/pine", 16:45:08 INFO - "revision": "46c85c0e2aba7ef183146e9b5200bd8c2039f2ab", 16:45:08 INFO - "stage_platform": "macosx64", 16:45:08 INFO - "builduid": "f7666c6f15404094bd04d4930faa51e6", 16:45:08 INFO - "slavebuilddir": "test" 16:45:08 INFO - } 16:45:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:45:08 INFO - Running post-action listener: _resource_record_post_action 16:45:08 INFO - [mozharness: 2017-04-05 23:45:08.624590Z] Finished read-buildbot-config step (success) 16:45:08 INFO - [mozharness: 2017-04-05 23:45:08.624722Z] Running download-and-extract step. 16:45:08 INFO - Running pre-action listener: _resource_record_pre_action 16:45:08 INFO - Running main action method: download_and_extract 16:45:08 INFO - mkdir: /builds/slave/test/build/tests 16:45:08 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')]} 16:45:08 INFO - trying https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:45:08 INFO - Downloading https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:45:09 INFO - Downloaded 1325 bytes. 16:45:09 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:45:09 INFO - Using the following test package requirements: 16:45:09 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:45:09 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:45:09 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:45:09 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:45:09 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'jsshell-mac.zip'], 16:45:09 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:45:09 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:45:09 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:45:09 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:45:09 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:45:09 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:45:09 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:45:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:45:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:45:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:45:09 INFO - Fetch https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:45:11 INFO - Expected file size: 52520658 16:45:11 INFO - Obtained file size: 52520658 16:45:27 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:45:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:45:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:45:27 INFO - Fetch https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:45:29 INFO - Expected file size: 52520658 16:45:29 INFO - Obtained file size: 52520658 16:45:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:45:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:45:45 INFO - Fetch https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:45:46 INFO - Expected file size: 14404152 16:45:46 INFO - Obtained file size: 14404152 16:45:46 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')]} 16:45:46 INFO - trying https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:45:46 INFO - Downloading https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:45:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:45:50 INFO - Downloaded 70989880 bytes. 16:45:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:45:50 INFO - mkdir: /builds/slave/test/properties 16:45:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:45:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:45:50 INFO - Contents: 16:45:50 INFO - build_url:https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:45:50 INFO - Running post-action listener: _resource_record_post_action 16:45:50 INFO - Running post-action listener: set_extra_try_arguments 16:45:50 INFO - [mozharness: 2017-04-05 23:45:50.775568Z] Finished download-and-extract step (success) 16:45:50 INFO - [mozharness: 2017-04-05 23:45:50.775697Z] Running populate-webroot step. 16:45:50 INFO - Running pre-action listener: _resource_record_pre_action 16:45:50 INFO - Running main action method: populate_webroot 16:45:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:45:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:45:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:45:50 INFO - '--tppagecycles', 16:45:50 INFO - '7'], 16:45:50 INFO - 'tests': ['tsvgm']}}, 16:45:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:45:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:45:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:45:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:45:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['damp', 'tps']}, 16:45:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'tests': ['damp', 'tps']}, 16:45:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['dromaeo_dom']}, 16:45:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:45:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['basic_compositor_video']}, 16:45:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:45:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'other-e10s': {'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['a11yr', 16:45:50 INFO - 'ts_paint', 16:45:50 INFO - 'tpaint', 16:45:50 INFO - 'sessionrestore', 16:45:50 INFO - 'sessionrestore_no_auto_restore', 16:45:50 INFO - 'tabpaint']}, 16:45:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['tsvgx', 16:45:50 INFO - 'tsvgr_opacity', 16:45:50 INFO - 'tart', 16:45:50 INFO - 'tscrollx', 16:45:50 INFO - 'cart', 16:45:50 INFO - 'tsvg_static']}, 16:45:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:45:50 INFO - 'tsvgr_opacity', 16:45:50 INFO - 'tart', 16:45:50 INFO - 'tscrollx', 16:45:50 INFO - 'cart', 16:45:50 INFO - 'tsvg_static']}, 16:45:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'talos_options': ['--disable-e10s'], 16:45:50 INFO - 'tests': ['tp5o']}, 16:45:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:45:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'talos_options': ['--disable-e10s', 16:45:50 INFO - '--xperf_path', 16:45:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:50 INFO - 'tests': ['tp5n']}, 16:45:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:45:50 INFO - 'talos_options': ['--xperf_path', 16:45:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:50 INFO - 'tests': ['tp5n']}}, 16:45:50 INFO - 'talos.zip': {'path': '', 16:45:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:45:50 INFO - Running post-action listener: _resource_record_post_action 16:45:50 INFO - [mozharness: 2017-04-05 23:45:50.793970Z] Finished populate-webroot step (success) 16:45:50 INFO - [mozharness: 2017-04-05 23:45:50.794101Z] Running create-virtualenv step. 16:45:50 INFO - Running pre-action listener: _resource_record_pre_action 16:45:50 INFO - Running main action method: create_virtualenv 16:45:50 INFO - Creating virtualenv /builds/slave/test/build/venv 16:45:50 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 16:45:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:45:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:45:50 INFO - Using real prefix '/tools/python27' 16:45:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:45:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:45:54 INFO - Installing pip.................done. 16:45:54 INFO - Return code: 0 16:45:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:45:54 INFO - Reading from file tmpfile_stdout 16:45:54 INFO - Output received: 16:45:54 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) 16:45:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:54 INFO - 'HOME': '/Users/cltbld', 16:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:54 INFO - 'LOGNAME': 'cltbld', 16:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:54 INFO - 'MOZ_NO_REMOTE': '1', 16:45:54 INFO - 'NO_EM_RESTART': '1', 16:45:54 INFO - 'PAGER': '/bin/cat', 16:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:54 INFO - 'PWD': '/builds/slave/test', 16:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:54 INFO - 'SHELL': '/bin/bash', 16:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:45:54 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:54 INFO - 'USER': 'cltbld', 16:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:54 INFO - 'XPC_FLAGS': '0x0', 16:45:54 INFO - 'XPC_SERVICE_NAME': '0', 16:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:55 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 16:45:55 INFO - Cleaning up... 16:45:55 INFO - Return code: 0 16:45:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:45:55 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')]} 16:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:55 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')]} 16:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:55 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:45:55 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 16:45:55 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 16:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:55 INFO - 'HOME': '/Users/cltbld', 16:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:55 INFO - 'LOGNAME': 'cltbld', 16:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:55 INFO - 'MOZ_NO_REMOTE': '1', 16:45:55 INFO - 'NO_EM_RESTART': '1', 16:45:55 INFO - 'PAGER': '/bin/cat', 16:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:55 INFO - 'PWD': '/builds/slave/test', 16:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:55 INFO - 'SHELL': '/bin/bash', 16:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:45:55 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:55 INFO - 'USER': 'cltbld', 16:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:55 INFO - 'XPC_FLAGS': '0x0', 16:45:55 INFO - 'XPC_SERVICE_NAME': '0', 16:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:55 INFO - Downloading/unpacking psutil>=3.1.1 16:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:58 INFO - Installing collected packages: psutil 16:45:58 INFO - Running setup.py install for psutil 16:45:58 INFO - building 'psutil._psutil_osx' extension 16:45:58 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 16:45:59 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 16:45:59 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 16:45:59 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 16:45:59 INFO - building 'psutil._psutil_posix' extension 16:45:59 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 16:45:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:59 INFO - ^ 16:45:59 INFO - 1 warning generated. 16:45:59 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 16:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:59 INFO - Successfully installed psutil 16:45:59 INFO - Cleaning up... 16:45:59 INFO - Return code: 0 16:45:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:45:59 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')]} 16:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:00 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')]} 16:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:00 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:00 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 16:46:00 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 16:46:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:00 INFO - 'HOME': '/Users/cltbld', 16:46:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:00 INFO - 'LOGNAME': 'cltbld', 16:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:00 INFO - 'MOZ_NO_REMOTE': '1', 16:46:00 INFO - 'NO_EM_RESTART': '1', 16:46:00 INFO - 'PAGER': '/bin/cat', 16:46:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:00 INFO - 'PWD': '/builds/slave/test', 16:46:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:00 INFO - 'SHELL': '/bin/bash', 16:46:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:00 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:00 INFO - 'USER': 'cltbld', 16:46:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:00 INFO - 'XPC_FLAGS': '0x0', 16:46:00 INFO - 'XPC_SERVICE_NAME': '0', 16:46:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:46:01 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 16:46:01 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 16:46:01 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 16:46:01 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 16:46:01 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 16:46:01 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 16:46:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:46:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:46:04 INFO - Installing collected packages: mozsystemmonitor 16:46:04 INFO - Running setup.py install for mozsystemmonitor 16:46:04 INFO - Successfully installed mozsystemmonitor 16:46:04 INFO - Cleaning up... 16:46:04 INFO - Return code: 0 16:46:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:04 INFO - 'HOME': '/Users/cltbld', 16:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:04 INFO - 'LOGNAME': 'cltbld', 16:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:04 INFO - 'MOZ_NO_REMOTE': '1', 16:46:04 INFO - 'NO_EM_RESTART': '1', 16:46:04 INFO - 'PAGER': '/bin/cat', 16:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:04 INFO - 'PWD': '/builds/slave/test', 16:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:04 INFO - 'SHELL': '/bin/bash', 16:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:04 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:04 INFO - 'USER': 'cltbld', 16:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:04 INFO - 'XPC_FLAGS': '0x0', 16:46:04 INFO - 'XPC_SERVICE_NAME': '0', 16:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:46:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:46:07 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 16:46:07 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 16:46:07 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 16:46:07 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 16:46:07 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 16:46:07 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 16:46:08 INFO - Downloading functools32-3.2.3-2.tar.gz 16:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:46:08 INFO - warning: no files found matching '*.txt' 16:46:08 INFO - no previously-included directories found matching 'build' 16:46:08 INFO - no previously-included directories found matching 'dist' 16:46:08 INFO - no previously-included directories found matching '.git*' 16:46:08 INFO - Installing collected packages: jsonschema, functools32 16:46:08 INFO - Running setup.py install for functools32 16:46:08 INFO - warning: no files found matching '*.txt' 16:46:08 INFO - no previously-included directories found matching 'build' 16:46:08 INFO - no previously-included directories found matching 'dist' 16:46:08 INFO - no previously-included directories found matching '.git*' 16:46:08 INFO - Successfully installed jsonschema functools32 16:46:08 INFO - Cleaning up... 16:46:08 INFO - Return code: 0 16:46:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:46:08 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')]} 16:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:08 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')]} 16:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:08 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:08 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 16:46:08 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 16:46:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:08 INFO - 'HOME': '/Users/cltbld', 16:46:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:08 INFO - 'LOGNAME': 'cltbld', 16:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:08 INFO - 'MOZ_NO_REMOTE': '1', 16:46:08 INFO - 'NO_EM_RESTART': '1', 16:46:08 INFO - 'PAGER': '/bin/cat', 16:46:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:08 INFO - 'PWD': '/builds/slave/test', 16:46:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:08 INFO - 'SHELL': '/bin/bash', 16:46:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:08 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:08 INFO - 'USER': 'cltbld', 16:46:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:08 INFO - 'XPC_FLAGS': '0x0', 16:46:08 INFO - 'XPC_SERVICE_NAME': '0', 16:46:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:46:08 INFO - Cleaning up... 16:46:08 INFO - Return code: 0 16:46:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:46:08 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')]} 16:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:08 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')]} 16:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:08 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:08 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 16:46:08 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 16:46:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:08 INFO - 'HOME': '/Users/cltbld', 16:46:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:08 INFO - 'LOGNAME': 'cltbld', 16:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:08 INFO - 'MOZ_NO_REMOTE': '1', 16:46:08 INFO - 'NO_EM_RESTART': '1', 16:46:08 INFO - 'PAGER': '/bin/cat', 16:46:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:08 INFO - 'PWD': '/builds/slave/test', 16:46:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:08 INFO - 'SHELL': '/bin/bash', 16:46:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:08 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:08 INFO - 'USER': 'cltbld', 16:46:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:08 INFO - 'XPC_FLAGS': '0x0', 16:46:08 INFO - 'XPC_SERVICE_NAME': '0', 16:46:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:11 INFO - Downloading blobuploader-1.2.4.tar.gz 16:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:46:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:46:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:12 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 16:46:13 INFO - Downloading docopt-0.6.1.tar.gz 16:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:46:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:46:13 INFO - Running setup.py install for blobuploader 16:46:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:46:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:46:13 INFO - Running setup.py install for requests 16:46:13 INFO - Running setup.py install for docopt 16:46:14 INFO - Successfully installed blobuploader requests docopt 16:46:14 INFO - Cleaning up... 16:46:14 INFO - Return code: 0 16:46:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46:14 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')]} 16:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:14 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')]} 16:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:14 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:14 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 16:46:14 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 16:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:14 INFO - 'HOME': '/Users/cltbld', 16:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:14 INFO - 'LOGNAME': 'cltbld', 16:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:14 INFO - 'MOZ_NO_REMOTE': '1', 16:46:14 INFO - 'NO_EM_RESTART': '1', 16:46:14 INFO - 'PAGER': '/bin/cat', 16:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:14 INFO - 'PWD': '/builds/slave/test', 16:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:14 INFO - 'SHELL': '/bin/bash', 16:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:14 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:14 INFO - 'USER': 'cltbld', 16:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:14 INFO - 'XPC_FLAGS': '0x0', 16:46:14 INFO - 'XPC_SERVICE_NAME': '0', 16:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:46:14 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-GyD1aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:46:14 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-g4UOBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:46:14 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Hhy1lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:46:14 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-EDaFkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:46:14 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-TAebNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-IsDotV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-LwdWcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-jAHFrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-q9bHi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-mRHy4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-bxAFiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-e05r5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-e9zdAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4gcbto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4Pm1lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:46:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-BGhlBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:46:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-pZRnqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:46:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:46:16 INFO - Running setup.py install for manifestparser 16:46:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:46:16 INFO - Running setup.py install for mozcrash 16:46:16 INFO - Running setup.py install for mozdebug 16:46:16 INFO - Running setup.py install for mozdevice 16:46:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:46:16 INFO - Running setup.py install for mozfile 16:46:16 INFO - Running setup.py install for mozhttpd 16:46:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Running setup.py install for mozinfo 16:46:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Running setup.py install for mozInstall 16:46:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Running setup.py install for mozleak 16:46:17 INFO - Running setup.py install for mozlog 16:46:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Running setup.py install for moznetwork 16:46:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:46:17 INFO - Running setup.py install for mozprocess 16:46:18 INFO - Running setup.py install for mozprofile 16:46:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:46:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:46:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:46:18 INFO - Running setup.py install for mozrunner 16:46:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:46:18 INFO - Running setup.py install for mozscreenshot 16:46:18 INFO - Running setup.py install for moztest 16:46:18 INFO - Running setup.py install for mozversion 16:46:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:46:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:46:18 INFO - Cleaning up... 16:46:18 INFO - Return code: 0 16:46:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:18 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:18 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 16:46:18 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 16:46:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:18 INFO - 'HOME': '/Users/cltbld', 16:46:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:18 INFO - 'LOGNAME': 'cltbld', 16:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:18 INFO - 'MOZ_NO_REMOTE': '1', 16:46:18 INFO - 'NO_EM_RESTART': '1', 16:46:18 INFO - 'PAGER': '/bin/cat', 16:46:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:18 INFO - 'PWD': '/builds/slave/test', 16:46:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:18 INFO - 'SHELL': '/bin/bash', 16:46:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:18 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:18 INFO - 'USER': 'cltbld', 16:46:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:18 INFO - 'XPC_FLAGS': '0x0', 16:46:18 INFO - 'XPC_SERVICE_NAME': '0', 16:46:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-KqDrUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-0ynSdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-O6I4YV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-cAPkHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ZoTrpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-LjbiVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-aG7kAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-_Mk4dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:46:19 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)) 16:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:46:19 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-XYc5zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-HymzUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-aY0RXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-m6SYoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-O6dZqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-wYUZnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Jx1t_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-44PDb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:46:20 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)) 16:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:46:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-xmlwpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:46:20 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)) 16:46:20 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)) 16:46:20 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)) 16:46:20 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)) 16:46:20 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)) 16:46:20 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)) 16:46:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:23 INFO - Downloading blessings-1.6.tar.gz 16:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:46:23 INFO - Installing collected packages: blessings 16:46:23 INFO - Running setup.py install for blessings 16:46:23 INFO - Successfully installed blessings 16:46:23 INFO - Cleaning up... 16:46:23 INFO - Return code: 0 16:46:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:46:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:46:24 INFO - Reading from file tmpfile_stdout 16:46:24 INFO - Current package versions: 16:46:24 INFO - blessings == 1.6 16:46:24 INFO - blobuploader == 1.2.4 16:46:24 INFO - docopt == 0.6.1 16:46:24 INFO - functools32 == 3.2.3-2 16:46:24 INFO - jsonschema == 2.5.1 16:46:24 INFO - manifestparser == 1.1 16:46:24 INFO - mozInstall == 1.12 16:46:24 INFO - mozcrash == 1.0 16:46:24 INFO - mozdebug == 0.1 16:46:24 INFO - mozdevice == 0.49 16:46:24 INFO - mozfile == 1.2 16:46:24 INFO - mozhttpd == 0.7 16:46:24 INFO - mozinfo == 0.9 16:46:24 INFO - mozleak == 0.1 16:46:24 INFO - mozlog == 3.4 16:46:24 INFO - moznetwork == 0.27 16:46:24 INFO - mozprocess == 0.25 16:46:24 INFO - mozprofile == 0.28 16:46:24 INFO - mozrunner == 6.13 16:46:24 INFO - mozscreenshot == 0.1 16:46:24 INFO - mozsystemmonitor == 0.3 16:46:24 INFO - moztest == 0.8 16:46:24 INFO - mozversion == 1.4 16:46:24 INFO - psutil == 3.1.1 16:46:24 INFO - requests == 1.2.3 16:46:24 INFO - wsgiref == 0.1.2 16:46:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46: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')]} 16:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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')]} 16:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:24 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 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46:24 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 16:46:24 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 16:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:24 INFO - 'HOME': '/Users/cltbld', 16:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:24 INFO - 'LOGNAME': 'cltbld', 16:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:24 INFO - 'MOZ_NO_REMOTE': '1', 16:46:24 INFO - 'NO_EM_RESTART': '1', 16:46:24 INFO - 'PAGER': '/bin/cat', 16:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:24 INFO - 'PWD': '/builds/slave/test', 16:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:24 INFO - 'SHELL': '/bin/bash', 16:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:24 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:24 INFO - 'USER': 'cltbld', 16:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:24 INFO - 'XPC_FLAGS': '0x0', 16:46:24 INFO - 'XPC_SERVICE_NAME': '0', 16:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 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)) 16:46:24 INFO - Cleaning up... 16:46:24 INFO - Return code: 0 16:46:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:46: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')]} 16:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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')]} 16:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c405b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c408768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb392d8ad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, '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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:46: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'] in /builds/slave/test/build 16:46: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 16:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:24 INFO - 'HOME': '/Users/cltbld', 16:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:24 INFO - 'LOGNAME': 'cltbld', 16:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:24 INFO - 'MOZ_NO_REMOTE': '1', 16:46:24 INFO - 'NO_EM_RESTART': '1', 16:46:24 INFO - 'PAGER': '/bin/cat', 16:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:24 INFO - 'PWD': '/builds/slave/test', 16:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:24 INFO - 'SHELL': '/bin/bash', 16:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:24 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:24 INFO - 'USER': 'cltbld', 16:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:24 INFO - 'XPC_FLAGS': '0x0', 16:46:24 INFO - 'XPC_SERVICE_NAME': '0', 16:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:46:24 INFO - Cleaning up... 16:46:24 INFO - Return code: 0 16:46:24 INFO - Running post-action listener: _resource_record_post_action 16:46:24 INFO - Running post-action listener: _start_resource_monitoring 16:46:24 INFO - Starting resource monitoring. 16:46:24 INFO - [mozharness: 2017-04-05 23:46:24.752773Z] Finished create-virtualenv step (success) 16:46:24 INFO - [mozharness: 2017-04-05 23:46:24.753708Z] Running install step. 16:46:24 INFO - Running pre-action listener: _resource_record_pre_action 16:46:24 INFO - Running main action method: install 16:46:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:46:25 INFO - Reading from file tmpfile_stdout 16:46:25 INFO - Detecting whether we're running mozinstall >=1.0... 16:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:46:25 INFO - Reading from file tmpfile_stdout 16:46:25 INFO - Output received: 16:46:25 INFO - Usage: mozinstall [options] installer 16:46:25 INFO - Options: 16:46:25 INFO - -h, --help show this help message and exit 16:46:25 INFO - -d DEST, --destination=DEST 16:46:25 INFO - Directory to install application into. [default: 16:46:25 INFO - "/builds/slave/test"] 16:46:25 INFO - --app=APP Application being installed. [default: firefox] 16:46:25 INFO - mkdir: /builds/slave/test/build/application 16:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:46:44 INFO - Reading from file tmpfile_stdout 16:46:44 INFO - Output received: 16:46:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:46:44 INFO - Running post-action listener: _resource_record_post_action 16:46:44 INFO - [mozharness: 2017-04-05 23:46:44.789992Z] Finished install step (success) 16:46:44 INFO - [mozharness: 2017-04-05 23:46:44.790143Z] Running run-tests step. 16:46:44 INFO - Running pre-action listener: _resource_record_pre_action 16:46:44 INFO - Running main action method: run_tests 16:46:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:46:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:46:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:46:44 INFO - 2017-04-05 16:46:44.804 screenresolution[1620:7408] starting screenresolution argv=screenresolution get 16:46:44 INFO - 2017-04-05 16:46:44.807 screenresolution[1620:7408] Display 0: 1600x1200x32@60 16:46:44 INFO - 2017-04-05 16:46:44.813 screenresolution[1621:7410] starting screenresolution argv=screenresolution list 16:46:44 INFO - Available Modes on Display 0 16:46:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:46:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:46:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:46:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:46:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:46:45 INFO - Intel Iris: 16:46:45 INFO - Chipset Model: Intel Iris 16:46:45 INFO - Type: GPU 16:46:45 INFO - Bus: Built-In 16:46:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:46:45 INFO - Vendor: Intel (0x8086) 16:46:45 INFO - Device ID: 0x0a2e 16:46:45 INFO - Revision ID: 0x0009 16:46:45 INFO - Displays: 16:46:45 INFO - Display: 16:46:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:46:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:46:45 INFO - Display Serial Number: 1600x1200 60 16:46:45 INFO - Main Display: Yes 16:46:45 INFO - Mirror: Off 16:46:45 INFO - Online: Yes 16:46:45 INFO - Rotation: Supported 16:46:45 INFO - Return code: 0 16:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:46:45 INFO - Python 2.7.3 16:46:45 INFO - Return code: 0 16:46:45 INFO - grabbing minidump binary from tooltool 16:46:45 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')]} 16:46:45 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 0x10c1e3d30>, '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 0x10c10adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:46:45 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 16:46:45 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 16:46:45 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 16:46:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:46:45 INFO - Return code: 0 16:46:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:46:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:46:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:46:45 INFO - ENV: RUST_BACKTRACE is now 1 16:46:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:46:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 16:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --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 16:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMCrxhzcNH/Render', 16:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:45 INFO - 'HOME': '/Users/cltbld', 16:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:45 INFO - 'LOGNAME': 'cltbld', 16:46:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:46:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:45 INFO - 'MOZ_NO_REMOTE': '1', 16:46:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:46:45 INFO - 'NO_EM_RESTART': '1', 16:46:45 INFO - 'PAGER': '/bin/cat', 16:46:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:45 INFO - 'PWD': '/builds/slave/test', 16:46:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:45 INFO - 'RUST_BACKTRACE': '1', 16:46:45 INFO - 'SHELL': '/bin/bash', 16:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6FJB8Rfif/Listeners', 16:46:45 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:45 INFO - 'USER': 'cltbld', 16:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:45 INFO - 'XPC_FLAGS': '0x0', 16:46:45 INFO - 'XPC_SERVICE_NAME': '0', 16:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 16:46:45 INFO - mozversion application_buildid: 20170405114721 16:46:45 INFO - mozversion application_changeset: 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab 16:46:45 INFO - mozversion application_display_name: Nightly 16:46:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:46:45 INFO - mozversion application_name: Firefox 16:46:45 INFO - mozversion application_remotingname: firefox 16:46:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:46:45 INFO - mozversion application_vendor: Mozilla 16:46:45 INFO - mozversion application_version: 55.0a1 16:46:45 INFO - mozversion platform_buildid: 20170405114721 16:46:45 INFO - mozversion platform_changeset: 46c85c0e2aba7ef183146e9b5200bd8c2039f2ab 16:46:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:46:45 INFO - mozversion platform_version: 55.0a1 16:46:45 INFO - using testdate: 1491436005 16:46:45 INFO - actual date: 1491436005 16:46:45 INFO - starting webserver on 'localhost:49241' 16:46:45 INFO - SUITE-START | Running 2 tests 16:46:45 INFO - TEST-START | dromaeo_css 16:46:45 INFO - operating with platform_type : mac_ 16:46:45 INFO - Initialising browser for dromaeo_css test... 16:46:45 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmp44viPu/profile http://localhost:49241/getInfo.html) 16:46:48 INFO - PID 1631 | 2017-04-05 16:46:48.040 plugin-container[1633:7588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 16:46:48 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:48 INFO - PID 1631 | 2017-04-05 16:46:48.044 plugin-container[1633:7588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1b, name = 'com.apple.CFPasteboardClient' 16:46:48 INFO - PID 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:48 INFO - PID 1631 | 2017-04-05 16:46:48.044 plugin-container[1633:7588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:48 INFO - PID 1631 | __metrics Screen width/height:1600/1200 16:46:48 INFO - PID 1631 | colorDepth:24 16:46:48 INFO - PID 1631 | Browser inner width/height: 1024/689 16:46:48 INFO - PID 1631 | __metrics 16:46:48 ERROR - PID 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:46:49 INFO - TEST-INFO | 1631: exit 0 16:46:49 INFO - Browser initialized. 16:46:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:46:49 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmp44viPu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:52 INFO - PID 1634 | 2017-04-05 16:46:52.221 plugin-container[1638:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 16:46:52 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:52 INFO - PID 1634 | 2017-04-05 16:46:52.222 plugin-container[1638:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:46:52 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:52 INFO - PID 1634 | 2017-04-05 16:46:52.222 plugin-container[1638:7782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:47:01 INFO - PID 1634 | RSS: Main: 250679296 16:47:01 INFO - PID 1634 | 16:50:06 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 16:50:08 INFO - PID 1634 | RSS: Main: 259547136 16:50:08 INFO - PID 1634 | 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 16:51:49 INFO - PID 1634 | 16:51:49 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:51:49 INFO - PID 1634 | Full stack: 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 16:51:49 INFO - PID 1634 | 16:51:49 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:51:49 INFO - PID 1634 | Full stack: 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 16:51:49 INFO - PID 1634 | 16:51:49 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:51:49 INFO - PID 1634 | Full stack: 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | ************************* 16:51:49 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 16:51:49 INFO - PID 1634 | 16:51:49 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:51:49 INFO - PID 1634 | Full stack: 16:51:49 INFO - PID 1634 | ************************* 16:53:10 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 16:53:10 INFO - PID 1634 | RSS: Main: 264081408 16:53:10 INFO - PID 1634 | 16:56:12 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 16:56:13 INFO - PID 1634 | RSS: Main: 275169280 16:56:13 INFO - PID 1634 | 16:59:14 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 16:59:15 INFO - PID 1634 | RSS: Main: 267538432 16:59:15 INFO - PID 1634 | 17:02:18 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 17:02:19 INFO - PID 1634 | RSS: Main: 277884928 17:02:19 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 17:05:21 INFO - PID 1634 | RSS: Main: 271613952 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | __start_tp_report 17:05:21 INFO - PID 1634 | _x_x_mozilla_page_load 17:05:21 INFO - PID 1634 | _x_x_mozilla_page_load_details 17:05:21 INFO - PID 1634 | |i|pagename|runs| 17:05:21 INFO - PID 1634 | |0;dojo.html;2990.4019196160775;3065.6934306569337;3570.8928732138047;3630.5824830144534;3652.689521390682;6208.78269260576;6262.154609127766;6272.215973003369;6273.874522509553;6322.557420980534;6234.345393733661;6257.843553911154;6263.561550691454;6343.111964324999;6364.45902098321;5551.361593416748;5554.694491802959;5597.832065038035;5606.327240731118;5630.296212973379;5431.185322201682;5552.555795536347;5615.775368985256;5648.519875810561;5665.433456654322;5437.673739575638;5511.862203444946;5557.277553917985;5562.833115006556;5568.415316391761;5482.23248745176;5488.725563721838;5502.752376143083;5509.724513774336;5556.416576259516;5427.674339539601;5467.20725494805;5489.286392768914;5502.339719233678;5567.081431563787;6056.545759068087;6161.938380616181;6221.688915554249;6242.15730876335;6263.624182549016;6735.191776986744;6736.124303840469;6747.561408508432;6754.831129221809;6761;5651.5761317900915;6807.795766127024;6820.522563420563;6825.283345248777;6829.214640316328;6228.564000520011;6261.655608941461;6324.08300796382;6351.555391122623;6375.744970201233;6975.407090397373;6985.93014069863;7023.473239507058;7036.296370363025;7039.859202815915;6372.522060845455;6412.775552855627;6519.967400163064;6570.77002304917;6626.436752875963;6716.126903502611;6736.5958042517605;6796.25241223465;6821.931780682229;6832.795016149524;6433.678316084177;6525.673716314165;6629.602223866584;6659.334066593399;6736.427403670645;5960;6743.527953043338;6743.797686069426;6758.189017317954;6773.8983915241315;5412.025835349675;5415;5425.891482170414;5426.782928682887;5462.535684466785;4769.165396055676;4785.712857228507;4795.040991801654;4832.395950506187;4869.391326084239;6191.349908259658;6362.013887847391;6385.616862988236;6420.967895160495;6434.871302573923;6825.590464572141;6845;6846.383825455733;6848.14398200225;6865.931340686529;6075.936711075632;6298.527610429236;6349.9682501587195;6402.263739669905;6423.839404014843;5118.232265160255;6029.065494848305;6034.125051867542;6064.332923378425;6080.300765411946;7019.964900175569;7072.151341838807;7073;7084.822879428056;7131.536450130725;6576.572522786004;6753.932460675331;6849.760258390932;6853.040574319699;6878.621675807678;6866.828329291808;6922;7057.752978645723;7072.398846098139;7084.2915708428745;6703.363180497845;6707.8323041922395;6711.597304161766;6718.8320291993095;6719.361660642231;4913.28757330178;6055.879662262496;6534.411902928759;6623.105880706043;6838.8290292741085;6516.218053833571;6590.044443555754;6602.900956485752;6616.305287944889;6627.536072474881;6353.14232578596;6398.104265402754;6415.615063096229;6544.8363790905605;6638.933610663831;6220.346863579259;6432.388923052303;6450.903236451551;6451.032345148265;6486.383793539606;6568;6585.440237579668;6591.34086591337;6592.505562082767;6596.142501474777;6302.779402720883;6350.7777227796805;6404.839879003062;6409.134766806422;6413.2945376007665 17:05:21 INFO - PID 1634 | |1;ext.html;749.5839808906055;808.1958451340917;835.6782638684107;1113.2485572238736;1290.6386211860677;31077.223069423246;40415.19169616606;40444.79777601115;40517.3922391164;40583.79708101463;39818.60181398185;40148.79925600375;40197.79901100498;40234.57419683832;40261.798691006574;8058.798530036752;8255.33957283419;8519.233269005788;8743.650253989865;8779.165979231995;6455.612663240197;8895.243904268145;8897.288216942661;8898.688545900895;8940.508272045035;8798.6920457784;8834.42576232546;8883.466991980502;8890.822183556325;8916.687915922945;8818.514981676006;8831.823363532725;8851.60167792451;8856.202941735275;8871.955640221822;8639.95680021596;8640.827183456358;8704.390692651517;8712.259457946082;8714.607842647096;38781.41827872584;39013.854238016895;39090.609093908985;39101.80449097765;39228.80385598083;3478.113081164287;3504.3516949364193;3535.328287625343;3536.2043540203504;3550.9644903550893;30826.53760193599;30994.53508197356;31019.37961240785;31051.378972420425;31070;3095.5820964169775;3104.9548207725197;3119.8128112313107;3143.2770462793687;3154.810711357326;337727.31136344164;338869;342136.2893185543;352265;352682;50027.499725003014;50293;50341;50449.24326135111;50481.495185048414;52620.210696839575;52695.47304526982;52789;52791.208131878055;52935;13987.37056832445;14048.367823447968;14117.364718587483;14133;14153.867690584728;11272.21094523392;11439.828402573969;11462.312261264351;11467.655970320737;11491.367974761468;12338.629841104781;13156.736865262641;14100.942429317842;14148.646283842987;14171.64570885736;5874;5908.822735317947;5942.465178133903;5963.522918166536;6000.099985002197;913.2374467319729;997.805427941559;1000.1348833259178;1003.5233264199593;1007.1238022920106;22642.547149056925;24266;24338.756612433648;24545.631815522782;24657.260282191564;905.542700936023;1096.4024606589428;1101.3391964821203;1140.0822338017808;1154.4574050196395;11829.171957963032;12475.875241247653;12498.50005999768;12515.74968500643;12519.3740312984;479972;480133;481578;482356;483185;55228;55546.44453555493;55587.72206138943;55593.444065558826;55736.44263557313;1488.0550850209977;1519.6428839222774;1557.8442155784558;1560.6566555357804;1568.215892053973;23791.524169516168;23850.04599815988;23955.520889582458;23979.400514987265;24060.87969560141;39548;41359;51283;52438;52911.20633190582;468492;472292.21662267623;472369;472525;472794.272057275;27218;27844.30389240204;33950.151246218054;35410;35739.46390804088;41885.37171942362;42166;43078.78460607677;43389;43503.78248108739;23368.76631233666;28463.715362846935;29184.56223156612;29695.554566681087;30200.605110487548;29528.852355738083;30127.54808677828;30246;30542;30605.23486912845;33203.83398082994;35117.47323790196;35204.29591408209;35712.10719732028;35989;22512.5497490046;22712.545749084595;24799.132030379573;29474.410511790073;30398.39203215879;20894.791052089284;23381.41546461352;24144.551326920442;24531.859364301436;24658.260252192464 17:05:21 INFO - PID 1634 | |2;jquery.html;431.90282186508057;444.81540160833254;445.42317698580337;461.6722127289624;461.67913300256316;17510.73733893991;22356.776432235692;22658.09367625295;23180.884095579542;23200;22164.778352216472;22443.32670019898;22974.425639359026;23224.535509289803;23248.186313479033;6197.318294987548;6352.523560732952;6464.676766161673;6574.506911981596;6587.835304117411;6316.684165791715;6493.350664933516;6525.086487891699;6528.902066469007;6538.05198246254;6386.329435409285;6403.263624683151;6411.583247088947;6432.228132624092;6436.903446448307;6304.369563043682;6396.5522413430845;6419.64691941943;6421.133147025139;6475.7733479328;5006.973570418056;5472.5621950243885;5739.196512488255;5742.913856292159;5746.540276777848;27937.441251175067;28185.154445366672;28588.714112858812;28648.42703145946;29001.56497652537;2128.744550653916;2194.8353873459555;2214.446388402899;2241.3948233976735;2247.078697733921;17970.101494925202;18073.36743213981;18395.264189432408;18589.349372772023;18591.349302774473;1830.8352248297717;2133.349328454821;2174.1303478608525;2194.078487035449;2199.2192771566024;157449.21275393548;158087.2095639526;161237.58143627856;162138;165603.1719841405;12686.175398599062;12881.291528965998;12935.28855912915;12940.870591293966;13136.27750473733;16244.675106497803;16333.918330408273;16594.6681066378;16600.750988735177;16626.916865415595;8596.398252122292;8923;8950.641974321084;9023.458592484472;9081.228095611947;6718.630475323904;6995.440364770806;8331.875021874677;8347.207015333533;8482.194191551793;8048.758537243892;8174.550399727953;8217.712380066669;8230.176982301722;8232.917670823335;2011.87928724277;2049.4364049886103;2205.724284464442;2214.247155966979;2364.2788949370265;927.5130556457914;941.0166376136954;953.8998405167496;975.9463229522445;989.6635144051057;19491.902540487208;20935;20973.475663108544;21021.894890525757;21266.78733212679;969.6266937229077;988.32565131752;989.248171389749;1012.8480727890875;1022.8005538919839;10606.41664708433;10608.257421980386;10631.30896491741;10636.574537018587;10646.893531064745;590504;687082;696343;711768;716332;149030;149728;151920;152501;153563;1025.9025392587691;1158.3744777819884;1178.5462596900084;1202.9338386388658;1204.2413279633774;16906;17334.566635834206;17450.82549174492;17572.938014530504;17584.64830703353;57162.85604291638;62393;62566.374336257875;62965;63038.369616305084;80618.59690701743;81069;82148.58925705333;83082.5845870791;85524;7648.34989025939;8724.476531408136;8738.519381433827;9128.49793261377;9290.024547422423;14406.783898241745;14461.204633744825;14562.781558276847;14779.408823646934;14816.777748334;11694.824577631172;11794.882051179378;12040.397980101136;12185.817212741993;12261.26432414058;8979.371443998858;11231.15766317513;11340.546378144782;11661.883381166079;11685.480887484573;12021.398930053638;12274.263544187377;12285.788558059636;12296.385180740748;12321;8764.561771911507;8800.955995219983;8931.19619234272;9102;9134.817303654023;11892.583759568373;11937.582184623496;12059.15585908978;12178.939105304416;12282.20165689263 17:05:21 INFO - PID 1634 | |3;mootools.html;1135.5230803062714;1331.2411925202632;1401.913517024306;1469.654631161677;1473.0719646622626;11484.942575287123;11654.475548600312;11949.64151075469;11993.880061199396;12038.036957043427;11744.941275293611;12050.15648904577;12113.939430302858;12181.208221465598;12197.817032744493;2507.849529028255;2546.31249562618;2548.069954466625;2552.1450314144786;2555.706093799209;2469.6665950096685;2510.435152090774;2519.533886231044;2536.0996846119638;2541.2376287113884;2466.70399552054;2522.672052633155;2522.8738563071865;2528.456381877896;2537.061287323693;2363.539109873584;2407.5786737320987;2409.590369637166;2510.6485092087123;2516.018752686454;2429.854208747463;2480.7395223501453;2500.174942269047;2539.631753395747;2541.55522783514;11560.479778409908;11660.300381977024;11693.70765730855;11836.822447663291;11843.763124737457;6513.967430162867;6746.460283177383;6754.864902701967;6768.593884366954;6798.184217893835;4023.8189281482405;4265.872023839259;4275.273203555403;4306.44016277885;4329.1558146161515;1203.5486692490317;1219.5731493977125;1264.6206138158607;1264.981689739759;1271.4215032160346;15006.474773382988;15577.610559735984;16542.669146617;16669.49991500257;16701.749473757904;5914.970425147847;5977.252843394575;6213.161223234896;6287.1198032275515;6298.0552917062805;10190.43952582615;13576.389062492211;13628.318584070757;13809.792853107212;13814.65463363424;5658.688772117491;6704.02791595216;6767.120274364301;6828.0440738296675;6832.658367081626;6164.6917654117115;6259.843503912348;6268.780592679325;6311.652859092702;6342.143810585604;4658.34783130362;4690.7654617269;4839.879003024882;4878.463369029404;4882.340883980688;1455.6433673749787;1490.6049896777363;1490.903091299062;1496.6333248354224;1507.0957425544586;665.7936039827572;761.46316846623;764.0907320288838;793.8928244687008;825.162460102996;5686.545076393879;5722.084466485342;6749.953757167548;6847.041414202015;7018.824529386704;781.9209490902518;891.662506240639;939.2110627073291;940.2994768897215;947.1097168661436;4491.977540112279;4502.16709908668;4574.268117101248;4686.320483529869;4720.881977950578;15001.474948376754;15348.769768453481;15403.460878869186;15455.227238638023;15467;14648;14947;15229;15235.162066086472;15279.465218717292;9323;10590.629327973484;10866.511007004703;10871.347719136877;10872.347659140476;5266.73666316675;6333.828241775286;6751.426128779109;7056.929430705627;7125.394341480825;11568.88431115678;12910.741785164057;12928.741425171256;12930.870691292967;13337.133086349357;13502.729945401234;13808.654783630489;13881.652958676113;14032.508862189774;14081.647958801112;8390.412671112963;9366.250699944125;9411.341206115507;9463.337566370288;9591;10444.843327350247;10522.894771052192;10652.786944261226;10654.082265192339;10665.306755060896;7098.964505177441;8840.690575829814;8985.281177505914;8989.505577193317;9117.08829117083;7889.802754931173;8716.38985271025;8834.425762325429;8848.46909185451;8962.14859588338;7329.74345897891;8061.355091592542;8121.187881212068;8169.142240064708;8172.305354044735;5656.462636049569;5709.571782116275;5809.738561764815;5874.765009399576;5933.258342707161;6015.969920150371;6097.2988106366565;6245.68771561411;6265.655388953652;6316.11574379578 17:05:21 INFO - PID 1634 | |4;prototype.html;331.9137024373662;365.94876717259604;412.8534370298543;413.9689523285753;420.8211510108203;5051.595872330218;5077.669951453153;5816.487713194568;6375.04374343848;6421.523049698572;4811.879703007417;5109.795608175677;6066.817995460143;6142.986407242811;6202.47278981286;3733.6453036961443;4086;4244.427002354689;4417.447819022622;4549.749763762991;3966.2746705183195;4101.692373072016;4368.5194628590825;4581.244094724367;4583.152116858375;4525.253333200035;4540.296254080623;4542.727436353829;4547.136044151601;4551.795169217393;4301.870943871689;4475.216837053503;4554.497015984717;4559.110973360196;4563.178627846986;3870.2066076454257;3894.9610503894883;3903.941440878389;3906.648401643866;3930.449737036817;5534.446555344473;6030.09548567714;6053.243344581927;6059.878922399326;6069.878602427971;4547.954520454786;5211;5415.214793854909;5472.097103977839;5492.121260598325;6644.272489152807;6656.301088385698;6664.93335066648;6669;6680.899786503206;3916.3733802591732;4036.6367026967714;4202.096549241897;4435.201663700533;4458;5477.219903531368;5747.48272655463;7094.25510321414;7095.751648692322;7116.395106415961;3748.887533374003;3979.9602003980167;4214.304639734471;4470.664700147437;4489.506154323022;3788.4127960165665;4020.73865198767;4553.840615578439;4570.588647021784;4573.268277075652;4150.634138541002;4735.958089220366;5397.703126328011;5413.0513015314455;5421.83734487966;6640.302768209365;6664.300248473937;6672.633005184664;6673.265940746514;6692.163479565054;1295.585412667956;1518.8608543592306;1559.5087547422524;1565.882558808094;1568.6784209237078;948.2745699539857;1000.4347543637822;1025.8666373371564;1034.3897100710467;1044.3785947361307;5132.230165475208;5803.622764520377;6228.626282423069;6277.686115694197;6310.737852429533;6873.381395674413;6905;6970.825729356807;6974.407175390149;6990.720371185197;6011.458968692751;6218.657973811484;6246.063090536365;6283.277423096402;6333.429991300805;2910.665273493533;3164.7973769967266;3290.844496854564;3450.4128101073284;3621.022323972513;6216.471599913967;6884;7201.603911784902;7211.459140564479;7211.819704507325;5815.709214539256;6707.396334329934;6723.966380168068;6862.382385585322;7059.893646795394;6930.203026652;7048.682809273595;7056.706570364652;7163.785086447415;7189.892151617834;5784.971075144597;6185;6689.76585819494;6920.792376228721;6968.268331825288;6751.527393082633;6799.966000170165;6839.555428897106;6849.4177994871;6872;6723.899141512979;6859.794206173823;6861.039454476477;7140.857182856417;7245;3791.616060138085;4419.867423958609;4469.6534319482535;4498.730076195438;4513.323001549794;6272.275124340843;6550.574212676161;6904.965475172793;6907.4410888813145;6969.849974754312;5644.294463192101;5881.676622759845;5917.556183286185;6506.219253689398;6552.5740826848005;5845.970770146122;6147.3423313128615;6585.958352910613;6645.767398141042;6690;6213.720382582705;6237.781677641442;6388.201474815648;6473.7376211638275;6502.967485162544;5848.736806843617;5925.348211696796;6606.867862642816;6621.271660117287;6679.4990375721045;6219.40915613016;6495.025746137928;6511.413972742476;6518.380753828568;6533.477321814338 17:05:21 INFO - PID 1634 | |5;yui.html;771.2711487644178;772.0349563046192;825.8554752918238;834.9624266907989;838.0822998816294;15962.68074638508;16266.186690665478;16324.34702611897;16372.099534525581;16409.507714768562;14942.402303907831;14974.85025149751;15110.622234444118;15375;15446.459373921914;955.4888134847823;958.8609651600497;965.1892410375283;966.6761634852332;970.3256236915347;1000.3297790480359;1011.7925825205815;1023.1303392116715;1025.174734338857;1025.5077562769875;1064.5635289531294;1066.6853278282924;1067.343583696029;1067.935923844568;1068.1988508618535;1040.3081950502906;1050.7058023753361;1056.75166335911;1060.0565496707936;1062.5324857062867;778.0740918307253;784.1531146361915;784.3881772217679;785.4344871692373;788.688468055115;16654.583635409213;17041.488755337235;17064;17119.65760684792;17315.220815063352;14874.330655120546;14958.252087395587;15007.557655181385;15124.168170750605;15149;13356.332183390889;13407.46370145193;13464.788169064734;13480.05639605228;13534;2919.2645564829477;3367.326534693071;3398.8300584970652;3403.2512847173584;3408.216110294646;106259.46870265677;109828.4508577452;109895;110247.44876275648;115313.42343288314;9730.854037189449;11664.48946748849;11731.765364692828;11807.527698891949;12027.699307517207;20646.896765516078;24245;24386;24782.752172478045;24892.5021499569;7088.326608972139;8142.348612111017;8191.631376587949;8222.424430289822;8363.661814109713;4072.9389059164137;4158.293090174678;4181.895452613649;4228.365745138254;4243.427137336482;3826.8469261229798;3933.783641899723;4211.347241177622;4275.722078064916;4300.397944287803;908.7591788176138;925.726910561386;935.6304259817334;936.1387523478417;936.9765755856158;611.4069352727847;620.8509957610141;637.1366797988751;640.810960766575;651.8690072723821;7583.355414789694;8626.353023523261;9607.231421486264;9890.554925028391;10256.435896025641;574.8965186266512;596.3946594206797;605.4762630324738;638.9744410223632;661.6427129350097;4862.635302352267;4868.367112275381;4912.140375434285;4917.188663870421;4939.244204889366;105527.4723626377;108047;108966.45516772525;110061.44969275262;110525.89474105155;96541.51729241309;96878.0312196869;97571;98169;98241.03517929601;57889.71055144782;74753.99999999891;84389.57805210934;85909.14090859011;86305;9358.625654973997;10106.444145572064;10249.897501024894;11008.304860712899;11019.338839669645;40014.599489017746;40502.18995620012;40522.79738601406;41328;42582.787086064374;52375.476245238584;52525.7373713129;52586.21120683116;52751.73624131855;52960.20559691531;12561.623151305475;13390.397432115773;13549.051566390259;13560;13880.722385552433;14470.276486175439;14555.636109097357;14686.853131468975;14796.7780483295;14913.776293355391;13286.124764081356;13579.52471663487;13880.028860747138;13908.721825563229;13991.23048232357;11095.500702468247;13198.406071726604;13523.797143042666;13667.453301868212;13795.379207935466;12160.817587736368;12625.747485050431;12937.288449135387;13139.211647301192;13751.106178098391;8815.691450799191;9740.220782337537;9838.60645574169;10234.77859549714;10297.43364114951;9276.536173191449;10968.17738669587;11051.613193538187;11388.373639449579;11756.530433695789 17:05:21 INFO - PID 1634 | __end_tp_report 17:05:21 INFO - PID 1634 | __start_cc_report 17:05:21 INFO - PID 1634 | _x_x_mozilla_cycle_collect,427 17:05:21 INFO - PID 1634 | __end_cc_report 17:05:21 INFO - PID 1634 | __startTimestamp1491437121922__endTimestamp 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | ------- Summary: start ------- 17:05:21 INFO - PID 1634 | Number of tests: 6 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#0] dojo.html Cycles:165 Average:6205.24 Median:6404.84 stddev:755.02 (11.8%) stddev-sans-first:713.97 17:05:21 INFO - PID 1634 | Values: 2990.4 3065.7 3570.9 3630.6 3652.7 6208.8 6262.2 6272.2 6273.9 6322.6 6234.3 6257.8 6263.6 6343.1 6364.5 5551.4 5554.7 5597.8 5606.3 5630.3 5431.2 5552.6 5615.8 5648.5 5665.4 5437.7 5511.9 5557.3 5562.8 5568.4 5482.2 5488.7 5502.8 5509.7 5556.4 5427.7 5467.2 5489.3 5502.3 5567.1 6056.5 6161.9 6221.7 6242.2 6263.6 6735.2 6736.1 6747.6 6754.8 6761.0 5651.6 6807.8 6820.5 6825.3 6829.2 6228.6 6261.7 6324.1 6351.6 6375.7 6975.4 6985.9 7023.5 7036.3 7039.9 6372.5 6412.8 6520.0 6570.8 6626.4 6716.1 6736.6 6796.3 6821.9 6832.8 6433.7 6525.7 6629.6 6659.3 6736.4 5960.0 6743.5 6743.8 6758.2 6773.9 5412.0 5415.0 5425.9 5426.8 5462.5 4769.2 4785.7 4795.0 4832.4 4869.4 6191.3 6362.0 6385.6 6421.0 6434.9 6825.6 6845.0 6846.4 6848.1 6865.9 6075.9 6298.5 6350.0 6402.3 6423.8 5118.2 6029.1 6034.1 6064.3 6080.3 7020.0 7072.2 7073.0 7084.8 7131.5 6576.6 6753.9 6849.8 6853.0 6878.6 6866.8 6922.0 7057.8 7072.4 7084.3 6703.4 6707.8 6711.6 6718.8 6719.4 4913.3 6055.9 6534.4 6623.1 6838.8 6516.2 6590.0 6602.9 6616.3 6627.5 6353.1 6398.1 6415.6 6544.8 6638.9 6220.3 6432.4 6450.9 6451.0 6486.4 6568.0 6585.4 6591.3 6592.5 6596.1 6302.8 6350.8 6404.8 6409.1 6413.3 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#1] ext.html Cycles:180 Average:56401.89 Median:19480.48 stddev:116304.72 (597.0%) stddev-sans-first:116555.93 17:05:21 INFO - PID 1634 | Values: 749.6 808.2 835.7 1113.2 1290.6 31077.2 40415.2 40444.8 40517.4 40583.8 39818.6 40148.8 40197.8 40234.6 40261.8 8058.8 8255.3 8519.2 8743.7 8779.2 6455.6 8895.2 8897.3 8898.7 8940.5 8798.7 8834.4 8883.5 8890.8 8916.7 8818.5 8831.8 8851.6 8856.2 8872.0 8640.0 8640.8 8704.4 8712.3 8714.6 38781.4 39013.9 39090.6 39101.8 39228.8 3478.1 3504.4 3535.3 3536.2 3551.0 30826.5 30994.5 31019.4 31051.4 31070.0 3095.6 3105.0 3119.8 3143.3 3154.8 337727.3 338869.0 342136.3 352265.0 352682.0 50027.5 50293.0 50341.0 50449.2 50481.5 52620.2 52695.5 52789.0 52791.2 52935.0 13987.4 14048.4 14117.4 14133.0 14153.9 11272.2 11439.8 11462.3 11467.7 11491.4 12338.6 13156.7 14100.9 14148.6 14171.6 5874.0 5908.8 5942.5 5963.5 6000.1 913.2 997.8 1000.1 1003.5 1007.1 22642.5 24266.0 24338.8 24545.6 24657.3 905.5 1096.4 1101.3 1140.1 1154.5 11829.2 12475.9 12498.5 12515.7 12519.4 479972.0 480133.0 481578.0 482356.0 483185.0 55228.0 55546.4 55587.7 55593.4 55736.4 1488.1 1519.6 1557.8 1560.7 1568.2 23791.5 23850.0 23955.5 23979.4 24060.9 39548.0 41359.0 51283.0 52438.0 52911.2 468492.0 472292.2 472369.0 472525.0 472794.3 27218.0 27844.3 33950.2 35410.0 35739.5 41885.4 42166.0 43078.8 43389.0 43503.8 23368.8 28463.7 29184.6 29695.6 30200.6 29528.9 30127.5 30246.0 30542.0 30605.2 33203.8 35117.5 35204.3 35712.1 35989.0 22512.5 22712.5 24799.1 29474.4 30398.4 20894.8 23381.4 24144.6 24531.9 24658.3 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#2] jquery.html Cycles:180 Average:40333.78 Median:23077.65 stddev:114667.05 (496.9%) stddev-sans-first:114949.58 17:05:21 INFO - PID 1634 | Values: 431.9 444.8 445.4 461.7 461.7 17510.7 22356.8 22658.1 23180.9 23200.0 22164.8 22443.3 22974.4 23224.5 23248.2 6197.3 6352.5 6464.7 6574.5 6587.8 6316.7 6493.4 6525.1 6528.9 6538.1 6386.3 6403.3 6411.6 6432.2 6436.9 6304.4 6396.6 6419.6 6421.1 6475.8 5007.0 5472.6 5739.2 5742.9 5746.5 27937.4 28185.2 28588.7 28648.4 29001.6 2128.7 2194.8 2214.4 2241.4 2247.1 17970.1 18073.4 18395.3 18589.3 18591.3 1830.8 2133.3 2174.1 2194.1 2199.2 157449.2 158087.2 161237.6 162138.0 165603.2 12686.2 12881.3 12935.3 12940.9 13136.3 16244.7 16333.9 16594.7 16600.8 16626.9 8596.4 8923.0 8950.6 9023.5 9081.2 6718.6 6995.4 8331.9 8347.2 8482.2 8048.8 8174.6 8217.7 8230.2 8232.9 2011.9 2049.4 2205.7 2214.2 2364.3 927.5 941.0 953.9 975.9 989.7 19491.9 20935.0 20973.5 21021.9 21266.8 969.6 988.3 989.2 1012.8 1022.8 10606.4 10608.3 10631.3 10636.6 10646.9 590504.0 687082.0 696343.0 711768.0 716332.0 149030.0 149728.0 151920.0 152501.0 153563.0 1025.9 1158.4 1178.5 1202.9 1204.2 16906.0 17334.6 17450.8 17572.9 17584.6 57162.9 62393.0 62566.4 62965.0 63038.4 80618.6 81069.0 82148.6 83082.6 85524.0 7648.3 8724.5 8738.5 9128.5 9290.0 14406.8 14461.2 14562.8 14779.4 14816.8 11694.8 11794.9 12040.4 12185.8 12261.3 8979.4 11231.2 11340.5 11661.9 11685.5 12021.4 12274.3 12285.8 12296.4 12321.0 8764.6 8801.0 8931.2 9102.0 9134.8 11892.6 11937.6 12059.2 12178.9 12282.2 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#3] mootools.html Cycles:180 Average:7215.07 Median:2547.19 stddev:4546.23 (178.5%) stddev-sans-first:4536.03 17:05:21 INFO - PID 1634 | Values: 1135.5 1331.2 1401.9 1469.7 1473.1 11484.9 11654.5 11949.6 11993.9 12038.0 11744.9 12050.2 12113.9 12181.2 12197.8 2507.8 2546.3 2548.1 2552.1 2555.7 2469.7 2510.4 2519.5 2536.1 2541.2 2466.7 2522.7 2522.9 2528.5 2537.1 2363.5 2407.6 2409.6 2510.6 2516.0 2429.9 2480.7 2500.2 2539.6 2541.6 11560.5 11660.3 11693.7 11836.8 11843.8 6514.0 6746.5 6754.9 6768.6 6798.2 4023.8 4265.9 4275.3 4306.4 4329.2 1203.5 1219.6 1264.6 1265.0 1271.4 15006.5 15577.6 16542.7 16669.5 16701.7 5915.0 5977.3 6213.2 6287.1 6298.1 10190.4 13576.4 13628.3 13809.8 13814.7 5658.7 6704.0 6767.1 6828.0 6832.7 6164.7 6259.8 6268.8 6311.7 6342.1 4658.3 4690.8 4839.9 4878.5 4882.3 1455.6 1490.6 1490.9 1496.6 1507.1 665.8 761.5 764.1 793.9 825.2 5686.5 5722.1 6750.0 6847.0 7018.8 781.9 891.7 939.2 940.3 947.1 4492.0 4502.2 4574.3 4686.3 4720.9 15001.5 15348.8 15403.5 15455.2 15467.0 14648.0 14947.0 15229.0 15235.2 15279.5 9323.0 10590.6 10866.5 10871.3 10872.3 5266.7 6333.8 6751.4 7056.9 7125.4 11568.9 12910.7 12928.7 12930.9 13337.1 13502.7 13808.7 13881.7 14032.5 14081.6 8390.4 9366.3 9411.3 9463.3 9591.0 10444.8 10522.9 10652.8 10654.1 10665.3 7099.0 8840.7 8985.3 8989.5 9117.1 7889.8 8716.4 8834.4 8848.5 8962.1 7329.7 8061.4 8121.2 8169.1 8172.3 5656.5 5709.6 5809.7 5874.8 5933.3 6016.0 6097.3 6245.7 6265.7 6316.1 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#4] prototype.html Cycles:180 Average:5293.34 Median:5921.45 stddev:1718.72 (29.0%) stddev-sans-first:1682.72 17:05:21 INFO - PID 1634 | Values: 331.9 365.9 412.9 414.0 420.8 5051.6 5077.7 5816.5 6375.0 6421.5 4811.9 5109.8 6066.8 6143.0 6202.5 3733.6 4086.0 4244.4 4417.4 4549.7 3966.3 4101.7 4368.5 4581.2 4583.2 4525.3 4540.3 4542.7 4547.1 4551.8 4301.9 4475.2 4554.5 4559.1 4563.2 3870.2 3895.0 3903.9 3906.6 3930.4 5534.4 6030.1 6053.2 6059.9 6069.9 4548.0 5211.0 5415.2 5472.1 5492.1 6644.3 6656.3 6664.9 6669.0 6680.9 3916.4 4036.6 4202.1 4435.2 4458.0 5477.2 5747.5 7094.3 7095.8 7116.4 3748.9 3980.0 4214.3 4470.7 4489.5 3788.4 4020.7 4553.8 4570.6 4573.3 4150.6 4736.0 5397.7 5413.1 5421.8 6640.3 6664.3 6672.6 6673.3 6692.2 1295.6 1518.9 1559.5 1565.9 1568.7 948.3 1000.4 1025.9 1034.4 1044.4 5132.2 5803.6 6228.6 6277.7 6310.7 6873.4 6905.0 6970.8 6974.4 6990.7 6011.5 6218.7 6246.1 6283.3 6333.4 2910.7 3164.8 3290.8 3450.4 3621.0 6216.5 6884.0 7201.6 7211.5 7211.8 5815.7 6707.4 6724.0 6862.4 7059.9 6930.2 7048.7 7056.7 7163.8 7189.9 5785.0 6185.0 6689.8 6920.8 6968.3 6751.5 6800.0 6839.6 6849.4 6872.0 6723.9 6859.8 6861.0 7140.9 7245.0 3791.6 4419.9 4469.7 4498.7 4513.3 6272.3 6550.6 6905.0 6907.4 6969.8 5644.3 5881.7 5917.6 6506.2 6552.6 5846.0 6147.3 6586.0 6645.8 6690.0 6213.7 6237.8 6388.2 6473.7 6503.0 5848.7 5925.3 6606.9 6621.3 6679.5 6219.4 6495.0 6511.4 6518.4 6533.5 17:05:21 INFO - PID 1634 | 17:05:21 INFO - PID 1634 | [#5] yui.html Cycles:180 Average:20566.06 Median:22445.95 stddev:30012.70 (133.7%) stddev-sans-first:30060.09 17:05:21 INFO - PID 1634 | Values: 771.3 772.0 825.9 835.0 838.1 15962.7 16266.2 16324.3 16372.1 16409.5 14942.4 14974.9 15110.6 15375.0 15446.5 955.5 958.9 965.2 966.7 970.3 1000.3 1011.8 1023.1 1025.2 1025.5 1064.6 1066.7 1067.3 1067.9 1068.2 1040.3 1050.7 1056.8 1060.1 1062.5 778.1 784.2 784.4 785.4 788.7 16654.6 17041.5 17064.0 17119.7 17315.2 14874.3 14958.3 15007.6 15124.2 15149.0 13356.3 13407.5 13464.8 13480.1 13534.0 2919.3 3367.3 3398.8 3403.3 3408.2 106259.5 109828.5 109895.0 110247.4 115313.4 9730.9 11664.5 11731.8 11807.5 12027.7 20646.9 24245.0 24386.0 24782.8 24892.5 7088.3 8142.3 8191.6 8222.4 8363.7 4072.9 4158.3 4181.9 4228.4 4243.4 3826.8 3933.8 4211.3 4275.7 4300.4 908.8 925.7 935.6 936.1 937.0 611.4 620.9 637.1 640.8 651.9 7583.4 8626.4 9607.2 9890.6 10256.4 574.9 596.4 605.5 639.0 661.6 4862.6 4868.4 4912.1 4917.2 4939.2 105527.5 108047.0 108966.5 110061.4 110525.9 96541.5 96878.0 97571.0 98169.0 98241.0 57889.7 74754.0 84389.6 85909.1 86305.0 9358.6 10106.4 10249.9 11008.3 11019.3 40014.6 40502.2 40522.8 41328.0 42582.8 52375.5 52525.7 52586.2 52751.7 52960.2 12561.6 13390.4 13549.1 13560.0 13880.7 14470.3 14555.6 14686.9 14796.8 14913.8 13286.1 13579.5 13880.0 13908.7 13991.2 11095.5 13198.4 13523.8 13667.5 13795.4 12160.8 12625.7 12937.3 13139.2 13751.1 8815.7 9740.2 9838.6 10234.8 10297.4 9276.5 10968.2 11051.6 11388.4 11756.5 17:05:21 INFO - PID 1634 | -------- Summary: end -------- 17:05:21 INFO - PID 1634 | 17:05:22 INFO - TEST-INFO | 1634: exit 0 17:05:22 INFO - TEST-OK | dromaeo_css | took 1117164ms 17:05:22 INFO - TEST-START | kraken 17:05:22 INFO - operating with platform_type : mac_ 17:05:22 INFO - Initialising browser for kraken test... 17:05:22 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpi8_YFe/profile http://localhost:49241/getInfo.html) 17:05:25 INFO - PID 1645 | 2017-04-05 17:05:25.692 plugin-container[1646:10081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 17:05:25 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:25 INFO - PID 1645 | 2017-04-05 17:05:25.696 plugin-container[1646:10081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 17:05:25 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:25 INFO - PID 1645 | 2017-04-05 17:05:25.696 plugin-container[1646:10081] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:05:25 INFO - PID 1645 | __metrics Screen width/height:1600/1200 17:05:25 INFO - PID 1645 | colorDepth:24 17:05:25 INFO - PID 1645 | Browser inner width/height: 1024/689 17:05:25 INFO - PID 1645 | __metrics 17:05:25 ERROR - PID 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:05:26 INFO - TEST-INFO | 1645: exit 0 17:05:26 INFO - Browser initialized. 17:05:26 INFO - Running cycle 1/1 for kraken test... 17:05:26 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpi8_YFe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:29 INFO - PID 1647 | 2017-04-05 17:05:29.344 plugin-container[1648:10217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 17:05:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:29 INFO - PID 1647 | 2017-04-05 17:05:29.345 plugin-container[1648:10217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 17:05:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:29 INFO - PID 1647 | 2017-04-05 17:05:29.345 plugin-container[1648:10217] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:05:39 INFO - PID 1647 | RSS: Main: 234799104 17:05:39 INFO - PID 1647 | 17:05:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 17:05:42 INFO - PID 1647 | RSS: Main: 249798656 17:05:42 INFO - PID 1647 | 17:05:45 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 17:05:46 INFO - PID 1647 | RSS: Main: 258301952 17:05:46 INFO - PID 1647 | 17:05:49 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 17:05:50 INFO - PID 1647 | RSS: Main: 260562944 17:05:50 INFO - PID 1647 | 17:05:52 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 17:05:53 INFO - PID 1647 | RSS: Main: 261591040 17:05:53 INFO - PID 1647 | 17:05:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:05:55 INFO - PID 1647 | RSS: Main: 263348224 17:05:55 INFO - PID 1647 | 17:05:58 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:59 INFO - PID 1647 | RSS: Main: 269131776 17:05:59 INFO - PID 1647 | 17:06:02 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 17:06:03 INFO - PID 1647 | RSS: Main: 269709312 17:06:03 INFO - PID 1647 | 17:06:06 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 17:06:07 INFO - PID 1647 | RSS: Main: 267100160 17:06:07 INFO - PID 1647 | 17:06:08 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:06:09 INFO - PID 1647 | RSS: Main: 268058624 17:06:09 INFO - PID 1647 | 17:06:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:06:12 INFO - PID 1647 | RSS: Main: 268472320 17:06:12 INFO - PID 1647 | 17:06:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:06:15 INFO - PID 1647 | RSS: Main: 268759040 17:06:15 INFO - PID 1647 | 17:06:18 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:06:19 INFO - PID 1647 | RSS: Main: 269389824 17:06:19 INFO - PID 1647 | 17:06:22 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:06:22 INFO - PID 1647 | RSS: Main: 269709312 17:06:22 INFO - PID 1647 | 17:06:24 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 17:06:25 INFO - PID 1647 | RSS: Main: 272396288 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | __start_tp_report 17:06:25 INFO - PID 1647 | _x_x_mozilla_page_load 17:06:25 INFO - PID 1647 | _x_x_mozilla_page_load_details 17:06:25 INFO - PID 1647 | |i|pagename|runs| 17:06:25 INFO - PID 1647 | |0;ai-astar;109;121;123;102;183;183;128;124;123;121 17:06:25 INFO - PID 1647 | |1;audio-beat-detection;154;117;119;163;128;118;117;118;150;152 17:06:25 INFO - PID 1647 | |2;audio-dft;216;219;216;232;223;224;208;212;202;211 17:06:25 INFO - PID 1647 | |3;audio-fft;83;57;60;92;57;61;81;91;104;58 17:06:25 INFO - PID 1647 | |4;audio-oscillator;88;71;88;73;73;70;96;97;109;125 17:06:25 INFO - PID 1647 | |5;imaging-gaussian-blur;92;93;90;90;105;90;91;138;142;90 17:06:25 INFO - PID 1647 | |6;imaging-darkroom;100;99;101;98;103;114;99;100;100;100 17:06:25 INFO - PID 1647 | |7;imaging-desaturate;133;89;117;160;148;104;163;162;128;100 17:06:25 INFO - PID 1647 | |8;json-parse-financial;53;51;52;51;52;63;66;67;52;52 17:06:25 INFO - PID 1647 | |9;json-stringify-tinderbox;58;68;69;79;56;78;55;62;82;89 17:06:25 INFO - PID 1647 | |10;stanford-crypto-aes;79;105;113;115;104;89;89;104;113;116 17:06:25 INFO - PID 1647 | |11;stanford-crypto-ccm;178;147;151;168;162;165;154;161;227;180 17:06:25 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;197;196;198;195;195;197;194;199;211;226 17:06:25 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;63;88;100;75;69;69;79;72;71;72 17:06:25 INFO - PID 1647 | __end_tp_report 17:06:25 INFO - PID 1647 | __start_cc_report 17:06:25 INFO - PID 1647 | _x_x_mozilla_cycle_collect,1011 17:06:25 INFO - PID 1647 | __end_cc_report 17:06:25 INFO - PID 1647 | __startTimestamp1491437185051__endTimestamp 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | ------- Summary: start ------- 17:06:25 INFO - PID 1647 | Number of tests: 14 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:131.70 Median:123.50 stddev:28.12 (22.8%) stddev-sans-first:28.60 17:06:25 INFO - PID 1647 | Values: 109.0 121.0 123.0 102.0 183.0 183.0 128.0 124.0 123.0 121.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:139.00 stddev:18.77 (13.5%) stddev-sans-first:18.40 17:06:25 INFO - PID 1647 | Values: 154.0 117.0 119.0 163.0 128.0 118.0 117.0 118.0 150.0 152.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:216.30 Median:217.50 stddev:8.68 (4.0%) stddev-sans-first:9.21 17:06:25 INFO - PID 1647 | Values: 216.0 219.0 216.0 232.0 223.0 224.0 208.0 212.0 202.0 211.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:74.40 Median:71.00 stddev:17.77 (25.0%) stddev-sans-first:18.57 17:06:25 INFO - PID 1647 | Values: 83.0 57.0 60.0 92.0 57.0 61.0 81.0 91.0 104.0 58.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:80.50 stddev:18.28 (22.7%) stddev-sans-first:19.39 17:06:25 INFO - PID 1647 | Values: 88.0 71.0 88.0 73.0 73.0 70.0 96.0 97.0 109.0 125.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:102.10 Median:90.00 stddev:20.50 (22.8%) stddev-sans-first:21.42 17:06:25 INFO - PID 1647 | Values: 92.0 93.0 90.0 90.0 105.0 90.0 91.0 138.0 142.0 90.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:108.50 stddev:4.62 (4.3%) stddev-sans-first:4.88 17:06:25 INFO - PID 1647 | Values: 100.0 99.0 101.0 98.0 103.0 114.0 99.0 100.0 100.0 100.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:130.40 Median:154.00 stddev:27.44 (17.8%) stddev-sans-first:29.08 17:06:25 INFO - PID 1647 | Values: 133.0 89.0 117.0 160.0 148.0 104.0 163.0 162.0 128.0 100.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:52.50 stddev:6.61 (12.6%) stddev-sans-first:6.92 17:06:25 INFO - PID 1647 | Values: 53.0 51.0 52.0 51.0 52.0 63.0 66.0 67.0 52.0 52.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:73.50 stddev:11.94 (16.2%) stddev-sans-first:11.90 17:06:25 INFO - PID 1647 | Values: 58.0 68.0 69.0 79.0 56.0 78.0 55.0 62.0 82.0 89.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:102.70 Median:115.50 stddev:12.85 (11.1%) stddev-sans-first:10.38 17:06:25 INFO - PID 1647 | Values: 79.0 105.0 113.0 115.0 104.0 89.0 89.0 104.0 113.0 116.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:169.30 Median:166.50 stddev:22.92 (13.8%) stddev-sans-first:24.09 17:06:25 INFO - PID 1647 | Values: 178.0 147.0 151.0 168.0 162.0 165.0 154.0 161.0 227.0 180.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:197.50 stddev:10.09 (5.1%) stddev-sans-first:10.60 17:06:25 INFO - PID 1647 | Values: 197.0 196.0 198.0 195.0 195.0 197.0 194.0 199.0 211.0 226.0 17:06:25 INFO - PID 1647 | 17:06:25 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:72.00 stddev:10.82 (15.0%) stddev-sans-first:10.44 17:06:25 INFO - PID 1647 | Values: 63.0 88.0 100.0 75.0 69.0 69.0 79.0 72.0 71.0 72.0 17:06:25 INFO - PID 1647 | -------- Summary: end -------- 17:06:25 INFO - PID 1647 | 17:06:26 INFO - TEST-INFO | 1647: exit 0 17:06:26 INFO - TEST-OK | kraken | took 63731ms 17:06:26 INFO - SUITE-END | took 1180s 17:06:26 INFO - Completed test suite (00:19:41) 17:06:26 INFO - javascript benchmark 17:06:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7886.946779126467, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2990.4019196160775, 3065.6934306569337, 3570.8928732138047, 3630.5824830144534, 3652.689521390682, 6208.78269260576, 6262.154609127766, 6272.215973003369, 6273.874522509553, 6322.557420980534, 6234.345393733661, 6257.843553911154, 6263.561550691454, 6343.111964324999, 6364.45902098321, 5551.361593416748, 5554.694491802959, 5597.832065038035, 5606.327240731118, 5630.296212973379, 5431.185322201682, 5552.555795536347, 5615.775368985256, 5648.519875810561, 5665.433456654322, 5437.673739575638, 5511.862203444946, 5557.277553917985, 5562.833115006556, 5568.415316391761, 5482.23248745176, 5488.725563721838, 5502.752376143083, 5509.724513774336, 5556.416576259516, 5427.674339539601, 5467.20725494805, 5489.286392768914, 5502.339719233678, 5567.081431563787, 6056.545759068087, 6161.938380616181, 6221.688915554249, 6242.15730876335, 6263.624182549016, 6735.191776986744, 6736.124303840469, 6747.561408508432, 6754.831129221809, 6761.0, 5651.5761317900915, 6807.795766127024, 6820.522563420563, 6825.283345248777, 6829.214640316328, 6228.564000520011, 6261.655608941461, 6324.08300796382, 6351.555391122623, 6375.744970201233, 6975.407090397373, 6985.93014069863, 7023.473239507058, 7036.296370363025, 7039.859202815915, 6372.522060845455, 6412.775552855627, 6519.967400163064, 6570.77002304917, 6626.436752875963, 6716.126903502611, 6736.5958042517605, 6796.25241223465, 6821.931780682229, 6832.795016149524, 6433.678316084177, 6525.673716314165, 6629.602223866584, 6659.334066593399, 6736.427403670645, 5960.0, 6743.527953043338, 6743.797686069426, 6758.189017317954, 6773.8983915241315, 5412.025835349675, 5415.0, 5425.891482170414, 5426.782928682887, 5462.535684466785, 4769.165396055676, 4785.712857228507, 4795.040991801654, 4832.395950506187, 4869.391326084239, 6191.349908259658, 6362.013887847391, 6385.616862988236, 6420.967895160495, 6434.871302573923, 6825.590464572141, 6845.0, 6846.383825455733, 6848.14398200225, 6865.931340686529, 6075.936711075632, 6298.527610429236, 6349.9682501587195, 6402.263739669905, 6423.839404014843, 5118.232265160255, 6029.065494848305, 6034.125051867542, 6064.332923378425, 6080.300765411946, 7019.964900175569, 7072.151341838807, 7073.0, 7084.822879428056, 7131.536450130725, 6576.572522786004, 6753.932460675331, 6849.760258390932, 6853.040574319699, 6878.621675807678, 6866.828329291808, 6922.0, 7057.752978645723, 7072.398846098139, 7084.2915708428745, 6703.363180497845, 6707.8323041922395, 6711.597304161766, 6718.8320291993095, 6719.361660642231, 4913.28757330178, 6055.879662262496, 6534.411902928759, 6623.105880706043, 6838.8290292741085, 6516.218053833571, 6590.044443555754, 6602.900956485752, 6616.305287944889, 6627.536072474881, 6353.14232578596, 6398.104265402754, 6415.615063096229, 6544.8363790905605, 6638.933610663831, 6220.346863579259, 6432.388923052303, 6450.903236451551, 6451.032345148265, 6486.383793539606, 6568.0, 6585.440237579668, 6591.34086591337, 6592.505562082767, 6596.142501474777, 6302.779402720883, 6350.7777227796805, 6404.839879003062, 6409.134766806422, 6413.2945376007665], "value": 6152.784683679648, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.5839808906055, 808.1958451340917, 835.6782638684107, 1113.2485572238736, 1290.6386211860677, 31077.223069423246, 40415.19169616606, 40444.79777601115, 40517.3922391164, 40583.79708101463, 39818.60181398185, 40148.79925600375, 40197.79901100498, 40234.57419683832, 40261.798691006574, 8058.798530036752, 8255.33957283419, 8519.233269005788, 8743.650253989865, 8779.165979231995, 6455.612663240197, 8895.243904268145, 8897.288216942661, 8898.688545900895, 8940.508272045035, 8798.6920457784, 8834.42576232546, 8883.466991980502, 8890.822183556325, 8916.687915922945, 8818.514981676006, 8831.823363532725, 8851.60167792451, 8856.202941735275, 8871.955640221822, 8639.95680021596, 8640.827183456358, 8704.390692651517, 8712.259457946082, 8714.607842647096, 38781.41827872584, 39013.854238016895, 39090.609093908985, 39101.80449097765, 39228.80385598083, 3478.113081164287, 3504.3516949364193, 3535.328287625343, 3536.2043540203504, 3550.9644903550893, 30826.53760193599, 30994.53508197356, 31019.37961240785, 31051.378972420425, 31070.0, 3095.5820964169775, 3104.9548207725197, 3119.8128112313107, 3143.2770462793687, 3154.810711357326, 337727.31136344164, 338869.0, 342136.2893185543, 352265.0, 352682.0, 50027.499725003014, 50293.0, 50341.0, 50449.24326135111, 50481.495185048414, 52620.210696839575, 52695.47304526982, 52789.0, 52791.208131878055, 52935.0, 13987.37056832445, 14048.367823447968, 14117.364718587483, 14133.0, 14153.867690584728, 11272.21094523392, 11439.828402573969, 11462.312261264351, 11467.655970320737, 11491.367974761468, 12338.629841104781, 13156.736865262641, 14100.942429317842, 14148.646283842987, 14171.64570885736, 5874.0, 5908.822735317947, 5942.465178133903, 5963.522918166536, 6000.099985002197, 913.2374467319729, 997.805427941559, 1000.1348833259178, 1003.5233264199593, 1007.1238022920106, 22642.547149056925, 24266.0, 24338.756612433648, 24545.631815522782, 24657.260282191564, 905.542700936023, 1096.4024606589428, 1101.3391964821203, 1140.0822338017808, 1154.4574050196395, 11829.171957963032, 12475.875241247653, 12498.50005999768, 12515.74968500643, 12519.3740312984, 479972.0, 480133.0, 481578.0, 482356.0, 483185.0, 55228.0, 55546.44453555493, 55587.72206138943, 55593.444065558826, 55736.44263557313, 1488.0550850209977, 1519.6428839222774, 1557.8442155784558, 1560.6566555357804, 1568.215892053973, 23791.524169516168, 23850.04599815988, 23955.520889582458, 23979.400514987265, 24060.87969560141, 39548.0, 41359.0, 51283.0, 52438.0, 52911.20633190582, 468492.0, 472292.21662267623, 472369.0, 472525.0, 472794.272057275, 27218.0, 27844.30389240204, 33950.151246218054, 35410.0, 35739.46390804088, 41885.37171942362, 42166.0, 43078.78460607677, 43389.0, 43503.78248108739, 23368.76631233666, 28463.715362846935, 29184.56223156612, 29695.554566681087, 30200.605110487548, 29528.852355738083, 30127.54808677828, 30246.0, 30542.0, 30605.23486912845, 33203.83398082994, 35117.47323790196, 35204.29591408209, 35712.10719732028, 35989.0, 22512.5497490046, 22712.545749084595, 24799.132030379573, 29474.410511790073, 30398.39203215879, 20894.791052089284, 23381.41546461352, 24144.551326920442, 24531.859364301436, 24658.260252192464], "value": 18128.81107407243, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.90282186508057, 444.81540160833254, 445.42317698580337, 461.6722127289624, 461.67913300256316, 17510.73733893991, 22356.776432235692, 22658.09367625295, 23180.884095579542, 23200.0, 22164.778352216472, 22443.32670019898, 22974.425639359026, 23224.535509289803, 23248.186313479033, 6197.318294987548, 6352.523560732952, 6464.676766161673, 6574.506911981596, 6587.835304117411, 6316.684165791715, 6493.350664933516, 6525.086487891699, 6528.902066469007, 6538.05198246254, 6386.329435409285, 6403.263624683151, 6411.583247088947, 6432.228132624092, 6436.903446448307, 6304.369563043682, 6396.5522413430845, 6419.64691941943, 6421.133147025139, 6475.7733479328, 5006.973570418056, 5472.5621950243885, 5739.196512488255, 5742.913856292159, 5746.540276777848, 27937.441251175067, 28185.154445366672, 28588.714112858812, 28648.42703145946, 29001.56497652537, 2128.744550653916, 2194.8353873459555, 2214.446388402899, 2241.3948233976735, 2247.078697733921, 17970.101494925202, 18073.36743213981, 18395.264189432408, 18589.349372772023, 18591.349302774473, 1830.8352248297717, 2133.349328454821, 2174.1303478608525, 2194.078487035449, 2199.2192771566024, 157449.21275393548, 158087.2095639526, 161237.58143627856, 162138.0, 165603.1719841405, 12686.175398599062, 12881.291528965998, 12935.28855912915, 12940.870591293966, 13136.27750473733, 16244.675106497803, 16333.918330408273, 16594.6681066378, 16600.750988735177, 16626.916865415595, 8596.398252122292, 8923.0, 8950.641974321084, 9023.458592484472, 9081.228095611947, 6718.630475323904, 6995.440364770806, 8331.875021874677, 8347.207015333533, 8482.194191551793, 8048.758537243892, 8174.550399727953, 8217.712380066669, 8230.176982301722, 8232.917670823335, 2011.87928724277, 2049.4364049886103, 2205.724284464442, 2214.247155966979, 2364.2788949370265, 927.5130556457914, 941.0166376136954, 953.8998405167496, 975.9463229522445, 989.6635144051057, 19491.902540487208, 20935.0, 20973.475663108544, 21021.894890525757, 21266.78733212679, 969.6266937229077, 988.32565131752, 989.248171389749, 1012.8480727890875, 1022.8005538919839, 10606.41664708433, 10608.257421980386, 10631.30896491741, 10636.574537018587, 10646.893531064745, 590504.0, 687082.0, 696343.0, 711768.0, 716332.0, 149030.0, 149728.0, 151920.0, 152501.0, 153563.0, 1025.9025392587691, 1158.3744777819884, 1178.5462596900084, 1202.9338386388658, 1204.2413279633774, 16906.0, 17334.566635834206, 17450.82549174492, 17572.938014530504, 17584.64830703353, 57162.85604291638, 62393.0, 62566.374336257875, 62965.0, 63038.369616305084, 80618.59690701743, 81069.0, 82148.58925705333, 83082.5845870791, 85524.0, 7648.34989025939, 8724.476531408136, 8738.519381433827, 9128.49793261377, 9290.024547422423, 14406.783898241745, 14461.204633744825, 14562.781558276847, 14779.408823646934, 14816.777748334, 11694.824577631172, 11794.882051179378, 12040.397980101136, 12185.817212741993, 12261.26432414058, 8979.371443998858, 11231.15766317513, 11340.546378144782, 11661.883381166079, 11685.480887484573, 12021.398930053638, 12274.263544187377, 12285.788558059636, 12296.385180740748, 12321.0, 8764.561771911507, 8800.955995219983, 8931.19619234272, 9102.0, 9134.817303654023, 11892.583759568373, 11937.582184623496, 12059.15585908978, 12178.939105304416, 12282.20165689263], "value": 10678.33207976401, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1135.5230803062714, 1331.2411925202632, 1401.913517024306, 1469.654631161677, 1473.0719646622626, 11484.942575287123, 11654.475548600312, 11949.64151075469, 11993.880061199396, 12038.036957043427, 11744.941275293611, 12050.15648904577, 12113.939430302858, 12181.208221465598, 12197.817032744493, 2507.849529028255, 2546.31249562618, 2548.069954466625, 2552.1450314144786, 2555.706093799209, 2469.6665950096685, 2510.435152090774, 2519.533886231044, 2536.0996846119638, 2541.2376287113884, 2466.70399552054, 2522.672052633155, 2522.8738563071865, 2528.456381877896, 2537.061287323693, 2363.539109873584, 2407.5786737320987, 2409.590369637166, 2510.6485092087123, 2516.018752686454, 2429.854208747463, 2480.7395223501453, 2500.174942269047, 2539.631753395747, 2541.55522783514, 11560.479778409908, 11660.300381977024, 11693.70765730855, 11836.822447663291, 11843.763124737457, 6513.967430162867, 6746.460283177383, 6754.864902701967, 6768.593884366954, 6798.184217893835, 4023.8189281482405, 4265.872023839259, 4275.273203555403, 4306.44016277885, 4329.1558146161515, 1203.5486692490317, 1219.5731493977125, 1264.6206138158607, 1264.981689739759, 1271.4215032160346, 15006.474773382988, 15577.610559735984, 16542.669146617, 16669.49991500257, 16701.749473757904, 5914.970425147847, 5977.252843394575, 6213.161223234896, 6287.1198032275515, 6298.0552917062805, 10190.43952582615, 13576.389062492211, 13628.318584070757, 13809.792853107212, 13814.65463363424, 5658.688772117491, 6704.02791595216, 6767.120274364301, 6828.0440738296675, 6832.658367081626, 6164.6917654117115, 6259.843503912348, 6268.780592679325, 6311.652859092702, 6342.143810585604, 4658.34783130362, 4690.7654617269, 4839.879003024882, 4878.463369029404, 4882.340883980688, 1455.6433673749787, 1490.6049896777363, 1490.903091299062, 1496.6333248354224, 1507.0957425544586, 665.7936039827572, 761.46316846623, 764.0907320288838, 793.8928244687008, 825.162460102996, 5686.545076393879, 5722.084466485342, 6749.953757167548, 6847.041414202015, 7018.824529386704, 781.9209490902518, 891.662506240639, 939.2110627073291, 940.2994768897215, 947.1097168661436, 4491.977540112279, 4502.16709908668, 4574.268117101248, 4686.320483529869, 4720.881977950578, 15001.474948376754, 15348.769768453481, 15403.460878869186, 15455.227238638023, 15467.0, 14648.0, 14947.0, 15229.0, 15235.162066086472, 15279.465218717292, 9323.0, 10590.629327973484, 10866.511007004703, 10871.347719136877, 10872.347659140476, 5266.73666316675, 6333.828241775286, 6751.426128779109, 7056.929430705627, 7125.394341480825, 11568.88431115678, 12910.741785164057, 12928.741425171256, 12930.870691292967, 13337.133086349357, 13502.729945401234, 13808.654783630489, 13881.652958676113, 14032.508862189774, 14081.647958801112, 8390.412671112963, 9366.250699944125, 9411.341206115507, 9463.337566370288, 9591.0, 10444.843327350247, 10522.894771052192, 10652.786944261226, 10654.082265192339, 10665.306755060896, 7098.964505177441, 8840.690575829814, 8985.281177505914, 8989.505577193317, 9117.08829117083, 7889.802754931173, 8716.38985271025, 8834.425762325429, 8848.46909185451, 8962.14859588338, 7329.74345897891, 8061.355091592542, 8121.187881212068, 8169.142240064708, 8172.305354044735, 5656.462636049569, 5709.571782116275, 5809.738561764815, 5874.765009399576, 5933.258342707161, 6015.969920150371, 6097.2988106366565, 6245.68771561411, 6265.655388953652, 6316.11574379578], "value": 5462.1403696314255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.9137024373662, 365.94876717259604, 412.8534370298543, 413.9689523285753, 420.8211510108203, 5051.595872330218, 5077.669951453153, 5816.487713194568, 6375.04374343848, 6421.523049698572, 4811.879703007417, 5109.795608175677, 6066.817995460143, 6142.986407242811, 6202.47278981286, 3733.6453036961443, 4086.0, 4244.427002354689, 4417.447819022622, 4549.749763762991, 3966.2746705183195, 4101.692373072016, 4368.5194628590825, 4581.244094724367, 4583.152116858375, 4525.253333200035, 4540.296254080623, 4542.727436353829, 4547.136044151601, 4551.795169217393, 4301.870943871689, 4475.216837053503, 4554.497015984717, 4559.110973360196, 4563.178627846986, 3870.2066076454257, 3894.9610503894883, 3903.941440878389, 3906.648401643866, 3930.449737036817, 5534.446555344473, 6030.09548567714, 6053.243344581927, 6059.878922399326, 6069.878602427971, 4547.954520454786, 5211.0, 5415.214793854909, 5472.097103977839, 5492.121260598325, 6644.272489152807, 6656.301088385698, 6664.93335066648, 6669.0, 6680.899786503206, 3916.3733802591732, 4036.6367026967714, 4202.096549241897, 4435.201663700533, 4458.0, 5477.219903531368, 5747.48272655463, 7094.25510321414, 7095.751648692322, 7116.395106415961, 3748.887533374003, 3979.9602003980167, 4214.304639734471, 4470.664700147437, 4489.506154323022, 3788.4127960165665, 4020.73865198767, 4553.840615578439, 4570.588647021784, 4573.268277075652, 4150.634138541002, 4735.958089220366, 5397.703126328011, 5413.0513015314455, 5421.83734487966, 6640.302768209365, 6664.300248473937, 6672.633005184664, 6673.265940746514, 6692.163479565054, 1295.585412667956, 1518.8608543592306, 1559.5087547422524, 1565.882558808094, 1568.6784209237078, 948.2745699539857, 1000.4347543637822, 1025.8666373371564, 1034.3897100710467, 1044.3785947361307, 5132.230165475208, 5803.622764520377, 6228.626282423069, 6277.686115694197, 6310.737852429533, 6873.381395674413, 6905.0, 6970.825729356807, 6974.407175390149, 6990.720371185197, 6011.458968692751, 6218.657973811484, 6246.063090536365, 6283.277423096402, 6333.429991300805, 2910.665273493533, 3164.7973769967266, 3290.844496854564, 3450.4128101073284, 3621.022323972513, 6216.471599913967, 6884.0, 7201.603911784902, 7211.459140564479, 7211.819704507325, 5815.709214539256, 6707.396334329934, 6723.966380168068, 6862.382385585322, 7059.893646795394, 6930.203026652, 7048.682809273595, 7056.706570364652, 7163.785086447415, 7189.892151617834, 5784.971075144597, 6185.0, 6689.76585819494, 6920.792376228721, 6968.268331825288, 6751.527393082633, 6799.966000170165, 6839.555428897106, 6849.4177994871, 6872.0, 6723.899141512979, 6859.794206173823, 6861.039454476477, 7140.857182856417, 7245.0, 3791.616060138085, 4419.867423958609, 4469.6534319482535, 4498.730076195438, 4513.323001549794, 6272.275124340843, 6550.574212676161, 6904.965475172793, 6907.4410888813145, 6969.849974754312, 5644.294463192101, 5881.676622759845, 5917.556183286185, 6506.219253689398, 6552.5740826848005, 5845.970770146122, 6147.3423313128615, 6585.958352910613, 6645.767398141042, 6690.0, 6213.720382582705, 6237.781677641442, 6388.201474815648, 6473.7376211638275, 6502.967485162544, 5848.736806843617, 5925.348211696796, 6606.867862642816, 6621.271660117287, 6679.4990375721045, 6219.40915613016, 6495.025746137928, 6511.413972742476, 6518.380753828568, 6533.477321814338], "value": 4755.947593302823, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.2711487644178, 772.0349563046192, 825.8554752918238, 834.9624266907989, 838.0822998816294, 15962.68074638508, 16266.186690665478, 16324.34702611897, 16372.099534525581, 16409.507714768562, 14942.402303907831, 14974.85025149751, 15110.622234444118, 15375.0, 15446.459373921914, 955.4888134847823, 958.8609651600497, 965.1892410375283, 966.6761634852332, 970.3256236915347, 1000.3297790480359, 1011.7925825205815, 1023.1303392116715, 1025.174734338857, 1025.5077562769875, 1064.5635289531294, 1066.6853278282924, 1067.343583696029, 1067.935923844568, 1068.1988508618535, 1040.3081950502906, 1050.7058023753361, 1056.75166335911, 1060.0565496707936, 1062.5324857062867, 778.0740918307253, 784.1531146361915, 784.3881772217679, 785.4344871692373, 788.688468055115, 16654.583635409213, 17041.488755337235, 17064.0, 17119.65760684792, 17315.220815063352, 14874.330655120546, 14958.252087395587, 15007.557655181385, 15124.168170750605, 15149.0, 13356.332183390889, 13407.46370145193, 13464.788169064734, 13480.05639605228, 13534.0, 2919.2645564829477, 3367.326534693071, 3398.8300584970652, 3403.2512847173584, 3408.216110294646, 106259.46870265677, 109828.4508577452, 109895.0, 110247.44876275648, 115313.42343288314, 9730.854037189449, 11664.48946748849, 11731.765364692828, 11807.527698891949, 12027.699307517207, 20646.896765516078, 24245.0, 24386.0, 24782.752172478045, 24892.5021499569, 7088.326608972139, 8142.348612111017, 8191.631376587949, 8222.424430289822, 8363.661814109713, 4072.9389059164137, 4158.293090174678, 4181.895452613649, 4228.365745138254, 4243.427137336482, 3826.8469261229798, 3933.783641899723, 4211.347241177622, 4275.722078064916, 4300.397944287803, 908.7591788176138, 925.726910561386, 935.6304259817334, 936.1387523478417, 936.9765755856158, 611.4069352727847, 620.8509957610141, 637.1366797988751, 640.810960766575, 651.8690072723821, 7583.355414789694, 8626.353023523261, 9607.231421486264, 9890.554925028391, 10256.435896025641, 574.8965186266512, 596.3946594206797, 605.4762630324738, 638.9744410223632, 661.6427129350097, 4862.635302352267, 4868.367112275381, 4912.140375434285, 4917.188663870421, 4939.244204889366, 105527.4723626377, 108047.0, 108966.45516772525, 110061.44969275262, 110525.89474105155, 96541.51729241309, 96878.0312196869, 97571.0, 98169.0, 98241.03517929601, 57889.71055144782, 74753.99999999891, 84389.57805210934, 85909.14090859011, 86305.0, 9358.625654973997, 10106.444145572064, 10249.897501024894, 11008.304860712899, 11019.338839669645, 40014.599489017746, 40502.18995620012, 40522.79738601406, 41328.0, 42582.787086064374, 52375.476245238584, 52525.7373713129, 52586.21120683116, 52751.73624131855, 52960.20559691531, 12561.623151305475, 13390.397432115773, 13549.051566390259, 13560.0, 13880.722385552433, 14470.276486175439, 14555.636109097357, 14686.853131468975, 14796.7780483295, 14913.776293355391, 13286.124764081356, 13579.52471663487, 13880.028860747138, 13908.721825563229, 13991.23048232357, 11095.500702468247, 13198.406071726604, 13523.797143042666, 13667.453301868212, 13795.379207935466, 12160.817587736368, 12625.747485050431, 12937.288449135387, 13139.211647301192, 13751.106178098391, 8815.691450799191, 9740.220782337537, 9838.60645574169, 10234.77859549714, 10297.43364114951, 9276.536173191449, 10968.17738669587, 11051.613193538187, 11388.373639449579, 11756.530433695789], "value": 7778.173140143785, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1652.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 123.0, 102.0, 183.0, 183.0, 128.0, 124.0, 123.0, 121.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 119.0, 163.0, 128.0, 118.0, 117.0, 118.0, 150.0, 152.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 219.0, 216.0, 232.0, 223.0, 224.0, 208.0, 212.0, 202.0, 211.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 60.0, 92.0, 57.0, 61.0, 81.0, 91.0, 104.0, 58.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 88.0, 73.0, 73.0, 70.0, 96.0, 97.0, 109.0, 125.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 90.0, 90.0, 105.0, 90.0, 91.0, 138.0, 142.0, 90.0], "value": 102.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 99.0, 101.0, 98.0, 103.0, 114.0, 99.0, 100.0, 100.0, 100.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 89.0, 117.0, 160.0, 148.0, 104.0, 163.0, 162.0, 128.0, 100.0], "value": 130.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 51.0, 52.0, 63.0, 66.0, 67.0, 52.0, 52.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 68.0, 69.0, 79.0, 56.0, 78.0, 55.0, 62.0, 82.0, 89.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 105.0, 113.0, 115.0, 104.0, 89.0, 89.0, 104.0, 113.0, 116.0], "value": 102.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 147.0, 151.0, 168.0, 162.0, 165.0, 154.0, 161.0, 227.0, 180.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 198.0, 195.0, 195.0, 197.0, 194.0, 199.0, 211.0, 226.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 88.0, 100.0, 75.0, 69.0, 69.0, 79.0, 72.0, 71.0, 72.0], "value": 75.8, "unit": "score"}]}]} 17:06:26 INFO - Return code: 0 17:06:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:06:26 ERROR - # TBPL SUCCESS # 17:06:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:06:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:06:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:06:26 INFO - 2017-04-05 17:06:26.870 screenresolution[1652:10602] starting screenresolution argv=screenresolution get 17:06:26 INFO - 2017-04-05 17:06:26.873 screenresolution[1652:10602] Display 0: 1600x1200x32@60 17:06:26 INFO - 2017-04-05 17:06:26.879 screenresolution[1653:10605] starting screenresolution argv=screenresolution list 17:06:26 INFO - Available Modes on Display 0 17:06:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:06:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:06:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:06:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:06:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:06:27 INFO - Intel Iris: 17:06:27 INFO - Chipset Model: Intel Iris 17:06:27 INFO - Type: GPU 17:06:27 INFO - Bus: Built-In 17:06:27 INFO - VRAM (Dynamic, Max): 1536 MB 17:06:27 INFO - Vendor: Intel (0x8086) 17:06:27 INFO - Device ID: 0x0a2e 17:06:27 INFO - Revision ID: 0x0009 17:06:27 INFO - Displays: 17:06:27 INFO - Display: 17:06:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:06:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:06:27 INFO - Display Serial Number: 1600x1200 60 17:06:27 INFO - Main Display: Yes 17:06:27 INFO - Mirror: Off 17:06:27 INFO - Online: Yes 17:06:27 INFO - Rotation: Supported 17:06:27 INFO - Return code: 0 17:06:27 INFO - Running post-action listener: _resource_record_post_action 17:06:27 INFO - [mozharness: 2017-04-06 00:06:27.114318Z] Finished run-tests step (success) 17:06:27 INFO - Running post-run listener: _resource_record_post_run 17:06:27 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39115264; Write bytes: 521451520; Read time: 251; Write time: 1665 17:06:27 INFO - TinderboxPrint: CPU usage
54.3% 17:06:27 INFO - TinderboxPrint: I/O read bytes / time
39,115,264 / 251 17:06:27 INFO - TinderboxPrint: I/O write bytes / time
521,451,520 / 1,665 17:06:27 INFO - TinderboxPrint: CPU idle
2,194.9 (45.7%) 17:06:27 INFO - TinderboxPrint: CPU system
967.7 (20.1%) 17:06:27 INFO - TinderboxPrint: CPU user
1,643.1 (34.2%) 17:06:27 INFO - TinderboxPrint: Swap in / out
246,333,440 / 0 17:06:27 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208482816; Write bytes: 208142336; Read time: 15891; Write time: 468 17:06:27 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35256832; Write bytes: 311969792; Read time: 226; Write time: 1191 17:06:27 INFO - Running post-run listener: _upload_blobber_files 17:06:27 INFO - Blob upload gear active. 17:06:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:06:27 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:06:27 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:06:27 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:06:28 INFO - (blobuploader) - INFO - Open directory for files ... 17:06:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:06:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:29 INFO - (blobuploader) - INFO - Done attempting. 17:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:06:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:30 INFO - (blobuploader) - INFO - Done attempting. 17:06:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:06:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:06:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:30 INFO - (blobuploader) - INFO - Done attempting. 17:06:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:06:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:06:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:33 INFO - (blobuploader) - INFO - Done attempting. 17:06:33 INFO - (blobuploader) - INFO - Iteration through files over. 17:06:33 INFO - Return code: 0 17:06:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:06:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:06:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/875685dde415e308f2cf729b0fe921d1704884cf2d5ea97bf3b8a3c8a55b8b50c17ba04c5aba9112d7f68cffa95bee79a38d5749168d7e40e10196dfa57ff7cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d9cc0f3662e401dd8429427d66ab4a672de137b6399d410e9053e5f7378d0d05d6c7803e877dd83c89c7fb678fcb495bddf87bfc7249c02c91c15cbcac30f643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/203c576526377165349c5cd5277b0f3940224fdc579e08d11a0867b5493ccc189d69e1a2b4deab54d8f7c0ea7d7b545ca596f176bca63eb60adfd99e59caee96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/45c067c73f72d1def322031d8ce7bebda97b136f6e0fae72a00f2af924a2f50028c237af4e63199b2017065fd02cf7dd56af1f397811cd5dae395bdcd7eec19f"} 17:06:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:06:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:06:33 INFO - Contents: 17:06:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/875685dde415e308f2cf729b0fe921d1704884cf2d5ea97bf3b8a3c8a55b8b50c17ba04c5aba9112d7f68cffa95bee79a38d5749168d7e40e10196dfa57ff7cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d9cc0f3662e401dd8429427d66ab4a672de137b6399d410e9053e5f7378d0d05d6c7803e877dd83c89c7fb678fcb495bddf87bfc7249c02c91c15cbcac30f643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/203c576526377165349c5cd5277b0f3940224fdc579e08d11a0867b5493ccc189d69e1a2b4deab54d8f7c0ea7d7b545ca596f176bca63eb60adfd99e59caee96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/45c067c73f72d1def322031d8ce7bebda97b136f6e0fae72a00f2af924a2f50028c237af4e63199b2017065fd02cf7dd56af1f397811cd5dae395bdcd7eec19f"} 17:06:33 INFO - Running post-run listener: copy_logs_to_upload_dir 17:06:33 INFO - Copying logs to upload dir... 17:06:33 INFO - mkdir: /builds/slave/test/build/upload/logs 17:06:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.260905 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-05 17:06:33.231727) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 17:06:33.235617) ========= 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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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/Pine/sha512/875685dde415e308f2cf729b0fe921d1704884cf2d5ea97bf3b8a3c8a55b8b50c17ba04c5aba9112d7f68cffa95bee79a38d5749168d7e40e10196dfa57ff7cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d9cc0f3662e401dd8429427d66ab4a672de137b6399d410e9053e5f7378d0d05d6c7803e877dd83c89c7fb678fcb495bddf87bfc7249c02c91c15cbcac30f643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/203c576526377165349c5cd5277b0f3940224fdc579e08d11a0867b5493ccc189d69e1a2b4deab54d8f7c0ea7d7b545ca596f176bca63eb60adfd99e59caee96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/45c067c73f72d1def322031d8ce7bebda97b136f6e0fae72a00f2af924a2f50028c237af4e63199b2017065fd02cf7dd56af1f397811cd5dae395bdcd7eec19f"} build_url:https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/av0FTri6TKydnb_LUrzMPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/875685dde415e308f2cf729b0fe921d1704884cf2d5ea97bf3b8a3c8a55b8b50c17ba04c5aba9112d7f68cffa95bee79a38d5749168d7e40e10196dfa57ff7cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d9cc0f3662e401dd8429427d66ab4a672de137b6399d410e9053e5f7378d0d05d6c7803e877dd83c89c7fb678fcb495bddf87bfc7249c02c91c15cbcac30f643", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/203c576526377165349c5cd5277b0f3940224fdc579e08d11a0867b5493ccc189d69e1a2b4deab54d8f7c0ea7d7b545ca596f176bca63eb60adfd99e59caee96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/45c067c73f72d1def322031d8ce7bebda97b136f6e0fae72a00f2af924a2f50028c237af4e63199b2017065fd02cf7dd56af1f397811cd5dae395bdcd7eec19f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 17:06:33.269910) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:06:33.270212) ========= 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.aMCrxhzcNH/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.S6FJB8Rfif/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005114 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:06:33.319850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 17:06:33.320221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 17:06:33.320593) ========= ========= Total master_lag: 0.43 =========