builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1490393551.11 results: success (0) buildid: 20170324135417 builduid: d4de066085b946f09269d5446f006596 revision: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.107780) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.108140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.108414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.278179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.278429) ========= 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.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-24 15:12:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.9M=0s 2017-03-24 15:12:31 (29.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.647271 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.957600) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:31.957909) ========= 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.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.026342 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.005661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.006009) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 --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 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-24 15:12:32,087 truncating revision to first 12 chars 2017-03-24 15:12:32,087 Setting DEBUG logging. 2017-03-24 15:12:32,087 attempt 1/10 2017-03-24 15:12:32,087 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/29bc3de2ac24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 15:12:32,412 unpacking tar archive at: pine-29bc3de2ac24/testing/mozharness/ program finished with exit code 0 elapsedTime=0.598844 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.627743) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.628017) ========= script_repo_revision: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.628407) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.628690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 15:12:32.648459) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-24 15:12:32.648725) ========= /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.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:12:32 INFO - MultiFileLogger online at 20170324 15:12:32 in /builds/slave/test 15:12:32 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 15:12:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:12:32 INFO - {'append_to_log': False, 15:12:32 INFO - 'base_work_dir': '/builds/slave/test', 15:12:32 INFO - 'blob_upload_branch': 'Pine', 15:12:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:12:32 INFO - 'branch': 'Pine', 15:12:32 INFO - 'buildbot_json_path': 'buildprops.json', 15:12:32 INFO - 'config_files': ('talos/mac_config.py',), 15:12:32 INFO - 'default_actions': ('clobber', 15:12:32 INFO - 'read-buildbot-config', 15:12:32 INFO - 'download-and-extract', 15:12:32 INFO - 'populate-webroot', 15:12:32 INFO - 'create-virtualenv', 15:12:32 INFO - 'install', 15:12:32 INFO - 'run-tests'), 15:12:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:12:32 INFO - 'download_minidump_stackwalk': True, 15:12:32 INFO - 'download_symbols': 'ondemand', 15:12:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:12:32 INFO - 'tooltool.py': '/tools/tooltool.py', 15:12:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:12:32 INFO - '/tools/misc-python/virtualenv.py')}, 15:12:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:12:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:12:32 INFO - 'gecko_profile': False, 15:12:32 INFO - 'gecko_profile_interval': 0, 15:12:32 INFO - 'installer_path': 'installer.exe', 15:12:32 INFO - 'log_level': 'info', 15:12:32 INFO - 'log_name': 'talos', 15:12:32 INFO - 'log_to_console': True, 15:12:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:12:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:12:32 INFO - 'opt_config_files': (), 15:12:32 INFO - 'pip_index': False, 15:12:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:12:32 INFO - 'cmd': ('bash', 15:12:32 INFO - '-c', 15:12:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:12:32 INFO - 'enabled': True, 15:12:32 INFO - 'halt_on_failure': False, 15:12:32 INFO - 'name': 'check_screen_resolution'},), 15:12:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:12:32 INFO - 'cmd': ('bash', 15:12:32 INFO - '-c', 15:12:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:12:32 INFO - 'enabled': True, 15:12:32 INFO - 'halt_on_failure': False, 15:12:32 INFO - 'name': 'check_screen_resolution'},), 15:12:32 INFO - 'run_cmd_checks_enabled': True, 15:12:32 INFO - 'suite': 'dromaeojs-e10s', 15:12:32 INFO - 'system_bits': '32', 15:12:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:12:32 INFO - 'title': 't-yosemite-r7-0383', 15:12:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:12:32 INFO - 'use_talos_json': True, 15:12:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:12:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:12:32 INFO - 'work_dir': 'build'} 15:12:32 INFO - [mozharness: 2017-03-24 22:12:32.838042Z] Running clobber step. 15:12:32 INFO - Running pre-action listener: _resource_record_pre_action 15:12:32 INFO - Running main action method: clobber 15:12:32 INFO - rmtree: /builds/slave/test/build 15:12:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:12:35 INFO - Running post-action listener: _resource_record_post_action 15:12:35 INFO - [mozharness: 2017-03-24 22:12:35.329832Z] Finished clobber step (success) 15:12:35 INFO - [mozharness: 2017-03-24 22:12:35.329999Z] Running read-buildbot-config step. 15:12:35 INFO - Running pre-action listener: _resource_record_pre_action 15:12:35 INFO - Running main action method: read_buildbot_config 15:12:35 INFO - Using buildbot properties: 15:12:35 INFO - { 15:12:35 INFO - "project": "", 15:12:35 INFO - "product": "firefox", 15:12:35 INFO - "script_repo_revision": "default", 15:12:35 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 15:12:35 INFO - "repository": "", 15:12:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 15:12:35 INFO - "buildid": "20170324135417", 15:12:35 INFO - "pgo_build": "False", 15:12:35 INFO - "basedir": "/builds/slave/test", 15:12:35 INFO - "buildnumber": 1, 15:12:35 INFO - "slavename": "t-yosemite-r7-0383", 15:12:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:12:35 INFO - "platform": "yosemite_r7", 15:12:35 INFO - "scheduler": "tests-pine-macosx64-talos", 15:12:35 INFO - "branch": "pine", 15:12:35 INFO - "repo_path": "projects/pine", 15:12:35 INFO - "revision": "29bc3de2ac24b25f4719ec6d29a8b85e22458ad3", 15:12:35 INFO - "stage_platform": "macosx64", 15:12:35 INFO - "builduid": "d4de066085b946f09269d5446f006596", 15:12:35 INFO - "slavebuilddir": "test" 15:12:35 INFO - } 15:12:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:12:35 INFO - Running post-action listener: _resource_record_post_action 15:12:35 INFO - [mozharness: 2017-03-24 22:12:35.333391Z] Finished read-buildbot-config step (success) 15:12:35 INFO - [mozharness: 2017-03-24 22:12:35.333504Z] Running download-and-extract step. 15:12:35 INFO - Running pre-action listener: _resource_record_pre_action 15:12:35 INFO - Running main action method: download_and_extract 15:12:35 INFO - mkdir: /builds/slave/test/build/tests 15:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:35 INFO - trying https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:12:35 INFO - Downloading https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/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 15:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/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 15:12:36 INFO - Downloaded 1325 bytes. 15:12:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:12:36 INFO - Using the following test package requirements: 15:12:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:12:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:12:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:12:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:12:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'jsshell-mac.zip'], 15:12:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:12:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:12:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:12:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:12:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:12:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:12:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:12:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:12:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:12:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:12:36 INFO - Fetch https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:12:39 INFO - Expected file size: 52408572 15:12:39 INFO - Obtained file size: 52408572 15:12:55 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 15:12:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:12:55 INFO - Fetch https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:12:57 INFO - Expected file size: 52408572 15:12:57 INFO - Obtained file size: 52408572 15:13:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:13:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:13:12 INFO - Fetch https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:13:13 INFO - Expected file size: 14404171 15:13:13 INFO - Obtained file size: 14404171 15:13: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')]} 15:13:14 INFO - trying https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:13:14 INFO - Downloading https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:13:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/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 15:13:16 INFO - Downloaded 75508714 bytes. 15:13:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:13:16 INFO - mkdir: /builds/slave/test/properties 15:13:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:13:16 INFO - Writing to file /builds/slave/test/properties/build_url 15:13:16 INFO - Contents: 15:13:16 INFO - build_url:https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:13:16 INFO - Running post-action listener: _resource_record_post_action 15:13:16 INFO - Running post-action listener: set_extra_try_arguments 15:13:16 INFO - [mozharness: 2017-03-24 22:13:16.985090Z] Finished download-and-extract step (success) 15:13:16 INFO - [mozharness: 2017-03-24 22:13:16.985252Z] Running populate-webroot step. 15:13:16 INFO - Running pre-action listener: _resource_record_pre_action 15:13:16 INFO - Running main action method: populate_webroot 15:13:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:13:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:13:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:13:16 INFO - '--tppagecycles', 15:13:16 INFO - '7'], 15:13:16 INFO - 'tests': ['tsvgm']}}, 15:13:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:13:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:13:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:13:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:13:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:13:16 INFO - 'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:13:16 INFO - 'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['damp', 'tps']}, 15:13:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:13:16 INFO - 'tests': ['damp', 'tps']}, 15:13:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['dromaeo_dom']}, 15:13:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:13:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['basic_compositor_video']}, 15:13:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:13:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['a11yr', 15:13:16 INFO - 'ts_paint', 15:13:16 INFO - 'tpaint', 15:13:16 INFO - 'sessionrestore', 15:13:16 INFO - 'sessionrestore_no_auto_restore', 15:13:16 INFO - 'tabpaint']}, 15:13:16 INFO - 'other-e10s': {'tests': ['a11yr', 15:13:16 INFO - 'ts_paint', 15:13:16 INFO - 'tpaint', 15:13:16 INFO - 'sessionrestore', 15:13:16 INFO - 'sessionrestore_no_auto_restore', 15:13:16 INFO - 'tabpaint']}, 15:13:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:13:16 INFO - 'ts_paint', 15:13:16 INFO - 'tpaint', 15:13:16 INFO - 'sessionrestore', 15:13:16 INFO - 'sessionrestore_no_auto_restore', 15:13:16 INFO - 'tabpaint']}, 15:13:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:13:16 INFO - 'ts_paint', 15:13:16 INFO - 'tpaint', 15:13:16 INFO - 'sessionrestore', 15:13:16 INFO - 'sessionrestore_no_auto_restore', 15:13:16 INFO - 'tabpaint']}, 15:13:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:13:16 INFO - 'tests': ['a11yr', 15:13:16 INFO - 'ts_paint', 15:13:16 INFO - 'tpaint', 15:13:16 INFO - 'sessionrestore', 15:13:16 INFO - 'sessionrestore_no_auto_restore', 15:13:16 INFO - 'tabpaint']}, 15:13:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:13:17 INFO - 'tests': ['a11yr', 15:13:17 INFO - 'ts_paint', 15:13:17 INFO - 'tpaint', 15:13:17 INFO - 'sessionrestore', 15:13:17 INFO - 'sessionrestore_no_auto_restore', 15:13:17 INFO - 'tabpaint']}, 15:13:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:13:17 INFO - 'tests': ['tsvgx', 15:13:17 INFO - 'tsvgr_opacity', 15:13:17 INFO - 'tart', 15:13:17 INFO - 'tscrollx', 15:13:17 INFO - 'cart', 15:13:17 INFO - 'tsvg_static']}, 15:13:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:13:17 INFO - 'tsvgr_opacity', 15:13:17 INFO - 'tart', 15:13:17 INFO - 'tscrollx', 15:13:17 INFO - 'cart', 15:13:17 INFO - 'tsvg_static']}, 15:13:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:13:17 INFO - 'talos_options': ['--disable-e10s'], 15:13:17 INFO - 'tests': ['tp5o']}, 15:13:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:13:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:13:17 INFO - 'talos_options': ['--disable-e10s', 15:13:17 INFO - '--xperf_path', 15:13:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:17 INFO - 'tests': ['tp5n']}, 15:13:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:13:17 INFO - 'talos_options': ['--xperf_path', 15:13:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:17 INFO - 'tests': ['tp5n']}}, 15:13:17 INFO - 'talos.zip': {'path': '', 15:13:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:13:17 INFO - Running post-action listener: _resource_record_post_action 15:13:17 INFO - [mozharness: 2017-03-24 22:13:17.003755Z] Finished populate-webroot step (success) 15:13:17 INFO - [mozharness: 2017-03-24 22:13:17.003862Z] Running create-virtualenv step. 15:13:17 INFO - Running pre-action listener: _resource_record_pre_action 15:13:17 INFO - Running main action method: create_virtualenv 15:13:17 INFO - Creating virtualenv /builds/slave/test/build/venv 15:13:17 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 15:13:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:13:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:13:17 INFO - Using real prefix '/tools/python27' 15:13:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:13:17 INFO - Installing distribute.............................................................................................................................................................................................done. 15:13:20 INFO - Installing pip.................done. 15:13:20 INFO - Return code: 0 15:13:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:13:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:13:20 INFO - Reading from file tmpfile_stdout 15:13:20 INFO - Output received: 15:13:20 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) 15:13:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:21 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:21 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 15:13:21 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 15:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:21 INFO - 'HOME': '/Users/cltbld', 15:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:21 INFO - 'LOGNAME': 'cltbld', 15:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:21 INFO - 'MOZ_NO_REMOTE': '1', 15:13:21 INFO - 'NO_EM_RESTART': '1', 15:13:21 INFO - 'PAGER': '/bin/cat', 15:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:21 INFO - 'PWD': '/builds/slave/test', 15:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:21 INFO - 'SHELL': '/bin/bash', 15:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:21 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:21 INFO - 'USER': 'cltbld', 15:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:21 INFO - 'XPC_FLAGS': '0x0', 15:13:21 INFO - 'XPC_SERVICE_NAME': '0', 15:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:21 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 15:13:21 INFO - Cleaning up... 15:13:21 INFO - Return code: 0 15:13:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:21 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:21 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 15:13:21 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 15:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:21 INFO - 'HOME': '/Users/cltbld', 15:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:21 INFO - 'LOGNAME': 'cltbld', 15:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:21 INFO - 'MOZ_NO_REMOTE': '1', 15:13:21 INFO - 'NO_EM_RESTART': '1', 15:13:21 INFO - 'PAGER': '/bin/cat', 15:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:21 INFO - 'PWD': '/builds/slave/test', 15:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:21 INFO - 'SHELL': '/bin/bash', 15:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:21 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:21 INFO - 'USER': 'cltbld', 15:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:21 INFO - 'XPC_FLAGS': '0x0', 15:13:21 INFO - 'XPC_SERVICE_NAME': '0', 15:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:21 INFO - Downloading/unpacking psutil>=3.1.1 15:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:13:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:25 INFO - Installing collected packages: psutil 15:13:25 INFO - Running setup.py install for psutil 15:13:25 INFO - building 'psutil._psutil_osx' extension 15:13:25 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 15:13:26 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 15:13:26 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 15:13:26 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 15:13:26 INFO - building 'psutil._psutil_posix' extension 15:13:26 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 15:13:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:13:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:13:26 INFO - ^ 15:13:26 INFO - 1 warning generated. 15:13:26 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 15:13:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:26 INFO - Successfully installed psutil 15:13:26 INFO - Cleaning up... 15:13:26 INFO - Return code: 0 15:13:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:26 INFO - 'HOME': '/Users/cltbld', 15:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:26 INFO - 'LOGNAME': 'cltbld', 15:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:26 INFO - 'MOZ_NO_REMOTE': '1', 15:13:26 INFO - 'NO_EM_RESTART': '1', 15:13:26 INFO - 'PAGER': '/bin/cat', 15:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:26 INFO - 'PWD': '/builds/slave/test', 15:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:26 INFO - 'SHELL': '/bin/bash', 15:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:26 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:26 INFO - 'USER': 'cltbld', 15:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:26 INFO - 'XPC_FLAGS': '0x0', 15:13:26 INFO - 'XPC_SERVICE_NAME': '0', 15:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:13:29 INFO - Installing collected packages: mozsystemmonitor 15:13:29 INFO - Running setup.py install for mozsystemmonitor 15:13:30 INFO - Successfully installed mozsystemmonitor 15:13:30 INFO - Cleaning up... 15:13:30 INFO - Return code: 0 15:13:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:13:30 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')]} 15:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:30 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')]} 15:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:30 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:30 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 15:13:30 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 15:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:30 INFO - 'HOME': '/Users/cltbld', 15:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:30 INFO - 'LOGNAME': 'cltbld', 15:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:30 INFO - 'MOZ_NO_REMOTE': '1', 15:13:30 INFO - 'NO_EM_RESTART': '1', 15:13:30 INFO - 'PAGER': '/bin/cat', 15:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:30 INFO - 'PWD': '/builds/slave/test', 15:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:30 INFO - 'SHELL': '/bin/bash', 15:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:30 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:30 INFO - 'USER': 'cltbld', 15:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:30 INFO - 'XPC_FLAGS': '0x0', 15:13:30 INFO - 'XPC_SERVICE_NAME': '0', 15:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:30 INFO - Downloading/unpacking jsonschema==2.5.1 15:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:13:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:33 INFO - Downloading functools32-3.2.3-2.tar.gz 15:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:13:33 INFO - warning: no files found matching '*.txt' 15:13:33 INFO - no previously-included directories found matching 'build' 15:13:33 INFO - no previously-included directories found matching 'dist' 15:13:33 INFO - no previously-included directories found matching '.git*' 15:13:33 INFO - Installing collected packages: jsonschema, functools32 15:13:33 INFO - Running setup.py install for functools32 15:13:33 INFO - warning: no files found matching '*.txt' 15:13:33 INFO - no previously-included directories found matching 'build' 15:13:33 INFO - no previously-included directories found matching 'dist' 15:13:33 INFO - no previously-included directories found matching '.git*' 15:13:33 INFO - Successfully installed jsonschema functools32 15:13:33 INFO - Cleaning up... 15:13:34 INFO - Return code: 0 15:13:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:34 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:34 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 15:13:34 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 15:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:34 INFO - 'HOME': '/Users/cltbld', 15:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:34 INFO - 'LOGNAME': 'cltbld', 15:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:34 INFO - 'MOZ_NO_REMOTE': '1', 15:13:34 INFO - 'NO_EM_RESTART': '1', 15:13:34 INFO - 'PAGER': '/bin/cat', 15:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:34 INFO - 'PWD': '/builds/slave/test', 15:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:34 INFO - 'SHELL': '/bin/bash', 15:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:34 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:34 INFO - 'USER': 'cltbld', 15:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:34 INFO - 'XPC_FLAGS': '0x0', 15:13:34 INFO - 'XPC_SERVICE_NAME': '0', 15:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:13:34 INFO - Cleaning up... 15:13:34 INFO - Return code: 0 15:13:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:34 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:34 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 15:13:34 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 15:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:34 INFO - 'HOME': '/Users/cltbld', 15:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:34 INFO - 'LOGNAME': 'cltbld', 15:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:34 INFO - 'MOZ_NO_REMOTE': '1', 15:13:34 INFO - 'NO_EM_RESTART': '1', 15:13:34 INFO - 'PAGER': '/bin/cat', 15:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:34 INFO - 'PWD': '/builds/slave/test', 15:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:34 INFO - 'SHELL': '/bin/bash', 15:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:34 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:34 INFO - 'USER': 'cltbld', 15:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:34 INFO - 'XPC_FLAGS': '0x0', 15:13:34 INFO - 'XPC_SERVICE_NAME': '0', 15:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:34 INFO - Downloading/unpacking blobuploader==1.2.4 15:13:34 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 15:13:34 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 15:13:34 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 15:13:34 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 15:13:34 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 15:13:34 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 15:13:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:13:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:13:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:38 INFO - Downloading docopt-0.6.1.tar.gz 15:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:13:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:13:38 INFO - Running setup.py install for blobuploader 15:13:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:13:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:13:38 INFO - Running setup.py install for requests 15:13:39 INFO - Running setup.py install for docopt 15:13:39 INFO - Successfully installed blobuploader requests docopt 15:13:39 INFO - Cleaning up... 15:13:39 INFO - Return code: 0 15:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:39 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:39 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 15:13:39 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 15:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:39 INFO - 'HOME': '/Users/cltbld', 15:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:39 INFO - 'LOGNAME': 'cltbld', 15:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:39 INFO - 'MOZ_NO_REMOTE': '1', 15:13:39 INFO - 'NO_EM_RESTART': '1', 15:13:39 INFO - 'PAGER': '/bin/cat', 15:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:39 INFO - 'PWD': '/builds/slave/test', 15:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:39 INFO - 'SHELL': '/bin/bash', 15:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:39 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:39 INFO - 'USER': 'cltbld', 15:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:39 INFO - 'XPC_FLAGS': '0x0', 15:13:39 INFO - 'XPC_SERVICE_NAME': '0', 15:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-P5RGBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KSVtcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-WZomWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tF5eQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-CF2aCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-S0RBMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-FfJXpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-gM6DXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KvDht_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xYnar0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xZ1SRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-05JhXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-n_EZuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-EMo4DS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hn2k_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-QDG1N5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-mGFofb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:13:41 INFO - Running setup.py install for manifestparser 15:13:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:13:41 INFO - Running setup.py install for mozcrash 15:13:41 INFO - Running setup.py install for mozdebug 15:13:41 INFO - Running setup.py install for mozdevice 15:13:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:13:41 INFO - Running setup.py install for mozfile 15:13:42 INFO - Running setup.py install for mozhttpd 15:13:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Running setup.py install for mozinfo 15:13:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Running setup.py install for mozInstall 15:13:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Running setup.py install for mozleak 15:13:42 INFO - Running setup.py install for mozlog 15:13:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Running setup.py install for moznetwork 15:13:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:13:42 INFO - Running setup.py install for mozprocess 15:13:43 INFO - Running setup.py install for mozprofile 15:13:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:13:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:13:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:13:43 INFO - Running setup.py install for mozrunner 15:13:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:13:43 INFO - Running setup.py install for mozscreenshot 15:13:43 INFO - Running setup.py install for moztest 15:13:43 INFO - Running setup.py install for mozversion 15:13:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:13:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:13:43 INFO - Cleaning up... 15:13:43 INFO - Return code: 0 15:13:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:43 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:43 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 15:13:43 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 15:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:43 INFO - 'HOME': '/Users/cltbld', 15:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:43 INFO - 'LOGNAME': 'cltbld', 15:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:43 INFO - 'MOZ_NO_REMOTE': '1', 15:13:43 INFO - 'NO_EM_RESTART': '1', 15:13:43 INFO - 'PAGER': '/bin/cat', 15:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:43 INFO - 'PWD': '/builds/slave/test', 15:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:43 INFO - 'SHELL': '/bin/bash', 15:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:43 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:43 INFO - 'USER': 'cltbld', 15:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:43 INFO - 'XPC_FLAGS': '0x0', 15:13:43 INFO - 'XPC_SERVICE_NAME': '0', 15:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-HCcPFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KBJA5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tqCs8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-94AFbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-fLOsQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hwr_8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zhKOaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-HgTXEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:44 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)) 15:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:44 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-a7tK8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-uZ3eo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-H9jU68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-QWmMOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-urtx55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-232_pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-0iQuJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-PzJ6Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:45 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)) 15:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-rQYjVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:45 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)) 15:13:45 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)) 15:13:45 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)) 15:13:45 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)) 15:13:45 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)) 15:13:45 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)) 15:13:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:13:45 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 15:13:45 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 15:13:45 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 15:13:45 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 15:13:45 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 15:13:45 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 15:13:48 INFO - Downloading blessings-1.6.tar.gz 15:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:13:48 INFO - Installing collected packages: blessings 15:13:48 INFO - Running setup.py install for blessings 15:13:48 INFO - Successfully installed blessings 15:13:48 INFO - Cleaning up... 15:13:48 INFO - Return code: 0 15:13:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:13:49 INFO - Reading from file tmpfile_stdout 15:13:49 INFO - Current package versions: 15:13:49 INFO - blessings == 1.6 15:13:49 INFO - blobuploader == 1.2.4 15:13:49 INFO - docopt == 0.6.1 15:13:49 INFO - functools32 == 3.2.3-2 15:13:49 INFO - jsonschema == 2.5.1 15:13:49 INFO - manifestparser == 1.1 15:13:49 INFO - mozInstall == 1.12 15:13:49 INFO - mozcrash == 1.0 15:13:49 INFO - mozdebug == 0.1 15:13:49 INFO - mozdevice == 0.49 15:13:49 INFO - mozfile == 1.2 15:13:49 INFO - mozhttpd == 0.7 15:13:49 INFO - mozinfo == 0.9 15:13:49 INFO - mozleak == 0.1 15:13:49 INFO - mozlog == 3.4 15:13:49 INFO - moznetwork == 0.27 15:13:49 INFO - mozprocess == 0.25 15:13:49 INFO - mozprofile == 0.28 15:13:49 INFO - mozrunner == 6.13 15:13:49 INFO - mozscreenshot == 0.1 15:13:49 INFO - mozsystemmonitor == 0.3 15:13:49 INFO - moztest == 0.8 15:13:49 INFO - mozversion == 1.4 15:13:49 INFO - psutil == 3.1.1 15:13:49 INFO - requests == 1.2.3 15:13:49 INFO - wsgiref == 0.1.2 15:13:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:49 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')]} 15:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 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')]} 15:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:49 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 15:13:49 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 15:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:49 INFO - 'HOME': '/Users/cltbld', 15:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:49 INFO - 'LOGNAME': 'cltbld', 15:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:49 INFO - 'MOZ_NO_REMOTE': '1', 15:13:49 INFO - 'NO_EM_RESTART': '1', 15:13:49 INFO - 'PAGER': '/bin/cat', 15:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:49 INFO - 'PWD': '/builds/slave/test', 15:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:49 INFO - 'SHELL': '/bin/bash', 15:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:49 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:49 INFO - 'USER': 'cltbld', 15:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:49 INFO - 'XPC_FLAGS': '0x0', 15:13:49 INFO - 'XPC_SERVICE_NAME': '0', 15:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 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)) 15:13:49 INFO - Cleaning up... 15:13:49 INFO - Return code: 0 15:13:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:13:49 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')]} 15:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 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')]} 15:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 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 0x10bef0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa462d80c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, '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.32kKsYRw80/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.m5XStWqtHp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 15:13:49 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 15:13:49 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 15:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:49 INFO - 'HOME': '/Users/cltbld', 15:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:49 INFO - 'LOGNAME': 'cltbld', 15:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:49 INFO - 'MOZ_NO_REMOTE': '1', 15:13:49 INFO - 'NO_EM_RESTART': '1', 15:13:49 INFO - 'PAGER': '/bin/cat', 15:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:49 INFO - 'PWD': '/builds/slave/test', 15:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:49 INFO - 'SHELL': '/bin/bash', 15:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:13:49 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:49 INFO - 'USER': 'cltbld', 15:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:49 INFO - 'XPC_FLAGS': '0x0', 15:13:49 INFO - 'XPC_SERVICE_NAME': '0', 15:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:13:49 INFO - Cleaning up... 15:13:49 INFO - Return code: 0 15:13:49 INFO - Running post-action listener: _resource_record_post_action 15:13:49 INFO - Running post-action listener: _start_resource_monitoring 15:13:49 INFO - Starting resource monitoring. 15:13:49 INFO - [mozharness: 2017-03-24 22:13:49.806134Z] Finished create-virtualenv step (success) 15:13:49 INFO - [mozharness: 2017-03-24 22:13:49.807040Z] Running install step. 15:13:49 INFO - Running pre-action listener: _resource_record_pre_action 15:13:49 INFO - Running main action method: install 15:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:13:50 INFO - Reading from file tmpfile_stdout 15:13:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:13:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:13:50 INFO - Reading from file tmpfile_stdout 15:13:50 INFO - Output received: 15:13:50 INFO - Usage: mozinstall [options] installer 15:13:50 INFO - Options: 15:13:50 INFO - -h, --help show this help message and exit 15:13:50 INFO - -d DEST, --destination=DEST 15:13:50 INFO - Directory to install application into. [default: 15:13:50 INFO - "/builds/slave/test"] 15:13:50 INFO - --app=APP Application being installed. [default: firefox] 15:13:50 INFO - mkdir: /builds/slave/test/build/application 15:13:50 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'] 15:13:50 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 15:14:10 INFO - Reading from file tmpfile_stdout 15:14:10 INFO - Output received: 15:14:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:14:10 INFO - Running post-action listener: _resource_record_post_action 15:14:10 INFO - [mozharness: 2017-03-24 22:14:10.903114Z] Finished install step (success) 15:14:10 INFO - [mozharness: 2017-03-24 22:14:10.903253Z] Running run-tests step. 15:14:10 INFO - Running pre-action listener: _resource_record_pre_action 15:14:10 INFO - Running main action method: run_tests 15:14:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:14:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:14:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:14:10 INFO - 2017-03-24 15:14:10.917 screenresolution[1635:9470] starting screenresolution argv=screenresolution get 15:14:10 INFO - 2017-03-24 15:14:10.920 screenresolution[1635:9470] Display 0: 1600x1200x32@60 15:14:10 INFO - 2017-03-24 15:14:10.926 screenresolution[1636:9473] starting screenresolution argv=screenresolution list 15:14:10 INFO - Available Modes on Display 0 15:14:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:14:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:14:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:14:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:14:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:14:11 INFO - Intel Iris: 15:14:11 INFO - Chipset Model: Intel Iris 15:14:11 INFO - Type: GPU 15:14:11 INFO - Bus: Built-In 15:14:11 INFO - VRAM (Dynamic, Max): 1536 MB 15:14:11 INFO - Vendor: Intel (0x8086) 15:14:11 INFO - Device ID: 0x0a2e 15:14:11 INFO - Revision ID: 0x0009 15:14:11 INFO - Displays: 15:14:11 INFO - Display: 15:14:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:14:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:14:11 INFO - Display Serial Number: 1600x1200 60 15:14:11 INFO - Main Display: Yes 15:14:11 INFO - Mirror: Off 15:14:11 INFO - Online: Yes 15:14:11 INFO - Rotation: Supported 15:14:11 INFO - Return code: 0 15:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:14:11 INFO - Python 2.7.3 15:14:11 INFO - Return code: 0 15:14:11 INFO - grabbing minidump binary from tooltool 15:14:11 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')]} 15:14:11 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 0x10bcced30>, '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 0x10bbf5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:14:11 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 15:14:11 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 15:14:11 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 15:14:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:14:11 INFO - Return code: 0 15:14:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:14:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:14:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:14:11 INFO - ENV: RUST_BACKTRACE is now 1 15:14:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:14:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:14:11 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/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 15:14:11 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/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 15:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32kKsYRw80/Render', 15:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:11 INFO - 'HOME': '/Users/cltbld', 15:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:11 INFO - 'LOGNAME': 'cltbld', 15:14:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:14:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:11 INFO - 'MOZ_NO_REMOTE': '1', 15:14:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:14:11 INFO - 'NO_EM_RESTART': '1', 15:14:11 INFO - 'PAGER': '/bin/cat', 15:14:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:11 INFO - 'PWD': '/builds/slave/test', 15:14:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:11 INFO - 'RUST_BACKTRACE': '1', 15:14:11 INFO - 'SHELL': '/bin/bash', 15:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m5XStWqtHp/Listeners', 15:14:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 15:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:11 INFO - 'USER': 'cltbld', 15:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:11 INFO - 'XPC_FLAGS': '0x0', 15:14:11 INFO - 'XPC_SERVICE_NAME': '0', 15:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:11 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/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 15:14:11 INFO - mozversion application_buildid: 20170324135417 15:14:11 INFO - mozversion application_changeset: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 15:14:11 INFO - mozversion application_display_name: Nightly 15:14:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:14:11 INFO - mozversion application_name: Firefox 15:14:11 INFO - mozversion application_remotingname: firefox 15:14:11 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 15:14:11 INFO - mozversion application_vendor: Mozilla 15:14:11 INFO - mozversion application_version: 55.0a1 15:14:11 INFO - mozversion platform_buildid: 20170324135417 15:14:11 INFO - mozversion platform_changeset: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 15:14:11 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 15:14:11 INFO - mozversion platform_version: 55.0a1 15:14:11 INFO - using testdate: 1490393651 15:14:11 INFO - actual date: 1490393651 15:14:11 INFO - starting webserver on 'localhost:49244' 15:14:11 INFO - SUITE-START | Running 2 tests 15:14:11 INFO - TEST-START | dromaeo_css 15:14:11 INFO - operating with platform_type : mac_ 15:14:11 INFO - Initialising browser for dromaeo_css test... 15:14:11 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpNnGzxI/profile http://localhost:49244/getInfo.html) 15:14:14 INFO - PID 1646 | 2017-03-24 15:14:14.565 plugin-container[1648:9652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 15:14:14 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:14 INFO - PID 1646 | 2017-03-24 15:14:14.568 plugin-container[1648:9652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 15:14:14 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:14 INFO - PID 1646 | 2017-03-24 15:14:14.568 plugin-container[1648:9652] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:14:14 INFO - PID 1646 | __metrics Screen width/height:1600/1200 15:14:14 INFO - PID 1646 | colorDepth:24 15:14:14 INFO - PID 1646 | Browser inner width/height: 1024/689 15:14:14 INFO - PID 1646 | __metrics 15:14:14 ERROR - PID 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 15:14:15 INFO - TEST-INFO | 1646: exit 0 15:14:15 INFO - Browser initialized. 15:14:15 INFO - Running cycle 1/1 for dromaeo_css test... 15:14:15 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpNnGzxI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:18 INFO - PID 1650 | 2017-03-24 15:14:18.101 plugin-container[1651:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 15:14:18 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:18 INFO - PID 1650 | 2017-03-24 15:14:18.102 plugin-container[1651:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 15:14:18 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:18 INFO - PID 1650 | 2017-03-24 15:14:18.102 plugin-container[1651:9787] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:14:27 INFO - PID 1650 | RSS: Main: 228741120 15:14:27 INFO - PID 1650 | 15:17:31 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 15:17:33 INFO - PID 1650 | RSS: Main: 252121088 15:17:33 INFO - PID 1650 | 15:19:16 INFO - PID 1650 | ************************* 15:19:16 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 15:19:16 INFO - PID 1650 | 15:19:16 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:19:16 INFO - PID 1650 | Full stack: 15:19:16 INFO - PID 1650 | ************************* 15:20:35 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 15:20:36 INFO - PID 1650 | RSS: Main: 262311936 15:20:36 INFO - PID 1650 | 15:23:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 15:23:38 INFO - PID 1650 | RSS: Main: 273367040 15:23:38 INFO - PID 1650 | 15:26:40 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 15:26:41 INFO - PID 1650 | RSS: Main: 265682944 15:26:41 INFO - PID 1650 | 15:29:44 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 15:29:44 INFO - PID 1650 | RSS: Main: 275927040 15:29:44 INFO - PID 1650 | 15:32:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 15:32:48 INFO - PID 1650 | RSS: Main: 269602816 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | __start_tp_report 15:32:48 INFO - PID 1650 | _x_x_mozilla_page_load 15:32:48 INFO - PID 1650 | _x_x_mozilla_page_load_details 15:32:48 INFO - PID 1650 | |i|pagename|runs| 15:32:48 INFO - PID 1650 | |0;dojo.html;3560;3562.2341196642733;3576.9105772355706;3605.314990151871;3616.855325786968;6136.263648362203;6142.938570614292;6151.231096112986;6194.411530904568;6213.533984951124;6103.883205879434;6143.726821076208;6153.353897840718;6161.199044124269;6197.318294987548;5582.91625625616;5594.49649531542;5626.803061892834;5660.518855897254;5735.139729040657;5507.5593952483905;5605.943940560604;5712.771489140429;5718.199452076692;5796.30444346679;5493.807716729935;5518.862028449321;5524.226608274845;5615.129654903497;5666.065099258617;5529.060059789846;5540.058190107691;5598.496135347838;5642.4357564243655;5646.20953066571;5464.7540860660965;5556.138798486241;5580.107182850725;5586.608937374344;5601.327840659106;5994.700264986734;6187.535934804908;6194.132821405007;6194.349593292684;6217.285012223607;5970.850728731773;6217.067439884008;6373.139626150457;6446.484281257488;6625.801225963229;6754.3921047105505;6765.763198288086;6770.8307292317595;6774.695138718769;6776.186857577122;6126.754929802758;6256.937430625681;6257.124002639634;6266.624002559861;6273.309935907047;6640;6711.261761206263;6833.043373927654;6888.896666550006;6951.0616066830335;4918.336024636628;5860.560457965585;6367.490600752021;6462.48300135988;6479.352064793483;6683.899741503881;6733.9326606734285;6752.898706519406;6753.763618273337;6769.695363708645;5658.519025882763;6534.052562378523;6668.966655166693;6693.564918280394;6704.463642908556;6419.422251997249;6511.967440162769;6513.934860651333;6688.431483323972;6724.125863637696;5263.026340127089;5280.155175171954;5284.39229488606;5291.1798671206025;5297;4705.811767529261;4764.118638051971;4788.018456216468;4791.784369703371;4817.662763606514;6291.716872740738;6319.652419116901;6341.714622841983;6348.1430006949395;6355.110284560165;6466.385693359123;6509.218893732687;6785.898211526831;6800.897986530206;6814.557053791586;6237.719302631393;6245.250569931546;6299.748010079637;6305.211848518935;6308.432241098323;5911.9113213301835;5918.348981612019;5998.1602575639445;6010.188624535632;6037.849053773691;6607.5044371673275;6914.377706006484;6930.8613827724175;6939.340762627542;6940.375366217065;6579.80260592183;6682;6685.39831415175;6756.121704178622;6760.695768690519;6876;6914.861702765816;6916.861662766817;6920.377166055079;6929.618870962146;6067.089936509559;6593.943990898145;6594.142761440982;6599.769008084886;6613.636250006296;6450.0969864218105;6471.417572418505;6530.869382612416;6654.73381064752;6676.566023208476;5712.942870571241;6502.837429064311;6538.574992625464;6551.80344589663;6563.343665633399;6261.812145635638;6277.435030847246;6467.935320646733;6509.479241660751;6520.445762110274;5268.761840967293;5727.570432217517;6309.905351419824;6401.167848179707;6403.551751377545;6505.8698826024165;6510.479161666961;6510.934890651033;6514.511411644051;6515.250746164252;6182.598131121643;6224.502039836711;6362.395572420613;6362.586431882098;6380.649064301508 15:32:48 INFO - PID 1650 | |1;ext.html;736;762.5462849604486;827.4084029918608;866.5623859950724;1119.1829964126182;29076;30301.696983030164;31568;34444.44999975001;36714.08214794628;40043.998900027516;40401.797991010004;40496.98757531063;40517.79741101298;40683.38974915371;8453.281471074935;8644.827103457927;8753.649854005864;8785.121487851227;8828.823423531525;8343.99872015359;8761.912380876174;8878.73363799087;8882.644694212224;8888.333374996868;8368.456050356737;8880.333974951873;8923.241524470428;8930.419522731001;8931.642734290654;8856.468611883276;8879;8893.466392016435;8906.955465222698;8925.642974281021;8583.785405364853;8631.309495240366;8634.309255259564;8639.222469977669;8640.135986401372;38414.80792596047;38557.4577016919;38796.612033879865;38814.805925970475;38824;2584.870756462188;2591.2226332100295;2940.7500362469214;2982.8508574571183;2983.1796256029547;23527.945231434926;27429.17712468629;27557.31106722348;27863.442731145467;28747.850085996743;2351.2240960483173;2678.7723043541323;2681.865906704657;2707.945841083167;2760.4617099665575;221269.89365053232;229693;231597.84201079054;312609;312773.87226127676;44584;44834.55165448304;44856.551434485234;44883.775581122536;44962.77518612386;51039.74480127576;51341.22988155181;51416.97166056658;51457.48542514602;51468.22797658038;14149.221792801289;14279.71482566574;14288.928555357157;14294.927880409012;14312.427502899976;11657.358845263592;11668.824967625493;11673.299602023906;11678.299302041905;11679.649610511698;11175.664730058112;13464.316960379952;13529.59411217665;13592.524261650795;13652.453901843815;5219.634625576249;5898.321693005273;6013.368596297413;6055.818325450244;6064.9090263646085;982.9361091529028;996.2776986684596;997.6907158780801;1002.3885429887763;1040.2250323509033;24418.87790561036;24532.877335613208;24659.01363945422;24821.87589062079;24930.750692492842;906.6328137104483;1023.2325755683238;1105.8672959244782;1133.3426612564858;1148.7472755993667;10519.948005199572;12151.534685377206;12538.373081345897;12621.684457888627;12652.493900244071;433622;435740;436782;436941;437102;54651.180232296545;54947.450525495035;54966;55131;55190.72404638032;1488.78412630168;1494.5516345096646;1527.0684882221835;1529.9541013769606;1546.327347603796;24007.51984960326;27655.72344276612;27940.161795146178;29168.833246669896;29234.853825730734;44140.7792961046;44215.55784442114;44388;44626.77686611546;44899.551004489535;390627;391044.2238944049;391606.0419697883;393797;395176;31909.7236110553;32177.839110804296;32492.26338970954;32519.02442926716;32532.02403927886;40608.1878362437;40728.79635601803;41274.38088428616;41907;42160.36759448549;24353.634695479937;28124.71875281221;28603;29407.55888661629;29442.11673649794;28765.568516471852;28815.567766483102;29452.705472944996;29576.704232958255;29758.404831903674;34728;34737.82631086929;35518.11204719903;36396;36761.08097297589;22765.430864228525;28674.283142921595;29896.25259368533;30732.84633576818;31127;22383.32850014502;23999.400014999766;24279.635805462578;24336.269911902673;24925.002999880526 15:32:48 INFO - PID 1650 | |2;jquery.html;383.55316056793816;385.38531042986426;416.9638448455589;434.59799685291097;443.58081612875833;19938.90030549847;21052;22282.55434891301;22394.88802555989;22601.773982260172;22090.226842060492;22391.776082239172;23163;23354.532909341804;23529.411764705892;6108.992016317302;6293;6314.116023756677;6324.683765811714;6326.051092336141;6097.634141951475;6140.938590614103;6141.907871381933;6261.624302541862;6272.404121608462;6034.155218269446;6181.134641150243;6205.255369355684;6231.501479881621;6247.187865577492;5726.740117174215;6161.568690191689;6265.185525881652;6276.968615156918;6279;4711.670183087185;5374.892502149935;5385.192221166817;5560.610757246995;5622.746976386073;25881.352966175808;27102.5934610981;28234.2941426466;28362.858185709145;28576.857115714287;1725.568607848038;1971.3888694504749;2003.3989803059023;2025.7569091708942;2077.4079387374677;14398.70411662945;15078.5476435706;17143;17405.477835664824;17795.911020444946;1702.7445883117507;1715.1167148918316;1724.284421964883;1740.1386313774763;1748.4109854860149;118959.62080758423;140723;143360;163513;170470;12442.875571244173;12483.31341776211;12614.747705045847;12621.412610414844;12785.936070319774;13458.798118028237;16067.758983615255;16239.918800406158;16328.183590820649;16391.754123688155;7420.295071968154;8703.260222881127;8926.464412135292;8950.86573701395;9072;8354.45696029756;8391.24478796899;8458.619362128718;8605.354598405145;8624.310055195569;6662.966685166543;7924.603769811486;8031.678732850621;8062.516249025077;8108.5945702714625;2189.5949249388595;2201.273579718689;2205.2391924785925;2206.2388475975763;2369.1707902234425;800.7597720683888;860.5654144656908;901.4005686218591;914.7530166854912;920.7882187096872;20597.07313170911;21118.894405527873;21230.256941006992;21489.247876324567;21553.461163471038;943.8631398447324;948.7912659214961;984.5372674842812;984.6947446291673;988.6539711101057;8558;10033.749156271151;10693.41186234749;10835.079018283377;10858.565657373774;358355.624665625;368435;387575;395733;719395;154271.2286438583;156289;157979;160331.19834400754;163658;1116.9218154729092;1142.0806250967885;1156.0000599481389;1156.444906444911;1164.6680696001768;14078.296085195494;14839;14896.776548351567;16727.581810454834;18524.72212916834;61205.69397152986;61671;61742.38257617546;62160.68919655373;62704;81837.59081204556;82553.3489330198;83167.75248371401;83381;85410.57294713486;8472.49165050099;8673.436226645248;8854.158854908774;8863.92753925467;8992.86510702353;12573.245605263714;13308.733825323632;13494.920406367663;13817.79273310881;13843.584692459242;11216.439178041228;11483.425828708698;11587.88412115868;11846.881531184577;11892.46483908209;9029.322800790098;10290.3825770454;10292.279540432397;10359.896401035892;10593.470326483799;8898.932128144665;9785.657501987396;11594.536218551164;11848.940755296167;11966.64100076999;7672.846543068996;8446.282066024456;8605.95697021511;8707.825843483222;8887.555622218733;9391.779068721296;10169.186465082628;11268.605598804003;11378.829317560409;11733.589324373606 15:32:48 INFO - PID 1650 | |3;mootools.html;1065.5897479470405;1360.7958806179079;1361.945522179113;1370.4312710225256;1410.5909286306971;10437.112845408139;10589.311694739836;10953.780924381508;11001.22991390602;11145.832812507819;10533.104686101671;10583.259171857973;10779.78440431191;11020.50407731652;11051.502682379292;2414.843035202715;2457.324235835151;2458.323960910755;2458.7172475165316;2460.2127319257843;2395.0863994357583;2403.194929698553;2427.490227052312;2454.226918520669;2455.042533411971;2376.0495801679294;2433.756624337561;2441.2676197140877;2448.4735781806908;2449.1183174057323;2332.3119679694532;2407.3981504623844;2416.565018296697;2420.491696743686;2427.538767634144;1905.5712464695332;2410.3853517353136;2410.710714714228;2410.843295185807;2420.8668523231217;10834;10950.890491095146;10961.780764384748;11133.10935125188;11146.331220126738;6206.410391012847;6444.420002199825;6449.322821103763;6453.741850325981;6458.93541064588;3924.725269231155;3940.5271367435803;3944.1322908959983;3969.64273215412;3988.102676897704;1173.4719376280707;1301.3623324570988;1399.2793711238721;1450.2821103553704;1572.6304318485147;11540.596079137275;11926.224795388358;14891.77662335055;15020.399184032625;15201;5232.3459567554055;5262.684238945675;5378.677279363251;5385.488378604026;5389.487998640123;10240.795184096276;11310.773784524428;11364.602238921598;11394.430278486043;11408.657740267805;6044.818655440344;6167.105769663373;6237.500999919996;6245.188125543741;6253.6560489172625;5761.48146666802;5768.625039372428;5812.447817457335;5840.123981402823;5879.647221166743;4012.017055821284;4200.474940632421;4211.157768446262;4243.575642435793;4256.893577660583;1227.7605866856056;1261.1991385470292;1272.6945533071996;1273.2615083251692;1273.5733529267613;636.4812677667686;714.9177844547839;742.9925700743031;752.9360004399688;772.274062381371;5805.970970145122;6160.815175544741;6724.125863637696;6841.589504629738;6882.8967565486555;646.6217262901256;728.3990707666196;859.5229647545672;871.4335681806749;889.9688510902088;3778.3387907116144;3864.594217607167;4011.3782363733667;4121.1345617420075;4174.624283814472;11598.826017609743;13843.30783460823;14182.574522764333;14203.857961420461;14207.71584568303;13821.516246931511;13923.373448194856;14099.013069085271;14100.717985640435;14110.082844615066;8127.837443250984;10274.486275686186;10403.42781147029;10490.265681402228;10523.421211833424;5334.9466505336;5793.391693872083;6275.52933529978;6578.177727784027;6605.735770569124;11979.580714674945;12304.323262220305;12437.689057773629;12497.750044999231;12518.812217816921;10561.89438105609;12457.626271211879;12479.750404991668;12629.305388203737;12706.555270565486;9098.181163695299;9159.404638698463;9193.126652967958;9214.308926830381;9236.214921731727;10024.14794742455;10068.69793906184;10113.039261270167;10260.948695256775;10261.692149235534;8519.27586155159;8580.528070956158;8600.39797214214;8638.049814520524;8805.69180078694;8358.665653373797;8393.454425462325;8424.536650484282;8443.620037098222;8629.913700863162;7641.617919103911;7786.092407360233;7792.766217013499;7806.0632724073475;7812.843743124992;5174.172132458789;5388.110961691277;5510.91733623988;5591.832245032656;5615.494605485526;4993.450720420678;5945.970270148621;6020.789272375446;6035.6076855004285;6085.117657939662 15:32:48 INFO - PID 1650 | |4;prototype.html;314.8787716729059;385.691446842526;398.17974971558573;400.3194569232306;411.51852332770653;5564.86087847804;5629.183768353591;6317.0208617664275;6424.293327733945;6449.322821103787;5314.9468505314935;6085.513158947285;6266.811995640127;6327.810165695037;6347.206599175103;4871.634627402941;4890.682105663121;4893;4897.33885925399;4994.825181118662;4068.613481719247;4896.706197628136;4906.9263961040615;4908.950910490885;4909.582685471722;4681.742504162286;4815.855524334258;4848.61814382901;4853.587445067174;4888.071266459379;4247.638950689196;4819.0602832447685;4850.520591219676;4855.198892182786;4860.8055677772845;4286.314189729628;4305.63402110821;4314.9784251078545;4322.848700295506;4329.025969156946;5960.821175364746;6207.596506227081;6211.534134939898;6224.439800417984;6286.748530058792;4728.621710263205;4979.278004689299;5659.5755318350875;5664.235328230678;5674.404187560329;6584.242812076577;6749.325067493261;6800.6939687714175;6805.319468053155;6811.216710078305;4725.1730947084125;4779.904401911942;4792.808287668524;4802.7118372897385;4837.7097374157665;5903.970480147615;6118.877622447526;6871.9656401718175;7150.427965762727;7177.49757516974;4283.657307415461;4386.692931494765;4754.976225118921;4804.567588917014;4844.103840789465;4205.2640787862;4240.385144154142;4393.956060439418;4424.225760491901;4885.316055752198;5048.8892443662335;5459.3994660587305;5631.802886898939;5696.515796157373;5700.201971723962;6223.222097237845;6275.497960163176;6776.898346524806;6806.625635589991;6823.795286141423;1560;1728.5764987577872;1732.6139088728967;1735.1324337831084;1736.7568540404354;1157.65270418874;1184.5617121664866;1185.6798664360747;1186.5669030803867;1197.167968262067;6292.968535157295;6459.222534310292;6473.708683109271;6485.643289619116;6497.350264973465;6754.76358327466;6966.317967582518;7046.260142685047;7080.858382832417;7134.857302853914;5475.247920665349;5791.305043394819;6464.385883341075;6485;6516.250631177475;3099.4266060778828;3539.4867744177463;3699.519062521909;3718.981405092957;3728.2729867676085;5400.729963501888;6648.66756662165;6799.728010879509;7134;7210.603416812126;7098.8348553690585;7180.676869540883;7214.6031968241205;7277.743899391206;7292.598907060163;6726.226483954212;6877.415419689382;7250;7322.524035937647;7344.155422126524;6524.510661700487;7115.67979440916;7138.428925685829;7149.927510873411;7175.390091842043;6275.497960163085;6737.494687898329;6942.583444993316;6986.742386370502;7033.437325014089;5655.578375083015;6124.601900876429;6906.8618627626165;7200.84786434168;7401.777946661608;4536.342230376511;4548.658850586286;4559.931601025921;4567.1779079765965;4567.314902764612;6793.320667933167;7077.6461176941975;7085.574865508086;7100.680469378787;7124.928750712426;5728.79949201776;6409.7115629795835;6834.350736680008;6846.315368463114;6858.794236172923;5459.426760190123;6499.707513161825;6783.084283621648;6834.179898412222;6846.486513511606;6650.401463868275;6683.532152749261;6695.12963314766;6698.497612678971;6734.831629209309;6519.641419721961;6789.132988428221;6825.112735344572;6841.589504629738;6856.680291416375;6561.770338038145;6643.036759669917;6673.532852700265;6676.6995485202315;6680.899786503109 15:32:48 INFO - PID 1650 | |5;yui.html;598.114790110636;626.2672672972623;675.5878913862543;681.5331497923922;709.6487238816786;15296.08223506589;15490.225488725548;15963.92018039911;16124.59688507785;16128;14880.404783808663;15020.399184032653;15063;15398.846011539881;15403.306851191695;664.0669858848302;704.6265479295981;775.3835700618008;940.2243149401737;948.3456415073578;923.1599244687336;957.9952100239524;959.6593209410663;960.8943016268205;962.537981768748;1017.5370206556037;1017.8574999500075;1018.1803648063343;1018.9235807314444;1020.3367810922886;986.0041358228169;1007.566746299091;1008.2337423558075;1009.142229105262;1010.4998025977152;748.2554857916354;750.6884642873201;752.9096508419025;753.2279413601048;754.3248792330888;16266;16560.50318490447;16686;16765.24556394953;16956.660866782597;14437.566872993826;14546.345414456375;14602.988979624184;15037.022593531496;15068.246587670683;10404;11714.355710435922;11910.940445297718;12013.339266340348;12212.206206596631;2935.7064293570684;2959.940801183986;2984.179350678559;2993.2516870782306;3086.42901063304;47999.80000499981;64719;82084.58957705233;107034.46482767614;110254;11034.503447344889;11368.317900925971;11387.886121138848;11618.76762464746;12140.575079872162;19228.519287017938;23890.163844265368;24358.26925192247;24358.87820560886;24504;7053.047838541834;7970.800729981681;8053.315468185153;8143.51138931666;8348.123447038095;3847.8653247136217;4159.873132294261;4175.791210439465;4210.305299625588;4241.448611680462;4033.1731994940965;4096.221717873654;4096.262672718939;4149.066460046465;4150.983009162714;713.7038129176334;803.6704950970075;810.8054067023871;818.4802650317091;818.6193420059701;542.1894268069183;546.1834557336728;563.5013013483097;579.6464156864309;606.3784620763753;8889.688680942872;9973.360471729135;10045;10505.369677819355;10513.474326283655;551.3852054958772;585.7891159182691;595.9731812068468;607.3319348716377;613.7667686279275;4740.976295118571;4860.465348811628;4871.196252618278;4882.511748825089;4909.729964851896;92630.53684731534;99523;103513;104861.95138048675;107480;77082.61458692783;77319.61340193263;91459.54270228607;92461.07538924525;95171.09657806806;57511.71244143753;78307;79192.60403697945;79459.60270198727;79515.60242198753;8841.823163536821;9360.391574547904;9427.764305892133;9457.810843782949;9543.618255269712;35648.4652730204;35801.64198358087;36442;36702.63297367099;37962.0509487265;55176.448235518736;63241.051384230195;67097.32902671106;67857.32142678708;71238;11554.728472339759;11571.305721656727;11651.941740291244;11661.941690291495;11791;11685.76628467443;11753.275870659296;11858.940705296418;11965.341906195243;12324.753504929671;11629;11648.94175529117;11652.941735291608;11749.647510574696;12033.939830300793;11388.697535422536;11567.305961642329;11597.478113485082;11748.706282342668;12196.817047744113;10522.894771052192;10959.01368876805;11329.619303644648;11461.942690286494;11462.369569673414;8526.360522960678;8608.956955215184;8628.654853806029;8724.607392667218;9048.27613790882;8416.579171041301;9390.81218375615;9632.422054676741;9698.175655069399;9708.466034368177 15:32:48 INFO - PID 1650 | __end_tp_report 15:32:48 INFO - PID 1650 | __start_cc_report 15:32:48 INFO - PID 1650 | _x_x_mozilla_cycle_collect,429 15:32:48 INFO - PID 1650 | __end_cc_report 15:32:48 INFO - PID 1650 | __startTimestamp1490394768060__endTimestamp 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | ------- Summary: start ------- 15:32:48 INFO - PID 1650 | Number of tests: 6 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6135.00 Median:6305.21 stddev:689.28 (10.9%) stddev-sans-first:661.13 15:32:48 INFO - PID 1650 | Values: 3560.0 3562.2 3576.9 3605.3 3616.9 6136.3 6142.9 6151.2 6194.4 6213.5 6103.9 6143.7 6153.4 6161.2 6197.3 5582.9 5594.5 5626.8 5660.5 5735.1 5507.6 5605.9 5712.8 5718.2 5796.3 5493.8 5518.9 5524.2 5615.1 5666.1 5529.1 5540.1 5598.5 5642.4 5646.2 5464.8 5556.1 5580.1 5586.6 5601.3 5994.7 6187.5 6194.1 6194.3 6217.3 5970.9 6217.1 6373.1 6446.5 6625.8 6754.4 6765.8 6770.8 6774.7 6776.2 6126.8 6256.9 6257.1 6266.6 6273.3 6640.0 6711.3 6833.0 6888.9 6951.1 4918.3 5860.6 6367.5 6462.5 6479.4 6683.9 6733.9 6752.9 6753.8 6769.7 5658.5 6534.1 6669.0 6693.6 6704.5 6419.4 6512.0 6513.9 6688.4 6724.1 5263.0 5280.2 5284.4 5291.2 5297.0 4705.8 4764.1 4788.0 4791.8 4817.7 6291.7 6319.7 6341.7 6348.1 6355.1 6466.4 6509.2 6785.9 6800.9 6814.6 6237.7 6245.3 6299.7 6305.2 6308.4 5911.9 5918.3 5998.2 6010.2 6037.8 6607.5 6914.4 6930.9 6939.3 6940.4 6579.8 6682.0 6685.4 6756.1 6760.7 6876.0 6914.9 6916.9 6920.4 6929.6 6067.1 6593.9 6594.1 6599.8 6613.6 6450.1 6471.4 6530.9 6654.7 6676.6 5712.9 6502.8 6538.6 6551.8 6563.3 6261.8 6277.4 6467.9 6509.5 6520.4 5268.8 5727.6 6309.9 6401.2 6403.6 6505.9 6510.5 6510.9 6514.5 6515.3 6182.6 6224.5 6362.4 6362.6 6380.6 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:50175.46 Median:34586.22 stddev:98566.74 (285.0%) stddev-sans-first:98773.35 15:32:48 INFO - PID 1650 | Values: 736.0 762.5 827.4 866.6 1119.2 29076.0 30301.7 31568.0 34444.4 36714.1 40044.0 40401.8 40497.0 40517.8 40683.4 8453.3 8644.8 8753.6 8785.1 8828.8 8344.0 8761.9 8878.7 8882.6 8888.3 8368.5 8880.3 8923.2 8930.4 8931.6 8856.5 8879.0 8893.5 8907.0 8925.6 8583.8 8631.3 8634.3 8639.2 8640.1 38414.8 38557.5 38796.6 38814.8 38824.0 2584.9 2591.2 2940.8 2982.9 2983.2 23527.9 27429.2 27557.3 27863.4 28747.9 2351.2 2678.8 2681.9 2707.9 2760.5 221269.9 229693.0 231597.8 312609.0 312773.9 44584.0 44834.6 44856.6 44883.8 44962.8 51039.7 51341.2 51417.0 51457.5 51468.2 14149.2 14279.7 14288.9 14294.9 14312.4 11657.4 11668.8 11673.3 11678.3 11679.6 11175.7 13464.3 13529.6 13592.5 13652.5 5219.6 5898.3 6013.4 6055.8 6064.9 982.9 996.3 997.7 1002.4 1040.2 24418.9 24532.9 24659.0 24821.9 24930.8 906.6 1023.2 1105.9 1133.3 1148.7 10519.9 12151.5 12538.4 12621.7 12652.5 433622.0 435740.0 436782.0 436941.0 437102.0 54651.2 54947.5 54966.0 55131.0 55190.7 1488.8 1494.6 1527.1 1530.0 1546.3 24007.5 27655.7 27940.2 29168.8 29234.9 44140.8 44215.6 44388.0 44626.8 44899.6 390627.0 391044.2 391606.0 393797.0 395176.0 31909.7 32177.8 32492.3 32519.0 32532.0 40608.2 40728.8 41274.4 41907.0 42160.4 24353.6 28124.7 28603.0 29407.6 29442.1 28765.6 28815.6 29452.7 29576.7 29758.4 34728.0 34737.8 35518.1 36396.0 36761.1 22765.4 28674.3 29896.3 30732.8 31127.0 22383.3 23999.4 24279.6 24336.3 24925.0 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:33313.73 Median:26491.97 stddev:81904.62 (309.2%) stddev-sans-first:82097.06 15:32:48 INFO - PID 1650 | Values: 383.6 385.4 417.0 434.6 443.6 19938.9 21052.0 22282.6 22394.9 22601.8 22090.2 22391.8 23163.0 23354.5 23529.4 6109.0 6293.0 6314.1 6324.7 6326.1 6097.6 6140.9 6141.9 6261.6 6272.4 6034.2 6181.1 6205.3 6231.5 6247.2 5726.7 6161.6 6265.2 6277.0 6279.0 4711.7 5374.9 5385.2 5560.6 5622.7 25881.4 27102.6 28234.3 28362.9 28576.9 1725.6 1971.4 2003.4 2025.8 2077.4 14398.7 15078.5 17143.0 17405.5 17795.9 1702.7 1715.1 1724.3 1740.1 1748.4 118959.6 140723.0 143360.0 163513.0 170470.0 12442.9 12483.3 12614.7 12621.4 12785.9 13458.8 16067.8 16239.9 16328.2 16391.8 7420.3 8703.3 8926.5 8950.9 9072.0 8354.5 8391.2 8458.6 8605.4 8624.3 6663.0 7924.6 8031.7 8062.5 8108.6 2189.6 2201.3 2205.2 2206.2 2369.2 800.8 860.6 901.4 914.8 920.8 20597.1 21118.9 21230.3 21489.2 21553.5 943.9 948.8 984.5 984.7 988.7 8558.0 10033.7 10693.4 10835.1 10858.6 358355.6 368435.0 387575.0 395733.0 719395.0 154271.2 156289.0 157979.0 160331.2 163658.0 1116.9 1142.1 1156.0 1156.4 1164.7 14078.3 14839.0 14896.8 16727.6 18524.7 61205.7 61671.0 61742.4 62160.7 62704.0 81837.6 82553.3 83167.8 83381.0 85410.6 8472.5 8673.4 8854.2 8863.9 8992.9 12573.2 13308.7 13494.9 13817.8 13843.6 11216.4 11483.4 11587.9 11846.9 11892.5 9029.3 10290.4 10292.3 10359.9 10593.5 8898.9 9785.7 11594.5 11848.9 11966.6 7672.8 8446.3 8606.0 8707.8 8887.6 9391.8 10169.2 11268.6 11378.8 11733.6 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:6694.07 Median:2457.82 stddev:4107.00 (167.1%) stddev-sans-first:4096.74 15:32:48 INFO - PID 1650 | Values: 1065.6 1360.8 1361.9 1370.4 1410.6 10437.1 10589.3 10953.8 11001.2 11145.8 10533.1 10583.3 10779.8 11020.5 11051.5 2414.8 2457.3 2458.3 2458.7 2460.2 2395.1 2403.2 2427.5 2454.2 2455.0 2376.0 2433.8 2441.3 2448.5 2449.1 2332.3 2407.4 2416.6 2420.5 2427.5 1905.6 2410.4 2410.7 2410.8 2420.9 10834.0 10950.9 10961.8 11133.1 11146.3 6206.4 6444.4 6449.3 6453.7 6458.9 3924.7 3940.5 3944.1 3969.6 3988.1 1173.5 1301.4 1399.3 1450.3 1572.6 11540.6 11926.2 14891.8 15020.4 15201.0 5232.3 5262.7 5378.7 5385.5 5389.5 10240.8 11310.8 11364.6 11394.4 11408.7 6044.8 6167.1 6237.5 6245.2 6253.7 5761.5 5768.6 5812.4 5840.1 5879.6 4012.0 4200.5 4211.2 4243.6 4256.9 1227.8 1261.2 1272.7 1273.3 1273.6 636.5 714.9 743.0 752.9 772.3 5806.0 6160.8 6724.1 6841.6 6882.9 646.6 728.4 859.5 871.4 890.0 3778.3 3864.6 4011.4 4121.1 4174.6 11598.8 13843.3 14182.6 14203.9 14207.7 13821.5 13923.4 14099.0 14100.7 14110.1 8127.8 10274.5 10403.4 10490.3 10523.4 5334.9 5793.4 6275.5 6578.2 6605.7 11979.6 12304.3 12437.7 12497.8 12518.8 10561.9 12457.6 12479.8 12629.3 12706.6 9098.2 9159.4 9193.1 9214.3 9236.2 10024.1 10068.7 10113.0 10260.9 10261.7 8519.3 8580.5 8600.4 8638.0 8805.7 8358.7 8393.5 8424.5 8443.6 8629.9 7641.6 7786.1 7792.8 7806.1 7812.8 5174.2 5388.1 5510.9 5591.8 5615.5 4993.5 5946.0 6020.8 6035.6 6085.1 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5528.81 Median:6217.38 stddev:1691.42 (27.2%) stddev-sans-first:1650.27 15:32:48 INFO - PID 1650 | Values: 314.9 385.7 398.2 400.3 411.5 5564.9 5629.2 6317.0 6424.3 6449.3 5314.9 6085.5 6266.8 6327.8 6347.2 4871.6 4890.7 4893.0 4897.3 4994.8 4068.6 4896.7 4906.9 4909.0 4909.6 4681.7 4815.9 4848.6 4853.6 4888.1 4247.6 4819.1 4850.5 4855.2 4860.8 4286.3 4305.6 4315.0 4322.8 4329.0 5960.8 6207.6 6211.5 6224.4 6286.7 4728.6 4979.3 5659.6 5664.2 5674.4 6584.2 6749.3 6800.7 6805.3 6811.2 4725.2 4779.9 4792.8 4802.7 4837.7 5904.0 6118.9 6872.0 7150.4 7177.5 4283.7 4386.7 4755.0 4804.6 4844.1 4205.3 4240.4 4394.0 4424.2 4885.3 5048.9 5459.4 5631.8 5696.5 5700.2 6223.2 6275.5 6776.9 6806.6 6823.8 1560.0 1728.6 1732.6 1735.1 1736.8 1157.7 1184.6 1185.7 1186.6 1197.2 6293.0 6459.2 6473.7 6485.6 6497.4 6754.8 6966.3 7046.3 7080.9 7134.9 5475.2 5791.3 6464.4 6485.0 6516.3 3099.4 3539.5 3699.5 3719.0 3728.3 5400.7 6648.7 6799.7 7134.0 7210.6 7098.8 7180.7 7214.6 7277.7 7292.6 6726.2 6877.4 7250.0 7322.5 7344.2 6524.5 7115.7 7138.4 7149.9 7175.4 6275.5 6737.5 6942.6 6986.7 7033.4 5655.6 6124.6 6906.9 7200.8 7401.8 4536.3 4548.7 4559.9 4567.2 4567.3 6793.3 7077.6 7085.6 7100.7 7124.9 5728.8 6409.7 6834.4 6846.3 6858.8 5459.4 6499.7 6783.1 6834.2 6846.5 6650.4 6683.5 6695.1 6698.5 6734.8 6519.6 6789.1 6825.1 6841.6 6856.7 6561.8 6643.0 6673.5 6676.7 6680.9 15:32:48 INFO - PID 1650 | 15:32:48 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:18955.39 Median:4122.66 stddev:27238.40 (660.7%) stddev-sans-first:27279.93 15:32:48 INFO - PID 1650 | Values: 598.1 626.3 675.6 681.5 709.6 15296.1 15490.2 15963.9 16124.6 16128.0 14880.4 15020.4 15063.0 15398.8 15403.3 664.1 704.6 775.4 940.2 948.3 923.2 958.0 959.7 960.9 962.5 1017.5 1017.9 1018.2 1018.9 1020.3 986.0 1007.6 1008.2 1009.1 1010.5 748.3 750.7 752.9 753.2 754.3 16266.0 16560.5 16686.0 16765.2 16956.7 14437.6 14546.3 14603.0 15037.0 15068.2 10404.0 11714.4 11910.9 12013.3 12212.2 2935.7 2959.9 2984.2 2993.3 3086.4 47999.8 64719.0 82084.6 107034.5 110254.0 11034.5 11368.3 11387.9 11618.8 12140.6 19228.5 23890.2 24358.3 24358.9 24504.0 7053.0 7970.8 8053.3 8143.5 8348.1 3847.9 4159.9 4175.8 4210.3 4241.4 4033.2 4096.2 4096.3 4149.1 4151.0 713.7 803.7 810.8 818.5 818.6 542.2 546.2 563.5 579.6 606.4 8889.7 9973.4 10045.0 10505.4 10513.5 551.4 585.8 596.0 607.3 613.8 4741.0 4860.5 4871.2 4882.5 4909.7 92630.5 99523.0 103513.0 104862.0 107480.0 77082.6 77319.6 91459.5 92461.1 95171.1 57511.7 78307.0 79192.6 79459.6 79515.6 8841.8 9360.4 9427.8 9457.8 9543.6 35648.5 35801.6 36442.0 36702.6 37962.1 55176.4 63241.1 67097.3 67857.3 71238.0 11554.7 11571.3 11651.9 11661.9 11791.0 11685.8 11753.3 11858.9 11965.3 12324.8 11629.0 11648.9 11652.9 11749.6 12033.9 11388.7 11567.3 11597.5 11748.7 12196.8 10522.9 10959.0 11329.6 11461.9 11462.4 8526.4 8609.0 8628.7 8724.6 9048.3 8416.6 9390.8 9632.4 9698.2 9708.5 15:32:48 INFO - PID 1650 | -------- Summary: end -------- 15:32:48 INFO - PID 1650 | 15:32:48 INFO - TEST-INFO | 1650: exit 0 15:32:48 INFO - TEST-OK | dromaeo_css | took 1117272ms 15:32:48 INFO - TEST-START | kraken 15:32:48 INFO - operating with platform_type : mac_ 15:32:48 INFO - Initialising browser for kraken test... 15:32:48 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpLmAbW6/profile http://localhost:49244/getInfo.html) 15:32:51 INFO - PID 1658 | 2017-03-24 15:32:51.672 plugin-container[1659:11969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 15:32:51 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:51 INFO - PID 1658 | 2017-03-24 15:32:51.675 plugin-container[1659:11969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 15:32:51 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:51 INFO - PID 1658 | 2017-03-24 15:32:51.675 plugin-container[1659:11969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:32:51 INFO - PID 1658 | __metrics Screen width/height:1600/1200 15:32:51 INFO - PID 1658 | colorDepth:24 15:32:51 INFO - PID 1658 | Browser inner width/height: 1024/689 15:32:51 INFO - PID 1658 | __metrics 15:32:51 ERROR - PID 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 15:32:52 INFO - TEST-INFO | 1658: exit 0 15:32:52 INFO - Browser initialized. 15:32:52 INFO - Running cycle 1/1 for kraken test... 15:32:52 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpLmAbW6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:32:55 INFO - PID 1660 | 2017-03-24 15:32:55.118 plugin-container[1661:12094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 15:32:55 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:55 INFO - PID 1660 | 2017-03-24 15:32:55.119 plugin-container[1661:12094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 15:32:55 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:32:55 INFO - PID 1660 | 2017-03-24 15:32:55.119 plugin-container[1661:12094] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:33:04 INFO - PID 1660 | RSS: Main: 229048320 15:33:04 INFO - PID 1660 | 15:33:07 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 15:33:08 INFO - PID 1660 | RSS: Main: 243462144 15:33:08 INFO - PID 1660 | 15:33:11 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 15:33:11 INFO - PID 1660 | RSS: Main: 252006400 15:33:11 INFO - PID 1660 | 15:33:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 15:33:15 INFO - PID 1660 | RSS: Main: 254562304 15:33:15 INFO - PID 1660 | 15:33:17 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 15:33:18 INFO - PID 1660 | RSS: Main: 256790528 15:33:18 INFO - PID 1660 | 15:33:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:33:21 INFO - PID 1660 | RSS: Main: 257085440 15:33:21 INFO - PID 1660 | 15:33:24 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 15:33:24 INFO - PID 1660 | RSS: Main: 262782976 15:33:24 INFO - PID 1660 | 15:33:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 15:33:28 INFO - PID 1660 | RSS: Main: 263626752 15:33:28 INFO - PID 1660 | 15:33:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 15:33:32 INFO - PID 1660 | RSS: Main: 263647232 15:33:32 INFO - PID 1660 | 15:33:34 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:33:34 INFO - PID 1660 | RSS: Main: 263401472 15:33:34 INFO - PID 1660 | 15:33:36 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:33:37 INFO - PID 1660 | RSS: Main: 263159808 15:33:37 INFO - PID 1660 | 15:33:39 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:33:40 INFO - PID 1660 | RSS: Main: 263155712 15:33:40 INFO - PID 1660 | 15:33:43 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:33:44 INFO - PID 1660 | RSS: Main: 263569408 15:33:44 INFO - PID 1660 | 15:33:47 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:33:48 INFO - PID 1660 | RSS: Main: 260734976 15:33:48 INFO - PID 1660 | 15:33:49 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 15:33:50 INFO - PID 1660 | RSS: Main: 263729152 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | __start_tp_report 15:33:50 INFO - PID 1660 | _x_x_mozilla_page_load 15:33:50 INFO - PID 1660 | _x_x_mozilla_page_load_details 15:33:50 INFO - PID 1660 | |i|pagename|runs| 15:33:50 INFO - PID 1660 | |0;ai-astar;128;170;120;118;116;115;114;119;115;116 15:33:50 INFO - PID 1660 | |1;audio-beat-detection;145;115;120;157;98;148;148;144;119;133 15:33:50 INFO - PID 1660 | |2;audio-dft;195;208;207;242;272;205;197;199;201;195 15:33:50 INFO - PID 1660 | |3;audio-fft;84;58;61;82;59;58;58;60;78;74 15:33:50 INFO - PID 1660 | |4;audio-oscillator;86;77;120;104;106;102;84;73;89;75 15:33:50 INFO - PID 1660 | |5;imaging-gaussian-blur;91;116;87;136;112;90;90;92;90;90 15:33:50 INFO - PID 1660 | |6;imaging-darkroom;105;150;101;112;116;101;98;98;99;98 15:33:50 INFO - PID 1660 | |7;imaging-desaturate;89;96;143;151;90;92;94;113;143;92 15:33:50 INFO - PID 1660 | |8;json-parse-financial;66;65;66;67;66;67;68;66;69;80 15:33:50 INFO - PID 1660 | |9;json-stringify-tinderbox;56;59;76;88;93;60;56;58;59;59 15:33:50 INFO - PID 1660 | |10;stanford-crypto-aes;85;84;84;73;103;117;77;96;78;73 15:33:50 INFO - PID 1660 | |11;stanford-crypto-ccm;142;138;137;150;153;155;145;145;183;162 15:33:50 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;183;184;188;190;223;235;197;220;234;234 15:33:50 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;63;60;92;94;94;93;98;83;97 15:33:50 INFO - PID 1660 | __end_tp_report 15:33:50 INFO - PID 1660 | __start_cc_report 15:33:50 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1016 15:33:50 INFO - PID 1660 | __end_cc_report 15:33:50 INFO - PID 1660 | __startTimestamp1490394830226__endTimestamp 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | ------- Summary: start ------- 15:33:50 INFO - PID 1660 | Number of tests: 14 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:123.10 Median:118.50 stddev:16.97 (14.3%) stddev-sans-first:17.90 15:33:50 INFO - PID 1660 | Values: 128.0 170.0 120.0 118.0 116.0 115.0 114.0 119.0 115.0 116.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:146.50 stddev:18.87 (12.9%) stddev-sans-first:19.48 15:33:50 INFO - PID 1660 | Values: 145.0 115.0 120.0 157.0 98.0 148.0 148.0 144.0 119.0 133.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:212.10 Median:206.00 stddev:25.13 (12.2%) stddev-sans-first:25.88 15:33:50 INFO - PID 1660 | Values: 195.0 208.0 207.0 242.0 272.0 205.0 197.0 199.0 201.0 195.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:67.20 Median:67.50 stddev:10.93 (16.2%) stddev-sans-first:9.76 15:33:50 INFO - PID 1660 | Values: 84.0 58.0 61.0 82.0 59.0 58.0 58.0 60.0 78.0 74.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:76.00 stddev:15.66 (20.6%) stddev-sans-first:16.48 15:33:50 INFO - PID 1660 | Values: 86.0 77.0 120.0 104.0 106.0 102.0 84.0 73.0 89.0 75.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:90.00 stddev:16.35 (18.2%) stddev-sans-first:17.06 15:33:50 INFO - PID 1660 | Values: 91.0 116.0 87.0 136.0 112.0 90.0 90.0 92.0 90.0 90.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:124.00 stddev:16.10 (13.0%) stddev-sans-first:17.04 15:33:50 INFO - PID 1660 | Values: 105.0 150.0 101.0 112.0 116.0 101.0 98.0 98.0 99.0 98.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:110.30 Median:91.00 stddev:25.40 (27.9%) stddev-sans-first:25.75 15:33:50 INFO - PID 1660 | Values: 89.0 96.0 143.0 151.0 90.0 92.0 94.0 113.0 143.0 92.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:68.00 Median:67.00 stddev:4.37 (6.5%) stddev-sans-first:4.58 15:33:50 INFO - PID 1660 | Values: 66.0 65.0 66.0 67.0 66.0 67.0 68.0 66.0 69.0 80.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:59.50 stddev:13.98 (23.5%) stddev-sans-first:14.31 15:33:50 INFO - PID 1660 | Values: 56.0 59.0 76.0 88.0 93.0 60.0 56.0 58.0 59.0 59.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:81.00 stddev:14.27 (17.6%) stddev-sans-first:15.11 15:33:50 INFO - PID 1660 | Values: 85.0 84.0 84.0 73.0 103.0 117.0 77.0 96.0 78.0 73.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:151.50 stddev:13.68 (9.0%) stddev-sans-first:14.12 15:33:50 INFO - PID 1660 | Values: 142.0 138.0 137.0 150.0 153.0 155.0 145.0 145.0 183.0 162.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.80 Median:221.50 stddev:22.33 (10.1%) stddev-sans-first:21.65 15:33:50 INFO - PID 1660 | Values: 183.0 184.0 188.0 190.0 223.0 235.0 197.0 220.0 234.0 234.0 15:33:50 INFO - PID 1660 | 15:33:50 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.20 Median:93.50 stddev:14.85 (15.9%) stddev-sans-first:14.54 15:33:50 INFO - PID 1660 | Values: 68.0 63.0 60.0 92.0 94.0 94.0 93.0 98.0 83.0 97.0 15:33:50 INFO - PID 1660 | -------- Summary: end -------- 15:33:50 INFO - PID 1660 | 15:33:51 INFO - TEST-INFO | 1660: exit 0 15:33:51 INFO - TEST-OK | kraken | took 62325ms 15:33:51 INFO - SUITE-END | took 1179s 15:33:51 INFO - Completed test suite (00:19:39) 15:33:51 INFO - javascript benchmark 15:33:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7637.389458759087, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3560.0, 3562.2341196642733, 3576.9105772355706, 3605.314990151871, 3616.855325786968, 6136.263648362203, 6142.938570614292, 6151.231096112986, 6194.411530904568, 6213.533984951124, 6103.883205879434, 6143.726821076208, 6153.353897840718, 6161.199044124269, 6197.318294987548, 5582.91625625616, 5594.49649531542, 5626.803061892834, 5660.518855897254, 5735.139729040657, 5507.5593952483905, 5605.943940560604, 5712.771489140429, 5718.199452076692, 5796.30444346679, 5493.807716729935, 5518.862028449321, 5524.226608274845, 5615.129654903497, 5666.065099258617, 5529.060059789846, 5540.058190107691, 5598.496135347838, 5642.4357564243655, 5646.20953066571, 5464.7540860660965, 5556.138798486241, 5580.107182850725, 5586.608937374344, 5601.327840659106, 5994.700264986734, 6187.535934804908, 6194.132821405007, 6194.349593292684, 6217.285012223607, 5970.850728731773, 6217.067439884008, 6373.139626150457, 6446.484281257488, 6625.801225963229, 6754.3921047105505, 6765.763198288086, 6770.8307292317595, 6774.695138718769, 6776.186857577122, 6126.754929802758, 6256.937430625681, 6257.124002639634, 6266.624002559861, 6273.309935907047, 6640.0, 6711.261761206263, 6833.043373927654, 6888.896666550006, 6951.0616066830335, 4918.336024636628, 5860.560457965585, 6367.490600752021, 6462.48300135988, 6479.352064793483, 6683.899741503881, 6733.9326606734285, 6752.898706519406, 6753.763618273337, 6769.695363708645, 5658.519025882763, 6534.052562378523, 6668.966655166693, 6693.564918280394, 6704.463642908556, 6419.422251997249, 6511.967440162769, 6513.934860651333, 6688.431483323972, 6724.125863637696, 5263.026340127089, 5280.155175171954, 5284.39229488606, 5291.1798671206025, 5297.0, 4705.811767529261, 4764.118638051971, 4788.018456216468, 4791.784369703371, 4817.662763606514, 6291.716872740738, 6319.652419116901, 6341.714622841983, 6348.1430006949395, 6355.110284560165, 6466.385693359123, 6509.218893732687, 6785.898211526831, 6800.897986530206, 6814.557053791586, 6237.719302631393, 6245.250569931546, 6299.748010079637, 6305.211848518935, 6308.432241098323, 5911.9113213301835, 5918.348981612019, 5998.1602575639445, 6010.188624535632, 6037.849053773691, 6607.5044371673275, 6914.377706006484, 6930.8613827724175, 6939.340762627542, 6940.375366217065, 6579.80260592183, 6682.0, 6685.39831415175, 6756.121704178622, 6760.695768690519, 6876.0, 6914.861702765816, 6916.861662766817, 6920.377166055079, 6929.618870962146, 6067.089936509559, 6593.943990898145, 6594.142761440982, 6599.769008084886, 6613.636250006296, 6450.0969864218105, 6471.417572418505, 6530.869382612416, 6654.73381064752, 6676.566023208476, 5712.942870571241, 6502.837429064311, 6538.574992625464, 6551.80344589663, 6563.343665633399, 6261.812145635638, 6277.435030847246, 6467.935320646733, 6509.479241660751, 6520.445762110274, 5268.761840967293, 5727.570432217517, 6309.905351419824, 6401.167848179707, 6403.551751377545, 6505.8698826024165, 6510.479161666961, 6510.934890651033, 6514.511411644051, 6515.250746164252, 6182.598131121643, 6224.502039836711, 6362.395572420613, 6362.586431882098, 6380.649064301508], "value": 6092.099698054096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.0, 762.5462849604486, 827.4084029918608, 866.5623859950724, 1119.1829964126182, 29076.0, 30301.696983030164, 31568.0, 34444.44999975001, 36714.08214794628, 40043.998900027516, 40401.797991010004, 40496.98757531063, 40517.79741101298, 40683.38974915371, 8453.281471074935, 8644.827103457927, 8753.649854005864, 8785.121487851227, 8828.823423531525, 8343.99872015359, 8761.912380876174, 8878.73363799087, 8882.644694212224, 8888.333374996868, 8368.456050356737, 8880.333974951873, 8923.241524470428, 8930.419522731001, 8931.642734290654, 8856.468611883276, 8879.0, 8893.466392016435, 8906.955465222698, 8925.642974281021, 8583.785405364853, 8631.309495240366, 8634.309255259564, 8639.222469977669, 8640.135986401372, 38414.80792596047, 38557.4577016919, 38796.612033879865, 38814.805925970475, 38824.0, 2584.870756462188, 2591.2226332100295, 2940.7500362469214, 2982.8508574571183, 2983.1796256029547, 23527.945231434926, 27429.17712468629, 27557.31106722348, 27863.442731145467, 28747.850085996743, 2351.2240960483173, 2678.7723043541323, 2681.865906704657, 2707.945841083167, 2760.4617099665575, 221269.89365053232, 229693.0, 231597.84201079054, 312609.0, 312773.87226127676, 44584.0, 44834.55165448304, 44856.551434485234, 44883.775581122536, 44962.77518612386, 51039.74480127576, 51341.22988155181, 51416.97166056658, 51457.48542514602, 51468.22797658038, 14149.221792801289, 14279.71482566574, 14288.928555357157, 14294.927880409012, 14312.427502899976, 11657.358845263592, 11668.824967625493, 11673.299602023906, 11678.299302041905, 11679.649610511698, 11175.664730058112, 13464.316960379952, 13529.59411217665, 13592.524261650795, 13652.453901843815, 5219.634625576249, 5898.321693005273, 6013.368596297413, 6055.818325450244, 6064.9090263646085, 982.9361091529028, 996.2776986684596, 997.6907158780801, 1002.3885429887763, 1040.2250323509033, 24418.87790561036, 24532.877335613208, 24659.01363945422, 24821.87589062079, 24930.750692492842, 906.6328137104483, 1023.2325755683238, 1105.8672959244782, 1133.3426612564858, 1148.7472755993667, 10519.948005199572, 12151.534685377206, 12538.373081345897, 12621.684457888627, 12652.493900244071, 433622.0, 435740.0, 436782.0, 436941.0, 437102.0, 54651.180232296545, 54947.450525495035, 54966.0, 55131.0, 55190.72404638032, 1488.78412630168, 1494.5516345096646, 1527.0684882221835, 1529.9541013769606, 1546.327347603796, 24007.51984960326, 27655.72344276612, 27940.161795146178, 29168.833246669896, 29234.853825730734, 44140.7792961046, 44215.55784442114, 44388.0, 44626.77686611546, 44899.551004489535, 390627.0, 391044.2238944049, 391606.0419697883, 393797.0, 395176.0, 31909.7236110553, 32177.839110804296, 32492.26338970954, 32519.02442926716, 32532.02403927886, 40608.1878362437, 40728.79635601803, 41274.38088428616, 41907.0, 42160.36759448549, 24353.634695479937, 28124.71875281221, 28603.0, 29407.55888661629, 29442.11673649794, 28765.568516471852, 28815.567766483102, 29452.705472944996, 29576.704232958255, 29758.404831903674, 34728.0, 34737.82631086929, 35518.11204719903, 36396.0, 36761.08097297589, 22765.430864228525, 28674.283142921595, 29896.25259368533, 30732.84633576818, 31127.0, 22383.32850014502, 23999.400014999766, 24279.635805462578, 24336.269911902673, 24925.002999880526], "value": 17462.04027716619, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.55316056793816, 385.38531042986426, 416.9638448455589, 434.59799685291097, 443.58081612875833, 19938.90030549847, 21052.0, 22282.55434891301, 22394.88802555989, 22601.773982260172, 22090.226842060492, 22391.776082239172, 23163.0, 23354.532909341804, 23529.411764705892, 6108.992016317302, 6293.0, 6314.116023756677, 6324.683765811714, 6326.051092336141, 6097.634141951475, 6140.938590614103, 6141.907871381933, 6261.624302541862, 6272.404121608462, 6034.155218269446, 6181.134641150243, 6205.255369355684, 6231.501479881621, 6247.187865577492, 5726.740117174215, 6161.568690191689, 6265.185525881652, 6276.968615156918, 6279.0, 4711.670183087185, 5374.892502149935, 5385.192221166817, 5560.610757246995, 5622.746976386073, 25881.352966175808, 27102.5934610981, 28234.2941426466, 28362.858185709145, 28576.857115714287, 1725.568607848038, 1971.3888694504749, 2003.3989803059023, 2025.7569091708942, 2077.4079387374677, 14398.70411662945, 15078.5476435706, 17143.0, 17405.477835664824, 17795.911020444946, 1702.7445883117507, 1715.1167148918316, 1724.284421964883, 1740.1386313774763, 1748.4109854860149, 118959.62080758423, 140723.0, 143360.0, 163513.0, 170470.0, 12442.875571244173, 12483.31341776211, 12614.747705045847, 12621.412610414844, 12785.936070319774, 13458.798118028237, 16067.758983615255, 16239.918800406158, 16328.183590820649, 16391.754123688155, 7420.295071968154, 8703.260222881127, 8926.464412135292, 8950.86573701395, 9072.0, 8354.45696029756, 8391.24478796899, 8458.619362128718, 8605.354598405145, 8624.310055195569, 6662.966685166543, 7924.603769811486, 8031.678732850621, 8062.516249025077, 8108.5945702714625, 2189.5949249388595, 2201.273579718689, 2205.2391924785925, 2206.2388475975763, 2369.1707902234425, 800.7597720683888, 860.5654144656908, 901.4005686218591, 914.7530166854912, 920.7882187096872, 20597.07313170911, 21118.894405527873, 21230.256941006992, 21489.247876324567, 21553.461163471038, 943.8631398447324, 948.7912659214961, 984.5372674842812, 984.6947446291673, 988.6539711101057, 8558.0, 10033.749156271151, 10693.41186234749, 10835.079018283377, 10858.565657373774, 358355.624665625, 368435.0, 387575.0, 395733.0, 719395.0, 154271.2286438583, 156289.0, 157979.0, 160331.19834400754, 163658.0, 1116.9218154729092, 1142.0806250967885, 1156.0000599481389, 1156.444906444911, 1164.6680696001768, 14078.296085195494, 14839.0, 14896.776548351567, 16727.581810454834, 18524.72212916834, 61205.69397152986, 61671.0, 61742.38257617546, 62160.68919655373, 62704.0, 81837.59081204556, 82553.3489330198, 83167.75248371401, 83381.0, 85410.57294713486, 8472.49165050099, 8673.436226645248, 8854.158854908774, 8863.92753925467, 8992.86510702353, 12573.245605263714, 13308.733825323632, 13494.920406367663, 13817.79273310881, 13843.584692459242, 11216.439178041228, 11483.425828708698, 11587.88412115868, 11846.881531184577, 11892.46483908209, 9029.322800790098, 10290.3825770454, 10292.279540432397, 10359.896401035892, 10593.470326483799, 8898.932128144665, 9785.657501987396, 11594.536218551164, 11848.940755296167, 11966.64100076999, 7672.846543068996, 8446.282066024456, 8605.95697021511, 8707.825843483222, 8887.555622218733, 9391.779068721296, 10169.186465082628, 11268.605598804003, 11378.829317560409, 11733.589324373606], "value": 10116.593703998122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1065.5897479470405, 1360.7958806179079, 1361.945522179113, 1370.4312710225256, 1410.5909286306971, 10437.112845408139, 10589.311694739836, 10953.780924381508, 11001.22991390602, 11145.832812507819, 10533.104686101671, 10583.259171857973, 10779.78440431191, 11020.50407731652, 11051.502682379292, 2414.843035202715, 2457.324235835151, 2458.323960910755, 2458.7172475165316, 2460.2127319257843, 2395.0863994357583, 2403.194929698553, 2427.490227052312, 2454.226918520669, 2455.042533411971, 2376.0495801679294, 2433.756624337561, 2441.2676197140877, 2448.4735781806908, 2449.1183174057323, 2332.3119679694532, 2407.3981504623844, 2416.565018296697, 2420.491696743686, 2427.538767634144, 1905.5712464695332, 2410.3853517353136, 2410.710714714228, 2410.843295185807, 2420.8668523231217, 10834.0, 10950.890491095146, 10961.780764384748, 11133.10935125188, 11146.331220126738, 6206.410391012847, 6444.420002199825, 6449.322821103763, 6453.741850325981, 6458.93541064588, 3924.725269231155, 3940.5271367435803, 3944.1322908959983, 3969.64273215412, 3988.102676897704, 1173.4719376280707, 1301.3623324570988, 1399.2793711238721, 1450.2821103553704, 1572.6304318485147, 11540.596079137275, 11926.224795388358, 14891.77662335055, 15020.399184032625, 15201.0, 5232.3459567554055, 5262.684238945675, 5378.677279363251, 5385.488378604026, 5389.487998640123, 10240.795184096276, 11310.773784524428, 11364.602238921598, 11394.430278486043, 11408.657740267805, 6044.818655440344, 6167.105769663373, 6237.500999919996, 6245.188125543741, 6253.6560489172625, 5761.48146666802, 5768.625039372428, 5812.447817457335, 5840.123981402823, 5879.647221166743, 4012.017055821284, 4200.474940632421, 4211.157768446262, 4243.575642435793, 4256.893577660583, 1227.7605866856056, 1261.1991385470292, 1272.6945533071996, 1273.2615083251692, 1273.5733529267613, 636.4812677667686, 714.9177844547839, 742.9925700743031, 752.9360004399688, 772.274062381371, 5805.970970145122, 6160.815175544741, 6724.125863637696, 6841.589504629738, 6882.8967565486555, 646.6217262901256, 728.3990707666196, 859.5229647545672, 871.4335681806749, 889.9688510902088, 3778.3387907116144, 3864.594217607167, 4011.3782363733667, 4121.1345617420075, 4174.624283814472, 11598.826017609743, 13843.30783460823, 14182.574522764333, 14203.857961420461, 14207.71584568303, 13821.516246931511, 13923.373448194856, 14099.013069085271, 14100.717985640435, 14110.082844615066, 8127.837443250984, 10274.486275686186, 10403.42781147029, 10490.265681402228, 10523.421211833424, 5334.9466505336, 5793.391693872083, 6275.52933529978, 6578.177727784027, 6605.735770569124, 11979.580714674945, 12304.323262220305, 12437.689057773629, 12497.750044999231, 12518.812217816921, 10561.89438105609, 12457.626271211879, 12479.750404991668, 12629.305388203737, 12706.555270565486, 9098.181163695299, 9159.404638698463, 9193.126652967958, 9214.308926830381, 9236.214921731727, 10024.14794742455, 10068.69793906184, 10113.039261270167, 10260.948695256775, 10261.692149235534, 8519.27586155159, 8580.528070956158, 8600.39797214214, 8638.049814520524, 8805.69180078694, 8358.665653373797, 8393.454425462325, 8424.536650484282, 8443.620037098222, 8629.913700863162, 7641.617919103911, 7786.092407360233, 7792.766217013499, 7806.0632724073475, 7812.843743124992, 5174.172132458789, 5388.110961691277, 5510.91733623988, 5591.832245032656, 5615.494605485526, 4993.450720420678, 5945.970270148621, 6020.789272375446, 6035.6076855004285, 6085.117657939662], "value": 5111.212060165677, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.8787716729059, 385.691446842526, 398.17974971558573, 400.3194569232306, 411.51852332770653, 5564.86087847804, 5629.183768353591, 6317.0208617664275, 6424.293327733945, 6449.322821103787, 5314.9468505314935, 6085.513158947285, 6266.811995640127, 6327.810165695037, 6347.206599175103, 4871.634627402941, 4890.682105663121, 4893.0, 4897.33885925399, 4994.825181118662, 4068.613481719247, 4896.706197628136, 4906.9263961040615, 4908.950910490885, 4909.582685471722, 4681.742504162286, 4815.855524334258, 4848.61814382901, 4853.587445067174, 4888.071266459379, 4247.638950689196, 4819.0602832447685, 4850.520591219676, 4855.198892182786, 4860.8055677772845, 4286.314189729628, 4305.63402110821, 4314.9784251078545, 4322.848700295506, 4329.025969156946, 5960.821175364746, 6207.596506227081, 6211.534134939898, 6224.439800417984, 6286.748530058792, 4728.621710263205, 4979.278004689299, 5659.5755318350875, 5664.235328230678, 5674.404187560329, 6584.242812076577, 6749.325067493261, 6800.6939687714175, 6805.319468053155, 6811.216710078305, 4725.1730947084125, 4779.904401911942, 4792.808287668524, 4802.7118372897385, 4837.7097374157665, 5903.970480147615, 6118.877622447526, 6871.9656401718175, 7150.427965762727, 7177.49757516974, 4283.657307415461, 4386.692931494765, 4754.976225118921, 4804.567588917014, 4844.103840789465, 4205.2640787862, 4240.385144154142, 4393.956060439418, 4424.225760491901, 4885.316055752198, 5048.8892443662335, 5459.3994660587305, 5631.802886898939, 5696.515796157373, 5700.201971723962, 6223.222097237845, 6275.497960163176, 6776.898346524806, 6806.625635589991, 6823.795286141423, 1560.0, 1728.5764987577872, 1732.6139088728967, 1735.1324337831084, 1736.7568540404354, 1157.65270418874, 1184.5617121664866, 1185.6798664360747, 1186.5669030803867, 1197.167968262067, 6292.968535157295, 6459.222534310292, 6473.708683109271, 6485.643289619116, 6497.350264973465, 6754.76358327466, 6966.317967582518, 7046.260142685047, 7080.858382832417, 7134.857302853914, 5475.247920665349, 5791.305043394819, 6464.385883341075, 6485.0, 6516.250631177475, 3099.4266060778828, 3539.4867744177463, 3699.519062521909, 3718.981405092957, 3728.2729867676085, 5400.729963501888, 6648.66756662165, 6799.728010879509, 7134.0, 7210.603416812126, 7098.8348553690585, 7180.676869540883, 7214.6031968241205, 7277.743899391206, 7292.598907060163, 6726.226483954212, 6877.415419689382, 7250.0, 7322.524035937647, 7344.155422126524, 6524.510661700487, 7115.67979440916, 7138.428925685829, 7149.927510873411, 7175.390091842043, 6275.497960163085, 6737.494687898329, 6942.583444993316, 6986.742386370502, 7033.437325014089, 5655.578375083015, 6124.601900876429, 6906.8618627626165, 7200.84786434168, 7401.777946661608, 4536.342230376511, 4548.658850586286, 4559.931601025921, 4567.1779079765965, 4567.314902764612, 6793.320667933167, 7077.6461176941975, 7085.574865508086, 7100.680469378787, 7124.928750712426, 5728.79949201776, 6409.7115629795835, 6834.350736680008, 6846.315368463114, 6858.794236172923, 5459.426760190123, 6499.707513161825, 6783.084283621648, 6834.179898412222, 6846.486513511606, 6650.401463868275, 6683.532152749261, 6695.12963314766, 6698.497612678971, 6734.831629209309, 6519.641419721961, 6789.132988428221, 6825.112735344572, 6841.589504629738, 6856.680291416375, 6561.770338038145, 6643.036759669917, 6673.532852700265, 6676.6995485202315, 6680.899786503109], "value": 5002.496526005763, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.114790110636, 626.2672672972623, 675.5878913862543, 681.5331497923922, 709.6487238816786, 15296.08223506589, 15490.225488725548, 15963.92018039911, 16124.59688507785, 16128.0, 14880.404783808663, 15020.399184032653, 15063.0, 15398.846011539881, 15403.306851191695, 664.0669858848302, 704.6265479295981, 775.3835700618008, 940.2243149401737, 948.3456415073578, 923.1599244687336, 957.9952100239524, 959.6593209410663, 960.8943016268205, 962.537981768748, 1017.5370206556037, 1017.8574999500075, 1018.1803648063343, 1018.9235807314444, 1020.3367810922886, 986.0041358228169, 1007.566746299091, 1008.2337423558075, 1009.142229105262, 1010.4998025977152, 748.2554857916354, 750.6884642873201, 752.9096508419025, 753.2279413601048, 754.3248792330888, 16266.0, 16560.50318490447, 16686.0, 16765.24556394953, 16956.660866782597, 14437.566872993826, 14546.345414456375, 14602.988979624184, 15037.022593531496, 15068.246587670683, 10404.0, 11714.355710435922, 11910.940445297718, 12013.339266340348, 12212.206206596631, 2935.7064293570684, 2959.940801183986, 2984.179350678559, 2993.2516870782306, 3086.42901063304, 47999.80000499981, 64719.0, 82084.58957705233, 107034.46482767614, 110254.0, 11034.503447344889, 11368.317900925971, 11387.886121138848, 11618.76762464746, 12140.575079872162, 19228.519287017938, 23890.163844265368, 24358.26925192247, 24358.87820560886, 24504.0, 7053.047838541834, 7970.800729981681, 8053.315468185153, 8143.51138931666, 8348.123447038095, 3847.8653247136217, 4159.873132294261, 4175.791210439465, 4210.305299625588, 4241.448611680462, 4033.1731994940965, 4096.221717873654, 4096.262672718939, 4149.066460046465, 4150.983009162714, 713.7038129176334, 803.6704950970075, 810.8054067023871, 818.4802650317091, 818.6193420059701, 542.1894268069183, 546.1834557336728, 563.5013013483097, 579.6464156864309, 606.3784620763753, 8889.688680942872, 9973.360471729135, 10045.0, 10505.369677819355, 10513.474326283655, 551.3852054958772, 585.7891159182691, 595.9731812068468, 607.3319348716377, 613.7667686279275, 4740.976295118571, 4860.465348811628, 4871.196252618278, 4882.511748825089, 4909.729964851896, 92630.53684731534, 99523.0, 103513.0, 104861.95138048675, 107480.0, 77082.61458692783, 77319.61340193263, 91459.54270228607, 92461.07538924525, 95171.09657806806, 57511.71244143753, 78307.0, 79192.60403697945, 79459.60270198727, 79515.60242198753, 8841.823163536821, 9360.391574547904, 9427.764305892133, 9457.810843782949, 9543.618255269712, 35648.4652730204, 35801.64198358087, 36442.0, 36702.63297367099, 37962.0509487265, 55176.448235518736, 63241.051384230195, 67097.32902671106, 67857.32142678708, 71238.0, 11554.728472339759, 11571.305721656727, 11651.941740291244, 11661.941690291495, 11791.0, 11685.76628467443, 11753.275870659296, 11858.940705296418, 11965.341906195243, 12324.753504929671, 11629.0, 11648.94175529117, 11652.941735291608, 11749.647510574696, 12033.939830300793, 11388.697535422536, 11567.305961642329, 11597.478113485082, 11748.706282342668, 12196.817047744113, 10522.894771052192, 10959.01368876805, 11329.619303644648, 11461.942690286494, 11462.369569673414, 8526.360522960678, 8608.956955215184, 8628.654853806029, 8724.607392667218, 9048.27613790882, 8416.579171041301, 9390.81218375615, 9632.422054676741, 9698.175655069399, 9708.466034368177], "value": 7211.618301353129, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 170.0, 120.0, 118.0, 116.0, 115.0, 114.0, 119.0, 115.0, 116.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 120.0, 157.0, 98.0, 148.0, 148.0, 144.0, 119.0, 133.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 208.0, 207.0, 242.0, 272.0, 205.0, 197.0, 199.0, 201.0, 195.0], "value": 212.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 61.0, 82.0, 59.0, 58.0, 58.0, 60.0, 78.0, 74.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 77.0, 120.0, 104.0, 106.0, 102.0, 84.0, 73.0, 89.0, 75.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 116.0, 87.0, 136.0, 112.0, 90.0, 90.0, 92.0, 90.0, 90.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 150.0, 101.0, 112.0, 116.0, 101.0, 98.0, 98.0, 99.0, 98.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 143.0, 151.0, 90.0, 92.0, 94.0, 113.0, 143.0, 92.0], "value": 110.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 67.0, 66.0, 67.0, 68.0, 66.0, 69.0, 80.0], "value": 68.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 76.0, 88.0, 93.0, 60.0, 56.0, 58.0, 59.0, 59.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 73.0, 103.0, 117.0, 77.0, 96.0, 78.0, 73.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 137.0, 150.0, 153.0, 155.0, 145.0, 145.0, 183.0, 162.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 188.0, 190.0, 223.0, 235.0, 197.0, 220.0, 234.0, 234.0], "value": 208.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 60.0, 92.0, 94.0, 94.0, 93.0, 98.0, 83.0, 97.0], "value": 84.2, "unit": "score"}]}]} 15:33:51 INFO - Return code: 0 15:33:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:33:51 ERROR - # TBPL SUCCESS # 15:33:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:33:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:33:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:33:51 INFO - 2017-03-24 15:33:51.557 screenresolution[1665:12496] starting screenresolution argv=screenresolution get 15:33:51 INFO - 2017-03-24 15:33:51.561 screenresolution[1665:12496] Display 0: 1600x1200x32@60 15:33:51 INFO - 2017-03-24 15:33:51.567 screenresolution[1666:12499] starting screenresolution argv=screenresolution list 15:33:51 INFO - Available Modes on Display 0 15:33:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:33:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:33:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:33:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:33:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:33:51 INFO - Intel Iris: 15:33:51 INFO - Chipset Model: Intel Iris 15:33:51 INFO - Type: GPU 15:33:51 INFO - Bus: Built-In 15:33:51 INFO - VRAM (Dynamic, Max): 1536 MB 15:33:51 INFO - Vendor: Intel (0x8086) 15:33:51 INFO - Device ID: 0x0a2e 15:33:51 INFO - Revision ID: 0x0009 15:33:51 INFO - Displays: 15:33:51 INFO - Display: 15:33:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:33:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:33:51 INFO - Display Serial Number: 1600x1200 60 15:33:51 INFO - Main Display: Yes 15:33:51 INFO - Mirror: Off 15:33:51 INFO - Online: Yes 15:33:51 INFO - Rotation: Supported 15:33:51 INFO - Return code: 0 15:33:51 INFO - Running post-action listener: _resource_record_post_action 15:33:51 INFO - [mozharness: 2017-03-24 22:33:51.805678Z] Finished run-tests step (success) 15:33:51 INFO - Running post-run listener: _resource_record_post_run 15:33:51 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33665536; Write bytes: 520407040; Read time: 202; Write time: 1654 15:33:51 INFO - TinderboxPrint: CPU usage
54.2% 15:33:51 INFO - TinderboxPrint: I/O read bytes / time
33,665,536 / 202 15:33:51 INFO - TinderboxPrint: I/O write bytes / time
520,407,040 / 1,654 15:33:51 INFO - TinderboxPrint: CPU idle
2,199.4 (45.8%) 15:33:51 INFO - TinderboxPrint: CPU system
973.1 (20.2%) 15:33:51 INFO - TinderboxPrint: CPU user
1,633.2 (34.0%) 15:33:51 INFO - TinderboxPrint: Swap in / out
241,049,600 / 0 15:33:51 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212619776; Write bytes: 212504576; Read time: 17028; Write time: 478 15:33:51 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 29860352; Write bytes: 304468480; Read time: 177; Write time: 1158 15:33:52 INFO - Running post-run listener: _upload_blobber_files 15:33:52 INFO - Blob upload gear active. 15:33:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:33:52 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 15:33:52 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'] 15:33:52 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 15:33:52 INFO - (blobuploader) - INFO - Open directory for files ... 15:33:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:33:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:33:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:53 INFO - (blobuploader) - INFO - Done attempting. 15:33:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:33:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:33:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:54 INFO - (blobuploader) - INFO - Done attempting. 15:33:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:33:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:33:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:55 INFO - (blobuploader) - INFO - Done attempting. 15:33:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:33:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:33:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:57 INFO - (blobuploader) - INFO - Done attempting. 15:33:57 INFO - (blobuploader) - INFO - Iteration through files over. 15:33:57 INFO - Return code: 0 15:33:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:33:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:33:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5d11a3e9194673f64489b7eed080d0d3ab5bbdaf6261523807972c63581ef42ab380712a560dfb2f9285c5a0129773cd0d6423c6bb5f9e1f31c5e4dddb87a321", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/590da3410003b44d7baea57434923d4b29df5b9348792d752cdd9650d6ea5dca163b948f1c309cb7c63be8d24a36fa093b757559f8f15bc1e87186248834d242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/934a28a09daf51617fa93497db6d065b21ca8c6ff8f89390a947ed9e5703ff1c95b61024ee3b2aa11480a78a795eaa8eb76584345e4b13f3c9a8f07692762c63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ddc545259a28d9b3671a7257e32f6506834a8ae420ccc4287178042aaaa746d3f5f3e0044775f4bdb627fefd025490b21a3d5e294fcb8f68081902ec16781be3"} 15:33:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:33:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:33:57 INFO - Contents: 15:33:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5d11a3e9194673f64489b7eed080d0d3ab5bbdaf6261523807972c63581ef42ab380712a560dfb2f9285c5a0129773cd0d6423c6bb5f9e1f31c5e4dddb87a321", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/590da3410003b44d7baea57434923d4b29df5b9348792d752cdd9650d6ea5dca163b948f1c309cb7c63be8d24a36fa093b757559f8f15bc1e87186248834d242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/934a28a09daf51617fa93497db6d065b21ca8c6ff8f89390a947ed9e5703ff1c95b61024ee3b2aa11480a78a795eaa8eb76584345e4b13f3c9a8f07692762c63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ddc545259a28d9b3671a7257e32f6506834a8ae420ccc4287178042aaaa746d3f5f3e0044775f4bdb627fefd025490b21a3d5e294fcb8f68081902ec16781be3"} 15:33:57 INFO - Running post-run listener: copy_logs_to_upload_dir 15:33:57 INFO - Copying logs to upload dir... 15:33:57 INFO - mkdir: /builds/slave/test/build/upload/logs 15:33:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.049095 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-24 15:33:57.740073) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 15:33:57.745629) ========= 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.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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/5d11a3e9194673f64489b7eed080d0d3ab5bbdaf6261523807972c63581ef42ab380712a560dfb2f9285c5a0129773cd0d6423c6bb5f9e1f31c5e4dddb87a321", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/590da3410003b44d7baea57434923d4b29df5b9348792d752cdd9650d6ea5dca163b948f1c309cb7c63be8d24a36fa093b757559f8f15bc1e87186248834d242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/934a28a09daf51617fa93497db6d065b21ca8c6ff8f89390a947ed9e5703ff1c95b61024ee3b2aa11480a78a795eaa8eb76584345e4b13f3c9a8f07692762c63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ddc545259a28d9b3671a7257e32f6506834a8ae420ccc4287178042aaaa746d3f5f3e0044775f4bdb627fefd025490b21a3d5e294fcb8f68081902ec16781be3"} build_url:https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/dPL509wMTWGCnAzDSTU8JA/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/5d11a3e9194673f64489b7eed080d0d3ab5bbdaf6261523807972c63581ef42ab380712a560dfb2f9285c5a0129773cd0d6423c6bb5f9e1f31c5e4dddb87a321", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/590da3410003b44d7baea57434923d4b29df5b9348792d752cdd9650d6ea5dca163b948f1c309cb7c63be8d24a36fa093b757559f8f15bc1e87186248834d242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/934a28a09daf51617fa93497db6d065b21ca8c6ff8f89390a947ed9e5703ff1c95b61024ee3b2aa11480a78a795eaa8eb76584345e4b13f3c9a8f07692762c63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ddc545259a28d9b3671a7257e32f6506834a8ae420ccc4287178042aaaa746d3f5f3e0044775f4bdb627fefd025490b21a3d5e294fcb8f68081902ec16781be3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 15:33:57.780934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:33:57.781284) ========= 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.32kKsYRw80/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.m5XStWqtHp/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.004554 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 15:33:57.830342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 15:33:57.830682) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 15:33:57.831049) ========= ========= Total master_lag: 0.19 =========