builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0141 starttime: 1493770388.07 results: success (0) buildid: 20170502130219 builduid: b06873ae54f941c4ba16fe03c099e2ce revision: 0e3fb6f0c6327c7327b9105e3a320616842e7bcd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.069023) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.069644) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.070062) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.099201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.099721) ========= 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.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-02 17:13:08-- 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% 13.9M=0.001s 2017-05-02 17:13:08 (13.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138766 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.264784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.265124) ========= 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.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.030310 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:08.320301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 17:13:08.320905) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 0e3fb6f0c6327c7327b9105e3a320616842e7bcd --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 0e3fb6f0c6327c7327b9105e3a320616842e7bcd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-02 17:13:08,399 truncating revision to first 12 chars 2017-05-02 17:13:08,399 Setting DEBUG logging. 2017-05-02 17:13:08,399 attempt 1/10 2017-05-02 17:13:08,399 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/0e3fb6f0c632?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-02 17:13:09,529 unpacking tar archive at: pine-0e3fb6f0c632/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389205 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 17:13:09.736162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:09.736532) ========= script_repo_revision: 0e3fb6f0c6327c7327b9105e3a320616842e7bcd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:09.736976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:09.737298) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 17:13:09.758258) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-05-02 17:13:09.758629) ========= /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.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:13:09 INFO - MultiFileLogger online at 20170502 17:13:09 in /builds/slave/test 17:13:09 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 17:13:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:09 INFO - {'append_to_log': False, 17:13:09 INFO - 'base_work_dir': '/builds/slave/test', 17:13:09 INFO - 'blob_upload_branch': 'Pine', 17:13:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:09 INFO - 'branch': 'Pine', 17:13:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:09 INFO - 'config_files': ('talos/mac_config.py',), 17:13:09 INFO - 'default_actions': ('clobber', 17:13:09 INFO - 'read-buildbot-config', 17:13:09 INFO - 'download-and-extract', 17:13:09 INFO - 'populate-webroot', 17:13:09 INFO - 'create-virtualenv', 17:13:09 INFO - 'install', 17:13:09 INFO - 'run-tests'), 17:13:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:09 INFO - 'download_minidump_stackwalk': True, 17:13:09 INFO - 'download_symbols': 'ondemand', 17:13:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:09 INFO - 'gecko_profile': False, 17:13:09 INFO - 'gecko_profile_interval': 0, 17:13:09 INFO - 'installer_path': 'installer.exe', 17:13:09 INFO - 'log_level': 'info', 17:13:09 INFO - 'log_name': 'talos', 17:13:09 INFO - 'log_to_console': True, 17:13:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:13:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:13:09 INFO - 'opt_config_files': (), 17:13:09 INFO - 'pip_index': False, 17:13:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:09 INFO - 'cmd': ('bash', 17:13:09 INFO - '-c', 17:13:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:09 INFO - 'enabled': True, 17:13:09 INFO - 'halt_on_failure': False, 17:13:09 INFO - 'name': 'check_screen_resolution'},), 17:13:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:09 INFO - 'cmd': ('bash', 17:13:09 INFO - '-c', 17:13:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:09 INFO - 'enabled': True, 17:13:09 INFO - 'halt_on_failure': False, 17:13:09 INFO - 'name': 'check_screen_resolution'},), 17:13:09 INFO - 'run_cmd_checks_enabled': True, 17:13:09 INFO - 'suite': 'dromaeojs-e10s', 17:13:09 INFO - 'system_bits': '32', 17:13:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:09 INFO - 'title': 't-yosemite-r7-0141', 17:13:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:09 INFO - 'use_talos_json': True, 17:13:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:09 INFO - 'work_dir': 'build'} 17:13:09 INFO - [mozharness: 2017-05-03 00:13:09.950543Z] Running clobber step. 17:13:09 INFO - Running pre-action listener: _resource_record_pre_action 17:13:09 INFO - Running main action method: clobber 17:13:09 INFO - rmtree: /builds/slave/test/build 17:13:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:12 INFO - Running post-action listener: _resource_record_post_action 17:13:12 INFO - [mozharness: 2017-05-03 00:13:12.470259Z] Finished clobber step (success) 17:13:12 INFO - [mozharness: 2017-05-03 00:13:12.470417Z] Running read-buildbot-config step. 17:13:12 INFO - Running pre-action listener: _resource_record_pre_action 17:13:12 INFO - Running main action method: read_buildbot_config 17:13:12 INFO - Using buildbot properties: 17:13:12 INFO - { 17:13:12 INFO - "project": "", 17:13:12 INFO - "product": "firefox", 17:13:12 INFO - "script_repo_revision": "default", 17:13:12 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 17:13:12 INFO - "repository": "", 17:13:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 17:13:12 INFO - "buildid": "20170502130219", 17:13:12 INFO - "pgo_build": "False", 17:13:12 INFO - "basedir": "/builds/slave/test", 17:13:12 INFO - "buildnumber": 2, 17:13:12 INFO - "slavename": "t-yosemite-r7-0141", 17:13:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:13:12 INFO - "platform": "yosemite_r7", 17:13:12 INFO - "scheduler": "tests-pine-macosx64-talos", 17:13:12 INFO - "branch": "pine", 17:13:12 INFO - "repo_path": "projects/pine", 17:13:12 INFO - "revision": "0e3fb6f0c6327c7327b9105e3a320616842e7bcd", 17:13:12 INFO - "stage_platform": "macosx64", 17:13:12 INFO - "builduid": "b06873ae54f941c4ba16fe03c099e2ce", 17:13:12 INFO - "slavebuilddir": "test" 17:13:12 INFO - } 17:13:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:13:12 INFO - Running post-action listener: _resource_record_post_action 17:13:12 INFO - [mozharness: 2017-05-03 00:13:12.473795Z] Finished read-buildbot-config step (success) 17:13:12 INFO - [mozharness: 2017-05-03 00:13:12.473937Z] Running download-and-extract step. 17:13:12 INFO - Running pre-action listener: _resource_record_pre_action 17:13:12 INFO - Running main action method: download_and_extract 17:13:12 INFO - mkdir: /builds/slave/test/build/tests 17:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:12 INFO - trying https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:13:12 INFO - Downloading https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/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 17:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/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 17:13:13 INFO - Downloaded 1325 bytes. 17:13:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:13:13 INFO - Using the following test package requirements: 17:13:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:13:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:13:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:13:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:13:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'jsshell-mac.zip'], 17:13:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:13:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:13:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:13:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:13:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:13:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:13:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:13:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:13:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:13:13 INFO - Fetch https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:13:15 INFO - Content-Length response header: 52700280 17:13:15 INFO - Bytes received: 52700280 17:13:32 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 17:13:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:13:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:13:32 INFO - Fetch https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:13:35 INFO - Content-Length response header: 52700280 17:13:35 INFO - Bytes received: 52700280 17:13:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:13:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:13:50 INFO - Fetch https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:13:51 INFO - Content-Length response header: 14408811 17:13:51 INFO - Bytes received: 14408811 17:13: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')]} 17:13:51 INFO - trying https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:13:51 INFO - Downloading https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:13:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/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 17:13:53 INFO - Downloaded 71210682 bytes. 17:13:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:13:53 INFO - mkdir: /builds/slave/test/properties 17:13:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:13:53 INFO - Writing to file /builds/slave/test/properties/build_url 17:13:53 INFO - Contents: 17:13:53 INFO - build_url:https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:13:54 INFO - Running post-action listener: _resource_record_post_action 17:13:54 INFO - Running post-action listener: set_extra_try_arguments 17:13:54 INFO - [mozharness: 2017-05-03 00:13:54.699708Z] Finished download-and-extract step (success) 17:13:54 INFO - [mozharness: 2017-05-03 00:13:54.699830Z] Running populate-webroot step. 17:13:54 INFO - Running pre-action listener: _resource_record_pre_action 17:13:54 INFO - Running main action method: populate_webroot 17:13:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:13:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:13:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:13:54 INFO - '--tppagecycles', 17:13:54 INFO - '7'], 17:13:54 INFO - 'tests': ['tsvgm']}}, 17:13:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:13:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:13:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:13:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:13:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['damp', 'tps']}, 17:13:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'tests': ['damp', 'tps']}, 17:13:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['dromaeo_dom']}, 17:13:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:13:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:13:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:13:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['a11yr', 17:13:54 INFO - 'ts_paint', 17:13:54 INFO - 'tpaint', 17:13:54 INFO - 'sessionrestore', 17:13:54 INFO - 'sessionrestore_no_auto_restore', 17:13:54 INFO - 'tabpaint']}, 17:13:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:13:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:13:54 INFO - 'bloom_basic_ref']}, 17:13:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['tsvgx', 17:13:54 INFO - 'tsvgr_opacity', 17:13:54 INFO - 'tart', 17:13:54 INFO - 'tscrollx', 17:13:54 INFO - 'cart', 17:13:54 INFO - 'tsvg_static']}, 17:13:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:13:54 INFO - 'tsvgr_opacity', 17:13:54 INFO - 'tart', 17:13:54 INFO - 'tscrollx', 17:13:54 INFO - 'cart', 17:13:54 INFO - 'tsvg_static']}, 17:13:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'talos_options': ['--disable-e10s'], 17:13:54 INFO - 'tests': ['tp5o']}, 17:13:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:13:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'talos_options': ['--disable-e10s', 17:13:54 INFO - '--xperf_path', 17:13:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:54 INFO - 'tests': ['tp5n']}, 17:13:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:13:54 INFO - 'talos_options': ['--xperf_path', 17:13:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:54 INFO - 'tests': ['tp5n']}}, 17:13:54 INFO - 'talos.zip': {'path': '', 17:13:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:13:54 INFO - Running post-action listener: _resource_record_post_action 17:13:54 INFO - [mozharness: 2017-05-03 00:13:54.719223Z] Finished populate-webroot step (success) 17:13:54 INFO - [mozharness: 2017-05-03 00:13:54.719341Z] Running create-virtualenv step. 17:13:54 INFO - Running pre-action listener: _resource_record_pre_action 17:13:54 INFO - Running main action method: create_virtualenv 17:13:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:13:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:13:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:13:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:13:54 INFO - Using real prefix '/tools/python27' 17:13:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:13:55 INFO - Installing distribute.............................................................................................................................................................................................done. 17:13:58 INFO - Installing pip.................done. 17:13:58 INFO - Return code: 0 17:13:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:13:58 INFO - Reading from file tmpfile_stdout 17:13:58 INFO - Output received: 17:13:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:13:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:58 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:13:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:13:58 INFO - 'HOME': '/Users/cltbld', 17:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:13:58 INFO - 'LOGNAME': 'cltbld', 17:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:58 INFO - 'MOZ_NO_REMOTE': '1', 17:13:58 INFO - 'NO_EM_RESTART': '1', 17:13:58 INFO - 'PAGER': '/bin/cat', 17:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:58 INFO - 'PWD': '/builds/slave/test', 17:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:13:58 INFO - 'SHELL': '/bin/bash', 17:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:13:58 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:13:58 INFO - 'USER': 'cltbld', 17:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:58 INFO - 'XPC_FLAGS': '0x0', 17:13:58 INFO - 'XPC_SERVICE_NAME': '0', 17:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:13:59 INFO - Cleaning up... 17:13:59 INFO - Return code: 0 17:13:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:59 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:13:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:13:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:13:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:13:59 INFO - 'HOME': '/Users/cltbld', 17:13:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:13:59 INFO - 'LOGNAME': 'cltbld', 17:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:59 INFO - 'MOZ_NO_REMOTE': '1', 17:13:59 INFO - 'NO_EM_RESTART': '1', 17:13:59 INFO - 'PAGER': '/bin/cat', 17:13:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:59 INFO - 'PWD': '/builds/slave/test', 17:13:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:13:59 INFO - 'SHELL': '/bin/bash', 17:13:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:13:59 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:13:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:13:59 INFO - 'USER': 'cltbld', 17:13:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:13:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:59 INFO - 'XPC_FLAGS': '0x0', 17:13:59 INFO - 'XPC_SERVICE_NAME': '0', 17:13:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:59 INFO - Downloading/unpacking psutil>=3.1.1 17:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:02 INFO - Installing collected packages: psutil 17:14:02 INFO - Running setup.py install for psutil 17:14:03 INFO - building 'psutil._psutil_osx' extension 17:14:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:14:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:14:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:14:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:14:03 INFO - building 'psutil._psutil_posix' extension 17:14:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:14:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:14:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:14:03 INFO - ^ 17:14:03 INFO - 1 warning generated. 17:14:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:14:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:04 INFO - Successfully installed psutil 17:14:04 INFO - Cleaning up... 17:14:04 INFO - Return code: 0 17:14:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:05 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:05 INFO - 'HOME': '/Users/cltbld', 17:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:05 INFO - 'LOGNAME': 'cltbld', 17:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:05 INFO - 'MOZ_NO_REMOTE': '1', 17:14:05 INFO - 'NO_EM_RESTART': '1', 17:14:05 INFO - 'PAGER': '/bin/cat', 17:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:05 INFO - 'PWD': '/builds/slave/test', 17:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:05 INFO - 'SHELL': '/bin/bash', 17:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:05 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:05 INFO - 'USER': 'cltbld', 17:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:05 INFO - 'XPC_FLAGS': '0x0', 17:14:05 INFO - 'XPC_SERVICE_NAME': '0', 17:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:14:08 INFO - Installing collected packages: mozsystemmonitor 17:14:08 INFO - Running setup.py install for mozsystemmonitor 17:14:08 INFO - Successfully installed mozsystemmonitor 17:14:08 INFO - Cleaning up... 17:14:08 INFO - Return code: 0 17:14:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:08 INFO - 'HOME': '/Users/cltbld', 17:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:08 INFO - 'LOGNAME': 'cltbld', 17:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:08 INFO - 'MOZ_NO_REMOTE': '1', 17:14:08 INFO - 'NO_EM_RESTART': '1', 17:14:08 INFO - 'PAGER': '/bin/cat', 17:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:08 INFO - 'PWD': '/builds/slave/test', 17:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:08 INFO - 'SHELL': '/bin/bash', 17:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:08 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:08 INFO - 'USER': 'cltbld', 17:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:08 INFO - 'XPC_FLAGS': '0x0', 17:14:08 INFO - 'XPC_SERVICE_NAME': '0', 17:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:09 INFO - Downloading/unpacking jsonschema==2.5.1 17:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:12 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:12 INFO - warning: no files found matching '*.txt' 17:14:12 INFO - no previously-included directories found matching 'build' 17:14:12 INFO - no previously-included directories found matching 'dist' 17:14:12 INFO - no previously-included directories found matching '.git*' 17:14:12 INFO - Installing collected packages: jsonschema, functools32 17:14:12 INFO - Running setup.py install for functools32 17:14:12 INFO - warning: no files found matching '*.txt' 17:14:12 INFO - no previously-included directories found matching 'build' 17:14:12 INFO - no previously-included directories found matching 'dist' 17:14:12 INFO - no previously-included directories found matching '.git*' 17:14:12 INFO - Successfully installed jsonschema functools32 17:14:12 INFO - Cleaning up... 17:14:12 INFO - Return code: 0 17:14:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:12 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:12 INFO - 'HOME': '/Users/cltbld', 17:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:12 INFO - 'LOGNAME': 'cltbld', 17:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:12 INFO - 'MOZ_NO_REMOTE': '1', 17:14:12 INFO - 'NO_EM_RESTART': '1', 17:14:12 INFO - 'PAGER': '/bin/cat', 17:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:12 INFO - 'PWD': '/builds/slave/test', 17:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:12 INFO - 'SHELL': '/bin/bash', 17:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:12 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:12 INFO - 'USER': 'cltbld', 17:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:12 INFO - 'XPC_FLAGS': '0x0', 17:14:12 INFO - 'XPC_SERVICE_NAME': '0', 17:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:14:12 INFO - Cleaning up... 17:14:12 INFO - Return code: 0 17:14:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:12 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:12 INFO - 'HOME': '/Users/cltbld', 17:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:12 INFO - 'LOGNAME': 'cltbld', 17:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:12 INFO - 'MOZ_NO_REMOTE': '1', 17:14:12 INFO - 'NO_EM_RESTART': '1', 17:14:12 INFO - 'PAGER': '/bin/cat', 17:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:12 INFO - 'PWD': '/builds/slave/test', 17:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:12 INFO - 'SHELL': '/bin/bash', 17:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:12 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:12 INFO - 'USER': 'cltbld', 17:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:12 INFO - 'XPC_FLAGS': '0x0', 17:14:12 INFO - 'XPC_SERVICE_NAME': '0', 17:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:13 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:15 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:17 INFO - Downloading docopt-0.6.1.tar.gz 17:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:17 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:17 INFO - Running setup.py install for blobuploader 17:14:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:17 INFO - Running setup.py install for requests 17:14:17 INFO - Running setup.py install for docopt 17:14:18 INFO - Successfully installed blobuploader requests docopt 17:14:18 INFO - Cleaning up... 17:14:18 INFO - Return code: 0 17:14:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:18 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:18 INFO - 'HOME': '/Users/cltbld', 17:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:18 INFO - 'LOGNAME': 'cltbld', 17:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:18 INFO - 'MOZ_NO_REMOTE': '1', 17:14:18 INFO - 'NO_EM_RESTART': '1', 17:14:18 INFO - 'PAGER': '/bin/cat', 17:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:18 INFO - 'PWD': '/builds/slave/test', 17:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:18 INFO - 'SHELL': '/bin/bash', 17:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:18 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:18 INFO - 'USER': 'cltbld', 17:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:18 INFO - 'XPC_FLAGS': '0x0', 17:14:18 INFO - 'XPC_SERVICE_NAME': '0', 17:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-R3fBOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sgAwBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-2jNyaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-N6_vjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Vhdmag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-0Ei3NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-fICRdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bo8LsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-4tBLjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-uFY8MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-eJqvCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-XTBFVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Es3WeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Fsi1bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-dKqYgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:20 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-kJ6yBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:20 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_VPiOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:20 INFO - Running setup.py install for manifestparser 17:14:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:20 INFO - Running setup.py install for mozcrash 17:14:20 INFO - Running setup.py install for mozdebug 17:14:20 INFO - Running setup.py install for mozdevice 17:14:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:20 INFO - Running setup.py install for mozfile 17:14:20 INFO - Running setup.py install for mozhttpd 17:14:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Running setup.py install for mozinfo 17:14:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Running setup.py install for mozInstall 17:14:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Running setup.py install for mozleak 17:14:21 INFO - Running setup.py install for mozlog 17:14:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Running setup.py install for moznetwork 17:14:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:21 INFO - Running setup.py install for mozprocess 17:14:21 INFO - Running setup.py install for mozprofile 17:14:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:22 INFO - Running setup.py install for mozrunner 17:14:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:22 INFO - Running setup.py install for mozscreenshot 17:14:22 INFO - Running setup.py install for moztest 17:14:22 INFO - Running setup.py install for mozversion 17:14:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:22 INFO - Cleaning up... 17:14:22 INFO - Return code: 0 17:14:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14: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')]} 17:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14: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')]} 17:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:22 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:22 INFO - 'HOME': '/Users/cltbld', 17:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:22 INFO - 'LOGNAME': 'cltbld', 17:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:22 INFO - 'MOZ_NO_REMOTE': '1', 17:14:22 INFO - 'NO_EM_RESTART': '1', 17:14:22 INFO - 'PAGER': '/bin/cat', 17:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:22 INFO - 'PWD': '/builds/slave/test', 17:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:22 INFO - 'SHELL': '/bin/bash', 17:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:22 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:22 INFO - 'USER': 'cltbld', 17:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:22 INFO - 'XPC_FLAGS': '0x0', 17:14:22 INFO - 'XPC_SERVICE_NAME': '0', 17:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Hl9E6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-40S1aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-FBtMUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_GzugD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:23 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)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-aHxJLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-N2iWk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7vJ3Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-LJevDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:14:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:23 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SzAN2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-N7PAam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7XT6t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-RbPqp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:24 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)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-yQqPBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-MS0xsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-vIkyph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-kl84Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:14:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:24 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7auqDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:14:24 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)) 17:14:24 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)) 17:14:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:27 INFO - Downloading blessings-1.6.tar.gz 17:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:27 INFO - Installing collected packages: blessings 17:14:27 INFO - Running setup.py install for blessings 17:14:27 INFO - Successfully installed blessings 17:14:27 INFO - Cleaning up... 17:14:27 INFO - Return code: 0 17:14:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:28 INFO - Reading from file tmpfile_stdout 17:14:28 INFO - Current package versions: 17:14:28 INFO - blessings == 1.6 17:14:28 INFO - blobuploader == 1.2.4 17:14:28 INFO - docopt == 0.6.1 17:14:28 INFO - functools32 == 3.2.3-2 17:14:28 INFO - jsonschema == 2.5.1 17:14:28 INFO - manifestparser == 1.1 17:14:28 INFO - mozInstall == 1.12 17:14:28 INFO - mozcrash == 1.0 17:14:28 INFO - mozdebug == 0.1 17:14:28 INFO - mozdevice == 0.50 17:14:28 INFO - mozfile == 1.2 17:14:28 INFO - mozhttpd == 0.7 17:14:28 INFO - mozinfo == 0.9 17:14:28 INFO - mozleak == 0.1 17:14:28 INFO - mozlog == 3.4 17:14:28 INFO - moznetwork == 0.27 17:14:28 INFO - mozprocess == 0.25 17:14:28 INFO - mozprofile == 0.28 17:14:28 INFO - mozrunner == 6.13 17:14:28 INFO - mozscreenshot == 0.1 17:14:28 INFO - mozsystemmonitor == 0.3 17:14:28 INFO - moztest == 0.8 17:14:28 INFO - mozversion == 1.4 17:14:28 INFO - psutil == 3.1.1 17:14:28 INFO - requests == 1.2.3 17:14:28 INFO - wsgiref == 0.1.2 17:14:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:28 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 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:14:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:28 INFO - 'HOME': '/Users/cltbld', 17:14:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:28 INFO - 'LOGNAME': 'cltbld', 17:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:28 INFO - 'MOZ_NO_REMOTE': '1', 17:14:28 INFO - 'NO_EM_RESTART': '1', 17:14:28 INFO - 'PAGER': '/bin/cat', 17:14:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:28 INFO - 'PWD': '/builds/slave/test', 17:14:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:28 INFO - 'SHELL': '/bin/bash', 17:14:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:28 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:28 INFO - 'USER': 'cltbld', 17:14:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:28 INFO - 'XPC_FLAGS': '0x0', 17:14:28 INFO - 'XPC_SERVICE_NAME': '0', 17:14:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:14:28 INFO - Cleaning up... 17:14:28 INFO - Return code: 0 17:14:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105208b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10520b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa860dc4e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, '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.MNR6tWhHV6/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.s0kifMeBWE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14: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', 'jsonschema'] in /builds/slave/test/build 17:14: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 jsonschema 17:14:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:28 INFO - 'HOME': '/Users/cltbld', 17:14:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:28 INFO - 'LOGNAME': 'cltbld', 17:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:28 INFO - 'MOZ_NO_REMOTE': '1', 17:14:28 INFO - 'NO_EM_RESTART': '1', 17:14:28 INFO - 'PAGER': '/bin/cat', 17:14:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:28 INFO - 'PWD': '/builds/slave/test', 17:14:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:28 INFO - 'SHELL': '/bin/bash', 17:14:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:28 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:28 INFO - 'USER': 'cltbld', 17:14:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:28 INFO - 'XPC_FLAGS': '0x0', 17:14:28 INFO - 'XPC_SERVICE_NAME': '0', 17:14:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:14:28 INFO - Cleaning up... 17:14:28 INFO - Return code: 0 17:14:28 INFO - Running post-action listener: _resource_record_post_action 17:14:28 INFO - Running post-action listener: _start_resource_monitoring 17:14:28 INFO - Starting resource monitoring. 17:14:28 INFO - [mozharness: 2017-05-03 00:14:28.698913Z] Finished create-virtualenv step (success) 17:14:28 INFO - [mozharness: 2017-05-03 00:14:28.699848Z] Running install step. 17:14:28 INFO - Running pre-action listener: _resource_record_pre_action 17:14:28 INFO - Running main action method: install 17:14:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:28 INFO - Reading from file tmpfile_stdout 17:14:28 INFO - Detecting whether we're running mozinstall >=1.0... 17:14:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:14:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:14:29 INFO - Reading from file tmpfile_stdout 17:14:29 INFO - Output received: 17:14:29 INFO - Usage: mozinstall [options] installer 17:14:29 INFO - Options: 17:14:29 INFO - -h, --help show this help message and exit 17:14:29 INFO - -d DEST, --destination=DEST 17:14:29 INFO - Directory to install application into. [default: 17:14:29 INFO - "/builds/slave/test"] 17:14:29 INFO - --app=APP Application being installed. [default: firefox] 17:14:29 INFO - mkdir: /builds/slave/test/build/application 17:14:29 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'] 17:14:29 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 17:14:48 INFO - Reading from file tmpfile_stdout 17:14:48 INFO - Output received: 17:14:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:14:48 INFO - Running post-action listener: _resource_record_post_action 17:14:48 INFO - [mozharness: 2017-05-03 00:14:48.835908Z] Finished install step (success) 17:14:48 INFO - [mozharness: 2017-05-03 00:14:48.836044Z] Running run-tests step. 17:14:48 INFO - Running pre-action listener: _resource_record_pre_action 17:14:48 INFO - Running main action method: run_tests 17:14:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:14:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:14:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:14:48 INFO - 2017-05-02 17:14:48.850 screenresolution[1623:7366] starting screenresolution argv=screenresolution get 17:14:48 INFO - 2017-05-02 17:14:48.853 screenresolution[1623:7366] Display 0: 1600x1200x32@60 17:14:48 INFO - 2017-05-02 17:14:48.859 screenresolution[1624:7369] starting screenresolution argv=screenresolution list 17:14:48 INFO - Available Modes on Display 0 17:14:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:14:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:14:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:14:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:14:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:14:49 INFO - Intel Iris: 17:14:49 INFO - Chipset Model: Intel Iris 17:14:49 INFO - Type: GPU 17:14:49 INFO - Bus: Built-In 17:14:49 INFO - VRAM (Dynamic, Max): 1536 MB 17:14:49 INFO - Vendor: Intel (0x8086) 17:14:49 INFO - Device ID: 0x0a2e 17:14:49 INFO - Revision ID: 0x0009 17:14:49 INFO - Displays: 17:14:49 INFO - Display: 17:14:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:14:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:14:49 INFO - Display Serial Number: 1600x1200 60 17:14:49 INFO - Main Display: Yes 17:14:49 INFO - Mirror: Off 17:14:49 INFO - Online: Yes 17:14:49 INFO - Rotation: Supported 17:14:49 INFO - Return code: 0 17:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:14:49 INFO - Python 2.7.3 17:14:49 INFO - Return code: 0 17:14:49 INFO - grabbing minidump binary from tooltool 17:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:49 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 0x104fe6d30>, '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 0x104f0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fdbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:14:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:14:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:14:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:14:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:14:49 INFO - Return code: 0 17:14:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:14:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:14:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:14:49 INFO - ENV: RUST_BACKTRACE is now 1 17:14:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:14:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:14:49 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/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:14:49 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/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MNR6tWhHV6/Render', 17:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:49 INFO - 'HOME': '/Users/cltbld', 17:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:49 INFO - 'LOGNAME': 'cltbld', 17:14:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:14:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:49 INFO - 'MOZ_NO_REMOTE': '1', 17:14:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:14:49 INFO - 'NO_EM_RESTART': '1', 17:14:49 INFO - 'PAGER': '/bin/cat', 17:14:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:49 INFO - 'PWD': '/builds/slave/test', 17:14:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:49 INFO - 'RUST_BACKTRACE': '1', 17:14:49 INFO - 'SHELL': '/bin/bash', 17:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s0kifMeBWE/Listeners', 17:14:49 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 17:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:49 INFO - 'USER': 'cltbld', 17:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:49 INFO - 'XPC_FLAGS': '0x0', 17:14:49 INFO - 'XPC_SERVICE_NAME': '0', 17:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:49 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/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:14:49 INFO - mozversion application_buildid: 20170502130219 17:14:49 INFO - mozversion application_changeset: 0e3fb6f0c6327c7327b9105e3a320616842e7bcd 17:14:49 INFO - mozversion application_display_name: Nightly 17:14:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:14:49 INFO - mozversion application_name: Firefox 17:14:49 INFO - mozversion application_remotingname: firefox 17:14:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:14:49 INFO - mozversion application_vendor: Mozilla 17:14:49 INFO - mozversion application_version: 55.0a1 17:14:49 INFO - mozversion platform_buildid: 20170502130219 17:14:49 INFO - mozversion platform_changeset: 0e3fb6f0c6327c7327b9105e3a320616842e7bcd 17:14:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:14:49 INFO - mozversion platform_version: 55.0a1 17:14:49 INFO - using testdate: 1493770489 17:14:49 INFO - actual date: 1493770489 17:14:49 INFO - starting webserver on 'localhost:49230' 17:14:49 INFO - SUITE-START | Running 2 tests 17:14:49 INFO - TEST-START | dromaeo_css 17:14:49 INFO - operating with platform_type : mac_ 17:14:49 INFO - Initialising browser for dromaeo_css test... 17:14:49 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpSeXl67/profile http://localhost:49230/getInfo.html) 17:14:52 INFO - PID 1634 | 2017-05-02 17:14:52.460 plugin-container[1636:7555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483b, name = 'com.apple.tsm.portname' 17:14:52 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:52 INFO - PID 1634 | 2017-05-02 17:14:52.462 plugin-container[1636:7555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 17:14:52 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:52 INFO - PID 1634 | 2017-05-02 17:14:52.462 plugin-container[1636:7555] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:14:52 INFO - PID 1634 | __metrics Screen width/height:1600/1200 17:14:52 INFO - PID 1634 | colorDepth:24 17:14:52 INFO - PID 1634 | Browser inner width/height: 1024/691 17:14:52 INFO - PID 1634 | __metrics 17:14:53 INFO - TEST-INFO | 1634: exit 0 17:14:53 INFO - Browser initialized. 17:14:53 INFO - Running cycle 1/1 for dromaeo_css test... 17:14:53 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpSeXl67/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:55 INFO - PID 1637 | 2017-05-02 17:14:55.885 plugin-container[1638:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x952f, name = 'com.apple.tsm.portname' 17:14:55 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:55 INFO - PID 1637 | 2017-05-02 17:14:55.886 plugin-container[1638:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 17:14:55 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:55 INFO - PID 1637 | 2017-05-02 17:14:55.886 plugin-container[1638:7696] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:15:05 INFO - PID 1637 | RSS: Main: 237314048 17:15:05 INFO - PID 1637 | 17:18:09 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 17:18:11 INFO - PID 1637 | RSS: Main: 253501440 17:18:11 INFO - PID 1637 | 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:19:53 INFO - PID 1637 | 17:19:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:19:53 INFO - PID 1637 | Full stack: 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:19:53 INFO - PID 1637 | 17:19:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:19:53 INFO - PID 1637 | Full stack: 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:19:53 INFO - PID 1637 | 17:19:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:19:53 INFO - PID 1637 | Full stack: 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | ************************* 17:19:53 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:19:53 INFO - PID 1637 | 17:19:53 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:19:53 INFO - PID 1637 | Full stack: 17:19:53 INFO - PID 1637 | ************************* 17:21:13 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 17:21:13 INFO - PID 1637 | RSS: Main: 258543616 17:21:13 INFO - PID 1637 | 17:24:15 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 17:24:15 INFO - PID 1637 | RSS: Main: 268099584 17:24:15 INFO - PID 1637 | 17:27:17 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 17:27:18 INFO - PID 1637 | RSS: Main: 262045696 17:27:18 INFO - PID 1637 | 17:30:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 17:30:21 INFO - PID 1637 | RSS: Main: 270716928 17:30:21 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 17:33:23 INFO - PID 1637 | RSS: Main: 265383936 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | __start_tp_report 17:33:23 INFO - PID 1637 | _x_x_mozilla_page_load 17:33:23 INFO - PID 1637 | _x_x_mozilla_page_load_details 17:33:23 INFO - PID 1637 | |i|pagename|runs| 17:33:23 INFO - PID 1637 | |0;dojo.html;3402.234497238121;3593.874214402495;3665.541807274091;3673;3674.5223120994274;5537.197106419567;5905.671223974604;6191;6207.8448038799;6274.372562743729;5079.822206222783;6160.045192995081;6190.845228869281;6202.41077097675;6259.405356491137;5421.593380496459;5461.617686761928;5466.453354664522;5474.589405794562;5476.91784623232;5533.335999680044;5582.190582365554;5588.105903055512;5604.943950560503;5619.381867994538;5407.864803379947;5497;5537.529310008654;5549.084401073818;5597.8600534986745;5473.987312347229;5476.726163691839;5491.643043202179;5504.284443022421;5616.326040875121;5496.80761173357;5499.120140777456;5557.305336832896;5571.554275657938;5577.2191893135;5174.793008279665;5272.077386457393;6269;6271.811845644638;6272.059191121322;6534.705938232743;6754.763583274611;6771.848785706442;6781.627010514419;6802.081718967926;5475.6440831345835;6562.93437065628;6742.359475849787;6794.252632210453;6867.2102708188695;5266.78932842682;6261.7182226799905;6334.746610135589;6345.079963405326;6349.0476428536085;6969.721211151598;6981.755638552728;7000.159980802337;7029;7029.0862187915245;5744.339400968942;6055.879662262496;6407;6527;6682.498812589075;6459.644719540471;6871.312868713188;6872.896906546406;6897;6904.965475172692;6620.106285651375;6738.932610673831;6755.088063111515;6772.830679233059;6793.626350550669;6518.1200537927725;6573.276939536647;6609.933900661028;6627.304133066055;6665.466762658975;4556.567126123013;4713.552212539803;5362.436944120889;5363.168708850135;5365.1147560652835;4121.732087414309;4776.47458779534;4780.784864681098;4791.377120974251;4817.301491283744;5920.319163296276;5924.844655292177;6098.786542470993;6237.220347456568;6353.110564520971;5712.457316554921;6112.351504965139;6443.645599491979;6901.965490172617;6916.757913473004;6369.299377068519;6375.776847810304;6410.647414392253;6415.294317625057;6428.935710642927;5795.797147099831;5910.143029260819;6040;6060.151578778975;6073.726682299307;6924.861502769916;6985.825354366181;7040.436765058886;7059;7088.645567721696;6633.402993730588;6878.518503704692;6881;6902.689378978059;6917.93082069193;6841.828954276183;6860.725570977105;6989.231184569795;7073.752418665322;7109.253528379652;6779.220389655055;6783.525153239226;6786.287439818748;6802.14973128359;6815.216250131299;6613.338666133348;6704.698288577123;6727.899081513683;6739.292374300824;6843.828904277234;6573.868522629425;6634.436072933855;6660.167479065117;6668.199816022108;6697.196336439659;5819.010768169506;5894.911576326444;6640.601563906181;6645.534812563074;6668.29982851813;5314.5482633975;5705.34388545306;5815.836832633406;6404.327545607644;6502.934970650422;6519.674016299071;6583.0454584086;6614.5039122067;6628.90056649141;6656.933430665825;6415.358464153547;6452.22573291208;6458.677066146768;6467.223933128054;6483.189601299838 17:33:23 INFO - PID 1637 | |1;ext.html;802.6307898366751;821.1952286758977;903.3676426501451;1108.68402505286;1121.2487633285698;35083.824580877095;35916.64083359166;35929.82035089828;36068.63931360686;36176.45735313969;37384.43923341152;37642;37739.867803965855;37757.433638495444;38054.048648783726;7387.7783666490095;8109.107998120202;8236.588170591476;8255.463394879343;8308.875366869503;8323.334133269354;8398.328133749315;8410.36922230832;8411.369147313975;8430;7150.427965762752;8340.3744719146;8424;8454.28138608219;8471.49171049736;7159.319864612853;8400.999999999969;8408.075111737704;8411.327093832479;8418.957905210435;8213.425060245785;8232.423730338878;8290.792730181733;8296.460730052528;8326.833463330699;36755.81622091899;36818;36838.631613683785;36928.630713692786;37212.62787372119;3619.7828130312;3662.249238906018;3662.926741465182;3663.5786884508357;3665;29481.410371792444;29988.70011299881;30117.548236776467;30155.944541940928;30177.396452070836;3088.7992280501694;3157.163351711798;3160.920976975571;3193.425183466975;3197.392495425862;244268;252276.7386163076;254394.99999999814;274890;281151;32672;38165.809170953966;46373.536264637594;47454.76272618615;47489.28766068512;35126.473102903474;48872.02255954861;48985;48995.26507102396;49042.05747655152;14085.366158522891;14197.36111874968;14302.568563344048;14318;14331.14013159193;11317.943410282896;11557.711057223469;11559.884401155881;11574.131940104526;11586.13104017202;10829.404382758867;13141.474341026444;13269;13270.668233294246;13371.465141394236;4997.525235102659;5927.940720592739;5947.791827286024;5959.135925290809;5993.550483713739;895.4090300401704;1002.2533212756397;1039.2465462539599;1064.2443864856039;1089.809283375422;23239.373243872767;24278.393040174138;24316.513669726508;24398.512029759306;24437.87781061119;1037.2531777120462;1067.5996501312009;1145.9885401146048;1147.1511081799574;1190.6249531397582;12164.81752773709;12412.131150819356;12467.688307792378;16199.271032803552;16319.428819991243;288395;292406;326655;327432;328851;54133.188002179995;55121.44878551163;55812;55812.72093639506;55857.603559911324;1427.5075099090798;1496.6034000989746;1513.8561836625504;1521.8249901261274;1563.366836431247;29169.854150729112;29280.853595731885;29733.749148225936;29989.400211996075;29992.700072998992;36625.45061824128;38015.429768554044;38044.80977595094;38130.809345953094;38249.426258605585;235944.8202758975;285141.5742921272;285449;285725;287224;27711.72288277172;28171.859140704164;31124.844375777975;32825;33323.500147497325;34791.13022174466;40510.18979620332;40995;42678.78660606677;42807.50173743904;22831.885840570692;28978.565321520615;29812.850935745184;29951.700482995762;30318;29716;29919.102426927227;29947.850260748557;30126.288634022167;30257.546136808407;34089.46597403073;35805.1048723784;36066.639333607374;36123.81938090398;36186.276274473836;29816.254593635334;29864.552031719977;30425.39149216959;30560.235994100327;30631.693683062884;22987.195448159233;23490.765092348858;23844;23995.64006539938;24028.038878444666 17:33:23 INFO - PID 1637 | |2;jquery.html;395.4325542846016;426.8207352911777;431.02804668640977;431.27330448194795;450.06835849790934;18262.908685456587;18978.145983430742;19282.807171928293;20302.883341416215;21795.455113622167;22057.22799702011;22237;22984.965676544554;23021.539569208606;23088.884555577242;5792.82621521355;6042.607230530023;6096.695165241742;6102.969485152568;6110.388961103877;4184.748915065091;5212.296339994109;5242.9213561796605;5595.8808238352285;5933.317668468138;5140.177571588547;5176.352955880515;5188.610854185932;5207.895842083156;5220.138677118271;5154.793808247666;5163.885578069134;5195.116830138866;5199.4800519948085;5657.434256574351;5317.149256119041;5325.227841962933;5327.121025030865;5328.413874473805;5358.892822143535;27221.319467013283;27796;27834.721652783417;27838.025669101484;28022.299442513897;2215.634420320645;2255.8872056397277;2259.3109101723967;2259.45773014477;2261.095561775303;17867.73198402025;18114.003729794855;18378.63242735138;18381.632367352577;18462.723059154123;1869.8317151456433;2106.7893210678963;2147.4094623978376;2178.7712290209615;2219.5449932763745;171876;174324.99999999872;175030;175067;175824.3626345606;10870.402127883042;12780.808287875689;12856.100072994988;13009.414576344087;13108.934455327662;16595;16619.085950272853;16634.916825415796;16657.83342166563;16678.83321166797;8883.955580222058;9081.318901082444;9089.727308180765;9149.771255718528;9232.584533696;8522.78693032679;8619.026435879612;8626.223639872442;8751.824963500821;8776.736697899074;6880.8967865482055;7284.963575182091;7633.931249625057;7967.402444816662;8078.263173417626;2181.4219231903558;2204.647256438962;2204.908570257701;2213.457702862809;2385.725641551244;978.8972157923458;982.2878413150409;983.4148681534475;988.1008282462924;1014.2849291249652;19747.012649367472;20154.093065812074;20847.1661133556;20894.47763805917;20968.47578810542;997.8602995580625;1005.2259759984771;1020.78563501664;1029.341221618165;1032.2309879139939;10217.795644087077;10515.158787296998;10601.787964240671;10627.14982801374;10636.255462117577;470956.64521677175;521254;533399;536791;548624;113475;132490;132739;133351;136276;1159.513004538096;1166.080346379978;1198.9640310790517;1203.4043148641479;1206.7888119579038;16606.086665233044;17796.3771268005;17893.910530447785;18001.63996720032;18263.269469221083;55237.72381138069;55542;55887;55931.44068559262;56251.156232655725;58243;72266.63866680634;73746;74299.88550171643;74738.62630686811;8550.273226775793;8840.778980525281;8957.10428957099;8959.238464730572;9050.592723327598;13802.102863313852;13986.650333741738;14039.578812635636;14239;14300.427982880985;10933.63329583802;11214.55141794319;11473.655790325969;11594.594189203337;11984.580539681418;9644.324897257125;9693.660721874701;11145.54797944451;11413.315201087962;11484.19610627248;11942.820857686967;11955.103367247317;12100.576479823163;12245.938770306091;12316.815247771112;8879.778005549655;8880.822383552422;8917.73246802597;9036.45781253127;9051.954740226256;11791.64167166553;11996.460159292677;12149.088818338836;12189.268643881041;12221.327826969602 17:33:23 INFO - PID 1637 | |3;mootools.html;1185.3895243949366;1391.867772561607;1413.5405993052261;1419.2194293138778;1425.1164278147546;9656.5171741413;11417.657470275895;11889.940550297248;11922.582709605165;12094.939525302361;11703.18077734559;11711.941440292809;11884;11888.7622247555;11909.940450297758;2163.3942496101117;2498.925032249035;2503.649489071527;2531.227975467482;2546.0834099724084;2463.002483993985;2492.588722860735;2514.396544829246;2518.874056297187;2521.2688320387065;2468.8642124683133;2482.7889629381525;2517.3203235126507;2517.949641007179;2520.6723125993594;2452.399162205262;2453.1904471524444;2468.321211666797;2481.2680259323542;2494.7131079925857;2181.1711549611205;2420.3586049696846;2471.2586224132688;2483.4412257242157;2496.051500429843;11727.06183505326;11773.764524709457;11820.940895295469;11855.762884742344;11885.168038237327;6525.608463492205;6736.696848641774;6740.258571557125;6758.324167583251;6777.322267773183;3975.4221200919405;4293.162833247518;4359.04101097758;4372.650187984954;4386.71486353386;1197.4012993503247;1198.7542553776454;1202.4949521201029;1285.8585555588877;1292.424870932426;16264.024158550525;16483.175841207893;16734.330626775038;16919.06945118018;16969.151542422955;5500.642458240201;5546.500814926676;5659.5755318351285;5726.169705392695;5744.080947048452;11800.763984720257;11838.644840654793;11857.288562686266;11876.465559049864;11877.821832672516;6540.771073012422;6557.967210164014;6563.901541476882;6567.408933195939;6583.44040756541;6004.309504406961;6059.697015149313;6132.877342453126;6259.467945224706;6303.338149494237;4294.398784170218;4380.036391993756;4423.1153769246275;4429.136318417877;4443.133588950188;1262.1543565810746;1271.6693659648558;1286.7555164518712;1295.1387327427155;1295.8185853980451;640.5708175522411;752.27405553641;802.6588699802561;803.1165717710471;816.4162623724065;6142.508599312045;6203.441690247809;6253.093301471261;6351;6446.258680251831;574.5690731951037;577.9219805326227;592.0734051209843;595.1756816808751;601.2904772368647;4278.4010238566625;4575.199340115467;4639.489656137822;4784.354112194878;4818.8193892496565;14691.191984440959;15003.249837508081;15191.848081519265;15206.619834504227;15240.933134680688;14666;14962.925185374004;15041.623959400884;15133.848661513464;15255.61860953463;8532.70135545253;8569.84307118531;8698.521581313089;8766.430182038148;8807.471551706918;6030.638161710136;6791.607720417402;6863.313668633096;7050.964745176241;7178.748743793943;13241.933790330986;13571.524996625465;13741.24423156736;13751.106178098791;13847.792283115963;12318.568850090203;12592.87407125917;13374.197548147142;14187;14196.787048194492;7604.657790399335;7646.541207527566;9401.764955876159;9438.622455101719;9551.331406801457;10909.690837099599;11005.064569511682;11023.173262005543;11042;11049.834252486058;8982.86525702128;9223.86164207524;9307.441553506811;9308.394954327947;9384.343095983215;7752.806179845549;9027.007029226648;9043.276537877086;9112.817743645222;9248.537573121452;8189.7133600323705;8238.917610824055;8344.958275208584;8355.289800367036;8389.622466988878;6022.397760223943;6082.574219804571;6146.907796383147;6196.380361963767;6305.747770089145;5994.250718660168;6042.969785151046;6079.358573185127;6151.231096112986;6330.588511746721 17:33:23 INFO - PID 1637 | |4;prototype.html;324.03599292105986;366.34972923061565;408.33033824527763;411.6994593946419;412.583290876215;6260.84347891303;6367.458766004889;6446.194225721785;6464.830184530974;6503.772367967122;5368.194770784385;5613.129964855444;6187;6220.7822726204595;6261.217347831521;4474.910501789962;4524.230880750264;4533.886652833673;4540.341650460681;4554.65840061995;4145.834166633331;4474.82100715971;4487.9551204488025;4516.774161291939;4528.25283828168;4470.7317560946285;4473.2171869922795;4491.708038977472;4509.526499717532;4534.297183936478;3697.6672099510906;4442.977785111086;4507.008458139204;4507.301368287905;4525.298578720303;3764.416515440111;3791.829367678461;3833.5974722654278;3843.596422375638;3857.4599556062176;4966.6771659842;6065.4541091302;6075.544334174911;6086.417531441857;6144.662043587601;4876.585490233334;5345.518903298683;5358.892822143574;5361.517463428271;5435.565154787608;5479.53423958964;5515.007298686235;5570.721463926788;6392.264889537716;6570.470368907957;4338.132373525276;4361.80371883266;4375.7593332366705;4382.868513944587;4390.868273951786;5647.792394666;7108;7165.247648996832;7179.425645948364;7218.927810721878;3872.845086196577;4412.808541693789;4425.712328698624;4452.686457495047;4455.77721113943;4051.817668204938;4251.553586873396;4516.480604730433;4528.41130653013;4533.5013148553635;5219.660722053054;5348.358197016305;5374.247605335257;5384.9730751346;5435.266239057677;5574.58190635704;6734.696938637773;6747.291534388916;6760.898586521206;6803.217629972616;1318.881300682924;1501.3769285746512;1525.4737115695068;1532.8044125582064;1543.8919275650596;989.6536212325773;991.9504024798875;992.6873034993954;1001.8528784541804;1004.5835372125268;5937.673427961504;6396.872062558723;6436.774712885121;6436.8390790230615;6467.223933128054;6780.288069752703;6834.897476537856;7022.683979220948;7060.364567188875;7084.964575177091;6357.300696923335;6419.358064193543;6458.2895881453005;6458.644774537445;6469.350315669568;3361.983190084034;3544.7164226862305;3669.954063092059;3680.9263814723554;3685.686716629063;7259.745908893269;7267.239957827632;7347.669354879044;7358.374538164209;7359.963200183965;6160.028790786995;6337;6421.8073457796345;7216.9278307216255;7229.674664640103;7238.420926325882;7299.635018249066;7319.560826350436;7337.633118344062;7361.337479626859;6174.475169610633;6484.351564843478;6848.253695307664;7099.290070992859;7122.7507037253445;5818.6217895836635;6780.593164410151;6831.5653712718895;6886.724531018703;6949.895751563629;7266.6729997151315;7367.815804604714;7369;7370.262973702587;7379.151397589131;3535.469679548088;3770.377887648617;4464.843730469418;4482.170798402306;4541.500434952219;6054.848628784315;7042.859142817012;7044.964775176091;7118.323759242864;7144.821379465555;5641.802536911188;6838.760643377458;6861.181786826395;6863.794086177422;6938.895916561357;5500.359827282847;6724;6827;6840.281770414035;6846.965765171142;5570.161846590697;5610.551155907599;6560.475161986934;6679.76620818269;6795.490338224752;5784.913226301693;6163.722632481459;6765.391114799691;6805.9659701701175;6814.182298124257;6627.900581491185;6634.800955971328;6639.601623902581;6642;6708.932910670831 17:33:23 INFO - PID 1637 | |5;yui.html;734.4344854462064;763.6487215880694;826.8801023851538;867.7266661001784;874.2481465939292;16466.423675171372;16909.23908424121;16954.406595769135;18284.268629254846;19412.514687132792;17902.910485447588;18243.817561824377;18443.53891152722;18539;18785.81214187861;959.3236768078488;986.9259805514579;993.4585650820284;996.2627655534889;1001.2990906365574;844.6832437835811;885.4156256870467;989.2778271861546;1003.8042581696573;1009.1976878381704;1020.6580795433501;1032.8244198486238;1035.9119474844647;1047.6385646951828;1049.6903413493037;1025.9897401026005;1038.7351225437503;1039.786843697044;1041.2815157541272;1043.394830998027;759.3014426727369;766.4290103872595;774.2721841469001;775.3215936055951;776.3905334312537;16337.673246535122;16666;16873.915630421892;17279.481615551555;17570;16645.00129992204;16748.916255418768;17005.404810831562;17104.65790684192;17205.139743012798;11961.64115076549;13089.934550327187;13714.24571648559;13812.585622431343;13870.722585548334;3496.615372309044;3509.438489841639;3521.2781379817075;3525.065857547752;3528.065062758371;78942;113766;113999.29001064907;115629;117560.24397560261;13065.804012939814;13433.798493022612;13453.923686105089;13459.93270033663;13462;23891.522169556512;25365.492690146464;25536.616950745753;25891.611625825626;26364.20907372782;6717.932820671828;7888.290053895177;7932;7961.641726122339;7962.402819788424;3372.8313584320686;3872.167483990899;3893.980530097331;3946.9802650987135;3957.0700885291935;4152.2525945330135;4257.787110644455;4373.759443230653;4419.46966364032;4447.466304043471;907.2288554728428;939.8496240601471;945.3807755919884;945.3902233059775;950.092661508258;528.836060821139;649.5290914087249;672.176583684982;673.6261374936859;683.5010442377093;9270.629174832931;10313.535890884928;10494.370337779757;11191.72020699489;12163.574274900337;647.9319671434525;648.8021153548119;652.6280020388333;663.9900401493982;671.7380221713536;5367.731613419313;5403.5136837684795;5576.553875690016;5588.580856435784;5644.322681278273;86182;92184;98501;104846.90306193834;113688;79339.20660793319;90440.09559904448;96844;97698.511507442;98830;87390.12609873946;87538;87958.56020719983;88158.55920720355;88614.55692721494;9356.830396200476;10633.574657013633;11245.887541124484;11325.037371823488;11558.248713833575;36240.55037798458;42263.7886810564;42533.36199956941;44087.33868992032;44739.32891006573;46831;46859.9251161002;56950;57613.71193144175;58374.708126459096;14005.929970350084;14148.080374776018;14391.352389142723;14474;14521.92739036298;15481.458148964732;15508.84491155074;15577;15702.136382498615;15894.284757185724;14284.214368209432;14663.92668036653;14773.335199915815;14813;14824.332905019084;14212.289385530888;14379.352929118424;14540.200288984208;14754.778678320048;14760.409583616534;11994.160408771302;14506.927465362605;16063.437779677655;17023.659526809643;17333.220005100055;11452.3701196435;11527.596534121265;11570.421478926188;11767.705807354885;11791.29252244868;13113.475460981836;13148.40832162574;14068.08557443763;14173.362198700877;14222.928885355506 17:33:23 INFO - PID 1637 | __end_tp_report 17:33:23 INFO - PID 1637 | __start_cc_report 17:33:23 INFO - PID 1637 | _x_x_mozilla_cycle_collect,427 17:33:23 INFO - PID 1637 | __end_cc_report 17:33:23 INFO - PID 1637 | __startTimestamp1493771603654__endTimestamp 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | ------- Summary: start ------- 17:33:23 INFO - PID 1637 | Number of tests: 6 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6145.27 Median:6404.33 stddev:773.26 (12.1%) stddev-sans-first:745.09 17:33:23 INFO - PID 1637 | Values: 3402.2 3593.9 3665.5 3673.0 3674.5 5537.2 5905.7 6191.0 6207.8 6274.4 5079.8 6160.0 6190.8 6202.4 6259.4 5421.6 5461.6 5466.5 5474.6 5476.9 5533.3 5582.2 5588.1 5604.9 5619.4 5407.9 5497.0 5537.5 5549.1 5597.9 5474.0 5476.7 5491.6 5504.3 5616.3 5496.8 5499.1 5557.3 5571.6 5577.2 5174.8 5272.1 6269.0 6271.8 6272.1 6534.7 6754.8 6771.8 6781.6 6802.1 5475.6 6562.9 6742.4 6794.3 6867.2 5266.8 6261.7 6334.7 6345.1 6349.0 6969.7 6981.8 7000.2 7029.0 7029.1 5744.3 6055.9 6407.0 6527.0 6682.5 6459.6 6871.3 6872.9 6897.0 6905.0 6620.1 6738.9 6755.1 6772.8 6793.6 6518.1 6573.3 6609.9 6627.3 6665.5 4556.6 4713.6 5362.4 5363.2 5365.1 4121.7 4776.5 4780.8 4791.4 4817.3 5920.3 5924.8 6098.8 6237.2 6353.1 5712.5 6112.4 6443.6 6902.0 6916.8 6369.3 6375.8 6410.6 6415.3 6428.9 5795.8 5910.1 6040.0 6060.2 6073.7 6924.9 6985.8 7040.4 7059.0 7088.6 6633.4 6878.5 6881.0 6902.7 6917.9 6841.8 6860.7 6989.2 7073.8 7109.3 6779.2 6783.5 6786.3 6802.1 6815.2 6613.3 6704.7 6727.9 6739.3 6843.8 6573.9 6634.4 6660.2 6668.2 6697.2 5819.0 5894.9 6640.6 6645.5 6668.3 5314.5 5705.3 5815.8 6404.3 6502.9 6519.7 6583.0 6614.5 6628.9 6656.9 6415.4 6452.2 6458.7 6467.2 6483.2 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:43213.14 Median:164926.20 stddev:74562.19 (45.2%) stddev-sans-first:74703.36 17:33:23 INFO - PID 1637 | Values: 802.6 821.2 903.4 1108.7 1121.2 35083.8 35916.6 35929.8 36068.6 36176.5 37384.4 37642.0 37739.9 37757.4 38054.0 7387.8 8109.1 8236.6 8255.5 8308.9 8323.3 8398.3 8410.4 8411.4 8430.0 7150.4 8340.4 8424.0 8454.3 8471.5 7159.3 8401.0 8408.1 8411.3 8419.0 8213.4 8232.4 8290.8 8296.5 8326.8 36755.8 36818.0 36838.6 36928.6 37212.6 3619.8 3662.2 3662.9 3663.6 3665.0 29481.4 29988.7 30117.5 30155.9 30177.4 3088.8 3157.2 3160.9 3193.4 3197.4 244268.0 252276.7 254395.0 274890.0 281151.0 32672.0 38165.8 46373.5 47454.8 47489.3 35126.5 48872.0 48985.0 48995.3 49042.1 14085.4 14197.4 14302.6 14318.0 14331.1 11317.9 11557.7 11559.9 11574.1 11586.1 10829.4 13141.5 13269.0 13270.7 13371.5 4997.5 5927.9 5947.8 5959.1 5993.6 895.4 1002.3 1039.2 1064.2 1089.8 23239.4 24278.4 24316.5 24398.5 24437.9 1037.3 1067.6 1146.0 1147.2 1190.6 12164.8 12412.1 12467.7 16199.3 16319.4 288395.0 292406.0 326655.0 327432.0 328851.0 54133.2 55121.4 55812.0 55812.7 55857.6 1427.5 1496.6 1513.9 1521.8 1563.4 29169.9 29280.9 29733.7 29989.4 29992.7 36625.5 38015.4 38044.8 38130.8 38249.4 235944.8 285141.6 285449.0 285725.0 287224.0 27711.7 28171.9 31124.8 32825.0 33323.5 34791.1 40510.2 40995.0 42678.8 42807.5 22831.9 28978.6 29812.9 29951.7 30318.0 29716.0 29919.1 29947.9 30126.3 30257.5 34089.5 35805.1 36066.6 36123.8 36186.3 29816.3 29864.6 30425.4 30560.2 30631.7 22987.2 23490.8 23844.0 23995.6 24028.0 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:34994.65 Median:2257.60 stddev:89903.19 (3982.2%) stddev-sans-first:90117.86 17:33:23 INFO - PID 1637 | Values: 395.4 426.8 431.0 431.3 450.1 18262.9 18978.1 19282.8 20302.9 21795.5 22057.2 22237.0 22985.0 23021.5 23088.9 5792.8 6042.6 6096.7 6103.0 6110.4 4184.7 5212.3 5242.9 5595.9 5933.3 5140.2 5176.4 5188.6 5207.9 5220.1 5154.8 5163.9 5195.1 5199.5 5657.4 5317.1 5325.2 5327.1 5328.4 5358.9 27221.3 27796.0 27834.7 27838.0 28022.3 2215.6 2255.9 2259.3 2259.5 2261.1 17867.7 18114.0 18378.6 18381.6 18462.7 1869.8 2106.8 2147.4 2178.8 2219.5 171876.0 174325.0 175030.0 175067.0 175824.4 10870.4 12780.8 12856.1 13009.4 13108.9 16595.0 16619.1 16634.9 16657.8 16678.8 8884.0 9081.3 9089.7 9149.8 9232.6 8522.8 8619.0 8626.2 8751.8 8776.7 6880.9 7285.0 7633.9 7967.4 8078.3 2181.4 2204.6 2204.9 2213.5 2385.7 978.9 982.3 983.4 988.1 1014.3 19747.0 20154.1 20847.2 20894.5 20968.5 997.9 1005.2 1020.8 1029.3 1032.2 10217.8 10515.2 10601.8 10627.1 10636.3 470956.6 521254.0 533399.0 536791.0 548624.0 113475.0 132490.0 132739.0 133351.0 136276.0 1159.5 1166.1 1199.0 1203.4 1206.8 16606.1 17796.4 17893.9 18001.6 18263.3 55237.7 55542.0 55887.0 55931.4 56251.2 58243.0 72266.6 73746.0 74299.9 74738.6 8550.3 8840.8 8957.1 8959.2 9050.6 13802.1 13986.7 14039.6 14239.0 14300.4 10933.6 11214.6 11473.7 11594.6 11984.6 9644.3 9693.7 11145.5 11413.3 11484.2 11942.8 11955.1 12100.6 12245.9 12316.8 8879.8 8880.8 8917.7 9036.5 9052.0 11791.6 11996.5 12149.1 12189.3 12221.3 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7128.81 Median:4285.78 stddev:4533.75 (105.8%) stddev-sans-first:4524.46 17:33:23 INFO - PID 1637 | Values: 1185.4 1391.9 1413.5 1419.2 1425.1 9656.5 11417.7 11889.9 11922.6 12094.9 11703.2 11711.9 11884.0 11888.8 11909.9 2163.4 2498.9 2503.6 2531.2 2546.1 2463.0 2492.6 2514.4 2518.9 2521.3 2468.9 2482.8 2517.3 2517.9 2520.7 2452.4 2453.2 2468.3 2481.3 2494.7 2181.2 2420.4 2471.3 2483.4 2496.1 11727.1 11773.8 11820.9 11855.8 11885.2 6525.6 6736.7 6740.3 6758.3 6777.3 3975.4 4293.2 4359.0 4372.7 4386.7 1197.4 1198.8 1202.5 1285.9 1292.4 16264.0 16483.2 16734.3 16919.1 16969.2 5500.6 5546.5 5659.6 5726.2 5744.1 11800.8 11838.6 11857.3 11876.5 11877.8 6540.8 6558.0 6563.9 6567.4 6583.4 6004.3 6059.7 6132.9 6259.5 6303.3 4294.4 4380.0 4423.1 4429.1 4443.1 1262.2 1271.7 1286.8 1295.1 1295.8 640.6 752.3 802.7 803.1 816.4 6142.5 6203.4 6253.1 6351.0 6446.3 574.6 577.9 592.1 595.2 601.3 4278.4 4575.2 4639.5 4784.4 4818.8 14691.2 15003.2 15191.8 15206.6 15240.9 14666.0 14962.9 15041.6 15133.8 15255.6 8532.7 8569.8 8698.5 8766.4 8807.5 6030.6 6791.6 6863.3 7051.0 7178.7 13241.9 13571.5 13741.2 13751.1 13847.8 12318.6 12592.9 13374.2 14187.0 14196.8 7604.7 7646.5 9401.8 9438.6 9551.3 10909.7 11005.1 11023.2 11042.0 11049.8 8982.9 9223.9 9307.4 9308.4 9384.3 7752.8 9027.0 9043.3 9112.8 9248.5 8189.7 8238.9 8345.0 8355.3 8389.6 6022.4 6082.6 6146.9 6196.4 6305.7 5994.3 6043.0 6079.4 6151.2 6330.6 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5405.93 Median:6115.54 stddev:1765.86 (28.9%) stddev-sans-first:1729.13 17:33:23 INFO - PID 1637 | Values: 324.0 366.3 408.3 411.7 412.6 6260.8 6367.5 6446.2 6464.8 6503.8 5368.2 5613.1 6187.0 6220.8 6261.2 4474.9 4524.2 4533.9 4540.3 4554.7 4145.8 4474.8 4488.0 4516.8 4528.3 4470.7 4473.2 4491.7 4509.5 4534.3 3697.7 4443.0 4507.0 4507.3 4525.3 3764.4 3791.8 3833.6 3843.6 3857.5 4966.7 6065.5 6075.5 6086.4 6144.7 4876.6 5345.5 5358.9 5361.5 5435.6 5479.5 5515.0 5570.7 6392.3 6570.5 4338.1 4361.8 4375.8 4382.9 4390.9 5647.8 7108.0 7165.2 7179.4 7218.9 3872.8 4412.8 4425.7 4452.7 4455.8 4051.8 4251.6 4516.5 4528.4 4533.5 5219.7 5348.4 5374.2 5385.0 5435.3 5574.6 6734.7 6747.3 6760.9 6803.2 1318.9 1501.4 1525.5 1532.8 1543.9 989.7 992.0 992.7 1001.9 1004.6 5937.7 6396.9 6436.8 6436.8 6467.2 6780.3 6834.9 7022.7 7060.4 7085.0 6357.3 6419.4 6458.3 6458.6 6469.4 3362.0 3544.7 3670.0 3680.9 3685.7 7259.7 7267.2 7347.7 7358.4 7360.0 6160.0 6337.0 6421.8 7216.9 7229.7 7238.4 7299.6 7319.6 7337.6 7361.3 6174.5 6484.4 6848.3 7099.3 7122.8 5818.6 6780.6 6831.6 6886.7 6949.9 7266.7 7367.8 7369.0 7370.3 7379.2 3535.5 3770.4 4464.8 4482.2 4541.5 6054.8 7042.9 7045.0 7118.3 7144.8 5641.8 6838.8 6861.2 6863.8 6938.9 5500.4 6724.0 6827.0 6840.3 6847.0 5570.2 5610.6 6560.5 6679.8 6795.5 5784.9 6163.7 6765.4 6806.0 6814.2 6627.9 6634.8 6639.6 6642.0 6708.9 17:33:23 INFO - PID 1637 | 17:33:23 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21082.44 Median:19099.16 stddev:29345.82 (153.6%) stddev-sans-first:29388.37 17:33:23 INFO - PID 1637 | Values: 734.4 763.6 826.9 867.7 874.2 16466.4 16909.2 16954.4 18284.3 19412.5 17902.9 18243.8 18443.5 18539.0 18785.8 959.3 986.9 993.5 996.3 1001.3 844.7 885.4 989.3 1003.8 1009.2 1020.7 1032.8 1035.9 1047.6 1049.7 1026.0 1038.7 1039.8 1041.3 1043.4 759.3 766.4 774.3 775.3 776.4 16337.7 16666.0 16873.9 17279.5 17570.0 16645.0 16748.9 17005.4 17104.7 17205.1 11961.6 13089.9 13714.2 13812.6 13870.7 3496.6 3509.4 3521.3 3525.1 3528.1 78942.0 113766.0 113999.3 115629.0 117560.2 13065.8 13433.8 13453.9 13459.9 13462.0 23891.5 25365.5 25536.6 25891.6 26364.2 6717.9 7888.3 7932.0 7961.6 7962.4 3372.8 3872.2 3894.0 3947.0 3957.1 4152.3 4257.8 4373.8 4419.5 4447.5 907.2 939.8 945.4 945.4 950.1 528.8 649.5 672.2 673.6 683.5 9270.6 10313.5 10494.4 11191.7 12163.6 647.9 648.8 652.6 664.0 671.7 5367.7 5403.5 5576.6 5588.6 5644.3 86182.0 92184.0 98501.0 104846.9 113688.0 79339.2 90440.1 96844.0 97698.5 98830.0 87390.1 87538.0 87958.6 88158.6 88614.6 9356.8 10633.6 11245.9 11325.0 11558.2 36240.6 42263.8 42533.4 44087.3 44739.3 46831.0 46859.9 56950.0 57613.7 58374.7 14005.9 14148.1 14391.4 14474.0 14521.9 15481.5 15508.8 15577.0 15702.1 15894.3 14284.2 14663.9 14773.3 14813.0 14824.3 14212.3 14379.4 14540.2 14754.8 14760.4 11994.2 14506.9 16063.4 17023.7 17333.2 11452.4 11527.6 11570.4 11767.7 11791.3 13113.5 13148.4 14068.1 14173.4 14222.9 17:33:23 INFO - PID 1637 | -------- Summary: end -------- 17:33:23 INFO - PID 1637 | 17:33:24 INFO - TEST-INFO | 1637: exit 0 17:33:24 INFO - TEST-OK | dromaeo_css | took 1114780ms 17:33:24 INFO - TEST-START | kraken 17:33:24 INFO - operating with platform_type : mac_ 17:33:24 INFO - Initialising browser for kraken test... 17:33:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp2syIrL/profile http://localhost:49230/getInfo.html) 17:33:27 INFO - PID 1650 | 2017-05-02 17:33:27.119 plugin-container[1651:10067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:33:27 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:27 INFO - PID 1650 | 2017-05-02 17:33:27.122 plugin-container[1651:10067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x962b, name = 'com.apple.CFPasteboardClient' 17:33:27 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:27 INFO - PID 1650 | 2017-05-02 17:33:27.122 plugin-container[1651:10067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:27 INFO - PID 1650 | __metrics Screen width/height:1600/1200 17:33:27 INFO - PID 1650 | colorDepth:24 17:33:27 INFO - PID 1650 | Browser inner width/height: 1024/691 17:33:27 INFO - PID 1650 | __metrics 17:33:28 INFO - TEST-INFO | 1650: exit 0 17:33:28 INFO - Browser initialized. 17:33:28 INFO - Running cycle 1/1 for kraken test... 17:33:28 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp2syIrL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:30 INFO - PID 1652 | 2017-05-02 17:33:30.334 plugin-container[1653:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4637, name = 'com.apple.tsm.portname' 17:33:30 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:30 INFO - PID 1652 | 2017-05-02 17:33:30.335 plugin-container[1653:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.CFPasteboardClient' 17:33:30 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:30 INFO - PID 1652 | 2017-05-02 17:33:30.335 plugin-container[1653:10191] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:40 INFO - PID 1652 | RSS: Main: 237469696 17:33:40 INFO - PID 1652 | 17:33:43 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 17:33:44 INFO - PID 1652 | RSS: Main: 250576896 17:33:44 INFO - PID 1652 | 17:33:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 17:33:47 INFO - PID 1652 | RSS: Main: 258990080 17:33:47 INFO - PID 1652 | 17:33:50 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 17:33:51 INFO - PID 1652 | RSS: Main: 261554176 17:33:51 INFO - PID 1652 | 17:33:53 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 17:33:54 INFO - PID 1652 | RSS: Main: 263680000 17:33:54 INFO - PID 1652 | 17:33:56 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:33:56 INFO - PID 1652 | RSS: Main: 263991296 17:33:56 INFO - PID 1652 | 17:33:59 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:00 INFO - PID 1652 | RSS: Main: 269209600 17:34:00 INFO - PID 1652 | 17:34:03 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:03 INFO - PID 1652 | RSS: Main: 270417920 17:34:03 INFO - PID 1652 | 17:34:06 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 17:34:07 INFO - PID 1652 | RSS: Main: 269389824 17:34:07 INFO - PID 1652 | 17:34:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:09 INFO - PID 1652 | RSS: Main: 269242368 17:34:09 INFO - PID 1652 | 17:34:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:11 INFO - PID 1652 | RSS: Main: 269512704 17:34:11 INFO - PID 1652 | 17:34:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:14 INFO - PID 1652 | RSS: Main: 268992512 17:34:14 INFO - PID 1652 | 17:34:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:18 INFO - PID 1652 | RSS: Main: 269459456 17:34:18 INFO - PID 1652 | 17:34:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:34:22 INFO - PID 1652 | RSS: Main: 269533184 17:34:22 INFO - PID 1652 | 17:34:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 17:34:24 INFO - PID 1652 | RSS: Main: 270950400 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | __start_tp_report 17:34:24 INFO - PID 1652 | _x_x_mozilla_page_load 17:34:24 INFO - PID 1652 | _x_x_mozilla_page_load_details 17:34:24 INFO - PID 1652 | |i|pagename|runs| 17:34:24 INFO - PID 1652 | |0;ai-astar;99;106;149;172;171;171;146;115;116;111 17:34:24 INFO - PID 1652 | |1;audio-beat-detection;147;108;110;146;113;112;112;114;142;139 17:34:24 INFO - PID 1652 | |2;audio-dft;201;199;246;276;272;286;196;200;196;197 17:34:24 INFO - PID 1652 | |3;audio-fft;77;60;63;75;58;59;58;58;76;57 17:34:24 INFO - PID 1652 | |4;audio-oscillator;80;71;84;72;68;69;86;69;70;92 17:34:24 INFO - PID 1652 | |5;imaging-gaussian-blur;90;90;92;91;90;91;97;91;90;90 17:34:24 INFO - PID 1652 | |6;imaging-darkroom;118;110;97;97;98;97;98;95;98;96 17:34:24 INFO - PID 1652 | |7;imaging-desaturate;90;103;111;146;91;90;93;91;92;92 17:34:24 INFO - PID 1652 | |8;json-parse-financial;52;50;52;51;51;51;50;51;51;51 17:34:24 INFO - PID 1652 | |9;json-stringify-tinderbox;57;63;57;60;73;62;60;57;56;59 17:34:24 INFO - PID 1652 | |10;stanford-crypto-aes;83;86;85;92;84;82;76;105;106;76 17:34:24 INFO - PID 1652 | |11;stanford-crypto-ccm;137;157;141;134;139;147;142;133;181;141 17:34:24 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;236;182;183;184;187;184;188;187;186;185 17:34:24 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;68;69;68;67;70;66;67;70;68;71 17:34:24 INFO - PID 1652 | __end_tp_report 17:34:24 INFO - PID 1652 | __start_cc_report 17:34:24 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1005 17:34:24 INFO - PID 1652 | __end_cc_report 17:34:24 INFO - PID 1652 | __startTimestamp1493771664008__endTimestamp 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | ------- Summary: start ------- 17:34:24 INFO - PID 1652 | Number of tests: 14 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:135.60 Median:160.00 stddev:29.34 (18.3%) stddev-sans-first:27.97 17:34:24 INFO - PID 1652 | Values: 99.0 106.0 149.0 172.0 171.0 171.0 146.0 115.0 116.0 111.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:126.50 stddev:16.74 (13.2%) stddev-sans-first:15.61 17:34:24 INFO - PID 1652 | Values: 147.0 108.0 110.0 146.0 113.0 112.0 112.0 114.0 142.0 139.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:226.90 Median:223.50 stddev:38.41 (17.2%) stddev-sans-first:39.58 17:34:24 INFO - PID 1652 | Values: 201.0 199.0 246.0 276.0 272.0 286.0 196.0 200.0 196.0 197.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:64.10 Median:61.50 stddev:8.39 (13.6%) stddev-sans-first:7.48 17:34:24 INFO - PID 1652 | Values: 77.0 60.0 63.0 75.0 58.0 59.0 58.0 58.0 76.0 57.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.00 stddev:8.66 (11.4%) stddev-sans-first:9.07 17:34:24 INFO - PID 1652 | Values: 80.0 71.0 84.0 72.0 68.0 69.0 86.0 69.0 70.0 92.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:91.00 stddev:2.15 (2.4%) stddev-sans-first:2.24 17:34:24 INFO - PID 1652 | Values: 90.0 90.0 92.0 91.0 90.0 91.0 97.0 91.0 90.0 90.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:97.00 stddev:7.47 (7.7%) stddev-sans-first:4.45 17:34:24 INFO - PID 1652 | Values: 118.0 110.0 97.0 97.0 98.0 97.0 98.0 95.0 98.0 96.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:99.90 Median:91.00 stddev:17.59 (19.3%) stddev-sans-first:18.29 17:34:24 INFO - PID 1652 | Values: 90.0 103.0 111.0 146.0 91.0 90.0 93.0 91.0 92.0 92.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.60 17:34:24 INFO - PID 1652 | Values: 52.0 50.0 52.0 51.0 51.0 51.0 50.0 51.0 51.0 51.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:4.99 (8.3%) stddev-sans-first:5.14 17:34:24 INFO - PID 1652 | Values: 57.0 63.0 57.0 60.0 73.0 62.0 60.0 57.0 56.0 59.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:83.50 stddev:10.56 (12.7%) stddev-sans-first:11.08 17:34:24 INFO - PID 1652 | Values: 83.0 86.0 85.0 92.0 84.0 82.0 76.0 105.0 106.0 76.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:141.50 stddev:14.34 (10.1%) stddev-sans-first:14.90 17:34:24 INFO - PID 1652 | Values: 137.0 157.0 141.0 134.0 139.0 147.0 142.0 133.0 181.0 141.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.20 Median:186.50 stddev:16.21 (8.7%) stddev-sans-first:2.03 17:34:24 INFO - PID 1652 | Values: 236.0 182.0 183.0 184.0 187.0 184.0 188.0 187.0 186.0 185.0 17:34:24 INFO - PID 1652 | 17:34:24 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.58 (2.3%) stddev-sans-first:1.67 17:34:24 INFO - PID 1652 | Values: 68.0 69.0 68.0 67.0 70.0 66.0 67.0 70.0 68.0 71.0 17:34:24 INFO - PID 1652 | -------- Summary: end -------- 17:34:24 INFO - PID 1652 | 17:34:25 INFO - TEST-INFO | 1652: exit 0 17:34:25 INFO - TEST-OK | kraken | took 60904ms 17:34:25 INFO - SUITE-END | took 1175s 17:34:25 INFO - Completed test suite (00:19:35) 17:34:25 INFO - javascript benchmark 17:34:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7809.156610239619, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.234497238121, 3593.874214402495, 3665.541807274091, 3673.0, 3674.5223120994274, 5537.197106419567, 5905.671223974604, 6191.0, 6207.8448038799, 6274.372562743729, 5079.822206222783, 6160.045192995081, 6190.845228869281, 6202.41077097675, 6259.405356491137, 5421.593380496459, 5461.617686761928, 5466.453354664522, 5474.589405794562, 5476.91784623232, 5533.335999680044, 5582.190582365554, 5588.105903055512, 5604.943950560503, 5619.381867994538, 5407.864803379947, 5497.0, 5537.529310008654, 5549.084401073818, 5597.8600534986745, 5473.987312347229, 5476.726163691839, 5491.643043202179, 5504.284443022421, 5616.326040875121, 5496.80761173357, 5499.120140777456, 5557.305336832896, 5571.554275657938, 5577.2191893135, 5174.793008279665, 5272.077386457393, 6269.0, 6271.811845644638, 6272.059191121322, 6534.705938232743, 6754.763583274611, 6771.848785706442, 6781.627010514419, 6802.081718967926, 5475.6440831345835, 6562.93437065628, 6742.359475849787, 6794.252632210453, 6867.2102708188695, 5266.78932842682, 6261.7182226799905, 6334.746610135589, 6345.079963405326, 6349.0476428536085, 6969.721211151598, 6981.755638552728, 7000.159980802337, 7029.0, 7029.0862187915245, 5744.339400968942, 6055.879662262496, 6407.0, 6527.0, 6682.498812589075, 6459.644719540471, 6871.312868713188, 6872.896906546406, 6897.0, 6904.965475172692, 6620.106285651375, 6738.932610673831, 6755.088063111515, 6772.830679233059, 6793.626350550669, 6518.1200537927725, 6573.276939536647, 6609.933900661028, 6627.304133066055, 6665.466762658975, 4556.567126123013, 4713.552212539803, 5362.436944120889, 5363.168708850135, 5365.1147560652835, 4121.732087414309, 4776.47458779534, 4780.784864681098, 4791.377120974251, 4817.301491283744, 5920.319163296276, 5924.844655292177, 6098.786542470993, 6237.220347456568, 6353.110564520971, 5712.457316554921, 6112.351504965139, 6443.645599491979, 6901.965490172617, 6916.757913473004, 6369.299377068519, 6375.776847810304, 6410.647414392253, 6415.294317625057, 6428.935710642927, 5795.797147099831, 5910.143029260819, 6040.0, 6060.151578778975, 6073.726682299307, 6924.861502769916, 6985.825354366181, 7040.436765058886, 7059.0, 7088.645567721696, 6633.402993730588, 6878.518503704692, 6881.0, 6902.689378978059, 6917.93082069193, 6841.828954276183, 6860.725570977105, 6989.231184569795, 7073.752418665322, 7109.253528379652, 6779.220389655055, 6783.525153239226, 6786.287439818748, 6802.14973128359, 6815.216250131299, 6613.338666133348, 6704.698288577123, 6727.899081513683, 6739.292374300824, 6843.828904277234, 6573.868522629425, 6634.436072933855, 6660.167479065117, 6668.199816022108, 6697.196336439659, 5819.010768169506, 5894.911576326444, 6640.601563906181, 6645.534812563074, 6668.29982851813, 5314.5482633975, 5705.34388545306, 5815.836832633406, 6404.327545607644, 6502.934970650422, 6519.674016299071, 6583.0454584086, 6614.5039122067, 6628.90056649141, 6656.933430665825, 6415.358464153547, 6452.22573291208, 6458.677066146768, 6467.223933128054, 6483.189601299838], "value": 6094.7489994729995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.6307898366751, 821.1952286758977, 903.3676426501451, 1108.68402505286, 1121.2487633285698, 35083.824580877095, 35916.64083359166, 35929.82035089828, 36068.63931360686, 36176.45735313969, 37384.43923341152, 37642.0, 37739.867803965855, 37757.433638495444, 38054.048648783726, 7387.7783666490095, 8109.107998120202, 8236.588170591476, 8255.463394879343, 8308.875366869503, 8323.334133269354, 8398.328133749315, 8410.36922230832, 8411.369147313975, 8430.0, 7150.427965762752, 8340.3744719146, 8424.0, 8454.28138608219, 8471.49171049736, 7159.319864612853, 8400.999999999969, 8408.075111737704, 8411.327093832479, 8418.957905210435, 8213.425060245785, 8232.423730338878, 8290.792730181733, 8296.460730052528, 8326.833463330699, 36755.81622091899, 36818.0, 36838.631613683785, 36928.630713692786, 37212.62787372119, 3619.7828130312, 3662.249238906018, 3662.926741465182, 3663.5786884508357, 3665.0, 29481.410371792444, 29988.70011299881, 30117.548236776467, 30155.944541940928, 30177.396452070836, 3088.7992280501694, 3157.163351711798, 3160.920976975571, 3193.425183466975, 3197.392495425862, 244268.0, 252276.7386163076, 254394.99999999814, 274890.0, 281151.0, 32672.0, 38165.809170953966, 46373.536264637594, 47454.76272618615, 47489.28766068512, 35126.473102903474, 48872.02255954861, 48985.0, 48995.26507102396, 49042.05747655152, 14085.366158522891, 14197.36111874968, 14302.568563344048, 14318.0, 14331.14013159193, 11317.943410282896, 11557.711057223469, 11559.884401155881, 11574.131940104526, 11586.13104017202, 10829.404382758867, 13141.474341026444, 13269.0, 13270.668233294246, 13371.465141394236, 4997.525235102659, 5927.940720592739, 5947.791827286024, 5959.135925290809, 5993.550483713739, 895.4090300401704, 1002.2533212756397, 1039.2465462539599, 1064.2443864856039, 1089.809283375422, 23239.373243872767, 24278.393040174138, 24316.513669726508, 24398.512029759306, 24437.87781061119, 1037.2531777120462, 1067.5996501312009, 1145.9885401146048, 1147.1511081799574, 1190.6249531397582, 12164.81752773709, 12412.131150819356, 12467.688307792378, 16199.271032803552, 16319.428819991243, 288395.0, 292406.0, 326655.0, 327432.0, 328851.0, 54133.188002179995, 55121.44878551163, 55812.0, 55812.72093639506, 55857.603559911324, 1427.5075099090798, 1496.6034000989746, 1513.8561836625504, 1521.8249901261274, 1563.366836431247, 29169.854150729112, 29280.853595731885, 29733.749148225936, 29989.400211996075, 29992.700072998992, 36625.45061824128, 38015.429768554044, 38044.80977595094, 38130.809345953094, 38249.426258605585, 235944.8202758975, 285141.5742921272, 285449.0, 285725.0, 287224.0, 27711.72288277172, 28171.859140704164, 31124.844375777975, 32825.0, 33323.500147497325, 34791.13022174466, 40510.18979620332, 40995.0, 42678.78660606677, 42807.50173743904, 22831.885840570692, 28978.565321520615, 29812.850935745184, 29951.700482995762, 30318.0, 29716.0, 29919.102426927227, 29947.850260748557, 30126.288634022167, 30257.546136808407, 34089.46597403073, 35805.1048723784, 36066.639333607374, 36123.81938090398, 36186.276274473836, 29816.254593635334, 29864.552031719977, 30425.39149216959, 30560.235994100327, 30631.693683062884, 22987.195448159233, 23490.765092348858, 23844.0, 23995.64006539938, 24028.038878444666], "value": 17221.367493099984, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.4325542846016, 426.8207352911777, 431.02804668640977, 431.27330448194795, 450.06835849790934, 18262.908685456587, 18978.145983430742, 19282.807171928293, 20302.883341416215, 21795.455113622167, 22057.22799702011, 22237.0, 22984.965676544554, 23021.539569208606, 23088.884555577242, 5792.82621521355, 6042.607230530023, 6096.695165241742, 6102.969485152568, 6110.388961103877, 4184.748915065091, 5212.296339994109, 5242.9213561796605, 5595.8808238352285, 5933.317668468138, 5140.177571588547, 5176.352955880515, 5188.610854185932, 5207.895842083156, 5220.138677118271, 5154.793808247666, 5163.885578069134, 5195.116830138866, 5199.4800519948085, 5657.434256574351, 5317.149256119041, 5325.227841962933, 5327.121025030865, 5328.413874473805, 5358.892822143535, 27221.319467013283, 27796.0, 27834.721652783417, 27838.025669101484, 28022.299442513897, 2215.634420320645, 2255.8872056397277, 2259.3109101723967, 2259.45773014477, 2261.095561775303, 17867.73198402025, 18114.003729794855, 18378.63242735138, 18381.632367352577, 18462.723059154123, 1869.8317151456433, 2106.7893210678963, 2147.4094623978376, 2178.7712290209615, 2219.5449932763745, 171876.0, 174324.99999999872, 175030.0, 175067.0, 175824.3626345606, 10870.402127883042, 12780.808287875689, 12856.100072994988, 13009.414576344087, 13108.934455327662, 16595.0, 16619.085950272853, 16634.916825415796, 16657.83342166563, 16678.83321166797, 8883.955580222058, 9081.318901082444, 9089.727308180765, 9149.771255718528, 9232.584533696, 8522.78693032679, 8619.026435879612, 8626.223639872442, 8751.824963500821, 8776.736697899074, 6880.8967865482055, 7284.963575182091, 7633.931249625057, 7967.402444816662, 8078.263173417626, 2181.4219231903558, 2204.647256438962, 2204.908570257701, 2213.457702862809, 2385.725641551244, 978.8972157923458, 982.2878413150409, 983.4148681534475, 988.1008282462924, 1014.2849291249652, 19747.012649367472, 20154.093065812074, 20847.1661133556, 20894.47763805917, 20968.47578810542, 997.8602995580625, 1005.2259759984771, 1020.78563501664, 1029.341221618165, 1032.2309879139939, 10217.795644087077, 10515.158787296998, 10601.787964240671, 10627.14982801374, 10636.255462117577, 470956.64521677175, 521254.0, 533399.0, 536791.0, 548624.0, 113475.0, 132490.0, 132739.0, 133351.0, 136276.0, 1159.513004538096, 1166.080346379978, 1198.9640310790517, 1203.4043148641479, 1206.7888119579038, 16606.086665233044, 17796.3771268005, 17893.910530447785, 18001.63996720032, 18263.269469221083, 55237.72381138069, 55542.0, 55887.0, 55931.44068559262, 56251.156232655725, 58243.0, 72266.63866680634, 73746.0, 74299.88550171643, 74738.62630686811, 8550.273226775793, 8840.778980525281, 8957.10428957099, 8959.238464730572, 9050.592723327598, 13802.102863313852, 13986.650333741738, 14039.578812635636, 14239.0, 14300.427982880985, 10933.63329583802, 11214.55141794319, 11473.655790325969, 11594.594189203337, 11984.580539681418, 9644.324897257125, 9693.660721874701, 11145.54797944451, 11413.315201087962, 11484.19610627248, 11942.820857686967, 11955.103367247317, 12100.576479823163, 12245.938770306091, 12316.815247771112, 8879.778005549655, 8880.822383552422, 8917.73246802597, 9036.45781253127, 9051.954740226256, 11791.64167166553, 11996.460159292677, 12149.088818338836, 12189.268643881041, 12221.327826969602], "value": 10257.331636569195, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1185.3895243949366, 1391.867772561607, 1413.5405993052261, 1419.2194293138778, 1425.1164278147546, 9656.5171741413, 11417.657470275895, 11889.940550297248, 11922.582709605165, 12094.939525302361, 11703.18077734559, 11711.941440292809, 11884.0, 11888.7622247555, 11909.940450297758, 2163.3942496101117, 2498.925032249035, 2503.649489071527, 2531.227975467482, 2546.0834099724084, 2463.002483993985, 2492.588722860735, 2514.396544829246, 2518.874056297187, 2521.2688320387065, 2468.8642124683133, 2482.7889629381525, 2517.3203235126507, 2517.949641007179, 2520.6723125993594, 2452.399162205262, 2453.1904471524444, 2468.321211666797, 2481.2680259323542, 2494.7131079925857, 2181.1711549611205, 2420.3586049696846, 2471.2586224132688, 2483.4412257242157, 2496.051500429843, 11727.06183505326, 11773.764524709457, 11820.940895295469, 11855.762884742344, 11885.168038237327, 6525.608463492205, 6736.696848641774, 6740.258571557125, 6758.324167583251, 6777.322267773183, 3975.4221200919405, 4293.162833247518, 4359.04101097758, 4372.650187984954, 4386.71486353386, 1197.4012993503247, 1198.7542553776454, 1202.4949521201029, 1285.8585555588877, 1292.424870932426, 16264.024158550525, 16483.175841207893, 16734.330626775038, 16919.06945118018, 16969.151542422955, 5500.642458240201, 5546.500814926676, 5659.5755318351285, 5726.169705392695, 5744.080947048452, 11800.763984720257, 11838.644840654793, 11857.288562686266, 11876.465559049864, 11877.821832672516, 6540.771073012422, 6557.967210164014, 6563.901541476882, 6567.408933195939, 6583.44040756541, 6004.309504406961, 6059.697015149313, 6132.877342453126, 6259.467945224706, 6303.338149494237, 4294.398784170218, 4380.036391993756, 4423.1153769246275, 4429.136318417877, 4443.133588950188, 1262.1543565810746, 1271.6693659648558, 1286.7555164518712, 1295.1387327427155, 1295.8185853980451, 640.5708175522411, 752.27405553641, 802.6588699802561, 803.1165717710471, 816.4162623724065, 6142.508599312045, 6203.441690247809, 6253.093301471261, 6351.0, 6446.258680251831, 574.5690731951037, 577.9219805326227, 592.0734051209843, 595.1756816808751, 601.2904772368647, 4278.4010238566625, 4575.199340115467, 4639.489656137822, 4784.354112194878, 4818.8193892496565, 14691.191984440959, 15003.249837508081, 15191.848081519265, 15206.619834504227, 15240.933134680688, 14666.0, 14962.925185374004, 15041.623959400884, 15133.848661513464, 15255.61860953463, 8532.70135545253, 8569.84307118531, 8698.521581313089, 8766.430182038148, 8807.471551706918, 6030.638161710136, 6791.607720417402, 6863.313668633096, 7050.964745176241, 7178.748743793943, 13241.933790330986, 13571.524996625465, 13741.24423156736, 13751.106178098791, 13847.792283115963, 12318.568850090203, 12592.87407125917, 13374.197548147142, 14187.0, 14196.787048194492, 7604.657790399335, 7646.541207527566, 9401.764955876159, 9438.622455101719, 9551.331406801457, 10909.690837099599, 11005.064569511682, 11023.173262005543, 11042.0, 11049.834252486058, 8982.86525702128, 9223.86164207524, 9307.441553506811, 9308.394954327947, 9384.343095983215, 7752.806179845549, 9027.007029226648, 9043.276537877086, 9112.817743645222, 9248.537573121452, 8189.7133600323705, 8238.917610824055, 8344.958275208584, 8355.289800367036, 8389.622466988878, 6022.397760223943, 6082.574219804571, 6146.907796383147, 6196.380361963767, 6305.747770089145, 5994.250718660168, 6042.969785151046, 6079.358573185127, 6151.231096112986, 6330.588511746721], "value": 5322.540308655963, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.03599292105986, 366.34972923061565, 408.33033824527763, 411.6994593946419, 412.583290876215, 6260.84347891303, 6367.458766004889, 6446.194225721785, 6464.830184530974, 6503.772367967122, 5368.194770784385, 5613.129964855444, 6187.0, 6220.7822726204595, 6261.217347831521, 4474.910501789962, 4524.230880750264, 4533.886652833673, 4540.341650460681, 4554.65840061995, 4145.834166633331, 4474.82100715971, 4487.9551204488025, 4516.774161291939, 4528.25283828168, 4470.7317560946285, 4473.2171869922795, 4491.708038977472, 4509.526499717532, 4534.297183936478, 3697.6672099510906, 4442.977785111086, 4507.008458139204, 4507.301368287905, 4525.298578720303, 3764.416515440111, 3791.829367678461, 3833.5974722654278, 3843.596422375638, 3857.4599556062176, 4966.6771659842, 6065.4541091302, 6075.544334174911, 6086.417531441857, 6144.662043587601, 4876.585490233334, 5345.518903298683, 5358.892822143574, 5361.517463428271, 5435.565154787608, 5479.53423958964, 5515.007298686235, 5570.721463926788, 6392.264889537716, 6570.470368907957, 4338.132373525276, 4361.80371883266, 4375.7593332366705, 4382.868513944587, 4390.868273951786, 5647.792394666, 7108.0, 7165.247648996832, 7179.425645948364, 7218.927810721878, 3872.845086196577, 4412.808541693789, 4425.712328698624, 4452.686457495047, 4455.77721113943, 4051.817668204938, 4251.553586873396, 4516.480604730433, 4528.41130653013, 4533.5013148553635, 5219.660722053054, 5348.358197016305, 5374.247605335257, 5384.9730751346, 5435.266239057677, 5574.58190635704, 6734.696938637773, 6747.291534388916, 6760.898586521206, 6803.217629972616, 1318.881300682924, 1501.3769285746512, 1525.4737115695068, 1532.8044125582064, 1543.8919275650596, 989.6536212325773, 991.9504024798875, 992.6873034993954, 1001.8528784541804, 1004.5835372125268, 5937.673427961504, 6396.872062558723, 6436.774712885121, 6436.8390790230615, 6467.223933128054, 6780.288069752703, 6834.897476537856, 7022.683979220948, 7060.364567188875, 7084.964575177091, 6357.300696923335, 6419.358064193543, 6458.2895881453005, 6458.644774537445, 6469.350315669568, 3361.983190084034, 3544.7164226862305, 3669.954063092059, 3680.9263814723554, 3685.686716629063, 7259.745908893269, 7267.239957827632, 7347.669354879044, 7358.374538164209, 7359.963200183965, 6160.028790786995, 6337.0, 6421.8073457796345, 7216.9278307216255, 7229.674664640103, 7238.420926325882, 7299.635018249066, 7319.560826350436, 7337.633118344062, 7361.337479626859, 6174.475169610633, 6484.351564843478, 6848.253695307664, 7099.290070992859, 7122.7507037253445, 5818.6217895836635, 6780.593164410151, 6831.5653712718895, 6886.724531018703, 6949.895751563629, 7266.6729997151315, 7367.815804604714, 7369.0, 7370.262973702587, 7379.151397589131, 3535.469679548088, 3770.377887648617, 4464.843730469418, 4482.170798402306, 4541.500434952219, 6054.848628784315, 7042.859142817012, 7044.964775176091, 7118.323759242864, 7144.821379465555, 5641.802536911188, 6838.760643377458, 6861.181786826395, 6863.794086177422, 6938.895916561357, 5500.359827282847, 6724.0, 6827.0, 6840.281770414035, 6846.965765171142, 5570.161846590697, 5610.551155907599, 6560.475161986934, 6679.76620818269, 6795.490338224752, 5784.913226301693, 6163.722632481459, 6765.391114799691, 6805.9659701701175, 6814.182298124257, 6627.900581491185, 6634.800955971328, 6639.601623902581, 6642.0, 6708.932910670831], "value": 4845.051908344608, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.4344854462064, 763.6487215880694, 826.8801023851538, 867.7266661001784, 874.2481465939292, 16466.423675171372, 16909.23908424121, 16954.406595769135, 18284.268629254846, 19412.514687132792, 17902.910485447588, 18243.817561824377, 18443.53891152722, 18539.0, 18785.81214187861, 959.3236768078488, 986.9259805514579, 993.4585650820284, 996.2627655534889, 1001.2990906365574, 844.6832437835811, 885.4156256870467, 989.2778271861546, 1003.8042581696573, 1009.1976878381704, 1020.6580795433501, 1032.8244198486238, 1035.9119474844647, 1047.6385646951828, 1049.6903413493037, 1025.9897401026005, 1038.7351225437503, 1039.786843697044, 1041.2815157541272, 1043.394830998027, 759.3014426727369, 766.4290103872595, 774.2721841469001, 775.3215936055951, 776.3905334312537, 16337.673246535122, 16666.0, 16873.915630421892, 17279.481615551555, 17570.0, 16645.00129992204, 16748.916255418768, 17005.404810831562, 17104.65790684192, 17205.139743012798, 11961.64115076549, 13089.934550327187, 13714.24571648559, 13812.585622431343, 13870.722585548334, 3496.615372309044, 3509.438489841639, 3521.2781379817075, 3525.065857547752, 3528.065062758371, 78942.0, 113766.0, 113999.29001064907, 115629.0, 117560.24397560261, 13065.804012939814, 13433.798493022612, 13453.923686105089, 13459.93270033663, 13462.0, 23891.522169556512, 25365.492690146464, 25536.616950745753, 25891.611625825626, 26364.20907372782, 6717.932820671828, 7888.290053895177, 7932.0, 7961.641726122339, 7962.402819788424, 3372.8313584320686, 3872.167483990899, 3893.980530097331, 3946.9802650987135, 3957.0700885291935, 4152.2525945330135, 4257.787110644455, 4373.759443230653, 4419.46966364032, 4447.466304043471, 907.2288554728428, 939.8496240601471, 945.3807755919884, 945.3902233059775, 950.092661508258, 528.836060821139, 649.5290914087249, 672.176583684982, 673.6261374936859, 683.5010442377093, 9270.629174832931, 10313.535890884928, 10494.370337779757, 11191.72020699489, 12163.574274900337, 647.9319671434525, 648.8021153548119, 652.6280020388333, 663.9900401493982, 671.7380221713536, 5367.731613419313, 5403.5136837684795, 5576.553875690016, 5588.580856435784, 5644.322681278273, 86182.0, 92184.0, 98501.0, 104846.90306193834, 113688.0, 79339.20660793319, 90440.09559904448, 96844.0, 97698.511507442, 98830.0, 87390.12609873946, 87538.0, 87958.56020719983, 88158.55920720355, 88614.55692721494, 9356.830396200476, 10633.574657013633, 11245.887541124484, 11325.037371823488, 11558.248713833575, 36240.55037798458, 42263.7886810564, 42533.36199956941, 44087.33868992032, 44739.32891006573, 46831.0, 46859.9251161002, 56950.0, 57613.71193144175, 58374.708126459096, 14005.929970350084, 14148.080374776018, 14391.352389142723, 14474.0, 14521.92739036298, 15481.458148964732, 15508.84491155074, 15577.0, 15702.136382498615, 15894.284757185724, 14284.214368209432, 14663.92668036653, 14773.335199915815, 14813.0, 14824.332905019084, 14212.289385530888, 14379.352929118424, 14540.200288984208, 14754.778678320048, 14760.409583616534, 11994.160408771302, 14506.927465362605, 16063.437779677655, 17023.659526809643, 17333.220005100055, 11452.3701196435, 11527.596534121265, 11570.421478926188, 11767.705807354885, 11791.29252244868, 13113.475460981836, 13148.40832162574, 14068.08557443763, 14173.362198700877, 14222.928885355506], "value": 8168.080642063508, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 106.0, 149.0, 172.0, 171.0, 171.0, 146.0, 115.0, 116.0, 111.0], "value": 135.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 110.0, 146.0, 113.0, 112.0, 112.0, 114.0, 142.0, 139.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 246.0, 276.0, 272.0, 286.0, 196.0, 200.0, 196.0, 197.0], "value": 226.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 60.0, 63.0, 75.0, 58.0, 59.0, 58.0, 58.0, 76.0, 57.0], "value": 64.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 84.0, 72.0, 68.0, 69.0, 86.0, 69.0, 70.0, 92.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 91.0, 90.0, 91.0, 97.0, 91.0, 90.0, 90.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 110.0, 97.0, 97.0, 98.0, 97.0, 98.0, 95.0, 98.0, 96.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 103.0, 111.0, 146.0, 91.0, 90.0, 93.0, 91.0, 92.0, 92.0], "value": 99.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 51.0, 51.0, 51.0, 50.0, 51.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 57.0, 60.0, 73.0, 62.0, 60.0, 57.0, 56.0, 59.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 92.0, 84.0, 82.0, 76.0, 105.0, 106.0, 76.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 157.0, 141.0, 134.0, 139.0, 147.0, 142.0, 133.0, 181.0, 141.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 182.0, 183.0, 184.0, 187.0, 184.0, 188.0, 187.0, 186.0, 185.0], "value": 190.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 67.0, 70.0, 66.0, 67.0, 70.0, 68.0, 71.0], "value": 68.4, "unit": "score"}]}]} 17:34:25 INFO - Return code: 0 17:34:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:34:25 ERROR - # TBPL SUCCESS # 17:34:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:34:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:34:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:34:25 INFO - 2017-05-02 17:34:25.368 screenresolution[1658:10581] starting screenresolution argv=screenresolution get 17:34:25 INFO - 2017-05-02 17:34:25.372 screenresolution[1658:10581] Display 0: 1600x1200x32@60 17:34:25 INFO - 2017-05-02 17:34:25.378 screenresolution[1659:10584] starting screenresolution argv=screenresolution list 17:34:25 INFO - Available Modes on Display 0 17:34:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:34:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:34:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:34:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:34:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:34:25 INFO - Intel Iris: 17:34:25 INFO - Chipset Model: Intel Iris 17:34:25 INFO - Type: GPU 17:34:25 INFO - Bus: Built-In 17:34:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:34:25 INFO - Vendor: Intel (0x8086) 17:34:25 INFO - Device ID: 0x0a2e 17:34:25 INFO - Revision ID: 0x0009 17:34:25 INFO - Displays: 17:34:25 INFO - Display: 17:34:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:34:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:34:25 INFO - Display Serial Number: 1600x1200 60 17:34:25 INFO - Main Display: Yes 17:34:25 INFO - Mirror: Off 17:34:25 INFO - Online: Yes 17:34:25 INFO - Rotation: Supported 17:34:25 INFO - Return code: 0 17:34:25 INFO - Running post-action listener: _resource_record_post_action 17:34:25 INFO - [mozharness: 2017-05-03 00:34:25.627680Z] Finished run-tests step (success) 17:34:25 INFO - Running post-run listener: _resource_record_post_run 17:34:25 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40389632; Write bytes: 513130496; Read time: 392; Write time: 1743 17:34:25 INFO - TinderboxPrint: CPU usage
54.1% 17:34:25 INFO - TinderboxPrint: I/O read bytes / time
40,389,632 / 392 17:34:25 INFO - TinderboxPrint: I/O write bytes / time
513,130,496 / 1,743 17:34:25 INFO - TinderboxPrint: CPU idle
2,196.5 (45.9%) 17:34:25 INFO - TinderboxPrint: CPU system
965.7 (20.2%) 17:34:25 INFO - TinderboxPrint: CPU user
1,623.5 (33.9%) 17:34:25 INFO - TinderboxPrint: Swap in / out
248,737,792 / 0 17:34:25 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208138752; Write bytes: 205977600; Read time: 16080; Write time: 434 17:34:25 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35429376; Write bytes: 299915264; Read time: 358; Write time: 1279 17:34:26 INFO - Running post-run listener: _upload_blobber_files 17:34:26 INFO - Blob upload gear active. 17:34:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:34:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:34:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:34:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:34:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:34:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:34:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:27 INFO - (blobuploader) - INFO - Done attempting. 17:34:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:34:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:28 INFO - (blobuploader) - INFO - Done attempting. 17:34:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:34:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:34:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:29 INFO - (blobuploader) - INFO - Done attempting. 17:34:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:34:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:32 INFO - (blobuploader) - INFO - Done attempting. 17:34:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:34:32 INFO - Return code: 0 17:34:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:34:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:34:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6c6ce23f7af8f75917ff572ac6ec9278284389686553a669f09eba79d744f1654fd6d9e7cb0c7112cd9276d056b7abadf2a63b39227eddcf1a6fe91c6feb8b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69fb8fe1c5b431e83c69bb79532d82ed0ce1f68e9c343279ee209b7c31e7932eb9edad3efd80b81e1f026e03009fd979244937eb4d8f5484568a17639e535314", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a685d7bda355b51f039987a430acfeb15d25b971be268476b88f2c92e6b72ce91d3493eafa4badc25852cbd950bbf2702057e3172e71c79e84708b370877ff9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/99f077d59e593c43545220f31fd1fcde696bc234a0a743862f21b7029d4606d5cf16ffd635d9c419670e2ca2baf6c106be1ab555666e00964f988fa90c672656"} 17:34:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:34:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:34:32 INFO - Contents: 17:34:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6c6ce23f7af8f75917ff572ac6ec9278284389686553a669f09eba79d744f1654fd6d9e7cb0c7112cd9276d056b7abadf2a63b39227eddcf1a6fe91c6feb8b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69fb8fe1c5b431e83c69bb79532d82ed0ce1f68e9c343279ee209b7c31e7932eb9edad3efd80b81e1f026e03009fd979244937eb4d8f5484568a17639e535314", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a685d7bda355b51f039987a430acfeb15d25b971be268476b88f2c92e6b72ce91d3493eafa4badc25852cbd950bbf2702057e3172e71c79e84708b370877ff9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/99f077d59e593c43545220f31fd1fcde696bc234a0a743862f21b7029d4606d5cf16ffd635d9c419670e2ca2baf6c106be1ab555666e00964f988fa90c672656"} 17:34:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:34:32 INFO - Copying logs to upload dir... 17:34:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:34:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.643489 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-05-02 17:34:32.446559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 17:34:32.450514) ========= 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.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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/6c6ce23f7af8f75917ff572ac6ec9278284389686553a669f09eba79d744f1654fd6d9e7cb0c7112cd9276d056b7abadf2a63b39227eddcf1a6fe91c6feb8b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69fb8fe1c5b431e83c69bb79532d82ed0ce1f68e9c343279ee209b7c31e7932eb9edad3efd80b81e1f026e03009fd979244937eb4d8f5484568a17639e535314", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a685d7bda355b51f039987a430acfeb15d25b971be268476b88f2c92e6b72ce91d3493eafa4badc25852cbd950bbf2702057e3172e71c79e84708b370877ff9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/99f077d59e593c43545220f31fd1fcde696bc234a0a743862f21b7029d4606d5cf16ffd635d9c419670e2ca2baf6c106be1ab555666e00964f988fa90c672656"} build_url:https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/H_MqJF_-S2qDsU56c441wg/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/6c6ce23f7af8f75917ff572ac6ec9278284389686553a669f09eba79d744f1654fd6d9e7cb0c7112cd9276d056b7abadf2a63b39227eddcf1a6fe91c6feb8b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69fb8fe1c5b431e83c69bb79532d82ed0ce1f68e9c343279ee209b7c31e7932eb9edad3efd80b81e1f026e03009fd979244937eb4d8f5484568a17639e535314", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a685d7bda355b51f039987a430acfeb15d25b971be268476b88f2c92e6b72ce91d3493eafa4badc25852cbd950bbf2702057e3172e71c79e84708b370877ff9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/99f077d59e593c43545220f31fd1fcde696bc234a0a743862f21b7029d4606d5cf16ffd635d9c419670e2ca2baf6c106be1ab555666e00964f988fa90c672656"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 17:34:32.485325) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:34:32.485650) ========= 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.MNR6tWhHV6/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.s0kifMeBWE/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 17:34:32.533910) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 17:34:32.534282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 17:34:32.534785) ========= ========= Total master_lag: 0.19 =========