builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0278 starttime: 1493675133.76 results: success (0) buildid: 20170501111719 builduid: 7cf6c96fe3994190bcfc5c047fd50b33 revision: a7501c0dd13e1163ff65a83d30143303101ca313 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.760640) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.761063) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.761338) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.791192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.791521) ========= 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.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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-05-01 14:45:33-- 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% 171M=0s 2017-05-01 14:45:33 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.962755) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:33.963067) ========= 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.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.026717 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:34.014836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 14:45:34.015184) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev a7501c0dd13e1163ff65a83d30143303101ca313 --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 a7501c0dd13e1163ff65a83d30143303101ca313 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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-05-01 14:45:34,121 truncating revision to first 12 chars 2017-05-01 14:45:34,121 Setting DEBUG logging. 2017-05-01 14:45:34,121 attempt 1/10 2017-05-01 14:45:34,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/a7501c0dd13e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-01 14:45:35,528 unpacking tar archive at: pine-a7501c0dd13e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.691161 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 14:45:35.733606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:35.733910) ========= script_repo_revision: a7501c0dd13e1163ff65a83d30143303101ca313 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:35.734290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:35.734570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 14:45:35.754545) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-05-01 14:45:35.754830) ========= /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.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:45:35 INFO - MultiFileLogger online at 20170501 14:45:35 in /builds/slave/test 14:45:35 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 14:45:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:45:35 INFO - {'append_to_log': False, 14:45:35 INFO - 'base_work_dir': '/builds/slave/test', 14:45:35 INFO - 'blob_upload_branch': 'Pine', 14:45:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:45:35 INFO - 'branch': 'Pine', 14:45:35 INFO - 'buildbot_json_path': 'buildprops.json', 14:45:35 INFO - 'config_files': ('talos/mac_config.py',), 14:45:35 INFO - 'default_actions': ('clobber', 14:45:35 INFO - 'read-buildbot-config', 14:45:35 INFO - 'download-and-extract', 14:45:35 INFO - 'populate-webroot', 14:45:35 INFO - 'create-virtualenv', 14:45:35 INFO - 'install', 14:45:35 INFO - 'run-tests'), 14:45:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:45:35 INFO - 'download_minidump_stackwalk': True, 14:45:35 INFO - 'download_symbols': 'ondemand', 14:45:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:45:35 INFO - 'tooltool.py': '/tools/tooltool.py', 14:45:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:45:35 INFO - '/tools/misc-python/virtualenv.py')}, 14:45:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:45:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:45:35 INFO - 'gecko_profile': False, 14:45:35 INFO - 'gecko_profile_interval': 0, 14:45:35 INFO - 'installer_path': 'installer.exe', 14:45:35 INFO - 'log_level': 'info', 14:45:35 INFO - 'log_name': 'talos', 14:45:35 INFO - 'log_to_console': True, 14:45:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:45:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:45:35 INFO - 'opt_config_files': (), 14:45:35 INFO - 'pip_index': False, 14:45:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:45:35 INFO - 'cmd': ('bash', 14:45:35 INFO - '-c', 14:45:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:45:35 INFO - 'enabled': True, 14:45:35 INFO - 'halt_on_failure': False, 14:45:35 INFO - 'name': 'check_screen_resolution'},), 14:45:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:45:35 INFO - 'cmd': ('bash', 14:45:35 INFO - '-c', 14:45:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:45:35 INFO - 'enabled': True, 14:45:35 INFO - 'halt_on_failure': False, 14:45:35 INFO - 'name': 'check_screen_resolution'},), 14:45:35 INFO - 'run_cmd_checks_enabled': True, 14:45:35 INFO - 'suite': 'dromaeojs-e10s', 14:45:35 INFO - 'system_bits': '32', 14:45:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:45:35 INFO - 'title': 't-yosemite-r7-0278', 14:45:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:45:35 INFO - 'use_talos_json': True, 14:45:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:45:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:45:35 INFO - 'work_dir': 'build'} 14:45:35 INFO - [mozharness: 2017-05-01 21:45:35.963950Z] Running clobber step. 14:45:35 INFO - Running pre-action listener: _resource_record_pre_action 14:45:35 INFO - Running main action method: clobber 14:45:35 INFO - rmtree: /builds/slave/test/build 14:45:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:45:38 INFO - Running post-action listener: _resource_record_post_action 14:45:38 INFO - [mozharness: 2017-05-01 21:45:38.437838Z] Finished clobber step (success) 14:45:38 INFO - [mozharness: 2017-05-01 21:45:38.438118Z] Running read-buildbot-config step. 14:45:38 INFO - Running pre-action listener: _resource_record_pre_action 14:45:38 INFO - Running main action method: read_buildbot_config 14:45:38 INFO - Using buildbot properties: 14:45:38 INFO - { 14:45:38 INFO - "project": "", 14:45:38 INFO - "product": "firefox", 14:45:38 INFO - "script_repo_revision": "default", 14:45:38 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 14:45:38 INFO - "repository": "", 14:45:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 14:45:38 INFO - "buildid": "20170501111719", 14:45:38 INFO - "pgo_build": "False", 14:45:38 INFO - "basedir": "/builds/slave/test", 14:45:38 INFO - "buildnumber": 0, 14:45:38 INFO - "slavename": "t-yosemite-r7-0278", 14:45:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:45:38 INFO - "platform": "yosemite_r7", 14:45:38 INFO - "scheduler": "tests-pine-macosx64-talos", 14:45:38 INFO - "branch": "pine", 14:45:38 INFO - "repo_path": "projects/pine", 14:45:38 INFO - "revision": "a7501c0dd13e1163ff65a83d30143303101ca313", 14:45:38 INFO - "stage_platform": "macosx64", 14:45:38 INFO - "builduid": "7cf6c96fe3994190bcfc5c047fd50b33", 14:45:38 INFO - "slavebuilddir": "test" 14:45:38 INFO - } 14:45:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:45:38 INFO - Running post-action listener: _resource_record_post_action 14:45:38 INFO - [mozharness: 2017-05-01 21:45:38.443884Z] Finished read-buildbot-config step (success) 14:45:38 INFO - [mozharness: 2017-05-01 21:45:38.444112Z] Running download-and-extract step. 14:45:38 INFO - Running pre-action listener: _resource_record_pre_action 14:45:38 INFO - Running main action method: download_and_extract 14:45:38 INFO - mkdir: /builds/slave/test/build/tests 14:45:38 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')]} 14:45:38 INFO - trying https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:45:38 INFO - Downloading https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:45:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:45:39 INFO - Downloaded 1325 bytes. 14:45:39 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:45:39 INFO - Using the following test package requirements: 14:45:39 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:45:39 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:45:39 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:45:39 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:45:39 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'jsshell-mac.zip'], 14:45:39 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:45:39 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:45:39 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:45:39 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:45:39 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:45:39 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:39 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:45:39 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:45:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:45:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:45:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:45:41 INFO - Content-Length response header: 52701577 14:45:41 INFO - Bytes received: 52701577 14:45:57 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 14:45:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:45:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:45:57 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:45:58 INFO - Content-Length response header: 52701577 14:45:58 INFO - Bytes received: 52701577 14:46:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:46:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:46:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:46:15 INFO - Content-Length response header: 14408811 14:46:15 INFO - Bytes received: 14408811 14:46:15 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')]} 14:46:15 INFO - trying https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:46:15 INFO - Downloading https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:46:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:46:17 INFO - Downloaded 71263866 bytes. 14:46:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:46:17 INFO - mkdir: /builds/slave/test/properties 14:46:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:46:17 INFO - Writing to file /builds/slave/test/properties/build_url 14:46:17 INFO - Contents: 14:46:17 INFO - build_url:https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:46:18 INFO - Running post-action listener: _resource_record_post_action 14:46:18 INFO - Running post-action listener: set_extra_try_arguments 14:46:18 INFO - [mozharness: 2017-05-01 21:46:18.525047Z] Finished download-and-extract step (success) 14:46:18 INFO - [mozharness: 2017-05-01 21:46:18.525200Z] Running populate-webroot step. 14:46:18 INFO - Running pre-action listener: _resource_record_pre_action 14:46:18 INFO - Running main action method: populate_webroot 14:46:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:46:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:46:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:46:18 INFO - '--tppagecycles', 14:46:18 INFO - '7'], 14:46:18 INFO - 'tests': ['tsvgm']}}, 14:46:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:46:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:46:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:46:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:46:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:46:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:46:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['damp', 'tps']}, 14:46:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'tests': ['damp', 'tps']}, 14:46:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['dromaeo_dom']}, 14:46:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:46:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:46:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:46:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'other-e10s': {'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['a11yr', 14:46:18 INFO - 'ts_paint', 14:46:18 INFO - 'tpaint', 14:46:18 INFO - 'sessionrestore', 14:46:18 INFO - 'sessionrestore_no_auto_restore', 14:46:18 INFO - 'tabpaint']}, 14:46:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:46:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:46:18 INFO - 'bloom_basic_ref']}, 14:46:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['tsvgx', 14:46:18 INFO - 'tsvgr_opacity', 14:46:18 INFO - 'tart', 14:46:18 INFO - 'tscrollx', 14:46:18 INFO - 'cart', 14:46:18 INFO - 'tsvg_static']}, 14:46:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:46:18 INFO - 'tsvgr_opacity', 14:46:18 INFO - 'tart', 14:46:18 INFO - 'tscrollx', 14:46:18 INFO - 'cart', 14:46:18 INFO - 'tsvg_static']}, 14:46:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'talos_options': ['--disable-e10s'], 14:46:18 INFO - 'tests': ['tp5o']}, 14:46:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:46:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'talos_options': ['--disable-e10s', 14:46:18 INFO - '--xperf_path', 14:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:46:18 INFO - 'tests': ['tp5n']}, 14:46:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:46:18 INFO - 'talos_options': ['--xperf_path', 14:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:46:18 INFO - 'tests': ['tp5n']}}, 14:46:18 INFO - 'talos.zip': {'path': '', 14:46:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:46:18 INFO - Running post-action listener: _resource_record_post_action 14:46:18 INFO - [mozharness: 2017-05-01 21:46:18.544688Z] Finished populate-webroot step (success) 14:46:18 INFO - [mozharness: 2017-05-01 21:46:18.544804Z] Running create-virtualenv step. 14:46:18 INFO - Running pre-action listener: _resource_record_pre_action 14:46:18 INFO - Running main action method: create_virtualenv 14:46:18 INFO - Creating virtualenv /builds/slave/test/build/venv 14:46:18 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 14:46:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:46:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:46:18 INFO - Using real prefix '/tools/python27' 14:46:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:46:19 INFO - Installing distribute.............................................................................................................................................................................................done. 14:46:22 INFO - Installing pip.................done. 14:46:22 INFO - Return code: 0 14:46:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:46:22 INFO - Reading from file tmpfile_stdout 14:46:22 INFO - Output received: 14:46:22 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) 14:46:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:22 INFO - 'HOME': '/Users/cltbld', 14:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:22 INFO - 'LOGNAME': 'cltbld', 14:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:22 INFO - 'MOZ_NO_REMOTE': '1', 14:46:22 INFO - 'NO_EM_RESTART': '1', 14:46:22 INFO - 'PAGER': '/bin/cat', 14:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:22 INFO - 'PWD': '/builds/slave/test', 14:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:22 INFO - 'SHELL': '/bin/bash', 14:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:22 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:22 INFO - 'USER': 'cltbld', 14:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:22 INFO - 'XPC_FLAGS': '0x0', 14:46:22 INFO - 'XPC_SERVICE_NAME': '0', 14:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:22 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 14:46:22 INFO - Cleaning up... 14:46:22 INFO - Return code: 0 14:46:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:22 INFO - 'HOME': '/Users/cltbld', 14:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:22 INFO - 'LOGNAME': 'cltbld', 14:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:22 INFO - 'MOZ_NO_REMOTE': '1', 14:46:22 INFO - 'NO_EM_RESTART': '1', 14:46:22 INFO - 'PAGER': '/bin/cat', 14:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:22 INFO - 'PWD': '/builds/slave/test', 14:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:22 INFO - 'SHELL': '/bin/bash', 14:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:22 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:22 INFO - 'USER': 'cltbld', 14:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:22 INFO - 'XPC_FLAGS': '0x0', 14:46:22 INFO - 'XPC_SERVICE_NAME': '0', 14:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:23 INFO - Downloading/unpacking psutil>=3.1.1 14:46:23 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 14:46:23 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 14:46:23 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 14:46:23 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 14:46:23 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 14:46:23 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 14:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:46:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:46:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:46:26 INFO - Installing collected packages: psutil 14:46:26 INFO - Running setup.py install for psutil 14:46:26 INFO - building 'psutil._psutil_osx' extension 14:46: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:46:27 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 14:46:27 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 14:46:27 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 14:46:27 INFO - building 'psutil._psutil_posix' extension 14:46:27 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 14:46:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:46:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:46:27 INFO - ^ 14:46:27 INFO - 1 warning generated. 14:46:27 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 14:46:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:46:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:46:27 INFO - Successfully installed psutil 14:46:27 INFO - Cleaning up... 14:46:27 INFO - Return code: 0 14:46:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:46:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:28 INFO - 'HOME': '/Users/cltbld', 14:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:28 INFO - 'LOGNAME': 'cltbld', 14:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:28 INFO - 'MOZ_NO_REMOTE': '1', 14:46:28 INFO - 'NO_EM_RESTART': '1', 14:46:28 INFO - 'PAGER': '/bin/cat', 14:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:28 INFO - 'PWD': '/builds/slave/test', 14:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:28 INFO - 'SHELL': '/bin/bash', 14:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:28 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:28 INFO - 'USER': 'cltbld', 14:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:28 INFO - 'XPC_FLAGS': '0x0', 14:46:28 INFO - 'XPC_SERVICE_NAME': '0', 14:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:46:31 INFO - Installing collected packages: mozsystemmonitor 14:46:31 INFO - Running setup.py install for mozsystemmonitor 14:46:31 INFO - Successfully installed mozsystemmonitor 14:46:31 INFO - Cleaning up... 14:46:31 INFO - Return code: 0 14:46:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:31 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:31 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 14:46:31 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 14:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:31 INFO - 'HOME': '/Users/cltbld', 14:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:31 INFO - 'LOGNAME': 'cltbld', 14:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:31 INFO - 'MOZ_NO_REMOTE': '1', 14:46:31 INFO - 'NO_EM_RESTART': '1', 14:46:31 INFO - 'PAGER': '/bin/cat', 14:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:31 INFO - 'PWD': '/builds/slave/test', 14:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:31 INFO - 'SHELL': '/bin/bash', 14:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:31 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:31 INFO - 'USER': 'cltbld', 14:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:31 INFO - 'XPC_FLAGS': '0x0', 14:46:31 INFO - 'XPC_SERVICE_NAME': '0', 14:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:32 INFO - Downloading/unpacking jsonschema==2.5.1 14:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:46:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:46: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 14:46: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 14:46: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 14:46: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 14:46: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 14:46: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 14:46:35 INFO - Downloading functools32-3.2.3-2.tar.gz 14:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:46:35 INFO - warning: no files found matching '*.txt' 14:46:35 INFO - no previously-included directories found matching 'build' 14:46:35 INFO - no previously-included directories found matching 'dist' 14:46:35 INFO - no previously-included directories found matching '.git*' 14:46:35 INFO - Installing collected packages: jsonschema, functools32 14:46:35 INFO - Running setup.py install for functools32 14:46:35 INFO - warning: no files found matching '*.txt' 14:46:35 INFO - no previously-included directories found matching 'build' 14:46:35 INFO - no previously-included directories found matching 'dist' 14:46:35 INFO - no previously-included directories found matching '.git*' 14:46:35 INFO - Successfully installed jsonschema functools32 14:46:35 INFO - Cleaning up... 14:46:35 INFO - Return code: 0 14:46:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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')]} 14:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:35 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:35 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 14:46:35 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 14:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:35 INFO - 'HOME': '/Users/cltbld', 14:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:35 INFO - 'LOGNAME': 'cltbld', 14:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:35 INFO - 'MOZ_NO_REMOTE': '1', 14:46:35 INFO - 'NO_EM_RESTART': '1', 14:46:35 INFO - 'PAGER': '/bin/cat', 14:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:35 INFO - 'PWD': '/builds/slave/test', 14:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:35 INFO - 'SHELL': '/bin/bash', 14:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:35 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:35 INFO - 'USER': 'cltbld', 14:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:35 INFO - 'XPC_FLAGS': '0x0', 14:46:35 INFO - 'XPC_SERVICE_NAME': '0', 14:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:46:35 INFO - Cleaning up... 14:46:35 INFO - Return code: 0 14:46:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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')]} 14:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:35 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:35 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 14:46:35 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 14:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:35 INFO - 'HOME': '/Users/cltbld', 14:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:35 INFO - 'LOGNAME': 'cltbld', 14:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:35 INFO - 'MOZ_NO_REMOTE': '1', 14:46:35 INFO - 'NO_EM_RESTART': '1', 14:46:35 INFO - 'PAGER': '/bin/cat', 14:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:35 INFO - 'PWD': '/builds/slave/test', 14:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:35 INFO - 'SHELL': '/bin/bash', 14:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:35 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:35 INFO - 'USER': 'cltbld', 14:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:35 INFO - 'XPC_FLAGS': '0x0', 14:46:35 INFO - 'XPC_SERVICE_NAME': '0', 14:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:36 INFO - Downloading/unpacking blobuploader==1.2.4 14:46:36 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 14:46:36 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 14:46:36 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 14:46:36 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 14:46:36 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 14:46:36 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 14:46:38 INFO - Downloading blobuploader-1.2.4.tar.gz 14:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:46:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:46:38 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 14:46:38 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 14:46:38 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 14:46:38 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 14:46:38 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 14:46:38 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 14:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:46:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:39 INFO - Downloading docopt-0.6.1.tar.gz 14:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:46:39 INFO - Installing collected packages: blobuploader, requests, docopt 14:46:39 INFO - Running setup.py install for blobuploader 14:46:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:46:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:46:40 INFO - Running setup.py install for requests 14:46:40 INFO - Running setup.py install for docopt 14:46:40 INFO - Successfully installed blobuploader requests docopt 14:46:40 INFO - Cleaning up... 14:46:40 INFO - Return code: 0 14:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:40 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')]} 14:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:40 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')]} 14:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:40 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:40 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 14:46:40 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 14:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:40 INFO - 'HOME': '/Users/cltbld', 14:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:40 INFO - 'LOGNAME': 'cltbld', 14:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:40 INFO - 'MOZ_NO_REMOTE': '1', 14:46:40 INFO - 'NO_EM_RESTART': '1', 14:46:40 INFO - 'PAGER': '/bin/cat', 14:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:40 INFO - 'PWD': '/builds/slave/test', 14:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:40 INFO - 'SHELL': '/bin/bash', 14:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:40 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:40 INFO - 'USER': 'cltbld', 14:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:40 INFO - 'XPC_FLAGS': '0x0', 14:46:40 INFO - 'XPC_SERVICE_NAME': '0', 14:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ZMsHAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Ih2BMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ajZmmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Edlr4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-2iMpsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-x0SOAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-jesXjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-0U3MuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:41 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-dftJ1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-xbiDrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-zHYosj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-8kXLof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-6sucuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-KsOPvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-F2ATQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-WcmDHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:42 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-dA5xRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:46:42 INFO - Running setup.py install for manifestparser 14:46:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:46:42 INFO - Running setup.py install for mozcrash 14:46:43 INFO - Running setup.py install for mozdebug 14:46:43 INFO - Running setup.py install for mozdevice 14:46:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Running setup.py install for mozfile 14:46:43 INFO - Running setup.py install for mozhttpd 14:46:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Running setup.py install for mozinfo 14:46:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Running setup.py install for mozInstall 14:46:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:46:43 INFO - Running setup.py install for mozleak 14:46:44 INFO - Running setup.py install for mozlog 14:46:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Running setup.py install for moznetwork 14:46:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Running setup.py install for mozprocess 14:46:44 INFO - Running setup.py install for mozprofile 14:46:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Running setup.py install for mozrunner 14:46:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:46:44 INFO - Running setup.py install for mozscreenshot 14:46:45 INFO - Running setup.py install for moztest 14:46:45 INFO - Running setup.py install for mozversion 14:46:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:46:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:46:45 INFO - Cleaning up... 14:46:45 INFO - Return code: 0 14:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:45 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:45 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 14:46:45 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 14:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:45 INFO - 'HOME': '/Users/cltbld', 14:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:45 INFO - 'LOGNAME': 'cltbld', 14:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:45 INFO - 'MOZ_NO_REMOTE': '1', 14:46:45 INFO - 'NO_EM_RESTART': '1', 14:46:45 INFO - 'PAGER': '/bin/cat', 14:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:45 INFO - 'PWD': '/builds/slave/test', 14:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:45 INFO - 'SHELL': '/bin/bash', 14:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:45 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:45 INFO - 'USER': 'cltbld', 14:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:45 INFO - 'XPC_FLAGS': '0x0', 14:46:45 INFO - 'XPC_SERVICE_NAME': '0', 14:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:45 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-a19DZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:45 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)) 14:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:45 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-PAJSrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:45 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)) 14:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:45 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-n1ysJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:45 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)) 14:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:45 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-LknIzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-7pCPAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-8o8xpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-aQxusE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-eOb3MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-6yuxcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-I8PGw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-LiBQAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-qFBRVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:46 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)) 14:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:46 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-uttT73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:47 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)) 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:47 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-njd5Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:47 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)) 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:47 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-z0htDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:47 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)) 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:47 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-t7IiqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:47 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)) 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:47 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-xPvH49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:47 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)) 14:46:47 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)) 14:46:47 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)) 14:46:47 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)) 14:46:47 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:46:47 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:46:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:46:47 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 14:46:47 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 14:46:47 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 14:46:47 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 14:46:47 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 14:46:47 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 14:46:50 INFO - Downloading blessings-1.6.tar.gz 14:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:46:50 INFO - Installing collected packages: blessings 14:46:50 INFO - Running setup.py install for blessings 14:46:50 INFO - Successfully installed blessings 14:46:50 INFO - Cleaning up... 14:46:50 INFO - Return code: 0 14:46:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:46:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:46:50 INFO - Reading from file tmpfile_stdout 14:46:50 INFO - Current package versions: 14:46:50 INFO - blessings == 1.6 14:46:50 INFO - blobuploader == 1.2.4 14:46:50 INFO - docopt == 0.6.1 14:46:50 INFO - functools32 == 3.2.3-2 14:46:50 INFO - jsonschema == 2.5.1 14:46:50 INFO - manifestparser == 1.1 14:46:50 INFO - mozInstall == 1.12 14:46:50 INFO - mozcrash == 1.0 14:46:50 INFO - mozdebug == 0.1 14:46:50 INFO - mozdevice == 0.50 14:46:50 INFO - mozfile == 1.2 14:46:50 INFO - mozhttpd == 0.7 14:46:50 INFO - mozinfo == 0.9 14:46:50 INFO - mozleak == 0.1 14:46:50 INFO - mozlog == 3.4 14:46:50 INFO - moznetwork == 0.27 14:46:50 INFO - mozprocess == 0.25 14:46:50 INFO - mozprofile == 0.28 14:46:50 INFO - mozrunner == 6.13 14:46:50 INFO - mozscreenshot == 0.1 14:46:50 INFO - mozsystemmonitor == 0.3 14:46:50 INFO - moztest == 0.8 14:46:50 INFO - mozversion == 1.4 14:46:50 INFO - psutil == 3.1.1 14:46:50 INFO - requests == 1.2.3 14:46:50 INFO - wsgiref == 0.1.2 14:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:50 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:50 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 14:46:50 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 14:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:50 INFO - 'HOME': '/Users/cltbld', 14:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:50 INFO - 'LOGNAME': 'cltbld', 14:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:50 INFO - 'MOZ_NO_REMOTE': '1', 14:46:50 INFO - 'NO_EM_RESTART': '1', 14:46:50 INFO - 'PAGER': '/bin/cat', 14:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:50 INFO - 'PWD': '/builds/slave/test', 14:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:50 INFO - 'SHELL': '/bin/bash', 14:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:50 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:50 INFO - 'USER': 'cltbld', 14:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:50 INFO - 'XPC_FLAGS': '0x0', 14:46:50 INFO - 'XPC_SERVICE_NAME': '0', 14:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:50 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)) 14:46:50 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)) 14:46:50 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)) 14:46:50 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)) 14:46:50 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)) 14:46:51 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)) 14:46:51 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)) 14:46:51 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)) 14:46:51 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)) 14:46:51 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)) 14:46:51 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)) 14:46:51 INFO - Cleaning up... 14:46:51 INFO - Return code: 0 14:46:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:46:51 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')]} 14:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:51 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')]} 14:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:51 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 0x104eacb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6bb59b610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, '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.S882By4Kjq/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.EkRgYJe4vu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 14:46:51 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 14:46:51 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 14:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:51 INFO - 'HOME': '/Users/cltbld', 14:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:51 INFO - 'LOGNAME': 'cltbld', 14:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:51 INFO - 'MOZ_NO_REMOTE': '1', 14:46:51 INFO - 'NO_EM_RESTART': '1', 14:46:51 INFO - 'PAGER': '/bin/cat', 14:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:51 INFO - 'PWD': '/builds/slave/test', 14:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:51 INFO - 'SHELL': '/bin/bash', 14:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:46:51 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:51 INFO - 'USER': 'cltbld', 14:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:51 INFO - 'XPC_FLAGS': '0x0', 14:46:51 INFO - 'XPC_SERVICE_NAME': '0', 14:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:46:51 INFO - Cleaning up... 14:46:51 INFO - Return code: 0 14:46:51 INFO - Running post-action listener: _resource_record_post_action 14:46:51 INFO - Running post-action listener: _start_resource_monitoring 14:46:51 INFO - Starting resource monitoring. 14:46:51 INFO - [mozharness: 2017-05-01 21:46:51.309684Z] Finished create-virtualenv step (success) 14:46:51 INFO - [mozharness: 2017-05-01 21:46:51.310644Z] Running install step. 14:46:51 INFO - Running pre-action listener: _resource_record_pre_action 14:46:51 INFO - Running main action method: install 14:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:46:51 INFO - Reading from file tmpfile_stdout 14:46:51 INFO - Detecting whether we're running mozinstall >=1.0... 14:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:46:51 INFO - Reading from file tmpfile_stdout 14:46:51 INFO - Output received: 14:46:51 INFO - Usage: mozinstall [options] installer 14:46:51 INFO - Options: 14:46:51 INFO - -h, --help show this help message and exit 14:46:51 INFO - -d DEST, --destination=DEST 14:46:51 INFO - Directory to install application into. [default: 14:46:51 INFO - "/builds/slave/test"] 14:46:51 INFO - --app=APP Application being installed. [default: firefox] 14:46:51 INFO - mkdir: /builds/slave/test/build/application 14:46:51 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'] 14:46:51 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 14:47:11 INFO - Reading from file tmpfile_stdout 14:47:11 INFO - Output received: 14:47:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:47:11 INFO - Running post-action listener: _resource_record_post_action 14:47:11 INFO - [mozharness: 2017-05-01 21:47:11.544348Z] Finished install step (success) 14:47:11 INFO - [mozharness: 2017-05-01 21:47:11.544513Z] Running run-tests step. 14:47:11 INFO - Running pre-action listener: _resource_record_pre_action 14:47:11 INFO - Running main action method: run_tests 14:47:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:47:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:47:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:47:11 INFO - 2017-05-01 14:47:11.558 screenresolution[1645:7379] starting screenresolution argv=screenresolution get 14:47:11 INFO - 2017-05-01 14:47:11.561 screenresolution[1645:7379] Display 0: 1600x1200x32@60 14:47:11 INFO - 2017-05-01 14:47:11.567 screenresolution[1646:7381] starting screenresolution argv=screenresolution list 14:47:11 INFO - Available Modes on Display 0 14:47:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:47:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:47:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:47:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:47:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:47:11 INFO - Intel Iris: 14:47:11 INFO - Chipset Model: Intel Iris 14:47:11 INFO - Type: GPU 14:47:11 INFO - Bus: Built-In 14:47:11 INFO - VRAM (Dynamic, Max): 1536 MB 14:47:11 INFO - Vendor: Intel (0x8086) 14:47:11 INFO - Device ID: 0x0a2e 14:47:11 INFO - Revision ID: 0x0009 14:47:11 INFO - Displays: 14:47:11 INFO - Display: 14:47:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:47:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:47:11 INFO - Display Serial Number: 1600x1200 60 14:47:11 INFO - Main Display: Yes 14:47:11 INFO - Mirror: Off 14:47:11 INFO - Online: Yes 14:47:11 INFO - Rotation: Supported 14:47:11 INFO - Return code: 0 14:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:47:11 INFO - Python 2.7.3 14:47:11 INFO - Return code: 0 14:47:11 INFO - grabbing minidump binary from tooltool 14:47: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')]} 14:47: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 0x104c8ad30>, '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 0x104bb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c7fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:47: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 14:47: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 14:47: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 14:47:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:47:11 INFO - Return code: 0 14:47:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:47:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:47:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:47:11 INFO - ENV: RUST_BACKTRACE is now 1 14:47:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:47:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:47: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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 14:47: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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0278 --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 14:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S882By4Kjq/Render', 14:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:11 INFO - 'HOME': '/Users/cltbld', 14:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:11 INFO - 'LOGNAME': 'cltbld', 14:47:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:47:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:11 INFO - 'MOZ_NO_REMOTE': '1', 14:47:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:47:11 INFO - 'NO_EM_RESTART': '1', 14:47:11 INFO - 'PAGER': '/bin/cat', 14:47:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:11 INFO - 'PWD': '/builds/slave/test', 14:47:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:11 INFO - 'RUST_BACKTRACE': '1', 14:47:11 INFO - 'SHELL': '/bin/bash', 14:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EkRgYJe4vu/Listeners', 14:47:11 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 14:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:11 INFO - 'USER': 'cltbld', 14:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:11 INFO - 'XPC_FLAGS': '0x0', 14:47:11 INFO - 'XPC_SERVICE_NAME': '0', 14:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47: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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 14:47:12 INFO - mozversion application_buildid: 20170501111719 14:47:12 INFO - mozversion application_changeset: a7501c0dd13e1163ff65a83d30143303101ca313 14:47:12 INFO - mozversion application_display_name: Nightly 14:47:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:47:12 INFO - mozversion application_name: Firefox 14:47:12 INFO - mozversion application_remotingname: firefox 14:47:12 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 14:47:12 INFO - mozversion application_vendor: Mozilla 14:47:12 INFO - mozversion application_version: 55.0a1 14:47:12 INFO - mozversion platform_buildid: 20170501111719 14:47:12 INFO - mozversion platform_changeset: a7501c0dd13e1163ff65a83d30143303101ca313 14:47:12 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 14:47:12 INFO - mozversion platform_version: 55.0a1 14:47:12 INFO - using testdate: 1493675232 14:47:12 INFO - actual date: 1493675232 14:47:12 INFO - starting webserver on 'localhost:49235' 14:47:12 INFO - SUITE-START | Running 2 tests 14:47:12 INFO - TEST-START | dromaeo_css 14:47:12 INFO - operating with platform_type : mac_ 14:47:12 INFO - Initialising browser for dromaeo_css test... 14:47:12 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpfhaUff/profile http://localhost:49235/getInfo.html) 14:47:15 INFO - PID 1656 | 2017-05-01 14:47:15.373 plugin-container[1658:7563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 14:47:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:15 INFO - PID 1656 | 2017-05-01 14:47:15.377 plugin-container[1658:7563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 14:47:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:15 INFO - PID 1656 | 2017-05-01 14:47:15.377 plugin-container[1658:7563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:15 INFO - PID 1656 | __metrics Screen width/height:1600/1200 14:47:15 INFO - PID 1656 | colorDepth:24 14:47:15 INFO - PID 1656 | Browser inner width/height: 1024/689 14:47:15 INFO - PID 1656 | __metrics 14:47:16 INFO - TEST-INFO | 1656: exit 0 14:47:16 INFO - Browser initialized. 14:47:16 INFO - Running cycle 1/1 for dromaeo_css test... 14:47:16 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpfhaUff/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:18 INFO - PID 1659 | 2017-05-01 14:47:18.600 plugin-container[1660:7697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 14:47:18 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:18 INFO - PID 1659 | 2017-05-01 14:47:18.600 plugin-container[1660:7697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 14:47:18 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:18 INFO - PID 1659 | 2017-05-01 14:47:18.601 plugin-container[1660:7697] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:28 INFO - PID 1659 | RSS: Main: 237580288 14:47:28 INFO - PID 1659 | 14:50:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 14:50:34 INFO - PID 1659 | RSS: Main: 254840832 14:50:34 INFO - PID 1659 | 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | A coding exception was thrown and uncaught in a Task. 14:52:16 INFO - PID 1659 | 14:52:16 ERROR - PID 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:52:16 INFO - PID 1659 | Full stack: 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | A coding exception was thrown and uncaught in a Task. 14:52:16 INFO - PID 1659 | 14:52:16 ERROR - PID 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:52:16 INFO - PID 1659 | Full stack: 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | A coding exception was thrown and uncaught in a Task. 14:52:16 INFO - PID 1659 | 14:52:16 ERROR - PID 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:52:16 INFO - PID 1659 | Full stack: 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | ************************* 14:52:16 INFO - PID 1659 | A coding exception was thrown and uncaught in a Task. 14:52:16 INFO - PID 1659 | 14:52:16 ERROR - PID 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:52:16 INFO - PID 1659 | Full stack: 14:52:16 INFO - PID 1659 | ************************* 14:53:36 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 14:53:36 INFO - PID 1659 | RSS: Main: 257941504 14:53:36 INFO - PID 1659 | 14:56:38 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 14:56:39 INFO - PID 1659 | RSS: Main: 266801152 14:56:39 INFO - PID 1659 | 14:59:41 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 14:59:41 INFO - PID 1659 | RSS: Main: 259268608 14:59:41 INFO - PID 1659 | 15:02:44 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 15:02:45 INFO - PID 1659 | RSS: Main: 269361152 15:02:45 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 15:05:47 INFO - PID 1659 | RSS: Main: 264007680 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | __start_tp_report 15:05:47 INFO - PID 1659 | _x_x_mozilla_page_load 15:05:47 INFO - PID 1659 | _x_x_mozilla_page_load_details 15:05:47 INFO - PID 1659 | |i|pagename|runs| 15:05:47 INFO - PID 1659 | |0;dojo.html;3091.5980922480076;3615.0600843780626;3641.763285386448;3665;3668.1746607013424;5412.161115027172;5780.670445797464;6112.321110680151;6274.623522588642;6293.181886354768;6187.752489900398;6229.595076320036;6231.781887633933;6233.937660623393;6320.557560970734;5442.102053161234;5444.7822087116465;5462.535684466805;5463.535599474049;5473.480019398151;5518.310211223597;5574.777008919658;5587.3015873015875;5590;5606.719664016803;5469.699166545838;5505.476979686963;5507.339119305669;5521.0062188806;5586.888262234753;5516.979358818631;5518.117101263819;5542.057850165482;5550.91673624896;5567.749451274662;5432.565394768409;5457.536109430664;5475.7262136893;5486.588505862036;5510.724463776835;5530.562053865983;5624.803131890405;5964.254468191476;6201.410865967771;6261;6706.26231114577;6730.528862979593;6739.326067393271;6748.19021717397;6750.257471678112;6817.0796942412635;6821.795346139624;6836.1113055302985;6845.212800527904;6866.6566671666715;6242.032484964838;6261.530385221126;6362.459190968727;6363.649999250039;6393.041043843415;6919.100516932766;6938.548994315353;6962;6979.581225126509;7016.649167541603;6400.167978162809;6447.967760161263;6546.345365463416;6557.934420655828;6632.701528431232;6706.865862682719;6815.420689241371;6820.761273355409;6827.2831352708245;6849.828754281083;6137.867325793252;6249.0626406039455;6640.435562977201;6723.932760672331;6733.293004234485;6549.984752363392;6571.769988050395;6657.766978155838;6708;6729.024291477708;5285.841424757264;5348.144296912553;5362.329708786402;5365.7585408656705;5401.918971215435;4762.071396077801;4762.6189904808225;4765.380500534978;4769.880752981203;4791.664583479123;5143.871403214875;6003.549733769984;6336.20797400325;6384.297727249998;6447.226332839996;6841.897371539431;6845;6855.040294358794;6876.071730316343;6887.483438742114;6270.965290726979;6446.097546343516;6456.3543645636;6458.095866578683;6478.676066196671;5814.273215848019;6017.217761690952;6026.367231440723;6027.21646185993;6029.155918171459;6850.246472887879;6866.690998905061;6941.028256044056;6978.616176110159;6985.26654701249;6812.182538095461;6827.556208846409;6842.349976752201;6851.45188384938;6862.073620061427;6959.686814093277;6971.965140174267;7001;7028.297170282931;7044.6125463100025;6529.183852018497;6552.08270842092;6679.732810687712;6735.2927942567285;6771.255161932282;6808.489363297872;6835.726570937107;6856.005879147396;6889.311068893071;6923.273056329013;6362.300146983921;6385.96807015962;6486.740530378732;6528.249251336157;6637.867242655023;5541.584381171348;6639.502037347122;6643.202815662152;6643.40209381158;6667.43326817226;6274.215723034621;6324.841878953063;6513;6536.346365363425;6537.607743535403;6340.778072767431;6440.25937017249;6567.047778072249;6582.275949645439;6603.603783772989;6421.486281097595;6424.518161138027;6428.614283143026;6429.260635027032;6435 15:05:47 INFO - PID 1659 | |1;ext.html;696.72131147541;747.19676347926;749.5240522268359;1218.6100447856688;1273.3760457375888;36329.636703632954;37447;37482.25035499288;37507;37536.81231593845;37159.62840371596;37397.62602373975;37409.43885841715;37442;37465;7856;8025.157358477363;8329.66681332749;8567.35744819138;8588.01237857648;8561.015483219448;8569.057403685621;8637.179467950535;8645.567721613894;8667.69663061793;8634.093420190884;8682.348823838205;8689.04420513743;8696.086910874363;8709.912900871006;8656.480611163319;8665.046844847062;8690.478571285712;8701.695440659547;8719.302455803583;8445.028821685524;8456.704015359433;8497.36269779769;8526.488410695378;8537.530435826027;36493.27013459743;36541.634583654355;36651.71718989823;36768.264634707426;36777.81611091954;3654.5797233318244;3701.0747313171705;3707.017640325316;3708.2583483303447;3716.4425336199515;30051;30119.247018824484;30307.696923030708;30380.69619303801;30396.848015760002;3157.1317887580867;3194.552762613236;3199.840007999591;3200.071979126074;3206.246532064963;245100;254263.99999999814;299934;302369;305149;34116;44314.77842610766;44339;44358.669239922856;44478.77760611176;47093;47234.76382618133;47296.5270347299;52334;55180.17229741557;14058.71882562343;14090.295485225903;14228.075175113585;14240.572782816533;14271.286435678174;11240.04459620925;11295.604653837241;11310.264832785842;11330.830037549444;11347.262427942158;14020.088694234842;14130.222837743817;14146.797522210521;14158.787618185735;14239.003269771016;5980.790672326448;6200.906986395207;6217.875642487125;6225.128482012522;6261.123442718023;996.5913975270105;1012.8936461671566;1031.4791030529534;1057.2757661002083;1064.9094826939797;22137.446563836034;23239.3028209154;24134;24226.51546969051;24305.39236519101;1016.6035246253966;1111.8109921313396;1112.3437172068518;1143.731223162556;1152.3086148311113;10609.681709548726;11549.249298795552;11874.0500759939;12482.50069997208;12490.875091249152;289782;290439;290832;291028;292667;53182.46817531853;53649;53901.73049134729;53946;54046.729766350916;1516.726989141965;1523.6343277613514;1555.5955451582445;1557.5093845438878;1575;19423.70864437063;21865.344039678836;23758;23810.642840357756;23831.523369532166;35069;37575.81212093922;49383.25925111055;50020.49979500158;50033.99932001267;301755;302498;304665;306238;307959;34266.97199084031;35467.29065418729;35470.40383182711;35675.82162089173;35999.460008100425;35807.55464016502;42782.57217427786;43228;44836.551634484546;45015;29605.555916660836;30070.84964575163;30537.08388748341;30797.692023079482;31007.0697879064;29877.850610746806;29934;30546.389072218877;30799.692003080578;30884;36976.81511592425;37006;37034.444483332234;37129.62870371262;37221;24748.50502989966;28648.85675571609;29376.853115734284;31368.843155784074;31542;22251.66622500696;24005.039798407866;24390.658513781204;24396;24661.63007554852 15:05:47 INFO - PID 1659 | |2;jquery.html;410.5894105894106;433.5424985738733;433.70074648492516;442.8715672454988;456.9405977222963;20881.582368352643;21595.568088638218;21719.457013574647;21724.674129888044;22210.000549975248;18778.906105469487;21922;22944.885275573597;23099;23397.713125778075;5988.910166347487;6042.697865106749;6318.873622527547;6320.241571011485;6321.494280457552;5937.287525496925;6129.264488261392;6131.264248290211;6163.845903852395;6172.321044685081;5832.445917637818;6102.786402475915;6105.358937311585;6116.724747386356;6118.908216376735;5601.971990140044;5721.256236689224;6065.514758819328;6067.696615169246;6070.6357618543025;4257.6168144867115;5392.083345831218;5404.189371594253;5447.809326673548;5449.754761035763;27520.449591008066;27527.31181720453;27853.303667408276;28017;28213.435731285466;1895.5640202753443;2274.454131008546;2276.886155692209;2277.9202727904444;2280;17026.978381297162;18014.91910485375;18074.277028918827;18513.2594696212;18688.719669204973;1966.8721533100302;2159.168720042779;2203.0416768705504;2205.6250437425674;2208.9447763806033;171055;172953.99999999875;173379;174183.12908435502;174373;10164.593416263415;12455.813162802566;12670.429830657642;12694.047946404056;12856.228626282453;13253.801192982113;16335.59161020984;16424.67150657004;16456.588585285463;16472.341106355852;7415.369693576106;8692.739217823475;8783.91216087831;8892.510911899908;8957;6869.381755642016;6907.481938854606;8422.620982055822;8559.315254779603;8597.398182127316;6560.311167327457;6871.14110736158;7806.916268074079;7954.761357159295;7985.041794984639;1992.2529051605648;2204.5039866030015;2212.9889350553144;2219.8002179803893;2382.689520763573;843.4011851585443;949.6438835436711;969.3360048366891;986.8273052215834;1011.8684571005722;15512.612184695472;20385.79614203839;20782.376528704164;21039.05324260412;21266.468338291357;1009.2733232072895;1011.3476807459294;1018.7402212435782;1035.0736091198335;1035.5805898611075;7905.762827115196;10122.089011988955;10703.571857125784;10748.838767418494;10800.729981750519;505160;550978;556491;558756;567513;129337.59305645103;133423;138613;141511;142261;1201.9699507512382;1220.7192345760525;1251.612000279898;1255.6609715376796;1261.7476504699098;19224.8077519223;21794.89102554477;22168.778312217317;22251.99062295372;23741.64387534154;53455;60732.6963365198;61527;61809;62325.37674623196;78115;80354.59822701084;81356.7796483065;81908.59045704734;82196.58901705453;9041.59312830911;9086.500242486727;9105.635774568944;9162.862557061782;9211.078892110736;14280.571582852532;14395.784063239269;14575.344109514885;14583.416663333772;14707.117572945657;10140;12050.337231452355;12050.638480845239;12273.87726122763;12275.69310767238;9563.904360956301;11443.771124577295;11516.712082198012;11843.822342664695;11851.881481185077;11161.218714689894;12045.63863084109;12122.393880306126;12407.689807754878;12502.124851260325;8853.911460885309;8894;9184;9348.439093654402;9360.719178424659;11802.881971180179;12000.459979300778;12061.336626485627;12115.75768484643;12198.939005304917 15:05:47 INFO - PID 1659 | |3;mootools.html;1196.718771088794;1290.825738525299;1447.6670365815862;1471.3967273417888;1478.1869971515664;11345.602903898365;11362.772744545115;11841.822372664406;11845;12131;11620.477078531465;11690.707732306697;11866.703332416673;11913.583024594142;11987.520499180022;2532.303616505462;2556.386467247856;2577.6133579962966;2577.935551611206;2587.3919628887197;2480.6775119234476;2521.684789401325;2526.7220605733446;2530.6583611212436;2536.7843733282693;2498.562751518486;2525.4570267392505;2529.6964364276314;2558.1941688368197;2561.8590977496324;2418.04487227545;2422.1401402502124;2454.1901172613084;2470.901163953458;2472.8763561822016;2410.6625072489865;2479.3677612208953;2494.5634513960167;2513.195777351248;2534.569123249052;9899.406035637885;11882.989945854613;11962.581309654208;12034.879651203551;12040.157188996774;6649.6010239385305;6827.658617069176;6881.071055407506;6888.345607167311;6918.0314755934205;3284.293876816472;4338.522762496123;4374.628156606692;4408.845690400853;4412.271975124101;1279.1941077121446;1286.7040580666498;1302.042998396178;1303.270168705528;1330.115473210314;15033.774493382609;15517.456889008943;16501.504954851374;16680.33278668852;16681.332746690117;5486.807961721321;5532.3914369419335;5750.913736293959;5774.538036957033;5788.971055144697;11795.646130616095;11827.586034488751;11902.285862848257;11910.52357905674;11923;6548.148740663779;6582.835429114215;6595.538312318092;6599.373059559334;6605.339466053356;6053.364396738367;6106.664133472613;6200.3179650238435;6219.626822390671;6277.560570760094;4335.1329734053315;4429.49060857999;4430.246858034142;4443;4479.507254202035;1251.9061070419755;1269.936503174856;1273;1286.408252203978;1290.9031822613342;794.7814351053506;807.8828569857337;828.9596556321777;830.8878301429351;852.5694524265331;6144.324124346318;6161.075838624152;6267.1539342188225;6389.712462939179;6439.484841212692;573.5153795043182;617.0774691835734;622.6015350175891;626.4831514000876;629.8016124920636;3769.7738135711943;3969.1069509360163;4145.2124096421585;4309.202797482413;4382.474103107647;14472.493462728755;14710.632234194016;14867.70264594702;15223.771643425358;15244.923775381274;14675.339609717588;14887.851121488646;15257.694846103015;15265.771013434807;15286.923565382325;7466;8537.701180458778;8681.696140635047;8727.781805454788;8759.781005474786;6479.1901012373455;7120.857582848211;7145.749493838662;7218.747343842941;7228.819279518054;12546.059045571435;12566.497340106293;12648.177868438523;12903.096783225084;12963.546275880299;11730;13870.306484675524;14027;14208.857911421166;14333.211673358066;7348.228436014141;9403.01268366839;9521.428714277166;9539.809203816023;9548.85676714836;10693.625723099654;10777.62278320255;10806.243562950518;10830.18773591968;10840.295380800222;8969.230156834865;9177.632894684139;9190.724278271662;9322.860157097784;9368.203702685349;9061.411008284704;9114.043025482493;9147.993720690585;9157.221636161;9182.954085229798;8211.917880821115;8306.584670766559;8339.66641334364;8348.582570871311;8368.372372071997;6054.788082417271;6105.236845394325;6193.81418557444;6244.500439964882;6245.094461302995;5758.539316854725;6010.4891084258325;6141.877162456815;6176.227971503562;6279.560430769802 15:05:47 INFO - PID 1659 | |4;prototype.html;339.7808413573245;395.5629029921938;400.97995100245;413.1159319057217;414.0497558104151;4971.22762148338;5621.381648018714;6223.471004964577;6290.591111577755;6300.432961033506;5996.310424301196;6105.389461053893;6176.691165441721;6182.103594978726;6189.814305570829;3850.4994350734364;4332.740035597874;4613.423322084739;4660.883477913054;4710.670253082286;4713;4751.216049351853;4762.714067201853;4768.356271903301;4794.856154315376;4076.388541718736;4315.331123675835;4740.573348398643;4744.651397558214;4751.833685820997;4543.909121817578;4705.952940470585;4769.165396055711;4779.737114458703;4780.211265141247;4200.684948628865;4201.831926722927;4207.97896010518;4208.873733787991;4212.830392347943;5381.650192737498;5500.532454741352;5991.8915000725;6012.6392416455155;6019.759209631609;5424.891502169974;5576.191452239403;5601.775928962837;5655.632383895075;5673.574481913873;6078.574499785017;6144.446999770043;6611.669356571453;6676;6688.9665551672415;4684.812607495696;4806.543378379083;4818.518148185189;4833.347498087748;4837.379477874916;5782.653040817564;6487.967560162217;6749.358810912955;6974;7153.998440218374;4403.987082970871;4417.469903611587;4912.214045752662;4922.212446008622;4929.2359684248995;4681.555252251031;4975.353204083518;5011.799528018839;5014.7994080236385;5038.546530812245;4844.055409195244;4849.854504364804;5522.751476183581;5601.495865372054;5645.153227015899;6457.7094030767785;6638.83402914931;6659.300773418819;6672.599644021374;6692.218896323659;1570.9057456552644;1682.9074400908069;1712.9914350428169;1717.158592289769;1723.2331612432597;1139.601139601133;1183.2013390961067;1183.207251141737;1185.1111666250313;1203.1818363512896;6349.428551430394;6351.697901930185;6359;6362.395572420613;6380.202474690664;5858.916100521417;6639.634820084942;6919.4464442844455;6927.376536111673;6951;5296.814611488579;6028.824379246093;6062.333143354227;6318.684065796691;6398.232212134573;2738.246982079944;3353.7758718068217;3511.139770756181;3550.1479644885044;3598.0824889653495;7075.150981882104;7083.574985500783;7128.786136415916;7142.107236595426;7157.964210178916;6136.478399336017;6308.432241098231;6783.660816959133;6989.89515157273;7110.751123710749;5238.790448382022;5503.587230957694;5679.829605111853;6410.423061924449;6806.76176333826;6219.937800622117;6315.96842015787;6944.548604340702;6969.4424446045205;6986.825329366603;5420.985588330857;6426.935730642821;6587.835304117244;6831.975203719482;6875;5841.912371314519;6415.518836087405;7134.536255143399;7167.139943206848;7173.748918787817;4154.293770059157;4364.519902810752;4371.737695738266;4399.626031787334;4404.955950440454;5877.882442351214;6703.3296670332575;6845.4865885057825;6945.617991010341;6986.755463558751;5959.582829201914;6125.356837531995;6256.468200203034;6597.6041437511985;6701.296363881722;5163.483651634807;6036.215292012011;6428.389303016206;6566.606003639606;6670.733170673118;5886.90937257943;5965.642061476152;6494.3505649436565;6497.805065848032;6499.870002599827;6385.553011289165;6623.801285961429;6638.236602790741;6641.6716656667895;6659.334066593302;6398.248753036239;6428.8071357859335;6451.3548645135115;6483.189601299838;6483.740650373932 15:05:47 INFO - PID 1659 | |5;yui.html;808.1110778144041;834.8205135895781;844.2992316377404;871.2594294849379;876.307716903646;13876.30618469075;14057.437702491914;14660.340284687189;16227;16719;13063.869361306384;13250.602481925558;17313.30746770131;17388.391406300783;17420.216090275935;960.5725452173803;980.1913421427315;982.1553464020972;983.7245571240029;985.9802803943917;965.3339195954769;974.9025097490265;982.2387649571567;982.6855406269997;984.6455276100598;985.1527686189871;1010.4341568721503;1017.4505766885873;1023.4473384372467;1024.2676486312284;1004.4927311707578;1008.092716555099;1024.7438140464883;1025.3489034463096;1027.8509616105623;742.0020073001804;746.6453434618567;746.9141048779405;748.6294284329292;749.7375918428561;13715.931420342935;14255.572332830032;14542.781858272134;16900.830991690047;17361.47915562535;12336.62990110298;12537.811932821014;15161.24193790317;15350.539483815503;15359.15524646144;13886.02797804154;14253.714925701532;14259;14375.28123593816;14379.928100359535;3502.4921386399083;3530.3998320285614;3544.486049522805;3547.6274991126074;3550.840212190458;130808;139445.30277348548;139955.30022349925;140448.5955140456;141797.29101354428;13151.60545183646;13310.46758129664;13332.600021999355;13425.999999999805;13447.193168409927;25349.74650253511;25484.108056217945;25748.485030299664;25946.35134121662;26005.739942600332;7598.164201937782;7741.380689544823;7850.803729906684;7852.254035866584;7860.84278314442;3545.680888719976;3595.676389124991;3618.620044895301;3623.202895363016;3624.7462677612316;3946.4869566956686;4041.5958404159937;4342.478902531717;4739.66822322441;4755.595774359218;959.3092973059386;979.8584648884113;990.5542505872386;991.6281394477071;993.8757655293089;640.7565125252374;652.8922727749868;656.2748163279603;664.0537234440999;677.2584020497547;11035.889641103648;11706.76586468283;11762.76474470523;11863.881670997333;11864.940675296568;641.586176915896;646.3859333633068;653.7973228299243;660.7059858362983;665.4377051391566;5150.765647627902;5302.496262855023;5404.135338345845;5416.674999500043;5509.889802203934;84285.15714842774;104857.47571262247;107407;107711.38432923512;108650;56265.87468250694;82360.17639823645;84907.99999999876;85791;87016;79727.8040829388;79952.80070798943;80169;81557.21313442306;82491.9999999988;11077.335359878432;11979.401029948292;12089.576864810042;12507.874921251036;12540.184887982252;37970.62029379671;38843.417348739225;38853.64012259557;39148;39638.05141383132;49985.50014499954;50222;71625.34435261691;73873.63063184649;74127.92296423748;16297.59256018609;16609.920355176873;16622.3351065956;16645;16649.084300363113;17783.822161778735;17803.376881809076;18049.1877865499;18081.36715214961;18217.99801010957;13545.932270338586;16147.919260403623;16284.77864160169;16794.160291985107;16868.493945181664;16157.919210403872;16652.83347166513;16704.832951670327;16773.664526709643;16840.1579921001;14076.859231407965;15894.761578576099;16070.955387899749;17202.569935751708;17254;10561.577536898745;11392.544298227978;11729.648110556696;11781.705457363634;11801.881981180422;13403.597892063253;13456.730865382833;13832.654183645087;13980.021398502007;14005.789913151108 15:05:47 INFO - PID 1659 | __end_tp_report 15:05:47 INFO - PID 1659 | __start_cc_report 15:05:47 INFO - PID 1659 | _x_x_mozilla_cycle_collect,422 15:05:47 INFO - PID 1659 | __end_cc_report 15:05:47 INFO - PID 1659 | __startTimestamp1493676347271__endTimestamp 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | ------- Summary: start ------- 15:05:47 INFO - PID 1659 | Number of tests: 6 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#0] dojo.html Cycles:165 Average:6198.90 Median:6429.26 stddev:747.76 (11.6%) stddev-sans-first:709.21 15:05:47 INFO - PID 1659 | Values: 3091.6 3615.1 3641.8 3665.0 3668.2 5412.2 5780.7 6112.3 6274.6 6293.2 6187.8 6229.6 6231.8 6233.9 6320.6 5442.1 5444.8 5462.5 5463.5 5473.5 5518.3 5574.8 5587.3 5590.0 5606.7 5469.7 5505.5 5507.3 5521.0 5586.9 5517.0 5518.1 5542.1 5550.9 5567.7 5432.6 5457.5 5475.7 5486.6 5510.7 5530.6 5624.8 5964.3 6201.4 6261.0 6706.3 6730.5 6739.3 6748.2 6750.3 6817.1 6821.8 6836.1 6845.2 6866.7 6242.0 6261.5 6362.5 6363.6 6393.0 6919.1 6938.5 6962.0 6979.6 7016.6 6400.2 6448.0 6546.3 6557.9 6632.7 6706.9 6815.4 6820.8 6827.3 6849.8 6137.9 6249.1 6640.4 6723.9 6733.3 6550.0 6571.8 6657.8 6708.0 6729.0 5285.8 5348.1 5362.3 5365.8 5401.9 4762.1 4762.6 4765.4 4769.9 4791.7 5143.9 6003.5 6336.2 6384.3 6447.2 6841.9 6845.0 6855.0 6876.1 6887.5 6271.0 6446.1 6456.4 6458.1 6478.7 5814.3 6017.2 6026.4 6027.2 6029.2 6850.2 6866.7 6941.0 6978.6 6985.3 6812.2 6827.6 6842.3 6851.5 6862.1 6959.7 6972.0 7001.0 7028.3 7044.6 6529.2 6552.1 6679.7 6735.3 6771.3 6808.5 6835.7 6856.0 6889.3 6923.3 6362.3 6386.0 6486.7 6528.2 6637.9 5541.6 6639.5 6643.2 6643.4 6667.4 6274.2 6324.8 6513.0 6536.3 6537.6 6340.8 6440.3 6567.0 6582.3 6603.6 6421.5 6424.5 6428.6 6429.3 6435.0 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#1] ext.html Cycles:180 Average:44318.39 Median:18661.12 stddev:76720.61 (411.1%) stddev-sans-first:76865.92 15:05:47 INFO - PID 1659 | Values: 696.7 747.2 749.5 1218.6 1273.4 36329.6 37447.0 37482.3 37507.0 37536.8 37159.6 37397.6 37409.4 37442.0 37465.0 7856.0 8025.2 8329.7 8567.4 8588.0 8561.0 8569.1 8637.2 8645.6 8667.7 8634.1 8682.3 8689.0 8696.1 8709.9 8656.5 8665.0 8690.5 8701.7 8719.3 8445.0 8456.7 8497.4 8526.5 8537.5 36493.3 36541.6 36651.7 36768.3 36777.8 3654.6 3701.1 3707.0 3708.3 3716.4 30051.0 30119.2 30307.7 30380.7 30396.8 3157.1 3194.6 3199.8 3200.1 3206.2 245100.0 254264.0 299934.0 302369.0 305149.0 34116.0 44314.8 44339.0 44358.7 44478.8 47093.0 47234.8 47296.5 52334.0 55180.2 14058.7 14090.3 14228.1 14240.6 14271.3 11240.0 11295.6 11310.3 11330.8 11347.3 14020.1 14130.2 14146.8 14158.8 14239.0 5980.8 6200.9 6217.9 6225.1 6261.1 996.6 1012.9 1031.5 1057.3 1064.9 22137.4 23239.3 24134.0 24226.5 24305.4 1016.6 1111.8 1112.3 1143.7 1152.3 10609.7 11549.2 11874.1 12482.5 12490.9 289782.0 290439.0 290832.0 291028.0 292667.0 53182.5 53649.0 53901.7 53946.0 54046.7 1516.7 1523.6 1555.6 1557.5 1575.0 19423.7 21865.3 23758.0 23810.6 23831.5 35069.0 37575.8 49383.3 50020.5 50034.0 301755.0 302498.0 304665.0 306238.0 307959.0 34267.0 35467.3 35470.4 35675.8 35999.5 35807.6 42782.6 43228.0 44836.6 45015.0 29605.6 30070.8 30537.1 30797.7 31007.1 29877.9 29934.0 30546.4 30799.7 30884.0 36976.8 37006.0 37034.4 37129.6 37221.0 24748.5 28648.9 29376.9 31368.8 31542.0 22251.7 24005.0 24390.7 24396.0 24661.6 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#2] jquery.html Cycles:180 Average:36425.02 Median:2275.67 stddev:94020.44 (4131.5%) stddev-sans-first:94245.31 15:05:47 INFO - PID 1659 | Values: 410.6 433.5 433.7 442.9 456.9 20881.6 21595.6 21719.5 21724.7 22210.0 18778.9 21922.0 22944.9 23099.0 23397.7 5988.9 6042.7 6318.9 6320.2 6321.5 5937.3 6129.3 6131.3 6163.8 6172.3 5832.4 6102.8 6105.4 6116.7 6118.9 5602.0 5721.3 6065.5 6067.7 6070.6 4257.6 5392.1 5404.2 5447.8 5449.8 27520.4 27527.3 27853.3 28017.0 28213.4 1895.6 2274.5 2276.9 2277.9 2280.0 17027.0 18014.9 18074.3 18513.3 18688.7 1966.9 2159.2 2203.0 2205.6 2208.9 171055.0 172954.0 173379.0 174183.1 174373.0 10164.6 12455.8 12670.4 12694.0 12856.2 13253.8 16335.6 16424.7 16456.6 16472.3 7415.4 8692.7 8783.9 8892.5 8957.0 6869.4 6907.5 8422.6 8559.3 8597.4 6560.3 6871.1 7806.9 7954.8 7985.0 1992.3 2204.5 2213.0 2219.8 2382.7 843.4 949.6 969.3 986.8 1011.9 15512.6 20385.8 20782.4 21039.1 21266.5 1009.3 1011.3 1018.7 1035.1 1035.6 7905.8 10122.1 10703.6 10748.8 10800.7 505160.0 550978.0 556491.0 558756.0 567513.0 129337.6 133423.0 138613.0 141511.0 142261.0 1202.0 1220.7 1251.6 1255.7 1261.7 19224.8 21794.9 22168.8 22252.0 23741.6 53455.0 60732.7 61527.0 61809.0 62325.4 78115.0 80354.6 81356.8 81908.6 82196.6 9041.6 9086.5 9105.6 9162.9 9211.1 14280.6 14395.8 14575.3 14583.4 14707.1 10140.0 12050.3 12050.6 12273.9 12275.7 9563.9 11443.8 11516.7 11843.8 11851.9 11161.2 12045.6 12122.4 12407.7 12502.1 8853.9 8894.0 9184.0 9348.4 9360.7 11802.9 12000.5 12061.3 12115.8 12198.9 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#3] mootools.html Cycles:180 Average:7105.32 Median:3869.44 stddev:4473.61 (115.6%) stddev-sans-first:4464.12 15:05:47 INFO - PID 1659 | Values: 1196.7 1290.8 1447.7 1471.4 1478.2 11345.6 11362.8 11841.8 11845.0 12131.0 11620.5 11690.7 11866.7 11913.6 11987.5 2532.3 2556.4 2577.6 2577.9 2587.4 2480.7 2521.7 2526.7 2530.7 2536.8 2498.6 2525.5 2529.7 2558.2 2561.9 2418.0 2422.1 2454.2 2470.9 2472.9 2410.7 2479.4 2494.6 2513.2 2534.6 9899.4 11883.0 11962.6 12034.9 12040.2 6649.6 6827.7 6881.1 6888.3 6918.0 3284.3 4338.5 4374.6 4408.8 4412.3 1279.2 1286.7 1302.0 1303.3 1330.1 15033.8 15517.5 16501.5 16680.3 16681.3 5486.8 5532.4 5750.9 5774.5 5789.0 11795.6 11827.6 11902.3 11910.5 11923.0 6548.1 6582.8 6595.5 6599.4 6605.3 6053.4 6106.7 6200.3 6219.6 6277.6 4335.1 4429.5 4430.2 4443.0 4479.5 1251.9 1269.9 1273.0 1286.4 1290.9 794.8 807.9 829.0 830.9 852.6 6144.3 6161.1 6267.2 6389.7 6439.5 573.5 617.1 622.6 626.5 629.8 3769.8 3969.1 4145.2 4309.2 4382.5 14472.5 14710.6 14867.7 15223.8 15244.9 14675.3 14887.9 15257.7 15265.8 15286.9 7466.0 8537.7 8681.7 8727.8 8759.8 6479.2 7120.9 7145.7 7218.7 7228.8 12546.1 12566.5 12648.2 12903.1 12963.5 11730.0 13870.3 14027.0 14208.9 14333.2 7348.2 9403.0 9521.4 9539.8 9548.9 10693.6 10777.6 10806.2 10830.2 10840.3 8969.2 9177.6 9190.7 9322.9 9368.2 9061.4 9114.0 9148.0 9157.2 9183.0 8211.9 8306.6 8339.7 8348.6 8368.4 6054.8 6105.2 6193.8 6244.5 6245.1 5758.5 6010.5 6141.9 6176.2 6279.6 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#4] prototype.html Cycles:180 Average:5375.01 Median:5962.61 stddev:1620.80 (27.2%) stddev-sans-first:1580.67 15:05:47 INFO - PID 1659 | Values: 339.8 395.6 401.0 413.1 414.0 4971.2 5621.4 6223.5 6290.6 6300.4 5996.3 6105.4 6176.7 6182.1 6189.8 3850.5 4332.7 4613.4 4660.9 4710.7 4713.0 4751.2 4762.7 4768.4 4794.9 4076.4 4315.3 4740.6 4744.7 4751.8 4543.9 4706.0 4769.2 4779.7 4780.2 4200.7 4201.8 4208.0 4208.9 4212.8 5381.7 5500.5 5991.9 6012.6 6019.8 5424.9 5576.2 5601.8 5655.6 5673.6 6078.6 6144.4 6611.7 6676.0 6689.0 4684.8 4806.5 4818.5 4833.3 4837.4 5782.7 6488.0 6749.4 6974.0 7154.0 4404.0 4417.5 4912.2 4922.2 4929.2 4681.6 4975.4 5011.8 5014.8 5038.5 4844.1 4849.9 5522.8 5601.5 5645.2 6457.7 6638.8 6659.3 6672.6 6692.2 1570.9 1682.9 1713.0 1717.2 1723.2 1139.6 1183.2 1183.2 1185.1 1203.2 6349.4 6351.7 6359.0 6362.4 6380.2 5858.9 6639.6 6919.4 6927.4 6951.0 5296.8 6028.8 6062.3 6318.7 6398.2 2738.2 3353.8 3511.1 3550.1 3598.1 7075.2 7083.6 7128.8 7142.1 7158.0 6136.5 6308.4 6783.7 6989.9 7110.8 5238.8 5503.6 5679.8 6410.4 6806.8 6219.9 6316.0 6944.5 6969.4 6986.8 5421.0 6426.9 6587.8 6832.0 6875.0 5841.9 6415.5 7134.5 7167.1 7173.7 4154.3 4364.5 4371.7 4399.6 4405.0 5877.9 6703.3 6845.5 6945.6 6986.8 5959.6 6125.4 6256.5 6597.6 6701.3 5163.5 6036.2 6428.4 6566.6 6670.7 5886.9 5965.6 6494.4 6497.8 6499.9 6385.6 6623.8 6638.2 6641.7 6659.3 6398.2 6428.8 6451.4 6483.2 6483.7 15:05:47 INFO - PID 1659 | 15:05:47 INFO - PID 1659 | [#5] yui.html Cycles:180 Average:21726.26 Median:18065.28 stddev:31526.35 (174.5%) stddev-sans-first:31575.67 15:05:47 INFO - PID 1659 | Values: 808.1 834.8 844.3 871.3 876.3 13876.3 14057.4 14660.3 16227.0 16719.0 13063.9 13250.6 17313.3 17388.4 17420.2 960.6 980.2 982.2 983.7 986.0 965.3 974.9 982.2 982.7 984.6 985.2 1010.4 1017.5 1023.4 1024.3 1004.5 1008.1 1024.7 1025.3 1027.9 742.0 746.6 746.9 748.6 749.7 13715.9 14255.6 14542.8 16900.8 17361.5 12336.6 12537.8 15161.2 15350.5 15359.2 13886.0 14253.7 14259.0 14375.3 14379.9 3502.5 3530.4 3544.5 3547.6 3550.8 130808.0 139445.3 139955.3 140448.6 141797.3 13151.6 13310.5 13332.6 13426.0 13447.2 25349.7 25484.1 25748.5 25946.4 26005.7 7598.2 7741.4 7850.8 7852.3 7860.8 3545.7 3595.7 3618.6 3623.2 3624.7 3946.5 4041.6 4342.5 4739.7 4755.6 959.3 979.9 990.6 991.6 993.9 640.8 652.9 656.3 664.1 677.3 11035.9 11706.8 11762.8 11863.9 11864.9 641.6 646.4 653.8 660.7 665.4 5150.8 5302.5 5404.1 5416.7 5509.9 84285.2 104857.5 107407.0 107711.4 108650.0 56265.9 82360.2 84908.0 85791.0 87016.0 79727.8 79952.8 80169.0 81557.2 82492.0 11077.3 11979.4 12089.6 12507.9 12540.2 37970.6 38843.4 38853.6 39148.0 39638.1 49985.5 50222.0 71625.3 73873.6 74127.9 16297.6 16609.9 16622.3 16645.0 16649.1 17783.8 17803.4 18049.2 18081.4 18218.0 13545.9 16147.9 16284.8 16794.2 16868.5 16157.9 16652.8 16704.8 16773.7 16840.2 14076.9 15894.8 16071.0 17202.6 17254.0 10561.6 11392.5 11729.6 11781.7 11801.9 13403.6 13456.7 13832.7 13980.0 14005.8 15:05:47 INFO - PID 1659 | -------- Summary: end -------- 15:05:47 INFO - PID 1659 | 15:05:47 INFO - TEST-INFO | 1659: exit 0 15:05:47 INFO - TEST-OK | dromaeo_css | took 1115837ms 15:05:47 INFO - TEST-START | kraken 15:05:47 INFO - operating with platform_type : mac_ 15:05:47 INFO - Initialising browser for kraken test... 15:05:48 INFO - TEST-INFO | started process 1783 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpz6dB3J/profile http://localhost:49235/getInfo.html) 15:05:50 INFO - PID 1783 | 2017-05-01 15:05:50.442 plugin-container[1784:10324] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 15:05:50 INFO - PID 1783 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:05:50 INFO - PID 1783 | 2017-05-01 15:05:50.446 plugin-container[1784:10324] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 15:05:50 INFO - PID 1783 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:05:50 INFO - PID 1783 | 2017-05-01 15:05:50.446 plugin-container[1784:10324] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:05:50 INFO - PID 1783 | __metrics Screen width/height:1600/1200 15:05:50 INFO - PID 1783 | colorDepth:24 15:05:50 INFO - PID 1783 | Browser inner width/height: 1024/689 15:05:50 INFO - PID 1783 | __metrics 15:05:51 INFO - TEST-INFO | 1783: exit 0 15:05:51 INFO - Browser initialized. 15:05:51 INFO - Running cycle 1/1 for kraken test... 15:05:51 INFO - TEST-INFO | started process 1786 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpz6dB3J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:05:54 INFO - PID 1786 | 2017-05-01 15:05:54.347 plugin-container[1787:10454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 15:05:54 INFO - PID 1786 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:05:54 INFO - PID 1786 | 2017-05-01 15:05:54.348 plugin-container[1787:10454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 15:05:54 INFO - PID 1786 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:05:54 INFO - PID 1786 | 2017-05-01 15:05:54.348 plugin-container[1787:10454] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:06:04 INFO - PID 1786 | RSS: Main: 250826752 15:06:04 INFO - PID 1786 | 15:06:06 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 15:06:07 INFO - PID 1786 | RSS: Main: 261206016 15:06:07 INFO - PID 1786 | 15:06:10 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 15:06:11 INFO - PID 1786 | RSS: Main: 267862016 15:06:11 INFO - PID 1786 | 15:06:14 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 15:06:15 INFO - PID 1786 | RSS: Main: 264011776 15:06:15 INFO - PID 1786 | 15:06:17 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 15:06:17 INFO - PID 1786 | RSS: Main: 265089024 15:06:17 INFO - PID 1786 | 15:06:19 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:06:20 INFO - PID 1786 | RSS: Main: 267005952 15:06:20 INFO - PID 1786 | 15:06:23 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 15:06:23 INFO - PID 1786 | RSS: Main: 272228352 15:06:23 INFO - PID 1786 | 15:06:26 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 15:06:27 INFO - PID 1786 | RSS: Main: 273526784 15:06:27 INFO - PID 1786 | 15:06:30 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 15:06:31 INFO - PID 1786 | RSS: Main: 272912384 15:06:31 INFO - PID 1786 | 15:06:32 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:06:33 INFO - PID 1786 | RSS: Main: 272830464 15:06:33 INFO - PID 1786 | 15:06:35 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:06:35 INFO - PID 1786 | RSS: Main: 272875520 15:06:35 INFO - PID 1786 | 15:06:37 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:06:38 INFO - PID 1786 | RSS: Main: 272461824 15:06:38 INFO - PID 1786 | 15:06:41 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:06:42 INFO - PID 1786 | RSS: Main: 272502784 15:06:42 INFO - PID 1786 | 15:06:45 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:06:45 INFO - PID 1786 | RSS: Main: 272785408 15:06:45 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 15:06:47 INFO - PID 1786 | RSS: Main: 274251776 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | __start_tp_report 15:06:47 INFO - PID 1786 | _x_x_mozilla_page_load 15:06:47 INFO - PID 1786 | _x_x_mozilla_page_load_details 15:06:47 INFO - PID 1786 | |i|pagename|runs| 15:06:47 INFO - PID 1786 | |0;ai-astar;106;116;114;115;116;115;113;114;115;114 15:06:47 INFO - PID 1786 | |1;audio-beat-detection;149;119;110;153;110;125;146;100;160;148 15:06:47 INFO - PID 1786 | |2;audio-dft;200;211;200;230;215;233;280;220;203;199 15:06:47 INFO - PID 1786 | |3;audio-fft;85;58;57;82;58;61;62;58;77;89 15:06:47 INFO - PID 1786 | |4;audio-oscillator;80;70;84;74;69;69;89;70;69;99 15:06:47 INFO - PID 1786 | |5;imaging-gaussian-blur;91;91;90;91;92;96;110;95;90;90 15:06:47 INFO - PID 1786 | |6;imaging-darkroom;98;100;98;98;98;98;99;97;96;98 15:06:47 INFO - PID 1786 | |7;imaging-desaturate;88;83;77;137;144;104;92;99;111;155 15:06:47 INFO - PID 1786 | |8;json-parse-financial;48;51;49;52;51;51;51;50;50;50 15:06:47 INFO - PID 1786 | |9;json-stringify-tinderbox;57;65;68;74;94;67;60;60;61;61 15:06:47 INFO - PID 1786 | |10;stanford-crypto-aes;82;85;79;83;82;84;82;87;82;84 15:06:47 INFO - PID 1786 | |11;stanford-crypto-ccm;137;141;138;136;143;157;142;140;218;165 15:06:47 INFO - PID 1786 | |12;stanford-crypto-pbkdf2;187;191;189;186;187;182;186;187;192;188 15:06:47 INFO - PID 1786 | |13;stanford-crypto-sha256-iterative;66;67;71;71;68;71;67;69;70;67 15:06:47 INFO - PID 1786 | __end_tp_report 15:06:47 INFO - PID 1786 | __start_cc_report 15:06:47 INFO - PID 1786 | _x_x_mozilla_cycle_collect,1025 15:06:47 INFO - PID 1786 | __end_cc_report 15:06:47 INFO - PID 1786 | __startTimestamp1493676407715__endTimestamp 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | ------- Summary: start ------- 15:06:47 INFO - PID 1786 | Number of tests: 14 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:2.90 (2.5%) stddev-sans-first:1.00 15:06:47 INFO - PID 1786 | Values: 106.0 116.0 114.0 115.0 116.0 115.0 113.0 114.0 115.0 114.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:147.00 stddev:21.54 (14.7%) stddev-sans-first:21.95 15:06:47 INFO - PID 1786 | Values: 149.0 119.0 110.0 153.0 110.0 125.0 146.0 100.0 160.0 148.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#2] audio-dft Cycles:10 Average:219.10 Median:217.50 stddev:24.71 (11.4%) stddev-sans-first:25.23 15:06:47 INFO - PID 1786 | Values: 200.0 211.0 200.0 230.0 215.0 233.0 280.0 220.0 203.0 199.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#3] audio-fft Cycles:10 Average:68.70 Median:69.50 stddev:12.94 (18.6%) stddev-sans-first:12.31 15:06:47 INFO - PID 1786 | Values: 85.0 58.0 57.0 82.0 58.0 61.0 62.0 58.0 77.0 89.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:77.00 stddev:10.46 (13.6%) stddev-sans-first:11.05 15:06:47 INFO - PID 1786 | Values: 80.0 70.0 84.0 74.0 69.0 69.0 89.0 70.0 69.0 99.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:91.00 stddev:6.13 (6.7%) stddev-sans-first:6.43 15:06:47 INFO - PID 1786 | Values: 91.0 91.0 90.0 91.0 92.0 96.0 110.0 95.0 90.0 90.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:98.00 stddev:1.05 (1.1%) stddev-sans-first:1.12 15:06:47 INFO - PID 1786 | Values: 98.0 100.0 98.0 98.0 98.0 98.0 99.0 97.0 96.0 98.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#7] imaging-desaturate Cycles:10 Average:109.00 Median:80.00 stddev:27.25 (34.1%) stddev-sans-first:27.83 15:06:47 INFO - PID 1786 | Values: 88.0 83.0 77.0 137.0 144.0 104.0 92.0 99.0 111.0 155.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:51.00 stddev:1.16 (2.3%) stddev-sans-first:0.88 15:06:47 INFO - PID 1786 | Values: 48.0 51.0 49.0 52.0 51.0 51.0 51.0 50.0 50.0 50.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:66.00 stddev:10.81 (16.4%) stddev-sans-first:10.88 15:06:47 INFO - PID 1786 | Values: 57.0 65.0 68.0 74.0 94.0 67.0 60.0 60.0 61.0 61.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:2.16 (2.6%) stddev-sans-first:2.26 15:06:47 INFO - PID 1786 | Values: 82.0 85.0 79.0 83.0 82.0 84.0 82.0 87.0 82.0 84.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:142.50 stddev:25.10 (17.6%) stddev-sans-first:26.06 15:06:47 INFO - PID 1786 | Values: 137.0 141.0 138.0 136.0 143.0 157.0 142.0 140.0 218.0 165.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:187.50 stddev:2.80 (1.5%) stddev-sans-first:2.96 15:06:47 INFO - PID 1786 | Values: 187.0 191.0 189.0 186.0 187.0 182.0 186.0 187.0 192.0 188.0 15:06:47 INFO - PID 1786 | 15:06:47 INFO - PID 1786 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.50 stddev:1.95 (2.8%) stddev-sans-first:1.80 15:06:47 INFO - PID 1786 | Values: 66.0 67.0 71.0 71.0 68.0 71.0 67.0 69.0 70.0 67.0 15:06:47 INFO - PID 1786 | -------- Summary: end -------- 15:06:47 INFO - PID 1786 | 15:06:48 INFO - TEST-INFO | 1786: exit 0 15:06:48 INFO - TEST-OK | kraken | took 60806ms 15:06:48 INFO - SUITE-END | took 1176s 15:06:48 INFO - Completed test suite (00:19:36) 15:06:48 INFO - javascript benchmark 15:06:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7897.083029649686, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3091.5980922480076, 3615.0600843780626, 3641.763285386448, 3665.0, 3668.1746607013424, 5412.161115027172, 5780.670445797464, 6112.321110680151, 6274.623522588642, 6293.181886354768, 6187.752489900398, 6229.595076320036, 6231.781887633933, 6233.937660623393, 6320.557560970734, 5442.102053161234, 5444.7822087116465, 5462.535684466805, 5463.535599474049, 5473.480019398151, 5518.310211223597, 5574.777008919658, 5587.3015873015875, 5590.0, 5606.719664016803, 5469.699166545838, 5505.476979686963, 5507.339119305669, 5521.0062188806, 5586.888262234753, 5516.979358818631, 5518.117101263819, 5542.057850165482, 5550.91673624896, 5567.749451274662, 5432.565394768409, 5457.536109430664, 5475.7262136893, 5486.588505862036, 5510.724463776835, 5530.562053865983, 5624.803131890405, 5964.254468191476, 6201.410865967771, 6261.0, 6706.26231114577, 6730.528862979593, 6739.326067393271, 6748.19021717397, 6750.257471678112, 6817.0796942412635, 6821.795346139624, 6836.1113055302985, 6845.212800527904, 6866.6566671666715, 6242.032484964838, 6261.530385221126, 6362.459190968727, 6363.649999250039, 6393.041043843415, 6919.100516932766, 6938.548994315353, 6962.0, 6979.581225126509, 7016.649167541603, 6400.167978162809, 6447.967760161263, 6546.345365463416, 6557.934420655828, 6632.701528431232, 6706.865862682719, 6815.420689241371, 6820.761273355409, 6827.2831352708245, 6849.828754281083, 6137.867325793252, 6249.0626406039455, 6640.435562977201, 6723.932760672331, 6733.293004234485, 6549.984752363392, 6571.769988050395, 6657.766978155838, 6708.0, 6729.024291477708, 5285.841424757264, 5348.144296912553, 5362.329708786402, 5365.7585408656705, 5401.918971215435, 4762.071396077801, 4762.6189904808225, 4765.380500534978, 4769.880752981203, 4791.664583479123, 5143.871403214875, 6003.549733769984, 6336.20797400325, 6384.297727249998, 6447.226332839996, 6841.897371539431, 6845.0, 6855.040294358794, 6876.071730316343, 6887.483438742114, 6270.965290726979, 6446.097546343516, 6456.3543645636, 6458.095866578683, 6478.676066196671, 5814.273215848019, 6017.217761690952, 6026.367231440723, 6027.21646185993, 6029.155918171459, 6850.246472887879, 6866.690998905061, 6941.028256044056, 6978.616176110159, 6985.26654701249, 6812.182538095461, 6827.556208846409, 6842.349976752201, 6851.45188384938, 6862.073620061427, 6959.686814093277, 6971.965140174267, 7001.0, 7028.297170282931, 7044.6125463100025, 6529.183852018497, 6552.08270842092, 6679.732810687712, 6735.2927942567285, 6771.255161932282, 6808.489363297872, 6835.726570937107, 6856.005879147396, 6889.311068893071, 6923.273056329013, 6362.300146983921, 6385.96807015962, 6486.740530378732, 6528.249251336157, 6637.867242655023, 5541.584381171348, 6639.502037347122, 6643.202815662152, 6643.40209381158, 6667.43326817226, 6274.215723034621, 6324.841878953063, 6513.0, 6536.346365363425, 6537.607743535403, 6340.778072767431, 6440.25937017249, 6567.047778072249, 6582.275949645439, 6603.603783772989, 6421.486281097595, 6424.518161138027, 6428.614283143026, 6429.260635027032, 6435.0], "value": 6148.089822293897, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.72131147541, 747.19676347926, 749.5240522268359, 1218.6100447856688, 1273.3760457375888, 36329.636703632954, 37447.0, 37482.25035499288, 37507.0, 37536.81231593845, 37159.62840371596, 37397.62602373975, 37409.43885841715, 37442.0, 37465.0, 7856.0, 8025.157358477363, 8329.66681332749, 8567.35744819138, 8588.01237857648, 8561.015483219448, 8569.057403685621, 8637.179467950535, 8645.567721613894, 8667.69663061793, 8634.093420190884, 8682.348823838205, 8689.04420513743, 8696.086910874363, 8709.912900871006, 8656.480611163319, 8665.046844847062, 8690.478571285712, 8701.695440659547, 8719.302455803583, 8445.028821685524, 8456.704015359433, 8497.36269779769, 8526.488410695378, 8537.530435826027, 36493.27013459743, 36541.634583654355, 36651.71718989823, 36768.264634707426, 36777.81611091954, 3654.5797233318244, 3701.0747313171705, 3707.017640325316, 3708.2583483303447, 3716.4425336199515, 30051.0, 30119.247018824484, 30307.696923030708, 30380.69619303801, 30396.848015760002, 3157.1317887580867, 3194.552762613236, 3199.840007999591, 3200.071979126074, 3206.246532064963, 245100.0, 254263.99999999814, 299934.0, 302369.0, 305149.0, 34116.0, 44314.77842610766, 44339.0, 44358.669239922856, 44478.77760611176, 47093.0, 47234.76382618133, 47296.5270347299, 52334.0, 55180.17229741557, 14058.71882562343, 14090.295485225903, 14228.075175113585, 14240.572782816533, 14271.286435678174, 11240.04459620925, 11295.604653837241, 11310.264832785842, 11330.830037549444, 11347.262427942158, 14020.088694234842, 14130.222837743817, 14146.797522210521, 14158.787618185735, 14239.003269771016, 5980.790672326448, 6200.906986395207, 6217.875642487125, 6225.128482012522, 6261.123442718023, 996.5913975270105, 1012.8936461671566, 1031.4791030529534, 1057.2757661002083, 1064.9094826939797, 22137.446563836034, 23239.3028209154, 24134.0, 24226.51546969051, 24305.39236519101, 1016.6035246253966, 1111.8109921313396, 1112.3437172068518, 1143.731223162556, 1152.3086148311113, 10609.681709548726, 11549.249298795552, 11874.0500759939, 12482.50069997208, 12490.875091249152, 289782.0, 290439.0, 290832.0, 291028.0, 292667.0, 53182.46817531853, 53649.0, 53901.73049134729, 53946.0, 54046.729766350916, 1516.726989141965, 1523.6343277613514, 1555.5955451582445, 1557.5093845438878, 1575.0, 19423.70864437063, 21865.344039678836, 23758.0, 23810.642840357756, 23831.523369532166, 35069.0, 37575.81212093922, 49383.25925111055, 50020.49979500158, 50033.99932001267, 301755.0, 302498.0, 304665.0, 306238.0, 307959.0, 34266.97199084031, 35467.29065418729, 35470.40383182711, 35675.82162089173, 35999.460008100425, 35807.55464016502, 42782.57217427786, 43228.0, 44836.551634484546, 45015.0, 29605.555916660836, 30070.84964575163, 30537.08388748341, 30797.692023079482, 31007.0697879064, 29877.850610746806, 29934.0, 30546.389072218877, 30799.692003080578, 30884.0, 36976.81511592425, 37006.0, 37034.444483332234, 37129.62870371262, 37221.0, 24748.50502989966, 28648.85675571609, 29376.853115734284, 31368.843155784074, 31542.0, 22251.66622500696, 24005.039798407866, 24390.658513781204, 24396.0, 24661.63007554852], "value": 17486.69754942944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.5894105894106, 433.5424985738733, 433.70074648492516, 442.8715672454988, 456.9405977222963, 20881.582368352643, 21595.568088638218, 21719.457013574647, 21724.674129888044, 22210.000549975248, 18778.906105469487, 21922.0, 22944.885275573597, 23099.0, 23397.713125778075, 5988.910166347487, 6042.697865106749, 6318.873622527547, 6320.241571011485, 6321.494280457552, 5937.287525496925, 6129.264488261392, 6131.264248290211, 6163.845903852395, 6172.321044685081, 5832.445917637818, 6102.786402475915, 6105.358937311585, 6116.724747386356, 6118.908216376735, 5601.971990140044, 5721.256236689224, 6065.514758819328, 6067.696615169246, 6070.6357618543025, 4257.6168144867115, 5392.083345831218, 5404.189371594253, 5447.809326673548, 5449.754761035763, 27520.449591008066, 27527.31181720453, 27853.303667408276, 28017.0, 28213.435731285466, 1895.5640202753443, 2274.454131008546, 2276.886155692209, 2277.9202727904444, 2280.0, 17026.978381297162, 18014.91910485375, 18074.277028918827, 18513.2594696212, 18688.719669204973, 1966.8721533100302, 2159.168720042779, 2203.0416768705504, 2205.6250437425674, 2208.9447763806033, 171055.0, 172953.99999999875, 173379.0, 174183.12908435502, 174373.0, 10164.593416263415, 12455.813162802566, 12670.429830657642, 12694.047946404056, 12856.228626282453, 13253.801192982113, 16335.59161020984, 16424.67150657004, 16456.588585285463, 16472.341106355852, 7415.369693576106, 8692.739217823475, 8783.91216087831, 8892.510911899908, 8957.0, 6869.381755642016, 6907.481938854606, 8422.620982055822, 8559.315254779603, 8597.398182127316, 6560.311167327457, 6871.14110736158, 7806.916268074079, 7954.761357159295, 7985.041794984639, 1992.2529051605648, 2204.5039866030015, 2212.9889350553144, 2219.8002179803893, 2382.689520763573, 843.4011851585443, 949.6438835436711, 969.3360048366891, 986.8273052215834, 1011.8684571005722, 15512.612184695472, 20385.79614203839, 20782.376528704164, 21039.05324260412, 21266.468338291357, 1009.2733232072895, 1011.3476807459294, 1018.7402212435782, 1035.0736091198335, 1035.5805898611075, 7905.762827115196, 10122.089011988955, 10703.571857125784, 10748.838767418494, 10800.729981750519, 505160.0, 550978.0, 556491.0, 558756.0, 567513.0, 129337.59305645103, 133423.0, 138613.0, 141511.0, 142261.0, 1201.9699507512382, 1220.7192345760525, 1251.612000279898, 1255.6609715376796, 1261.7476504699098, 19224.8077519223, 21794.89102554477, 22168.778312217317, 22251.99062295372, 23741.64387534154, 53455.0, 60732.6963365198, 61527.0, 61809.0, 62325.37674623196, 78115.0, 80354.59822701084, 81356.7796483065, 81908.59045704734, 82196.58901705453, 9041.59312830911, 9086.500242486727, 9105.635774568944, 9162.862557061782, 9211.078892110736, 14280.571582852532, 14395.784063239269, 14575.344109514885, 14583.416663333772, 14707.117572945657, 10140.0, 12050.337231452355, 12050.638480845239, 12273.87726122763, 12275.69310767238, 9563.904360956301, 11443.771124577295, 11516.712082198012, 11843.822342664695, 11851.881481185077, 11161.218714689894, 12045.63863084109, 12122.393880306126, 12407.689807754878, 12502.124851260325, 8853.911460885309, 8894.0, 9184.0, 9348.439093654402, 9360.719178424659, 11802.881971180179, 12000.459979300778, 12061.336626485627, 12115.75768484643, 12198.939005304917], "value": 10492.06226602777, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.718771088794, 1290.825738525299, 1447.6670365815862, 1471.3967273417888, 1478.1869971515664, 11345.602903898365, 11362.772744545115, 11841.822372664406, 11845.0, 12131.0, 11620.477078531465, 11690.707732306697, 11866.703332416673, 11913.583024594142, 11987.520499180022, 2532.303616505462, 2556.386467247856, 2577.6133579962966, 2577.935551611206, 2587.3919628887197, 2480.6775119234476, 2521.684789401325, 2526.7220605733446, 2530.6583611212436, 2536.7843733282693, 2498.562751518486, 2525.4570267392505, 2529.6964364276314, 2558.1941688368197, 2561.8590977496324, 2418.04487227545, 2422.1401402502124, 2454.1901172613084, 2470.901163953458, 2472.8763561822016, 2410.6625072489865, 2479.3677612208953, 2494.5634513960167, 2513.195777351248, 2534.569123249052, 9899.406035637885, 11882.989945854613, 11962.581309654208, 12034.879651203551, 12040.157188996774, 6649.6010239385305, 6827.658617069176, 6881.071055407506, 6888.345607167311, 6918.0314755934205, 3284.293876816472, 4338.522762496123, 4374.628156606692, 4408.845690400853, 4412.271975124101, 1279.1941077121446, 1286.7040580666498, 1302.042998396178, 1303.270168705528, 1330.115473210314, 15033.774493382609, 15517.456889008943, 16501.504954851374, 16680.33278668852, 16681.332746690117, 5486.807961721321, 5532.3914369419335, 5750.913736293959, 5774.538036957033, 5788.971055144697, 11795.646130616095, 11827.586034488751, 11902.285862848257, 11910.52357905674, 11923.0, 6548.148740663779, 6582.835429114215, 6595.538312318092, 6599.373059559334, 6605.339466053356, 6053.364396738367, 6106.664133472613, 6200.3179650238435, 6219.626822390671, 6277.560570760094, 4335.1329734053315, 4429.49060857999, 4430.246858034142, 4443.0, 4479.507254202035, 1251.9061070419755, 1269.936503174856, 1273.0, 1286.408252203978, 1290.9031822613342, 794.7814351053506, 807.8828569857337, 828.9596556321777, 830.8878301429351, 852.5694524265331, 6144.324124346318, 6161.075838624152, 6267.1539342188225, 6389.712462939179, 6439.484841212692, 573.5153795043182, 617.0774691835734, 622.6015350175891, 626.4831514000876, 629.8016124920636, 3769.7738135711943, 3969.1069509360163, 4145.2124096421585, 4309.202797482413, 4382.474103107647, 14472.493462728755, 14710.632234194016, 14867.70264594702, 15223.771643425358, 15244.923775381274, 14675.339609717588, 14887.851121488646, 15257.694846103015, 15265.771013434807, 15286.923565382325, 7466.0, 8537.701180458778, 8681.696140635047, 8727.781805454788, 8759.781005474786, 6479.1901012373455, 7120.857582848211, 7145.749493838662, 7218.747343842941, 7228.819279518054, 12546.059045571435, 12566.497340106293, 12648.177868438523, 12903.096783225084, 12963.546275880299, 11730.0, 13870.306484675524, 14027.0, 14208.857911421166, 14333.211673358066, 7348.228436014141, 9403.01268366839, 9521.428714277166, 9539.809203816023, 9548.85676714836, 10693.625723099654, 10777.62278320255, 10806.243562950518, 10830.18773591968, 10840.295380800222, 8969.230156834865, 9177.632894684139, 9190.724278271662, 9322.860157097784, 9368.203702685349, 9061.411008284704, 9114.043025482493, 9147.993720690585, 9157.221636161, 9182.954085229798, 8211.917880821115, 8306.584670766559, 8339.66641334364, 8348.582570871311, 8368.372372071997, 6054.788082417271, 6105.236845394325, 6193.81418557444, 6244.500439964882, 6245.094461302995, 5758.539316854725, 6010.4891084258325, 6141.877162456815, 6176.227971503562, 6279.560430769802], "value": 5341.170294904541, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.7808413573245, 395.5629029921938, 400.97995100245, 413.1159319057217, 414.0497558104151, 4971.22762148338, 5621.381648018714, 6223.471004964577, 6290.591111577755, 6300.432961033506, 5996.310424301196, 6105.389461053893, 6176.691165441721, 6182.103594978726, 6189.814305570829, 3850.4994350734364, 4332.740035597874, 4613.423322084739, 4660.883477913054, 4710.670253082286, 4713.0, 4751.216049351853, 4762.714067201853, 4768.356271903301, 4794.856154315376, 4076.388541718736, 4315.331123675835, 4740.573348398643, 4744.651397558214, 4751.833685820997, 4543.909121817578, 4705.952940470585, 4769.165396055711, 4779.737114458703, 4780.211265141247, 4200.684948628865, 4201.831926722927, 4207.97896010518, 4208.873733787991, 4212.830392347943, 5381.650192737498, 5500.532454741352, 5991.8915000725, 6012.6392416455155, 6019.759209631609, 5424.891502169974, 5576.191452239403, 5601.775928962837, 5655.632383895075, 5673.574481913873, 6078.574499785017, 6144.446999770043, 6611.669356571453, 6676.0, 6688.9665551672415, 4684.812607495696, 4806.543378379083, 4818.518148185189, 4833.347498087748, 4837.379477874916, 5782.653040817564, 6487.967560162217, 6749.358810912955, 6974.0, 7153.998440218374, 4403.987082970871, 4417.469903611587, 4912.214045752662, 4922.212446008622, 4929.2359684248995, 4681.555252251031, 4975.353204083518, 5011.799528018839, 5014.7994080236385, 5038.546530812245, 4844.055409195244, 4849.854504364804, 5522.751476183581, 5601.495865372054, 5645.153227015899, 6457.7094030767785, 6638.83402914931, 6659.300773418819, 6672.599644021374, 6692.218896323659, 1570.9057456552644, 1682.9074400908069, 1712.9914350428169, 1717.158592289769, 1723.2331612432597, 1139.601139601133, 1183.2013390961067, 1183.207251141737, 1185.1111666250313, 1203.1818363512896, 6349.428551430394, 6351.697901930185, 6359.0, 6362.395572420613, 6380.202474690664, 5858.916100521417, 6639.634820084942, 6919.4464442844455, 6927.376536111673, 6951.0, 5296.814611488579, 6028.824379246093, 6062.333143354227, 6318.684065796691, 6398.232212134573, 2738.246982079944, 3353.7758718068217, 3511.139770756181, 3550.1479644885044, 3598.0824889653495, 7075.150981882104, 7083.574985500783, 7128.786136415916, 7142.107236595426, 7157.964210178916, 6136.478399336017, 6308.432241098231, 6783.660816959133, 6989.89515157273, 7110.751123710749, 5238.790448382022, 5503.587230957694, 5679.829605111853, 6410.423061924449, 6806.76176333826, 6219.937800622117, 6315.96842015787, 6944.548604340702, 6969.4424446045205, 6986.825329366603, 5420.985588330857, 6426.935730642821, 6587.835304117244, 6831.975203719482, 6875.0, 5841.912371314519, 6415.518836087405, 7134.536255143399, 7167.139943206848, 7173.748918787817, 4154.293770059157, 4364.519902810752, 4371.737695738266, 4399.626031787334, 4404.955950440454, 5877.882442351214, 6703.3296670332575, 6845.4865885057825, 6945.617991010341, 6986.755463558751, 5959.582829201914, 6125.356837531995, 6256.468200203034, 6597.6041437511985, 6701.296363881722, 5163.483651634807, 6036.215292012011, 6428.389303016206, 6566.606003639606, 6670.733170673118, 5886.90937257943, 5965.642061476152, 6494.3505649436565, 6497.805065848032, 6499.870002599827, 6385.553011289165, 6623.801285961429, 6638.236602790741, 6641.6716656667895, 6659.334066593302, 6398.248753036239, 6428.8071357859335, 6451.3548645135115, 6483.189601299838, 6483.740650373932], "value": 4879.57805033222, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.1110778144041, 834.8205135895781, 844.2992316377404, 871.2594294849379, 876.307716903646, 13876.30618469075, 14057.437702491914, 14660.340284687189, 16227.0, 16719.0, 13063.869361306384, 13250.602481925558, 17313.30746770131, 17388.391406300783, 17420.216090275935, 960.5725452173803, 980.1913421427315, 982.1553464020972, 983.7245571240029, 985.9802803943917, 965.3339195954769, 974.9025097490265, 982.2387649571567, 982.6855406269997, 984.6455276100598, 985.1527686189871, 1010.4341568721503, 1017.4505766885873, 1023.4473384372467, 1024.2676486312284, 1004.4927311707578, 1008.092716555099, 1024.7438140464883, 1025.3489034463096, 1027.8509616105623, 742.0020073001804, 746.6453434618567, 746.9141048779405, 748.6294284329292, 749.7375918428561, 13715.931420342935, 14255.572332830032, 14542.781858272134, 16900.830991690047, 17361.47915562535, 12336.62990110298, 12537.811932821014, 15161.24193790317, 15350.539483815503, 15359.15524646144, 13886.02797804154, 14253.714925701532, 14259.0, 14375.28123593816, 14379.928100359535, 3502.4921386399083, 3530.3998320285614, 3544.486049522805, 3547.6274991126074, 3550.840212190458, 130808.0, 139445.30277348548, 139955.30022349925, 140448.5955140456, 141797.29101354428, 13151.60545183646, 13310.46758129664, 13332.600021999355, 13425.999999999805, 13447.193168409927, 25349.74650253511, 25484.108056217945, 25748.485030299664, 25946.35134121662, 26005.739942600332, 7598.164201937782, 7741.380689544823, 7850.803729906684, 7852.254035866584, 7860.84278314442, 3545.680888719976, 3595.676389124991, 3618.620044895301, 3623.202895363016, 3624.7462677612316, 3946.4869566956686, 4041.5958404159937, 4342.478902531717, 4739.66822322441, 4755.595774359218, 959.3092973059386, 979.8584648884113, 990.5542505872386, 991.6281394477071, 993.8757655293089, 640.7565125252374, 652.8922727749868, 656.2748163279603, 664.0537234440999, 677.2584020497547, 11035.889641103648, 11706.76586468283, 11762.76474470523, 11863.881670997333, 11864.940675296568, 641.586176915896, 646.3859333633068, 653.7973228299243, 660.7059858362983, 665.4377051391566, 5150.765647627902, 5302.496262855023, 5404.135338345845, 5416.674999500043, 5509.889802203934, 84285.15714842774, 104857.47571262247, 107407.0, 107711.38432923512, 108650.0, 56265.87468250694, 82360.17639823645, 84907.99999999876, 85791.0, 87016.0, 79727.8040829388, 79952.80070798943, 80169.0, 81557.21313442306, 82491.9999999988, 11077.335359878432, 11979.401029948292, 12089.576864810042, 12507.874921251036, 12540.184887982252, 37970.62029379671, 38843.417348739225, 38853.64012259557, 39148.0, 39638.05141383132, 49985.50014499954, 50222.0, 71625.34435261691, 73873.63063184649, 74127.92296423748, 16297.59256018609, 16609.920355176873, 16622.3351065956, 16645.0, 16649.084300363113, 17783.822161778735, 17803.376881809076, 18049.1877865499, 18081.36715214961, 18217.99801010957, 13545.932270338586, 16147.919260403623, 16284.77864160169, 16794.160291985107, 16868.493945181664, 16157.919210403872, 16652.83347166513, 16704.832951670327, 16773.664526709643, 16840.1579921001, 14076.859231407965, 15894.761578576099, 16070.955387899749, 17202.569935751708, 17254.0, 10561.577536898745, 11392.544298227978, 11729.648110556696, 11781.705457363634, 11801.881981180422, 13403.597892063253, 13456.730865382833, 13832.654183645087, 13980.021398502007, 14005.789913151108], "value": 8249.78858319215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 115.0, 116.0, 115.0, 113.0, 114.0, 115.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 110.0, 153.0, 110.0, 125.0, 146.0, 100.0, 160.0, 148.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 211.0, 200.0, 230.0, 215.0, 233.0, 280.0, 220.0, 203.0, 199.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 57.0, 82.0, 58.0, 61.0, 62.0, 58.0, 77.0, 89.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 84.0, 74.0, 69.0, 69.0, 89.0, 70.0, 69.0, 99.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 91.0, 92.0, 96.0, 110.0, 95.0, 90.0, 90.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 98.0, 98.0, 98.0, 98.0, 99.0, 97.0, 96.0, 98.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 77.0, 137.0, 144.0, 104.0, 92.0, 99.0, 111.0, 155.0], "value": 109.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 49.0, 52.0, 51.0, 51.0, 51.0, 50.0, 50.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 68.0, 74.0, 94.0, 67.0, 60.0, 60.0, 61.0, 61.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 79.0, 83.0, 82.0, 84.0, 82.0, 87.0, 82.0, 84.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 138.0, 136.0, 143.0, 157.0, 142.0, 140.0, 218.0, 165.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 189.0, 186.0, 187.0, 182.0, 186.0, 187.0, 192.0, 188.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 71.0, 71.0, 68.0, 71.0, 67.0, 69.0, 70.0, 67.0], "value": 68.7, "unit": "score"}]}]} 15:06:48 INFO - Return code: 0 15:06:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:06:49 ERROR - # TBPL SUCCESS # 15:06:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:06:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:06:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:06:49 INFO - 2017-05-01 15:06:49.084 screenresolution[1797:10840] starting screenresolution argv=screenresolution get 15:06:49 INFO - 2017-05-01 15:06:49.089 screenresolution[1797:10840] Display 0: 1600x1200x32@60 15:06:49 INFO - 2017-05-01 15:06:49.095 screenresolution[1798:10843] starting screenresolution argv=screenresolution list 15:06:49 INFO - Available Modes on Display 0 15:06:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:06:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:06:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:06:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:06:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:06:49 INFO - Intel Iris: 15:06:49 INFO - Chipset Model: Intel Iris 15:06:49 INFO - Type: GPU 15:06:49 INFO - Bus: Built-In 15:06:49 INFO - VRAM (Dynamic, Max): 1536 MB 15:06:49 INFO - Vendor: Intel (0x8086) 15:06:49 INFO - Device ID: 0x0a2e 15:06:49 INFO - Revision ID: 0x0009 15:06:49 INFO - Displays: 15:06:49 INFO - Display: 15:06:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:06:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:06:49 INFO - Display Serial Number: 1600x1200 60 15:06:49 INFO - Main Display: Yes 15:06:49 INFO - Mirror: Off 15:06:49 INFO - Online: Yes 15:06:49 INFO - Rotation: Supported 15:06:49 INFO - Return code: 0 15:06:49 INFO - Running post-action listener: _resource_record_post_action 15:06:49 INFO - [mozharness: 2017-05-01 22:06:49.334761Z] Finished run-tests step (success) 15:06:49 INFO - Running post-run listener: _resource_record_post_run 15:06:49 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40078336; Write bytes: 517447680; Read time: 261; Write time: 1694 15:06:49 INFO - TinderboxPrint: CPU usage
54.1% 15:06:49 INFO - TinderboxPrint: I/O read bytes / time
40,078,336 / 261 15:06:49 INFO - TinderboxPrint: I/O write bytes / time
517,447,680 / 1,694 15:06:49 INFO - TinderboxPrint: CPU idle
2,197.1 (45.9%) 15:06:49 INFO - TinderboxPrint: CPU system
964.8 (20.1%) 15:06:49 INFO - TinderboxPrint: CPU user
1,627.5 (34.0%) 15:06:49 INFO - TinderboxPrint: Swap in / out
248,176,640 / 0 15:06:49 INFO - install - Wall time: 20s; CPU: 55.0%; Read bytes: 208146944; Write bytes: 206054912; Read time: 16032; Write time: 436 15:06:49 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35924992; Write bytes: 304171520; Read time: 229; Write time: 1229 15:06:49 INFO - Running post-run listener: _upload_blobber_files 15:06:49 INFO - Blob upload gear active. 15:06:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:06:49 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:06:49 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:06:49 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:06:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:06:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:06:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:06:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:06:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:06:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:06:51 INFO - (blobuploader) - INFO - Done attempting. 15:06:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:06:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:06:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:06:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:06:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:06:52 INFO - (blobuploader) - INFO - Done attempting. 15:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:06:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:06:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:06:53 INFO - (blobuploader) - INFO - Done attempting. 15:06:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:06:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:06:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:06:55 INFO - (blobuploader) - INFO - Done attempting. 15:06:55 INFO - (blobuploader) - INFO - Iteration through files over. 15:06:55 INFO - Return code: 0 15:06:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:06:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:06:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/df304280b27d3c9c03edaa73d00f4ba6938b13f572c8ab409401457653f93f33c5568f4deb3f2c073a2f0bb0bf2f3eddbe65a5368a12681a97eb198fe1baa551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16f6b8e7b0ae135f1f4b17cec724bd2d4296bcb5d8286ad16599fbe23071266db2a9d35999b609b05cd3b64d9790d607b50ca2aa01d8cf5f5c766aa4312d368", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b7a7688573274c7e3b8bf2e57ee0936c286e0fd2f971b9d1279b2d7400f3fe9e3031399673481618469af101cc402034e96d5f59559d4b471f30522291a6f0f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/baf53202812a8dbf0cd6f89e38d888c39f9fc5a8fc285d91ab22dd4cc7914b9ef3374cee9a608b404adc3c381c455ba8dcad453e44e6d267f6f579daec6b79d3"} 15:06:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:06:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:06:55 INFO - Contents: 15:06:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/df304280b27d3c9c03edaa73d00f4ba6938b13f572c8ab409401457653f93f33c5568f4deb3f2c073a2f0bb0bf2f3eddbe65a5368a12681a97eb198fe1baa551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16f6b8e7b0ae135f1f4b17cec724bd2d4296bcb5d8286ad16599fbe23071266db2a9d35999b609b05cd3b64d9790d607b50ca2aa01d8cf5f5c766aa4312d368", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b7a7688573274c7e3b8bf2e57ee0936c286e0fd2f971b9d1279b2d7400f3fe9e3031399673481618469af101cc402034e96d5f59559d4b471f30522291a6f0f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/baf53202812a8dbf0cd6f89e38d888c39f9fc5a8fc285d91ab22dd4cc7914b9ef3374cee9a608b404adc3c381c455ba8dcad453e44e6d267f6f579daec6b79d3"} 15:06:55 INFO - Running post-run listener: copy_logs_to_upload_dir 15:06:55 INFO - Copying logs to upload dir... 15:06:55 INFO - mkdir: /builds/slave/test/build/upload/logs 15:06:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.066194 ========= master_lag: 2.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-05-01 15:06:57.935755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 15:06:57.940000) ========= 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.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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/df304280b27d3c9c03edaa73d00f4ba6938b13f572c8ab409401457653f93f33c5568f4deb3f2c073a2f0bb0bf2f3eddbe65a5368a12681a97eb198fe1baa551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16f6b8e7b0ae135f1f4b17cec724bd2d4296bcb5d8286ad16599fbe23071266db2a9d35999b609b05cd3b64d9790d607b50ca2aa01d8cf5f5c766aa4312d368", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b7a7688573274c7e3b8bf2e57ee0936c286e0fd2f971b9d1279b2d7400f3fe9e3031399673481618469af101cc402034e96d5f59559d4b471f30522291a6f0f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/baf53202812a8dbf0cd6f89e38d888c39f9fc5a8fc285d91ab22dd4cc7914b9ef3374cee9a608b404adc3c381c455ba8dcad453e44e6d267f6f579daec6b79d3"} build_url:https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011030 build_url: 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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/df304280b27d3c9c03edaa73d00f4ba6938b13f572c8ab409401457653f93f33c5568f4deb3f2c073a2f0bb0bf2f3eddbe65a5368a12681a97eb198fe1baa551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16f6b8e7b0ae135f1f4b17cec724bd2d4296bcb5d8286ad16599fbe23071266db2a9d35999b609b05cd3b64d9790d607b50ca2aa01d8cf5f5c766aa4312d368", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b7a7688573274c7e3b8bf2e57ee0936c286e0fd2f971b9d1279b2d7400f3fe9e3031399673481618469af101cc402034e96d5f59559d4b471f30522291a6f0f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/baf53202812a8dbf0cd6f89e38d888c39f9fc5a8fc285d91ab22dd4cc7914b9ef3374cee9a608b404adc3c381c455ba8dcad453e44e6d267f6f579daec6b79d3"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 15:06:58.002540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 15:06:58.003010) ========= 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.S882By4Kjq/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.EkRgYJe4vu/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.005049 ========= master_lag: 1.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 15:06:59.345454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 15:06:59.348927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 15:06:59.349895) ========= ========= Total master_lag: 3.58 =========