builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0349 starttime: 1488464910.76 results: success (0) buildid: 20170302050322 builduid: 0987379bc2814ae0af968f2a3b71164c revision: 66535e831760421b270662aa8d0773b0fde7c9f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:30.755947) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:30.756415) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:30.756697) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:30.783882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:30.784312) ========= 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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-02 06:28:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.0M=0s 2017-03-02 06:28:31 (54.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.312358 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:31.122631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:31.122970) ========= 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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.032856 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:31.181175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:28:31.181582) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66535e831760421b270662aa8d0773b0fde7c9f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 66535e831760421b270662aa8d0773b0fde7c9f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-02 06:28:31,290 truncating revision to first 12 chars 2017-03-02 06:28:31,290 Setting DEBUG logging. 2017-03-02 06:28:31,290 attempt 1/10 2017-03-02 06:28:31,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66535e831760?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-02 06:28:31,985 unpacking tar archive at: mozilla-central-66535e831760/testing/mozharness/ program finished with exit code 0 elapsedTime=1.712865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-02 06:28:32.918543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:32.918976) ========= script_repo_revision: 66535e831760421b270662aa8d0773b0fde7c9f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:32.919392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:32.919664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 06:28:32.940007) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-02 06:28:32.940464) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:28:33 INFO - MultiFileLogger online at 20170302 06:28:33 in /builds/slave/test 06:28:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:28:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:28:33 INFO - {'append_to_log': False, 06:28:33 INFO - 'base_work_dir': '/builds/slave/test', 06:28:33 INFO - 'blob_upload_branch': 'Firefox', 06:28:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:28:33 INFO - 'branch': 'Firefox', 06:28:33 INFO - 'buildbot_json_path': 'buildprops.json', 06:28:33 INFO - 'config_files': ('talos/mac_config.py',), 06:28:33 INFO - 'default_actions': ('clobber', 06:28:33 INFO - 'read-buildbot-config', 06:28:33 INFO - 'download-and-extract', 06:28:33 INFO - 'populate-webroot', 06:28:33 INFO - 'create-virtualenv', 06:28:33 INFO - 'install', 06:28:33 INFO - 'run-tests'), 06:28:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:28:33 INFO - 'download_minidump_stackwalk': True, 06:28:33 INFO - 'download_symbols': 'ondemand', 06:28:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:28:33 INFO - 'tooltool.py': '/tools/tooltool.py', 06:28:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:28:33 INFO - '/tools/misc-python/virtualenv.py')}, 06:28:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:28:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:28:33 INFO - 'gecko_profile': False, 06:28:33 INFO - 'gecko_profile_interval': 0, 06:28:33 INFO - 'installer_path': 'installer.exe', 06:28:33 INFO - 'log_level': 'info', 06:28:33 INFO - 'log_name': 'talos', 06:28:33 INFO - 'log_to_console': True, 06:28:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:28:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:28:33 INFO - 'opt_config_files': (), 06:28:33 INFO - 'pip_index': False, 06:28:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:28:33 INFO - 'cmd': ('bash', 06:28:33 INFO - '-c', 06:28:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:28:33 INFO - 'enabled': True, 06:28:33 INFO - 'halt_on_failure': False, 06:28:33 INFO - 'name': 'check_screen_resolution'},), 06:28:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:28:33 INFO - 'cmd': ('bash', 06:28:33 INFO - '-c', 06:28:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:28:33 INFO - 'enabled': True, 06:28:33 INFO - 'halt_on_failure': False, 06:28:33 INFO - 'name': 'check_screen_resolution'},), 06:28:33 INFO - 'run_cmd_checks_enabled': True, 06:28:33 INFO - 'suite': 'dromaeojs-e10s', 06:28:33 INFO - 'system_bits': '32', 06:28:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:28:33 INFO - 'title': 't-yosemite-r7-0349', 06:28:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:28:33 INFO - 'use_talos_json': True, 06:28:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:28:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:28:33 INFO - 'work_dir': 'build'} 06:28:33 INFO - [mozharness: 2017-03-02 14:28:33.149878Z] Running clobber step. 06:28:33 INFO - Running pre-action listener: _resource_record_pre_action 06:28:33 INFO - Running main action method: clobber 06:28:33 INFO - rmtree: /builds/slave/test/build 06:28:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:28:35 INFO - Running post-action listener: _resource_record_post_action 06:28:35 INFO - [mozharness: 2017-03-02 14:28:35.579858Z] Finished clobber step (success) 06:28:35 INFO - [mozharness: 2017-03-02 14:28:35.579989Z] Running read-buildbot-config step. 06:28:35 INFO - Running pre-action listener: _resource_record_pre_action 06:28:35 INFO - Running main action method: read_buildbot_config 06:28:35 INFO - Using buildbot properties: 06:28:35 INFO - { 06:28:35 INFO - "project": "", 06:28:35 INFO - "product": "firefox", 06:28:35 INFO - "script_repo_revision": "production", 06:28:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:28:35 INFO - "repository": "", 06:28:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:28:35 INFO - "buildid": "20170302050322", 06:28:35 INFO - "pgo_build": "False", 06:28:35 INFO - "basedir": "/builds/slave/test", 06:28:35 INFO - "buildnumber": 69, 06:28:35 INFO - "slavename": "t-yosemite-r7-0349", 06:28:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:28:35 INFO - "platform": "yosemite_r7", 06:28:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:28:35 INFO - "branch": "mozilla-central", 06:28:35 INFO - "repo_path": "mozilla-central", 06:28:35 INFO - "revision": "66535e831760421b270662aa8d0773b0fde7c9f3", 06:28:35 INFO - "stage_platform": "macosx64", 06:28:35 INFO - "builduid": "0987379bc2814ae0af968f2a3b71164c", 06:28:35 INFO - "slavebuilddir": "test" 06:28:35 INFO - } 06:28:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:28:35 INFO - Running post-action listener: _resource_record_post_action 06:28:35 INFO - [mozharness: 2017-03-02 14:28:35.583287Z] Finished read-buildbot-config step (success) 06:28:35 INFO - [mozharness: 2017-03-02 14:28:35.583397Z] Running download-and-extract step. 06:28:35 INFO - Running pre-action listener: _resource_record_pre_action 06:28:35 INFO - Running main action method: download_and_extract 06:28:35 INFO - mkdir: /builds/slave/test/build/tests 06:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:35 INFO - trying https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:28:35 INFO - Downloading https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:28:36 INFO - Downloaded 1199 bytes. 06:28:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:28:36 INFO - Using the following test package requirements: 06:28:36 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:28:36 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:28:36 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:28:36 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'jsshell-mac.zip'], 06:28:36 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:28:36 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:28:36 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:28:36 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:28:36 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:28:36 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:28:36 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:28:36 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:28:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:28:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:28:36 INFO - Fetch https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:28:38 INFO - Expected file size: 51768375 06:28:38 INFO - Obtained file size: 51768375 06:28:54 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:28:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:28:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:28:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:28:55 INFO - Expected file size: 51768375 06:28:55 INFO - Obtained file size: 51768375 06:29:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:29:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:29:10 INFO - Fetch https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:29:12 INFO - Expected file size: 14404424 06:29:12 INFO - Obtained file size: 14404424 06:29: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')]} 06:29:12 INFO - trying https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:29:14 INFO - Downloaded 75025757 bytes. 06:29:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:29:14 INFO - mkdir: /builds/slave/test/properties 06:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:29:14 INFO - Writing to file /builds/slave/test/properties/build_url 06:29:14 INFO - Contents: 06:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:29:15 INFO - Running post-action listener: _resource_record_post_action 06:29:15 INFO - Running post-action listener: set_extra_try_arguments 06:29:15 INFO - [mozharness: 2017-03-02 14:29:15.307538Z] Finished download-and-extract step (success) 06:29:15 INFO - [mozharness: 2017-03-02 14:29:15.307671Z] Running populate-webroot step. 06:29:15 INFO - Running pre-action listener: _resource_record_pre_action 06:29:15 INFO - Running main action method: populate_webroot 06:29:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:29:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:29:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:29:15 INFO - '--tppagecycles', 06:29:15 INFO - '7'], 06:29:15 INFO - 'tests': ['tsvgm']}}, 06:29:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:29:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:29:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:29:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:29:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:29:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:29:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['damp', 'tps']}, 06:29:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'tests': ['damp', 'tps']}, 06:29:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['dromaeo_dom']}, 06:29:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:29:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['basic_compositor_video']}, 06:29:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:29:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'other-e10s': {'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['a11yr', 06:29:15 INFO - 'ts_paint', 06:29:15 INFO - 'tpaint', 06:29:15 INFO - 'sessionrestore', 06:29:15 INFO - 'sessionrestore_no_auto_restore', 06:29:15 INFO - 'tabpaint']}, 06:29:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['tsvgx', 06:29:15 INFO - 'tsvgr_opacity', 06:29:15 INFO - 'tart', 06:29:15 INFO - 'tscrollx', 06:29:15 INFO - 'cart', 06:29:15 INFO - 'tsvg_static']}, 06:29:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:29:15 INFO - 'tsvgr_opacity', 06:29:15 INFO - 'tart', 06:29:15 INFO - 'tscrollx', 06:29:15 INFO - 'cart', 06:29:15 INFO - 'tsvg_static']}, 06:29:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'talos_options': ['--disable-e10s'], 06:29:15 INFO - 'tests': ['tp5o']}, 06:29:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:29:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'talos_options': ['--disable-e10s', 06:29:15 INFO - '--xperf_path', 06:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:29:15 INFO - 'tests': ['tp5n']}, 06:29:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:29:15 INFO - 'talos_options': ['--xperf_path', 06:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:29:15 INFO - 'tests': ['tp5n']}}, 06:29:15 INFO - 'talos.zip': {'path': '', 06:29:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:29:15 INFO - Running post-action listener: _resource_record_post_action 06:29:15 INFO - [mozharness: 2017-03-02 14:29:15.326301Z] Finished populate-webroot step (success) 06:29:15 INFO - [mozharness: 2017-03-02 14:29:15.326393Z] Running create-virtualenv step. 06:29:15 INFO - Running pre-action listener: _resource_record_pre_action 06:29:15 INFO - Running main action method: create_virtualenv 06:29:15 INFO - Creating virtualenv /builds/slave/test/build/venv 06:29:15 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 06:29:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:29:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:29:15 INFO - Using real prefix '/tools/python27' 06:29:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:29:16 INFO - Installing distribute.............................................................................................................................................................................................done. 06:29:18 INFO - Installing pip.................done. 06:29:18 INFO - Return code: 0 06:29:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:29:19 INFO - Reading from file tmpfile_stdout 06:29:19 INFO - Output received: 06:29:19 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) 06:29:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:29:19 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')]} 06:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:19 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')]} 06:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:19 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:19 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 06:29:19 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 06:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:19 INFO - 'HOME': '/Users/cltbld', 06:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:19 INFO - 'LOGNAME': 'cltbld', 06:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:19 INFO - 'MOZ_NO_REMOTE': '1', 06:29:19 INFO - 'NO_EM_RESTART': '1', 06:29:19 INFO - 'PAGER': '/bin/cat', 06:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:19 INFO - 'PWD': '/builds/slave/test', 06:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:19 INFO - 'SHELL': '/bin/bash', 06:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:19 INFO - 'USER': 'cltbld', 06:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:19 INFO - 'XPC_FLAGS': '0x0', 06:29:19 INFO - 'XPC_SERVICE_NAME': '0', 06:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:19 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 06:29:19 INFO - Cleaning up... 06:29:19 INFO - Return code: 0 06:29:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:29:19 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')]} 06:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:19 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')]} 06:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:19 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:19 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 06:29:19 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 06:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:19 INFO - 'HOME': '/Users/cltbld', 06:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:19 INFO - 'LOGNAME': 'cltbld', 06:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:19 INFO - 'MOZ_NO_REMOTE': '1', 06:29:19 INFO - 'NO_EM_RESTART': '1', 06:29:19 INFO - 'PAGER': '/bin/cat', 06:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:19 INFO - 'PWD': '/builds/slave/test', 06:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:19 INFO - 'SHELL': '/bin/bash', 06:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:19 INFO - 'USER': 'cltbld', 06:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:19 INFO - 'XPC_FLAGS': '0x0', 06:29:19 INFO - 'XPC_SERVICE_NAME': '0', 06:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:19 INFO - Downloading/unpacking psutil>=3.1.1 06:29:19 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 06:29:19 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 06:29:19 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 06:29:19 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 06:29:19 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 06:29:19 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 06:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:29:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:29:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:29:23 INFO - Installing collected packages: psutil 06:29:23 INFO - Running setup.py install for psutil 06:29:23 INFO - building 'psutil._psutil_osx' extension 06:29:23 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 06:29:23 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 06:29:23 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 06:29:23 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 06:29:23 INFO - building 'psutil._psutil_posix' extension 06:29:23 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 06:29:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:29:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:29:24 INFO - ^ 06:29:24 INFO - 1 warning generated. 06:29:24 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 06:29:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:29:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:29:24 INFO - Successfully installed psutil 06:29:24 INFO - Cleaning up... 06:29:24 INFO - Return code: 0 06:29:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:24 INFO - 'HOME': '/Users/cltbld', 06:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:24 INFO - 'LOGNAME': 'cltbld', 06:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:24 INFO - 'MOZ_NO_REMOTE': '1', 06:29:24 INFO - 'NO_EM_RESTART': '1', 06:29:24 INFO - 'PAGER': '/bin/cat', 06:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:24 INFO - 'PWD': '/builds/slave/test', 06:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:24 INFO - 'SHELL': '/bin/bash', 06:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:24 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:24 INFO - 'USER': 'cltbld', 06:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:24 INFO - 'XPC_FLAGS': '0x0', 06:29:24 INFO - 'XPC_SERVICE_NAME': '0', 06:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:29: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 06:29: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 06:29: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 06:29: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 06:29: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 06:29: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 06:29:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:29:27 INFO - Installing collected packages: mozsystemmonitor 06:29:27 INFO - Running setup.py install for mozsystemmonitor 06:29:27 INFO - Successfully installed mozsystemmonitor 06:29:27 INFO - Cleaning up... 06:29:27 INFO - Return code: 0 06:29:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29: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==2.5.1'] in /builds/slave/test/build 06:29: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==2.5.1 06:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:28 INFO - 'HOME': '/Users/cltbld', 06:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:28 INFO - 'LOGNAME': 'cltbld', 06:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:28 INFO - 'MOZ_NO_REMOTE': '1', 06:29:28 INFO - 'NO_EM_RESTART': '1', 06:29:28 INFO - 'PAGER': '/bin/cat', 06:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:28 INFO - 'PWD': '/builds/slave/test', 06:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:28 INFO - 'SHELL': '/bin/bash', 06:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:28 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:28 INFO - 'USER': 'cltbld', 06:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:28 INFO - 'XPC_FLAGS': '0x0', 06:29:28 INFO - 'XPC_SERVICE_NAME': '0', 06:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:28 INFO - Downloading/unpacking jsonschema==2.5.1 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:29:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:31 INFO - Downloading functools32-3.2.3-2.tar.gz 06:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:29:31 INFO - warning: no files found matching '*.txt' 06:29:31 INFO - no previously-included directories found matching 'build' 06:29:31 INFO - no previously-included directories found matching 'dist' 06:29:31 INFO - no previously-included directories found matching '.git*' 06:29:32 INFO - Installing collected packages: jsonschema, functools32 06:29:32 INFO - Running setup.py install for functools32 06:29:32 INFO - warning: no files found matching '*.txt' 06:29:32 INFO - no previously-included directories found matching 'build' 06:29:32 INFO - no previously-included directories found matching 'dist' 06:29:32 INFO - no previously-included directories found matching '.git*' 06:29:32 INFO - Successfully installed jsonschema functools32 06:29:32 INFO - Cleaning up... 06:29:32 INFO - Return code: 0 06:29:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:29:32 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')]} 06:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:32 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')]} 06:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:32 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:32 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 06:29:32 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 06:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:32 INFO - 'HOME': '/Users/cltbld', 06:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:32 INFO - 'LOGNAME': 'cltbld', 06:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:32 INFO - 'MOZ_NO_REMOTE': '1', 06:29:32 INFO - 'NO_EM_RESTART': '1', 06:29:32 INFO - 'PAGER': '/bin/cat', 06:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:32 INFO - 'PWD': '/builds/slave/test', 06:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:32 INFO - 'SHELL': '/bin/bash', 06:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:32 INFO - 'USER': 'cltbld', 06:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:32 INFO - 'XPC_FLAGS': '0x0', 06:29:32 INFO - 'XPC_SERVICE_NAME': '0', 06:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:29:32 INFO - Cleaning up... 06:29:32 INFO - Return code: 0 06:29:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:29:32 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')]} 06:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:32 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')]} 06:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:32 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:32 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 06:29:32 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 06:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:32 INFO - 'HOME': '/Users/cltbld', 06:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:32 INFO - 'LOGNAME': 'cltbld', 06:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:32 INFO - 'MOZ_NO_REMOTE': '1', 06:29:32 INFO - 'NO_EM_RESTART': '1', 06:29:32 INFO - 'PAGER': '/bin/cat', 06:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:32 INFO - 'PWD': '/builds/slave/test', 06:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:32 INFO - 'SHELL': '/bin/bash', 06:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:32 INFO - 'USER': 'cltbld', 06:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:32 INFO - 'XPC_FLAGS': '0x0', 06:29:32 INFO - 'XPC_SERVICE_NAME': '0', 06:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:32 INFO - Downloading/unpacking blobuploader==1.2.4 06:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:35 INFO - Downloading blobuploader-1.2.4.tar.gz 06:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:29:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:29:35 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 06:29:35 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 06:29:35 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 06:29:35 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 06:29:35 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 06:29:35 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 06:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:29:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:36 INFO - Downloading docopt-0.6.1.tar.gz 06:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:29:36 INFO - Installing collected packages: blobuploader, requests, docopt 06:29:36 INFO - Running setup.py install for blobuploader 06:29:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:29:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:29:36 INFO - Running setup.py install for requests 06:29:37 INFO - Running setup.py install for docopt 06:29:37 INFO - Successfully installed blobuploader requests docopt 06:29:37 INFO - Cleaning up... 06:29:37 INFO - Return code: 0 06:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:37 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:37 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 06:29:37 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 06:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:37 INFO - 'HOME': '/Users/cltbld', 06:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:37 INFO - 'LOGNAME': 'cltbld', 06:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:37 INFO - 'MOZ_NO_REMOTE': '1', 06:29:37 INFO - 'NO_EM_RESTART': '1', 06:29:37 INFO - 'PAGER': '/bin/cat', 06:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:37 INFO - 'PWD': '/builds/slave/test', 06:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:37 INFO - 'SHELL': '/bin/bash', 06:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:37 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:37 INFO - 'USER': 'cltbld', 06:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:37 INFO - 'XPC_FLAGS': '0x0', 06:29:37 INFO - 'XPC_SERVICE_NAME': '0', 06:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:29:37 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-2Ypvr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:29:37 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-by4fhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1hFZvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-XU3zZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-wL73St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-R4hOxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VtQaSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-FY8dvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4OcL1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Wh5u9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-biQkFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:29:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-od6Mdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:29:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-2nR6kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:29:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-2mAvPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-WXM36L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:29:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-O6rrXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:29:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Iq5l22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:29:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:29:39 INFO - Running setup.py install for manifestparser 06:29:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozcrash 06:29:39 INFO - Running setup.py install for mozdebug 06:29:40 INFO - Running setup.py install for mozdevice 06:29:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozfile 06:29:40 INFO - Running setup.py install for mozhttpd 06:29:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozinfo 06:29:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozInstall 06:29:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozleak 06:29:40 INFO - Running setup.py install for mozlog 06:29:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Running setup.py install for moznetwork 06:29:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Running setup.py install for mozprocess 06:29:41 INFO - Running setup.py install for mozprofile 06:29:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Running setup.py install for mozrunner 06:29:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:29:41 INFO - Running setup.py install for mozscreenshot 06:29:41 INFO - Running setup.py install for moztest 06:29:42 INFO - Running setup.py install for mozversion 06:29:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:29:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:29:42 INFO - Cleaning up... 06:29:42 INFO - Return code: 0 06:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29:42 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')]} 06:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:42 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')]} 06:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:42 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:42 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 06:29:42 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 06:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:42 INFO - 'HOME': '/Users/cltbld', 06:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:42 INFO - 'LOGNAME': 'cltbld', 06:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:42 INFO - 'MOZ_NO_REMOTE': '1', 06:29:42 INFO - 'NO_EM_RESTART': '1', 06:29:42 INFO - 'PAGER': '/bin/cat', 06:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:42 INFO - 'PWD': '/builds/slave/test', 06:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:42 INFO - 'SHELL': '/bin/bash', 06:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:42 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:42 INFO - 'USER': 'cltbld', 06:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:42 INFO - 'XPC_FLAGS': '0x0', 06:29:42 INFO - 'XPC_SERVICE_NAME': '0', 06:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dCqiD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:29:42 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)) 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-qJOBws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:29:42 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)) 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lozoNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:29:42 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)) 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ufzV2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-SUmyY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:29:42 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)) 06:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:29:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-roV_LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-AKEa1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-yLewV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-tSIxpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-6iUdYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-deVQGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ieeS54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-wFhpRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hju9Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:29:43 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)) 06:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-WDEYYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:44 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)) 06:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:29:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-3i4DJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:29:44 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)) 06:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:29:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-u8ycC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:29:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:29:44 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 06:29:44 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 06:29:44 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 06:29:44 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 06:29:44 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 06:29:44 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 06:29:46 INFO - Downloading blessings-1.6.tar.gz 06:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:29:47 INFO - Installing collected packages: blessings 06:29:47 INFO - Running setup.py install for blessings 06:29:47 INFO - Successfully installed blessings 06:29:47 INFO - Cleaning up... 06:29:47 INFO - Return code: 0 06:29:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:29:47 INFO - Reading from file tmpfile_stdout 06:29:47 INFO - Current package versions: 06:29:47 INFO - blessings == 1.6 06:29:47 INFO - blobuploader == 1.2.4 06:29:47 INFO - docopt == 0.6.1 06:29:47 INFO - functools32 == 3.2.3-2 06:29:47 INFO - jsonschema == 2.5.1 06:29:47 INFO - manifestparser == 1.1 06:29:47 INFO - mozInstall == 1.12 06:29:47 INFO - mozcrash == 1.0 06:29:47 INFO - mozdebug == 0.1 06:29:47 INFO - mozdevice == 0.49 06:29:47 INFO - mozfile == 1.2 06:29:47 INFO - mozhttpd == 0.7 06:29:47 INFO - mozinfo == 0.9 06:29:47 INFO - mozleak == 0.1 06:29:47 INFO - mozlog == 3.4 06:29:47 INFO - moznetwork == 0.27 06:29:47 INFO - mozprocess == 0.25 06:29:47 INFO - mozprofile == 0.28 06:29:47 INFO - mozrunner == 6.13 06:29:47 INFO - mozscreenshot == 0.1 06:29:47 INFO - mozsystemmonitor == 0.3 06:29:47 INFO - moztest == 0.8 06:29:47 INFO - mozversion == 1.4 06:29:47 INFO - psutil == 3.1.1 06:29:47 INFO - requests == 1.2.3 06:29:47 INFO - wsgiref == 0.1.2 06:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29:47 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')]} 06:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:47 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')]} 06:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:47 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:47 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 06:29:47 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 06:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:47 INFO - 'HOME': '/Users/cltbld', 06:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:47 INFO - 'LOGNAME': 'cltbld', 06:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:47 INFO - 'MOZ_NO_REMOTE': '1', 06:29:47 INFO - 'NO_EM_RESTART': '1', 06:29:47 INFO - 'PAGER': '/bin/cat', 06:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:47 INFO - 'PWD': '/builds/slave/test', 06:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:47 INFO - 'SHELL': '/bin/bash', 06:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:47 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:47 INFO - 'USER': 'cltbld', 06:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:47 INFO - 'XPC_FLAGS': '0x0', 06:29:47 INFO - 'XPC_SERVICE_NAME': '0', 06:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 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)) 06:29:47 INFO - Cleaning up... 06:29:47 INFO - Return code: 0 06:29:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:29:47 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')]} 06:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:47 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')]} 06:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:47 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 0x103bb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bce3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, '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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 06:29:47 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 06:29:47 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 06:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:47 INFO - 'HOME': '/Users/cltbld', 06:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:47 INFO - 'LOGNAME': 'cltbld', 06:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:47 INFO - 'MOZ_NO_REMOTE': '1', 06:29:47 INFO - 'NO_EM_RESTART': '1', 06:29:47 INFO - 'PAGER': '/bin/cat', 06:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:47 INFO - 'PWD': '/builds/slave/test', 06:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:47 INFO - 'SHELL': '/bin/bash', 06:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:29:47 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:47 INFO - 'USER': 'cltbld', 06:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:47 INFO - 'XPC_FLAGS': '0x0', 06:29:47 INFO - 'XPC_SERVICE_NAME': '0', 06:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:29:48 INFO - Cleaning up... 06:29:48 INFO - Return code: 0 06:29:48 INFO - Running post-action listener: _resource_record_post_action 06:29:48 INFO - Running post-action listener: _start_resource_monitoring 06:29:48 INFO - Starting resource monitoring. 06:29:48 INFO - [mozharness: 2017-03-02 14:29:48.164528Z] Finished create-virtualenv step (success) 06:29:48 INFO - [mozharness: 2017-03-02 14:29:48.165452Z] Running install step. 06:29:48 INFO - Running pre-action listener: _resource_record_pre_action 06:29:48 INFO - Running main action method: install 06:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:29:48 INFO - Reading from file tmpfile_stdout 06:29:48 INFO - Detecting whether we're running mozinstall >=1.0... 06:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:29:48 INFO - Reading from file tmpfile_stdout 06:29:48 INFO - Output received: 06:29:48 INFO - Usage: mozinstall [options] installer 06:29:48 INFO - Options: 06:29:48 INFO - -h, --help show this help message and exit 06:29:48 INFO - -d DEST, --destination=DEST 06:29:48 INFO - Directory to install application into. [default: 06:29:48 INFO - "/builds/slave/test"] 06:29:48 INFO - --app=APP Application being installed. [default: firefox] 06:29:48 INFO - mkdir: /builds/slave/test/build/application 06:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:30:08 INFO - Reading from file tmpfile_stdout 06:30:08 INFO - Output received: 06:30:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:30:08 INFO - Running post-action listener: _resource_record_post_action 06:30:08 INFO - [mozharness: 2017-03-02 14:30:08.626884Z] Finished install step (success) 06:30:08 INFO - [mozharness: 2017-03-02 14:30:08.626976Z] Running run-tests step. 06:30:08 INFO - Running pre-action listener: _resource_record_pre_action 06:30:08 INFO - Running main action method: run_tests 06:30:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:30:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:30:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:30:08 INFO - 2017-03-02 06:30:08.639 screenresolution[1635:7393] starting screenresolution argv=screenresolution get 06:30:08 INFO - 2017-03-02 06:30:08.642 screenresolution[1635:7393] Display 0: 1600x1200x32@60 06:30:08 INFO - 2017-03-02 06:30:08.647 screenresolution[1636:7396] starting screenresolution argv=screenresolution list 06:30:08 INFO - Available Modes on Display 0 06:30:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:30:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:30:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:30:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:30:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:30:08 INFO - Intel Iris: 06:30:08 INFO - Chipset Model: Intel Iris 06:30:08 INFO - Type: GPU 06:30:08 INFO - Bus: Built-In 06:30:08 INFO - VRAM (Dynamic, Max): 1536 MB 06:30:08 INFO - Vendor: Intel (0x8086) 06:30:08 INFO - Device ID: 0x0a2e 06:30:08 INFO - Revision ID: 0x0009 06:30:08 INFO - Displays: 06:30:08 INFO - Display: 06:30:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:30:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:30:08 INFO - Display Serial Number: 1600x1200 60 06:30:08 INFO - Main Display: Yes 06:30:08 INFO - Mirror: Off 06:30:08 INFO - Online: Yes 06:30:08 INFO - Rotation: Supported 06:30:08 INFO - Return code: 0 06:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:30:08 INFO - Python 2.7.3 06:30:08 INFO - Return code: 0 06:30:08 INFO - grabbing minidump binary from tooltool 06:30: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')]} 06:30:08 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 0x103993d30>, '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 0x1038badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103988be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:30:08 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 06:30:08 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 06:30:08 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 06:30:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:30:08 INFO - Return code: 0 06:30:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:30:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:30:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:30:09 INFO - ENV: RUST_BACKTRACE is now 1 06:30:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:30:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 06:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --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 06:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wxt8sAfLXo/Render', 06:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:30:09 INFO - 'HOME': '/Users/cltbld', 06:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:30:09 INFO - 'LOGNAME': 'cltbld', 06:30:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:30:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:09 INFO - 'MOZ_NO_REMOTE': '1', 06:30:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:30:09 INFO - 'NO_EM_RESTART': '1', 06:30:09 INFO - 'PAGER': '/bin/cat', 06:30:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:09 INFO - 'PWD': '/builds/slave/test', 06:30:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:30:09 INFO - 'RUST_BACKTRACE': '1', 06:30:09 INFO - 'SHELL': '/bin/bash', 06:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qm1pBXci0z/Listeners', 06:30:09 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:30:09 INFO - 'USER': 'cltbld', 06:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:09 INFO - 'XPC_FLAGS': '0x0', 06:30:09 INFO - 'XPC_SERVICE_NAME': '0', 06:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:30:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 06:30:09 INFO - mozversion application_buildid: 20170302050322 06:30:09 INFO - mozversion application_changeset: 66535e831760421b270662aa8d0773b0fde7c9f3 06:30:09 INFO - mozversion application_display_name: Nightly 06:30:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:30:09 INFO - mozversion application_name: Firefox 06:30:09 INFO - mozversion application_remotingname: firefox 06:30:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:30:09 INFO - mozversion application_vendor: Mozilla 06:30:09 INFO - mozversion application_version: 54.0a1 06:30:09 INFO - mozversion platform_buildid: 20170302050322 06:30:09 INFO - mozversion platform_changeset: 66535e831760421b270662aa8d0773b0fde7c9f3 06:30:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:30:09 INFO - mozversion platform_version: 54.0a1 06:30:09 INFO - using testdate: 1488465009 06:30:09 INFO - actual date: 1488465009 06:30:09 INFO - starting webserver on 'localhost:49252' 06:30:09 INFO - SUITE-START | Running 2 tests 06:30:09 INFO - TEST-START | dromaeo_css 06:30:09 INFO - operating with platform_type : mac_ 06:30:09 INFO - Initialising browser for dromaeo_css test... 06:30:09 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpcKBOf_/profile http://localhost:49252/getInfo.html) 06:30:12 INFO - PROCESS | 1646 | 2017-03-02 06:30:12.338 plugin-container[1648:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 06:30:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:12 INFO - PROCESS | 1646 | 2017-03-02 06:30:12.341 plugin-container[1648:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 06:30:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:12 INFO - PROCESS | 1646 | 2017-03-02 06:30:12.341 plugin-container[1648:7578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:30:12 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 06:30:12 INFO - PROCESS | 1646 | colorDepth:24 06:30:12 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 06:30:12 INFO - PROCESS | 1646 | __metrics 06:30:12 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 06:30:13 INFO - TEST-INFO | 1646: exit 0 06:30:13 INFO - Browser initialized. 06:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 06:30:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpcKBOf_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:30:15 INFO - PROCESS | 1649 | 2017-03-02 06:30:15.694 plugin-container[1650:7717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 06:30:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:15 INFO - PROCESS | 1649 | 2017-03-02 06:30:15.694 plugin-container[1650:7717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 06:30:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:15 INFO - PROCESS | 1649 | 2017-03-02 06:30:15.694 plugin-container[1650:7717] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:30:25 INFO - PROCESS | 1649 | RSS: Main: 226242560 06:30:25 INFO - PROCESS | 1649 | 06:33:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 06:33:30 INFO - PROCESS | 1649 | RSS: Main: 254611456 06:33:30 INFO - PROCESS | 1649 | 06:35:13 INFO - PROCESS | 1649 | ************************* 06:35:13 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 06:35:13 INFO - PROCESS | 1649 | 06:35:13 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:35:13 INFO - PROCESS | 1649 | Full stack: 06:35:13 INFO - PROCESS | 1649 | ************************* 06:36:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 06:36:33 INFO - PROCESS | 1649 | RSS: Main: 258883584 06:36:33 INFO - PROCESS | 1649 | 06:39:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 06:39:35 INFO - PROCESS | 1649 | RSS: Main: 269955072 06:39:35 INFO - PROCESS | 1649 | 06:42:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 06:42:38 INFO - PROCESS | 1649 | RSS: Main: 262696960 06:42:38 INFO - PROCESS | 1649 | 06:45:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 06:45:42 INFO - PROCESS | 1649 | RSS: Main: 273055744 06:45:42 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 06:48:45 INFO - PROCESS | 1649 | RSS: Main: 266911744 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | __start_tp_report 06:48:45 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 06:48:45 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 06:48:45 INFO - PROCESS | 1649 | |i|pagename|runs| 06:48:45 INFO - PROCESS | 1649 | |0;dojo.html;3145.757425816802;3324.5844269466315;3422.298428822092;3477.356689012531;3482.0076278260685;5762.3373312069125;5903.23257976463;5909.58632895697;5949.791757288495;5996.910046349309;5778;5894.17481552583;5907.734151963161;6005.909911351333;6017.969910150443;5341.1988201769655;5347.866303342428;5390.460953904618;5458.808941687042;5495.258140151069;4616.3537104805355;5379.220013098098;5481.780728770845;5503.7798488060425;5526.557875369981;5284.128118860383;5331.280277162572;5438.374586922515;5461.1262198048125;5472.534834539069;5372.758225879845;5374.731263436852;5409;5420.4850539198715;5467.343918729778;5332;5340.678717479987;5347.7058761768085;5355.437679043722;5421.403645598981;5065.670731402447;5199.700074981255;5739.282589676291;5802.82591522255;5815.592908496407;6215.850067737483;6253.812385628393;6288.182536270256;6299.118123462719;6321.146645202884;6437.130987316699;6443.3234510376205;6459.386358295955;6459.61242325462;6477.578957367803;6085.908711369245;6087.4521293083835;6104.542159338067;6130.693465326716;6131.049687298476;6299.055141728686;6356.904646430307;6460.935390646127;6463.6768161591735;6496.22045354551;6126.975740326312;6190.690465476799;6244.781432649836;6276.748930042839;6307.148534947815;6263.812085637438;6296.527760417987;6354.523410744213;6355.745770169234;6411.4870810336;6363.681815909186;6368.172137622079;6379;6387.840303992437;6403.199600049994;6182.721777520022;6375.872482550323;6380.266269378988;6381.7128229228865;6404.231492220963;4225.3873188388125;4662.090892276042;5035.949640503548;5058.671186372948;5106.897862042739;4594.517575654575;4627.444706635158;4632.096741135513;4640.930386044212;4663.067386522709;5149.639525233272;6071.726772295257;6073.878522429527;6086.665233412116;6095.969520152459;6191.752329906843;6267.2165979252595;6322.272938612026;6407.391297826699;6474.12599299098;5995.610285331527;6052.9104761142535;6066.181065556182;6107.083937409424;6128.908066379008;4899.265110233422;5097.209932460474;5544.724713315879;5655.915161272585;5681.744321505542;6250.906236406367;6389.616623002635;6574.572652777554;6638.17022872141;6646.634435106115;5703.686297253691;6319.083732858618;6370.458511026614;6432.260290066515;6439.613623182624;6564.540482166393;6595.010748387588;6613.041109039066;6627.569208001464;6636.203655561363;6187.133801267736;6282.654454005074;6340.841478963062;6368.745250190126;6468.935310646833;5286.180642000496;5858.297004359504;6259.624422534663;6320.905186422299;6323.905141422791;5343.225232341367;6111.563782511017;6230.439260466398;6261.655608941552;6280.057991301342;6116.66358350295;6131.804871721949;6143.112737013596;6206.441420272197;6297.338779428094;5258.789648414174;5817.94182058174;6096.481799046954;6183.227096612924;6219.720112594853;5620.297462817148;5818.156367326629;6273.780417685359;6277.811665650038;6295.716692748746;5967.194428752063;5992.820215393545;6005.609635373687;6159.722812473539;6176.413240742122 06:48:45 INFO - PROCESS | 1649 | |1;ext.html;720.423661071143;783.2324322164279;802.3901834605701;937.2501998401277;947.9715608531747;37772.43341349879;38520.22959540811;40471;40552.59447405525;40639.79680101595;31791.205219869516;38693.80653096738;38785.80607096961;38789.121087891195;39360.606393936054;7731.613419329039;7841.333486653612;10326.225533085011;10597.629082982097;10620.937906209356;9022.684206052789;10267.691969240897;10367.377957322547;10399.272050956437;10401.84397234042;10383.42891140987;10383.740406489822;10412.843807342895;10417.53121109548;10417.635382761568;10351.948240258787;10364.119049880732;10372.170226381872;10381.84427233592;10407.68776936693;10353.741156471073;10384.273100882941;10395.324303920297;10401.84397234042;10427.374357538572;26269.949202031898;33550;33557;33565.16087097842;33587.82442614521;3730.393813490258;3733.1413774831667;3769.491118698968;3772.302124107049;3913.4912461380122;24668.753312467004;24848.3787905302;25354.366140846443;25441.10956116527;25563.61654575183;2995.6405231372155;3157.984210078935;3183.840807959593;3211.630662473822;3221.2269055426527;350350;353501;354852;355169;355391.2230438857;42433.36349954691;42486;42650.78674606669;42727.145457090686;42730.78634606869;43817.561824381984;44169.8957526058;44235.55764442379;44280.3357949631;44293;12912.354382280848;12991;13025.088243823031;13025.088243823031;13054.282014489294;11152.442377881236;11163.83254251187;11175.44122793857;11175.93828586283;11192.776144477064;9717;11474.598389056342;11551.884481155248;11596.768064638829;11597.362145081932;5434.510894019478;5519.917201241985;5535.363433205233;5535.474129957728;5561.610687251934;1146.9942650286696;1147.4108359921402;1156.8611766587894;1159.646307876106;1167.287954347847;21381.78618213798;23635.409114771923;25912.352191195372;25976.610350844752;26293.868530657226;1266.8851410758473;1268.6067319130914;1279.4370476990096;1292.3279894454831;1298.6039258026203;13493.595192144252;13609.52366667162;13635.863641363658;13651.931740341235;13821.378037988114;472622;477307;478866;488825;610649;36293;47103.52896470991;47525.049499010514;47632.52367476281;47792.7610361946;1193.9940300298442;1220.2434490615697;1356.2676154876256;1371.5542448704211;1399.342309114735;26219.21342359732;26338.209853703655;26561.33596660099;26584.335391615365;26599.867000665647;37252.62747372492;37386.62613373831;37473.437898431;37593.81203094077;37645.62354376531;438831;440868;442552;444715;446113;21850.562988740454;22086.466165413458;22205.77794222037;22312.888435557717;22376.104955802235;31079.533806993364;31393.8430307847;36055.63944360628;36831.8158409217;37008.814955925045;29568.704312956594;30357.848210758806;31121.53317700191;31217.687823122385;31428.37143257168;28924.843006279512;30455;30510.237244069074;31255.218619533785;31359.529607056367;28632;31211.90758323448;31278.843605781825;31301.06096817099;31304.843475782476;24593;25311.493770124864;26643.067492637663;28013.159605211877;28180;19254.903725481283;21478.56342985115;21704.457388565414;21709.565808683423;21961.341159765234 06:48:45 INFO - PROCESS | 1649 | |2;jquery.html;366.2437067455704;390.31499717994933;391.2488022995848;400.76555215199096;437.2872218284196;18721.53196170093;19378.321758738428;19580;20767.896160519194;20848.583028339424;20588.07353669085;20904.26835060773;21073.57852842942;21188.764212673872;21264.89367553164;6476.611403315805;6643.833904152387;6718.462522998148;6721.764738234163;6726.12560367154;6342.968285158568;6536.052272420497;6546.44355229806;6551.213854337486;6630.4032637062655;6591.406773390394;6593.769218077368;6604.702788374535;6618.437432818217;6618.470522358224;6416.903746443807;6431.549791514596;6533.836654083639;6564.9496080627105;6565.080888675589;5573.41479144692;6121.571489995702;6377.872442551123;6380.266269379034;6384.68076596173;24231.151909683253;24996.750032499625;25232.873835630704;25255;25275.747242527523;2636.6440530528325;2863.3414314707525;2866.512692842222;2902.6516817981765;2924.5613158026254;15212.239388030554;15256.771148432783;15450.613734656612;15584.298706558233;16080.59798505035;1967.6261510312995;1973.6482151117339;1997.7103320018662;2005.9420549939102;2041.8774873507637;112312.99999999919;142771.74156715625;148221;148952;153218;19087.61824763497;19427.320043798398;19964.60070798576;19976.001199940234;20216.191352346035;30316.63575139124;30376.392472150434;30402;30520.542191867138;30525.847370763004;9662;9760.316777825485;9926.255530835217;10064.597416103274;10111.292209545409;8133.064697559738;8200.26197642203;8230.8353832923;8364.498130112213;8390.74138879173;7749.7287594933905;9185.851768528933;9222.769430764285;9260.768480788034;9449.811003779885;2995.9700402996127;3181.681831816846;3891.1905963727004;3905.1603905160528;4031.8387264509092;1009.3144448770571;1047.7531737232669;1050.9947450262853;1057.3338796558119;1058.3226734889677;19287;20371.4907127323;20443.08006139727;20490.59018819645;20667.8966605169;1061.1669839176209;1064.744461329291;1068.6793961811425;1096.6545538155456;1107.9889201107885;9953.601855925826;10764.730881728019;10784.86758890321;10796.581946535543;10807;680278;692686;714268;734376;740212.1957560818;108882.45558772313;149940.50059499484;151529;152699;154439.22780386027;992.3847214726916;1002.9498525073717;1010.2372708604956;1024.4314405504867;1027.7841653252901;18067.90966045214;18156;18282.725759113357;18416.079196040413;18494;55603.443965559825;55613.721931390086;57843.42156578381;57974.42025579859;58688;60133;71477.21306967367;74664.13339666552;77742;82267.58866205631;10024.348417352849;10918.454077295944;11015.669529914116;11310.717232069264;11339.943300283776;12435;12585.811212831632;12839.935800320938;12869.549565765154;13174.275414852274;10484;10810.29733067348;11082.889171108185;11101.333919964827;11113.611023614134;9949.950250248703;10017.248706346907;10076.596936122472;10618.681439556825;10951.890481095086;10314.638987635397;10593;11138.610148644759;11151.442427878736;11202.887971120184;9167.49578773174;10016.449095299828;11248.94375528117;11299.265547739371;11390;5818.389069147848;8367.33061355078;10548.683539493828;10700.464976750976;10856.782864342827 06:48:45 INFO - PROCESS | 1649 | |3;mootools.html;1537.9077255364673;1561.570568093774;1589.2927647196996;1608.211976131695;1609.4930097019437;10374.844377334337;10738.08726258267;10958.561657533699;11018.669439916805;11044;10813.566947436822;10940.617078402258;10949.562017519289;10976.451177441137;10978.615748448807;2350.6944097267333;2398.8560686358787;2400.411899084727;2400.7479214682467;2406.554787364343;2361.633946738258;2363.5627408929404;2402.8318017738766;2408.169181632334;2414.2998530426157;2341.4497593065703;2359.9292021239303;2366.9112208384076;2376.1089591403224;2384.9284521464383;2328.7787660172257;2345.108858633717;2362.799162071226;2366.5621859955963;2372.252740386781;2328.6856274403076;2364.929052128439;2387.7612238776155;2391.4380120671626;2394.688690470245;9483.33616646835;10702.357858528514;10896.618618348399;10935.070519005894;10937.4531273436;5669.2346533218315;5831.067029275296;5833.854153646151;5838.795642152547;5855.092460668603;3387.2717365766234;3392.4402473591826;3458.5849698036145;3564.928701425983;3593.2993066352074;1171.6467480060544;1178.675864137369;1205.0961778666;1231.2304809494067;1237.2143688757615;13472.865271347357;13548;13645.181289122684;14195.858041419557;14357.282135893269;4191.266528357525;4296.990207301282;4415.381846541487;4721.929171062437;4728.763561821896;11368.090552755759;11373.374464404536;11391.316521008766;11422.942885285687;11439.59961401347;5660.349059858169;5663.688497132614;5669.1496275558175;5677.858053548612;5694.117411801178;4585.220512512814;5036.7733451994745;5238.6472657499235;5283.841484755387;5300.602454815904;3632.3824949758605;3787.8295476703615;3820;3869.5163104611925;3875.4961854958674;919.4253591505309;1017.1862509991977;1051.447989805343;1054.8575942247699;1070.25617196048;893.4326702543931;902.2331018634109;934.6775362499926;951.8905325887473;967.065868263473;6411.294757576663;6420.7431702731365;6485.70814313357;6495.415412612888;6550.148480697574;975.5024937281895;977.8692743180784;980.9705708828747;1003.9297249192487;1016.517154351677;3965.8413663453716;4002.4996875390575;4095.5699651536756;4135.400366946776;4176.916461670749;13238.07333486666;13619;13620.727585448236;13640;13719.588412347646;13197.802032969514;13412.12821166621;13473.124246923919;13599.52401665937;13635.590932272047;9788.755281117887;9876.654317098866;9939.155171810333;10055.547500362502;10064.748381290527;5997.190379298739;6248.0003199487865;6256.405641464054;6415.133956915756;6510.381513756186;11645.243059201124;11916.821247681119;11969.820452693042;11992.280463172237;12118.394080296126;12328.137030407785;12401.176411943356;12422.347841719318;12490;12576.496940122659;8914.242289405473;9024.232940200156;9039;9147.582124770672;9153.993060763443;9775.75560610962;9849.852252216366;9879.506024698592;9961.601535938482;9982.650607229003;8725;8730;8735.650573977224;8775.210231079234;8810.383273171074;7039.768040592978;8380.24577788002;8451.1548845115;8666.696665616926;8693.739187824376;7375.483716139819;7551.660175292015;7634.160242373446;7636.465447418627;7726.266004729542;5083.161278389024;5749.511291540266;5791.797287094932;5821.650700957956;5878.529717622494;5697.686627235542;5897.3807750185615;5917.082852157922;5963.4334738199805;5985.910211346921 06:48:45 INFO - PROCESS | 1649 | |4;prototype.html;340.8415086984552;375.4499658001028;377.9357509223432;382.85642883918524;390.0949796472184;4545.454545454546;5256.266741294525;5273.811368282168;5349.411564727887;5445.156000819879;4483.394741709868;5277.973610131944;5329.493698098684;5341.359036915565;5393.649412788166;4408.294672852344;4482.932756008662;4530.864074077766;4666.626669866419;4692;4135.808021748463;4237.894052648693;4637.188492013901;4691.671582989192;4706.2705280681375;3673.5959044505084;4183.807614829774;4512.202434490604;4722.693024953384;4756.334113224151;4550.726956382611;4552.430946131733;4649.232876575361;4717.386739723848;4762.761861906908;4414.470263568361;4427.203103441379;4427.579379958931;4472.932906006413;4505.873531617096;5074.4671809460215;5273.6572122811895;5289.788408463657;5291.888703372297;5315.681059136464;5093.388793344822;5173.508516690946;5423;5474.7262636868;5530.695811730353;4467.798949047301;5377.704226267554;5467.699276539789;5848.298204215522;5855.707214639251;4304.634106100985;4829.291440845148;4994.350734404504;5016.423111342206;5041.672291301091;5612.382637909826;5820.417958204188;6071.939280607226;6114.694265286762;6119.663418511936;4618.769061546909;4725.976370118127;4945.738836596644;5117.590592752571;5154.170269554329;4563.771811409482;4996.4503904570465;5292.841214763564;5327.188755823039;5334.866628334245;5244.318238628954;5405.972970135124;5426.375966763872;5435.0488664483555;5449.891002180014;5335.3864305604575;5701.600887937804;5705.773258749306;5711.543076553866;5801.7389217485315;1608.463916959301;1679.2863033210836;1694.2803054899287;1729.792424909019;1746.91243963465;1263.5388083349512;1310.905393996024;1354.627477443691;1400.4748219417718;1401.6916278418732;4719.4897632757875;5138.665986710852;5154.561862241751;5173.63784535079;5211.013574615422;5230.63385563007;5674.00704876645;5736.77052917887;5737.827865164052;5761.251037365116;4574.74838883858;4613.877616296541;4736.64475164364;5157.535821776059;5172.250023746536;2616.6336712860216;2940.3237255430945;2960.874867550317;2964;2969.5545668149516;5878.794242201502;5903.852403689943;5945.821625351246;5989.802039592099;5994.0409534474275;5012.423571289349;5395.271638328775;5570.860728481739;5986.820395388146;6086.178365920633;6014.6391216527145;6078.54410919174;6094;6109.969450152721;6112.847178820565;5229.816956406507;5250.868728281824;5886.470217680258;5930.258717660293;5933.795750294965;4945.933521216869;5413.458654134555;5728.341240757366;5767.68277744728;5778.075507918713;4916.680415772963;6056.878862422639;6119.2350956130485;6190.7833225838685;6201.472874805511;4032.4152997814567;4492.932606010847;4520.32195170727;4658.277966915133;4694.8309890822875;5008.547521218806;5600.299962504687;5718.770464350185;5898.139402389923;5900.734466948912;5401.731272865484;5609.018421776092;5692.007797270922;5725.656460612377;5739.5695322850115;4554.772261386851;5245.344331958505;5618.297712785902;5702.830919661543;5736.512396446182;4696.906061878812;5498.680316724038;5571.777128914798;5573.554115670818;5624.943750562606;5399.730013499231;5407.540359069523;5692.800751973827;5697.23087383198;5703.771849125988;4375.759333236702;5500;5502.80740174092;5557.888422315634;5589.692566908859 06:48:45 INFO - PROCESS | 1649 | |5;yui.html;585.1661382529894;726.3825748114102;744.5346658338539;770.445279398833;791.4578513718103;14817.629559261024;15665.765013524791;16521.504354869343;17253.568660783487;17370.913145434257;16716.498505044838;16831.663366732657;16875.57811054724;16880.83119168808;16894.07082610456;940.5203346293406;955.0640372435;980.4901451245383;981.40134517944;982.219135787051;850.8468475674376;974.7465658928694;994.3834822410116;995.3182070281844;995.8655581496514;1040.7866387390566;1051.3113910388709;1054.13560880078;1054.6361505280704;1054.6730513540788;1017.6845177994845;1033.2870319479534;1047.287844265899;1047.3035431438084;1049.5486940615533;707.3704403081261;760.9467337286392;780.1769133564088;781.1173374087246;781.2460975158942;12591.433385497674;15581.454649087227;15908.522744317688;16089.436869709622;16167.353305867751;14458.024043388048;14606.488772892897;14793.11241325524;14810.037347572374;14859.777103343458;11675.474603642771;11753.23603965748;11875.620902046425;11955.342456164908;12228.816567751492;3444.225049363898;3445.3970555152746;3470.514128022079;3548.414511605582;3577.94633080504;100684;104442;105805.94194058038;106020;107920.4603976975;10291.53688084038;10982;11143.832842507369;11603.883961160449;11755.529778808752;24258.87870560636;24314.392140196636;24603.63094553583;24664.13675521384;24772.00911963501;7153.964230178815;7683.884741728879;7733.994580704585;7821.256980586835;7909.920900791033;4140.751554906655;4216.241076606179;4253.8723838284905;4263.126059157865;4291.592298731615;3050.3746731919905;3484.2160513884173;3511.5786105667485;3517.6306487818924;3525.823708814549;584.8450160707416;620.0141774578442;620.2340109964191;636.1253276744476;643.1092936283281;600.8858316919772;656.9310222426672;678.9388954993975;700.9824754381182;721.8267615772177;7654.617269136521;8929.33030022751;9138.949020862552;9557.358559937187;9721.416714997124;530.3476723629957;546.7157078319332;555.6166245290817;557.7378632042934;578.7974209026806;4403.911921761547;4422.5356337584735;4660.6970546914345;4711.937836602522;4766.666333356632;94911;95633;96810.51594741982;99531;100576.49135262978;85478.57260713782;87405;88494.67257991135;89012.10987890168;89589;58840.82318353609;60230.49592452193;63906.36093639097;67445.66277168583;77175;10633.574657013633;10643.787124257626;10742.624008159677;10889;10908.672739817817;34665.82667086648;42197.789011055975;46011.53988460072;47518.287225690954;49680.75798105076;55016.72491637676;59526.404735952085;59595;66510.66978660497;67297;12501.93749031249;12579.167496514492;12628.621141365405;12753.684042148923;13067.147032697612;11129.833052504056;11538.94230528842;11677.182597218112;12014.879851201376;13846.792298115335;13154.539591114266;13347.08808021598;13547.390367433687;13554.457821687021;13996;13133.146345487512;13680.315984200552;13806.171629701848;13940.233287169303;14115;12753.362331883554;13154;13451.991100667685;13950.302484875918;14238.501652442115;10230.407430193109;10296.227782916161;10905.509252083517;11059.668209953714;11575.536978520764;9163.541822908695;9507.809843802946;11212.55149793999;11231.382273974683;11237.7752444949 06:48:45 INFO - PROCESS | 1649 | __end_tp_report 06:48:45 INFO - PROCESS | 1649 | __start_cc_report 06:48:45 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,425 06:48:45 INFO - PROCESS | 1649 | __end_cc_report 06:48:45 INFO - PROCESS | 1649 | __startTimestamp1488466125344__endTimestamp 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | ------- Summary: start ------- 06:48:45 INFO - PROCESS | 1649 | Number of tests: 6 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5855.15 Median:6104.54 stddev:681.21 (11.2%) stddev-sans-first:649.29 06:48:45 INFO - PROCESS | 1649 | Values: 3145.8 3324.6 3422.3 3477.4 3482.0 5762.3 5903.2 5909.6 5949.8 5996.9 5778.0 5894.2 5907.7 6005.9 6018.0 5341.2 5347.9 5390.5 5458.8 5495.3 4616.4 5379.2 5481.8 5503.8 5526.6 5284.1 5331.3 5438.4 5461.1 5472.5 5372.8 5374.7 5409.0 5420.5 5467.3 5332.0 5340.7 5347.7 5355.4 5421.4 5065.7 5199.7 5739.3 5802.8 5815.6 6215.9 6253.8 6288.2 6299.1 6321.1 6437.1 6443.3 6459.4 6459.6 6477.6 6085.9 6087.5 6104.5 6130.7 6131.0 6299.1 6356.9 6460.9 6463.7 6496.2 6127.0 6190.7 6244.8 6276.7 6307.1 6263.8 6296.5 6354.5 6355.7 6411.5 6363.7 6368.2 6379.0 6387.8 6403.2 6182.7 6375.9 6380.3 6381.7 6404.2 4225.4 4662.1 5035.9 5058.7 5106.9 4594.5 4627.4 4632.1 4640.9 4663.1 5149.6 6071.7 6073.9 6086.7 6096.0 6191.8 6267.2 6322.3 6407.4 6474.1 5995.6 6052.9 6066.2 6107.1 6128.9 4899.3 5097.2 5544.7 5655.9 5681.7 6250.9 6389.6 6574.6 6638.2 6646.6 5703.7 6319.1 6370.5 6432.3 6439.6 6564.5 6595.0 6613.0 6627.6 6636.2 6187.1 6282.7 6340.8 6368.7 6468.9 5286.2 5858.3 6259.6 6320.9 6323.9 5343.2 6111.6 6230.4 6261.7 6280.1 6116.7 6131.8 6143.1 6206.4 6297.3 5258.8 5817.9 6096.5 6183.2 6219.7 5620.3 5818.2 6273.8 6277.8 6295.7 5967.2 5992.8 6005.6 6159.7 6176.4 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:54728.01 Median:16676.74 stddev:117210.40 (702.8%) stddev-sans-first:117469.06 06:48:45 INFO - PROCESS | 1649 | Values: 720.4 783.2 802.4 937.3 948.0 37772.4 38520.2 40471.0 40552.6 40639.8 31791.2 38693.8 38785.8 38789.1 39360.6 7731.6 7841.3 10326.2 10597.6 10620.9 9022.7 10267.7 10367.4 10399.3 10401.8 10383.4 10383.7 10412.8 10417.5 10417.6 10351.9 10364.1 10372.2 10381.8 10407.7 10353.7 10384.3 10395.3 10401.8 10427.4 26269.9 33550.0 33557.0 33565.2 33587.8 3730.4 3733.1 3769.5 3772.3 3913.5 24668.8 24848.4 25354.4 25441.1 25563.6 2995.6 3158.0 3183.8 3211.6 3221.2 350350.0 353501.0 354852.0 355169.0 355391.2 42433.4 42486.0 42650.8 42727.1 42730.8 43817.6 44169.9 44235.6 44280.3 44293.0 12912.4 12991.0 13025.1 13025.1 13054.3 11152.4 11163.8 11175.4 11175.9 11192.8 9717.0 11474.6 11551.9 11596.8 11597.4 5434.5 5519.9 5535.4 5535.5 5561.6 1147.0 1147.4 1156.9 1159.6 1167.3 21381.8 23635.4 25912.4 25976.6 26293.9 1266.9 1268.6 1279.4 1292.3 1298.6 13493.6 13609.5 13635.9 13651.9 13821.4 472622.0 477307.0 478866.0 488825.0 610649.0 36293.0 47103.5 47525.0 47632.5 47792.8 1194.0 1220.2 1356.3 1371.6 1399.3 26219.2 26338.2 26561.3 26584.3 26599.9 37252.6 37386.6 37473.4 37593.8 37645.6 438831.0 440868.0 442552.0 444715.0 446113.0 21850.6 22086.5 22205.8 22312.9 22376.1 31079.5 31393.8 36055.6 36831.8 37008.8 29568.7 30357.8 31121.5 31217.7 31428.4 28924.8 30455.0 30510.2 31255.2 31359.5 28632.0 31211.9 31278.8 31301.1 31304.8 24593.0 25311.5 26643.1 28013.2 28180.0 19254.9 21478.6 21704.5 21709.6 21961.3 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:40425.85 Median:20808.24 stddev:118659.57 (570.3%) stddev-sans-first:118954.32 06:48:45 INFO - PROCESS | 1649 | Values: 366.2 390.3 391.2 400.8 437.3 18721.5 19378.3 19580.0 20767.9 20848.6 20588.1 20904.3 21073.6 21188.8 21264.9 6476.6 6643.8 6718.5 6721.8 6726.1 6343.0 6536.1 6546.4 6551.2 6630.4 6591.4 6593.8 6604.7 6618.4 6618.5 6416.9 6431.5 6533.8 6564.9 6565.1 5573.4 6121.6 6377.9 6380.3 6384.7 24231.2 24996.8 25232.9 25255.0 25275.7 2636.6 2863.3 2866.5 2902.7 2924.6 15212.2 15256.8 15450.6 15584.3 16080.6 1967.6 1973.6 1997.7 2005.9 2041.9 112313.0 142771.7 148221.0 148952.0 153218.0 19087.6 19427.3 19964.6 19976.0 20216.2 30316.6 30376.4 30402.0 30520.5 30525.8 9662.0 9760.3 9926.3 10064.6 10111.3 8133.1 8200.3 8230.8 8364.5 8390.7 7749.7 9185.9 9222.8 9260.8 9449.8 2996.0 3181.7 3891.2 3905.2 4031.8 1009.3 1047.8 1051.0 1057.3 1058.3 19287.0 20371.5 20443.1 20490.6 20667.9 1061.2 1064.7 1068.7 1096.7 1108.0 9953.6 10764.7 10784.9 10796.6 10807.0 680278.0 692686.0 714268.0 734376.0 740212.2 108882.5 149940.5 151529.0 152699.0 154439.2 992.4 1002.9 1010.2 1024.4 1027.8 18067.9 18156.0 18282.7 18416.1 18494.0 55603.4 55613.7 57843.4 57974.4 58688.0 60133.0 71477.2 74664.1 77742.0 82267.6 10024.3 10918.5 11015.7 11310.7 11339.9 12435.0 12585.8 12839.9 12869.5 13174.3 10484.0 10810.3 11082.9 11101.3 11113.6 9950.0 10017.2 10076.6 10618.7 10951.9 10314.6 10593.0 11138.6 11151.4 11202.9 9167.5 10016.4 11248.9 11299.3 11390.0 5818.4 8367.3 10548.7 10700.5 10856.8 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:6577.33 Median:3579.11 stddev:4079.98 (114.0%) stddev-sans-first:4073.85 06:48:45 INFO - PROCESS | 1649 | Values: 1537.9 1561.6 1589.3 1608.2 1609.5 10374.8 10738.1 10958.6 11018.7 11044.0 10813.6 10940.6 10949.6 10976.5 10978.6 2350.7 2398.9 2400.4 2400.7 2406.6 2361.6 2363.6 2402.8 2408.2 2414.3 2341.4 2359.9 2366.9 2376.1 2384.9 2328.8 2345.1 2362.8 2366.6 2372.3 2328.7 2364.9 2387.8 2391.4 2394.7 9483.3 10702.4 10896.6 10935.1 10937.5 5669.2 5831.1 5833.9 5838.8 5855.1 3387.3 3392.4 3458.6 3564.9 3593.3 1171.6 1178.7 1205.1 1231.2 1237.2 13472.9 13548.0 13645.2 14195.9 14357.3 4191.3 4297.0 4415.4 4721.9 4728.8 11368.1 11373.4 11391.3 11422.9 11439.6 5660.3 5663.7 5669.1 5677.9 5694.1 4585.2 5036.8 5238.6 5283.8 5300.6 3632.4 3787.8 3820.0 3869.5 3875.5 919.4 1017.2 1051.4 1054.9 1070.3 893.4 902.2 934.7 951.9 967.1 6411.3 6420.7 6485.7 6495.4 6550.1 975.5 977.9 981.0 1003.9 1016.5 3965.8 4002.5 4095.6 4135.4 4176.9 13238.1 13619.0 13620.7 13640.0 13719.6 13197.8 13412.1 13473.1 13599.5 13635.6 9788.8 9876.7 9939.2 10055.5 10064.7 5997.2 6248.0 6256.4 6415.1 6510.4 11645.2 11916.8 11969.8 11992.3 12118.4 12328.1 12401.2 12422.3 12490.0 12576.5 8914.2 9024.2 9039.0 9147.6 9154.0 9775.8 9849.9 9879.5 9961.6 9982.7 8725.0 8730.0 8735.7 8775.2 8810.4 7039.8 8380.2 8451.2 8666.7 8693.7 7375.5 7551.7 7634.2 7636.5 7726.3 5083.2 5749.5 5791.8 5821.7 5878.5 5697.7 5897.4 5917.1 5963.4 5985.9 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4850.90 Median:5264.96 stddev:1308.07 (24.8%) stddev-sans-first:1267.18 06:48:45 INFO - PROCESS | 1649 | Values: 340.8 375.4 377.9 382.9 390.1 4545.5 5256.3 5273.8 5349.4 5445.2 4483.4 5278.0 5329.5 5341.4 5393.6 4408.3 4482.9 4530.9 4666.6 4692.0 4135.8 4237.9 4637.2 4691.7 4706.3 3673.6 4183.8 4512.2 4722.7 4756.3 4550.7 4552.4 4649.2 4717.4 4762.8 4414.5 4427.2 4427.6 4472.9 4505.9 5074.5 5273.7 5289.8 5291.9 5315.7 5093.4 5173.5 5423.0 5474.7 5530.7 4467.8 5377.7 5467.7 5848.3 5855.7 4304.6 4829.3 4994.4 5016.4 5041.7 5612.4 5820.4 6071.9 6114.7 6119.7 4618.8 4726.0 4945.7 5117.6 5154.2 4563.8 4996.5 5292.8 5327.2 5334.9 5244.3 5406.0 5426.4 5435.0 5449.9 5335.4 5701.6 5705.8 5711.5 5801.7 1608.5 1679.3 1694.3 1729.8 1746.9 1263.5 1310.9 1354.6 1400.5 1401.7 4719.5 5138.7 5154.6 5173.6 5211.0 5230.6 5674.0 5736.8 5737.8 5761.3 4574.7 4613.9 4736.6 5157.5 5172.3 2616.6 2940.3 2960.9 2964.0 2969.6 5878.8 5903.9 5945.8 5989.8 5994.0 5012.4 5395.3 5570.9 5986.8 6086.2 6014.6 6078.5 6094.0 6110.0 6112.8 5229.8 5250.9 5886.5 5930.3 5933.8 4945.9 5413.5 5728.3 5767.7 5778.1 4916.7 6056.9 6119.2 6190.8 6201.5 4032.4 4492.9 4520.3 4658.3 4694.8 5008.5 5600.3 5718.8 5898.1 5900.7 5401.7 5609.0 5692.0 5725.7 5739.6 4554.8 5245.3 5618.3 5702.8 5736.5 4696.9 5498.7 5571.8 5573.6 5624.9 5399.7 5407.5 5692.8 5697.2 5703.8 4375.8 5500.0 5502.8 5557.9 5589.7 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19711.55 Median:3444.81 stddev:27812.98 (807.4%) stddev-sans-first:27853.93 06:48:45 INFO - PROCESS | 1649 | Values: 585.2 726.4 744.5 770.4 791.5 14817.6 15665.8 16521.5 17253.6 17370.9 16716.5 16831.7 16875.6 16880.8 16894.1 940.5 955.1 980.5 981.4 982.2 850.8 974.7 994.4 995.3 995.9 1040.8 1051.3 1054.1 1054.6 1054.7 1017.7 1033.3 1047.3 1047.3 1049.5 707.4 760.9 780.2 781.1 781.2 12591.4 15581.5 15908.5 16089.4 16167.4 14458.0 14606.5 14793.1 14810.0 14859.8 11675.5 11753.2 11875.6 11955.3 12228.8 3444.2 3445.4 3470.5 3548.4 3577.9 100684.0 104442.0 105805.9 106020.0 107920.5 10291.5 10982.0 11143.8 11603.9 11755.5 24258.9 24314.4 24603.6 24664.1 24772.0 7154.0 7683.9 7734.0 7821.3 7909.9 4140.8 4216.2 4253.9 4263.1 4291.6 3050.4 3484.2 3511.6 3517.6 3525.8 584.8 620.0 620.2 636.1 643.1 600.9 656.9 678.9 701.0 721.8 7654.6 8929.3 9138.9 9557.4 9721.4 530.3 546.7 555.6 557.7 578.8 4403.9 4422.5 4660.7 4711.9 4766.7 94911.0 95633.0 96810.5 99531.0 100576.5 85478.6 87405.0 88494.7 89012.1 89589.0 58840.8 60230.5 63906.4 67445.7 77175.0 10633.6 10643.8 10742.6 10889.0 10908.7 34665.8 42197.8 46011.5 47518.3 49680.8 55016.7 59526.4 59595.0 66510.7 67297.0 12501.9 12579.2 12628.6 12753.7 13067.1 11129.8 11538.9 11677.2 12014.9 13846.8 13154.5 13347.1 13547.4 13554.5 13996.0 13133.1 13680.3 13806.2 13940.2 14115.0 12753.4 13154.0 13452.0 13950.3 14238.5 10230.4 10296.2 10905.5 11059.7 11575.5 9163.5 9507.8 11212.6 11231.4 11237.8 06:48:45 INFO - PROCESS | 1649 | -------- Summary: end -------- 06:48:45 INFO - PROCESS | 1649 | 06:48:45 INFO - TEST-INFO | 1649: exit 0 06:48:46 INFO - TEST-OK | dromaeo_css | took 1116773ms 06:48:46 INFO - TEST-START | kraken 06:48:46 INFO - operating with platform_type : mac_ 06:48:46 INFO - Initialising browser for kraken test... 06:48:46 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmprBujfF/profile http://localhost:49252/getInfo.html) 06:48:48 INFO - PROCESS | 1661 | 2017-03-02 06:48:48.678 plugin-container[1662:10260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 06:48:48 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:48 INFO - PROCESS | 1661 | 2017-03-02 06:48:48.680 plugin-container[1662:10260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 06:48:48 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:48 INFO - PROCESS | 1661 | 2017-03-02 06:48:48.680 plugin-container[1662:10260] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:48:48 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 06:48:48 INFO - PROCESS | 1661 | colorDepth:24 06:48:48 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 06:48:48 INFO - PROCESS | 1661 | __metrics 06:48:48 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 06:48:49 INFO - TEST-INFO | 1661: exit 0 06:48:49 INFO - Browser initialized. 06:48:49 INFO - Running cycle 1/1 for kraken test... 06:48:49 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmprBujfF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:48:52 INFO - PROCESS | 1663 | 2017-03-02 06:48:52.253 plugin-container[1664:10386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 06:48:52 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:52 INFO - PROCESS | 1663 | 2017-03-02 06:48:52.254 plugin-container[1664:10386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 06:48:52 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:52 INFO - PROCESS | 1663 | 2017-03-02 06:48:52.254 plugin-container[1664:10386] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:49:01 INFO - PROCESS | 1663 | RSS: Main: 227856384 06:49:01 INFO - PROCESS | 1663 | 06:49:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 06:49:06 INFO - PROCESS | 1663 | RSS: Main: 241573888 06:49:06 INFO - PROCESS | 1663 | 06:49:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 06:49:09 INFO - PROCESS | 1663 | RSS: Main: 249704448 06:49:09 INFO - PROCESS | 1663 | 06:49:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 06:49:13 INFO - PROCESS | 1663 | RSS: Main: 252301312 06:49:13 INFO - PROCESS | 1663 | 06:49:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 06:49:16 INFO - PROCESS | 1663 | RSS: Main: 253546496 06:49:16 INFO - PROCESS | 1663 | 06:49:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:49:18 INFO - PROCESS | 1663 | RSS: Main: 255098880 06:49:18 INFO - PROCESS | 1663 | 06:49:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 06:49:22 INFO - PROCESS | 1663 | RSS: Main: 260124672 06:49:22 INFO - PROCESS | 1663 | 06:49:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 06:49:26 INFO - PROCESS | 1663 | RSS: Main: 259858432 06:49:26 INFO - PROCESS | 1663 | 06:49:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 06:49:30 INFO - PROCESS | 1663 | RSS: Main: 259510272 06:49:30 INFO - PROCESS | 1663 | 06:49:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:49:32 INFO - PROCESS | 1663 | RSS: Main: 259518464 06:49:32 INFO - PROCESS | 1663 | 06:49:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:49:35 INFO - PROCESS | 1663 | RSS: Main: 259346432 06:49:35 INFO - PROCESS | 1663 | 06:49:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:49:38 INFO - PROCESS | 1663 | RSS: Main: 259452928 06:49:38 INFO - PROCESS | 1663 | 06:49:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:49:41 INFO - PROCESS | 1663 | RSS: Main: 260046848 06:49:41 INFO - PROCESS | 1663 | 06:49:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:49:45 INFO - PROCESS | 1663 | RSS: Main: 259506176 06:49:45 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 06:49:47 INFO - PROCESS | 1663 | RSS: Main: 263057408 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | __start_tp_report 06:49:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 06:49:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 06:49:47 INFO - PROCESS | 1663 | |i|pagename|runs| 06:49:47 INFO - PROCESS | 1663 | |0;ai-astar;128;121;120;120;97;115;147;111;169;171 06:49:47 INFO - PROCESS | 1663 | |1;audio-beat-detection;175;127;110;145;105;143;101;104;122;119 06:49:47 INFO - PROCESS | 1663 | |2;audio-dft;212;216;201;195;247;199;193;198;196;196 06:49:47 INFO - PROCESS | 1663 | |3;audio-fft;83;77;80;98;60;66;59;63;75;78 06:49:47 INFO - PROCESS | 1663 | |4;audio-oscillator;87;73;110;99;98;76;81;71;70;83 06:49:47 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;90;90;105;92;125;96;126;127;90;89 06:49:47 INFO - PROCESS | 1663 | |6;imaging-darkroom;99;97;105;97;98;96;100;112;98;97 06:49:47 INFO - PROCESS | 1663 | |7;imaging-desaturate;90;88;91;124;92;139;136;90;91;92 06:49:47 INFO - PROCESS | 1663 | |8;json-parse-financial;67;64;65;66;67;66;65;67;65;67 06:49:47 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;58;57;63;65;65;59;57;56;58;58 06:49:47 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;87;104;104;100;88;84;93;88;86;90 06:49:47 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;145;146;171;150;154;147;144;148;182;150 06:49:47 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;179;179;171;175;176;175;175;176;173;173 06:49:47 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;68;69;67;67;69;68;67;69;66;69 06:49:47 INFO - PROCESS | 1663 | __end_tp_report 06:49:47 INFO - PROCESS | 1663 | __start_cc_report 06:49:47 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,995 06:49:47 INFO - PROCESS | 1663 | __end_cc_report 06:49:47 INFO - PROCESS | 1663 | __startTimestamp1488466187454__endTimestamp 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | ------- Summary: start ------- 06:49:47 INFO - PROCESS | 1663 | Number of tests: 14 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:129.90 Median:137.50 stddev:24.61 (17.9%) stddev-sans-first:26.09 06:49:47 INFO - PROCESS | 1663 | Values: 128.0 121.0 120.0 120.0 97.0 115.0 147.0 111.0 169.0 171.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:124.50 stddev:23.37 (18.8%) stddev-sans-first:16.39 06:49:47 INFO - PROCESS | 1663 | Values: 175.0 127.0 110.0 145.0 105.0 143.0 101.0 104.0 122.0 119.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:205.30 Median:200.00 stddev:16.47 (8.2%) stddev-sans-first:17.29 06:49:47 INFO - PROCESS | 1663 | Values: 212.0 216.0 201.0 195.0 247.0 199.0 193.0 198.0 196.0 196.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:73.90 Median:77.50 stddev:12.13 (15.7%) stddev-sans-first:12.41 06:49:47 INFO - PROCESS | 1663 | Values: 83.0 77.0 80.0 98.0 60.0 66.0 59.0 63.0 75.0 78.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:82.00 stddev:13.58 (16.6%) stddev-sans-first:14.38 06:49:47 INFO - PROCESS | 1663 | Values: 87.0 73.0 110.0 99.0 98.0 76.0 81.0 71.0 70.0 83.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:90.00 stddev:16.55 (18.4%) stddev-sans-first:16.88 06:49:47 INFO - PROCESS | 1663 | Values: 90.0 90.0 105.0 92.0 125.0 96.0 126.0 127.0 90.0 89.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:97.00 stddev:4.95 (5.1%) stddev-sans-first:5.24 06:49:47 INFO - PROCESS | 1663 | Values: 99.0 97.0 105.0 97.0 98.0 96.0 100.0 112.0 98.0 97.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:90.50 stddev:20.86 (23.1%) stddev-sans-first:21.57 06:49:47 INFO - PROCESS | 1663 | Values: 90.0 88.0 91.0 124.0 92.0 139.0 136.0 90.0 91.0 92.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.50 stddev:1.10 (1.7%) stddev-sans-first:1.09 06:49:47 INFO - PROCESS | 1663 | Values: 67.0 64.0 65.0 66.0 67.0 66.0 65.0 67.0 65.0 67.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:58.50 stddev:3.41 (5.8%) stddev-sans-first:3.56 06:49:47 INFO - PROCESS | 1663 | Values: 58.0 57.0 63.0 65.0 65.0 59.0 57.0 56.0 58.0 58.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:87.50 stddev:7.55 (8.6%) stddev-sans-first:7.75 06:49:47 INFO - PROCESS | 1663 | Values: 87.0 104.0 104.0 100.0 88.0 84.0 93.0 88.0 86.0 90.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:150.00 stddev:12.62 (8.4%) stddev-sans-first:12.99 06:49:47 INFO - PROCESS | 1663 | Values: 145.0 146.0 171.0 150.0 154.0 147.0 144.0 148.0 182.0 150.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.50 stddev:2.53 (1.4%) stddev-sans-first:2.28 06:49:47 INFO - PROCESS | 1663 | Values: 179.0 179.0 171.0 175.0 176.0 175.0 175.0 176.0 173.0 173.0 06:49:47 INFO - PROCESS | 1663 | 06:49:47 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:1.10 (1.6%) stddev-sans-first:1.17 06:49:47 INFO - PROCESS | 1663 | Values: 68.0 69.0 67.0 67.0 69.0 68.0 67.0 69.0 66.0 69.0 06:49:47 INFO - PROCESS | 1663 | -------- Summary: end -------- 06:49:47 INFO - PROCESS | 1663 | 06:49:48 INFO - TEST-INFO | 1663: exit 0 06:49:48 INFO - TEST-OK | kraken | took 62327ms 06:49:48 INFO - SUITE-END | took 1179s 06:49:48 INFO - Completed test suite (00:19:39) 06:49:48 INFO - javascript benchmark 06:49:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7578.53042191766, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.757425816802, 3324.5844269466315, 3422.298428822092, 3477.356689012531, 3482.0076278260685, 5762.3373312069125, 5903.23257976463, 5909.58632895697, 5949.791757288495, 5996.910046349309, 5778.0, 5894.17481552583, 5907.734151963161, 6005.909911351333, 6017.969910150443, 5341.1988201769655, 5347.866303342428, 5390.460953904618, 5458.808941687042, 5495.258140151069, 4616.3537104805355, 5379.220013098098, 5481.780728770845, 5503.7798488060425, 5526.557875369981, 5284.128118860383, 5331.280277162572, 5438.374586922515, 5461.1262198048125, 5472.534834539069, 5372.758225879845, 5374.731263436852, 5409.0, 5420.4850539198715, 5467.343918729778, 5332.0, 5340.678717479987, 5347.7058761768085, 5355.437679043722, 5421.403645598981, 5065.670731402447, 5199.700074981255, 5739.282589676291, 5802.82591522255, 5815.592908496407, 6215.850067737483, 6253.812385628393, 6288.182536270256, 6299.118123462719, 6321.146645202884, 6437.130987316699, 6443.3234510376205, 6459.386358295955, 6459.61242325462, 6477.578957367803, 6085.908711369245, 6087.4521293083835, 6104.542159338067, 6130.693465326716, 6131.049687298476, 6299.055141728686, 6356.904646430307, 6460.935390646127, 6463.6768161591735, 6496.22045354551, 6126.975740326312, 6190.690465476799, 6244.781432649836, 6276.748930042839, 6307.148534947815, 6263.812085637438, 6296.527760417987, 6354.523410744213, 6355.745770169234, 6411.4870810336, 6363.681815909186, 6368.172137622079, 6379.0, 6387.840303992437, 6403.199600049994, 6182.721777520022, 6375.872482550323, 6380.266269378988, 6381.7128229228865, 6404.231492220963, 4225.3873188388125, 4662.090892276042, 5035.949640503548, 5058.671186372948, 5106.897862042739, 4594.517575654575, 4627.444706635158, 4632.096741135513, 4640.930386044212, 4663.067386522709, 5149.639525233272, 6071.726772295257, 6073.878522429527, 6086.665233412116, 6095.969520152459, 6191.752329906843, 6267.2165979252595, 6322.272938612026, 6407.391297826699, 6474.12599299098, 5995.610285331527, 6052.9104761142535, 6066.181065556182, 6107.083937409424, 6128.908066379008, 4899.265110233422, 5097.209932460474, 5544.724713315879, 5655.915161272585, 5681.744321505542, 6250.906236406367, 6389.616623002635, 6574.572652777554, 6638.17022872141, 6646.634435106115, 5703.686297253691, 6319.083732858618, 6370.458511026614, 6432.260290066515, 6439.613623182624, 6564.540482166393, 6595.010748387588, 6613.041109039066, 6627.569208001464, 6636.203655561363, 6187.133801267736, 6282.654454005074, 6340.841478963062, 6368.745250190126, 6468.935310646833, 5286.180642000496, 5858.297004359504, 6259.624422534663, 6320.905186422299, 6323.905141422791, 5343.225232341367, 6111.563782511017, 6230.439260466398, 6261.655608941552, 6280.057991301342, 6116.66358350295, 6131.804871721949, 6143.112737013596, 6206.441420272197, 6297.338779428094, 5258.789648414174, 5817.94182058174, 6096.481799046954, 6183.227096612924, 6219.720112594853, 5620.297462817148, 5818.156367326629, 6273.780417685359, 6277.811665650038, 6295.716692748746, 5967.194428752063, 5992.820215393545, 6005.609635373687, 6159.722812473539, 6176.413240742122], "value": 5811.793820217194, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.423661071143, 783.2324322164279, 802.3901834605701, 937.2501998401277, 947.9715608531747, 37772.43341349879, 38520.22959540811, 40471.0, 40552.59447405525, 40639.79680101595, 31791.205219869516, 38693.80653096738, 38785.80607096961, 38789.121087891195, 39360.606393936054, 7731.613419329039, 7841.333486653612, 10326.225533085011, 10597.629082982097, 10620.937906209356, 9022.684206052789, 10267.691969240897, 10367.377957322547, 10399.272050956437, 10401.84397234042, 10383.42891140987, 10383.740406489822, 10412.843807342895, 10417.53121109548, 10417.635382761568, 10351.948240258787, 10364.119049880732, 10372.170226381872, 10381.84427233592, 10407.68776936693, 10353.741156471073, 10384.273100882941, 10395.324303920297, 10401.84397234042, 10427.374357538572, 26269.949202031898, 33550.0, 33557.0, 33565.16087097842, 33587.82442614521, 3730.393813490258, 3733.1413774831667, 3769.491118698968, 3772.302124107049, 3913.4912461380122, 24668.753312467004, 24848.3787905302, 25354.366140846443, 25441.10956116527, 25563.61654575183, 2995.6405231372155, 3157.984210078935, 3183.840807959593, 3211.630662473822, 3221.2269055426527, 350350.0, 353501.0, 354852.0, 355169.0, 355391.2230438857, 42433.36349954691, 42486.0, 42650.78674606669, 42727.145457090686, 42730.78634606869, 43817.561824381984, 44169.8957526058, 44235.55764442379, 44280.3357949631, 44293.0, 12912.354382280848, 12991.0, 13025.088243823031, 13025.088243823031, 13054.282014489294, 11152.442377881236, 11163.83254251187, 11175.44122793857, 11175.93828586283, 11192.776144477064, 9717.0, 11474.598389056342, 11551.884481155248, 11596.768064638829, 11597.362145081932, 5434.510894019478, 5519.917201241985, 5535.363433205233, 5535.474129957728, 5561.610687251934, 1146.9942650286696, 1147.4108359921402, 1156.8611766587894, 1159.646307876106, 1167.287954347847, 21381.78618213798, 23635.409114771923, 25912.352191195372, 25976.610350844752, 26293.868530657226, 1266.8851410758473, 1268.6067319130914, 1279.4370476990096, 1292.3279894454831, 1298.6039258026203, 13493.595192144252, 13609.52366667162, 13635.863641363658, 13651.931740341235, 13821.378037988114, 472622.0, 477307.0, 478866.0, 488825.0, 610649.0, 36293.0, 47103.52896470991, 47525.049499010514, 47632.52367476281, 47792.7610361946, 1193.9940300298442, 1220.2434490615697, 1356.2676154876256, 1371.5542448704211, 1399.342309114735, 26219.21342359732, 26338.209853703655, 26561.33596660099, 26584.335391615365, 26599.867000665647, 37252.62747372492, 37386.62613373831, 37473.437898431, 37593.81203094077, 37645.62354376531, 438831.0, 440868.0, 442552.0, 444715.0, 446113.0, 21850.562988740454, 22086.466165413458, 22205.77794222037, 22312.888435557717, 22376.104955802235, 31079.533806993364, 31393.8430307847, 36055.63944360628, 36831.8158409217, 37008.814955925045, 29568.704312956594, 30357.848210758806, 31121.53317700191, 31217.687823122385, 31428.37143257168, 28924.843006279512, 30455.0, 30510.237244069074, 31255.218619533785, 31359.529607056367, 28632.0, 31211.90758323448, 31278.843605781825, 31301.06096817099, 31304.843475782476, 24593.0, 25311.493770124864, 26643.067492637663, 28013.159605211877, 28180.0, 19254.903725481283, 21478.56342985115, 21704.457388565414, 21709.565808683423, 21961.341159765234], "value": 17550.23520794324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.2437067455704, 390.31499717994933, 391.2488022995848, 400.76555215199096, 437.2872218284196, 18721.53196170093, 19378.321758738428, 19580.0, 20767.896160519194, 20848.583028339424, 20588.07353669085, 20904.26835060773, 21073.57852842942, 21188.764212673872, 21264.89367553164, 6476.611403315805, 6643.833904152387, 6718.462522998148, 6721.764738234163, 6726.12560367154, 6342.968285158568, 6536.052272420497, 6546.44355229806, 6551.213854337486, 6630.4032637062655, 6591.406773390394, 6593.769218077368, 6604.702788374535, 6618.437432818217, 6618.470522358224, 6416.903746443807, 6431.549791514596, 6533.836654083639, 6564.9496080627105, 6565.080888675589, 5573.41479144692, 6121.571489995702, 6377.872442551123, 6380.266269379034, 6384.68076596173, 24231.151909683253, 24996.750032499625, 25232.873835630704, 25255.0, 25275.747242527523, 2636.6440530528325, 2863.3414314707525, 2866.512692842222, 2902.6516817981765, 2924.5613158026254, 15212.239388030554, 15256.771148432783, 15450.613734656612, 15584.298706558233, 16080.59798505035, 1967.6261510312995, 1973.6482151117339, 1997.7103320018662, 2005.9420549939102, 2041.8774873507637, 112312.99999999919, 142771.74156715625, 148221.0, 148952.0, 153218.0, 19087.61824763497, 19427.320043798398, 19964.60070798576, 19976.001199940234, 20216.191352346035, 30316.63575139124, 30376.392472150434, 30402.0, 30520.542191867138, 30525.847370763004, 9662.0, 9760.316777825485, 9926.255530835217, 10064.597416103274, 10111.292209545409, 8133.064697559738, 8200.26197642203, 8230.8353832923, 8364.498130112213, 8390.74138879173, 7749.7287594933905, 9185.851768528933, 9222.769430764285, 9260.768480788034, 9449.811003779885, 2995.9700402996127, 3181.681831816846, 3891.1905963727004, 3905.1603905160528, 4031.8387264509092, 1009.3144448770571, 1047.7531737232669, 1050.9947450262853, 1057.3338796558119, 1058.3226734889677, 19287.0, 20371.4907127323, 20443.08006139727, 20490.59018819645, 20667.8966605169, 1061.1669839176209, 1064.744461329291, 1068.6793961811425, 1096.6545538155456, 1107.9889201107885, 9953.601855925826, 10764.730881728019, 10784.86758890321, 10796.581946535543, 10807.0, 680278.0, 692686.0, 714268.0, 734376.0, 740212.1957560818, 108882.45558772313, 149940.50059499484, 151529.0, 152699.0, 154439.22780386027, 992.3847214726916, 1002.9498525073717, 1010.2372708604956, 1024.4314405504867, 1027.7841653252901, 18067.90966045214, 18156.0, 18282.725759113357, 18416.079196040413, 18494.0, 55603.443965559825, 55613.721931390086, 57843.42156578381, 57974.42025579859, 58688.0, 60133.0, 71477.21306967367, 74664.13339666552, 77742.0, 82267.58866205631, 10024.348417352849, 10918.454077295944, 11015.669529914116, 11310.717232069264, 11339.943300283776, 12435.0, 12585.811212831632, 12839.935800320938, 12869.549565765154, 13174.275414852274, 10484.0, 10810.29733067348, 11082.889171108185, 11101.333919964827, 11113.611023614134, 9949.950250248703, 10017.248706346907, 10076.596936122472, 10618.681439556825, 10951.890481095086, 10314.638987635397, 10593.0, 11138.610148644759, 11151.442427878736, 11202.887971120184, 9167.49578773174, 10016.449095299828, 11248.94375528117, 11299.265547739371, 11390.0, 5818.389069147848, 8367.33061355078, 10548.683539493828, 10700.464976750976, 10856.782864342827], "value": 10996.291937076654, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.9077255364673, 1561.570568093774, 1589.2927647196996, 1608.211976131695, 1609.4930097019437, 10374.844377334337, 10738.08726258267, 10958.561657533699, 11018.669439916805, 11044.0, 10813.566947436822, 10940.617078402258, 10949.562017519289, 10976.451177441137, 10978.615748448807, 2350.6944097267333, 2398.8560686358787, 2400.411899084727, 2400.7479214682467, 2406.554787364343, 2361.633946738258, 2363.5627408929404, 2402.8318017738766, 2408.169181632334, 2414.2998530426157, 2341.4497593065703, 2359.9292021239303, 2366.9112208384076, 2376.1089591403224, 2384.9284521464383, 2328.7787660172257, 2345.108858633717, 2362.799162071226, 2366.5621859955963, 2372.252740386781, 2328.6856274403076, 2364.929052128439, 2387.7612238776155, 2391.4380120671626, 2394.688690470245, 9483.33616646835, 10702.357858528514, 10896.618618348399, 10935.070519005894, 10937.4531273436, 5669.2346533218315, 5831.067029275296, 5833.854153646151, 5838.795642152547, 5855.092460668603, 3387.2717365766234, 3392.4402473591826, 3458.5849698036145, 3564.928701425983, 3593.2993066352074, 1171.6467480060544, 1178.675864137369, 1205.0961778666, 1231.2304809494067, 1237.2143688757615, 13472.865271347357, 13548.0, 13645.181289122684, 14195.858041419557, 14357.282135893269, 4191.266528357525, 4296.990207301282, 4415.381846541487, 4721.929171062437, 4728.763561821896, 11368.090552755759, 11373.374464404536, 11391.316521008766, 11422.942885285687, 11439.59961401347, 5660.349059858169, 5663.688497132614, 5669.1496275558175, 5677.858053548612, 5694.117411801178, 4585.220512512814, 5036.7733451994745, 5238.6472657499235, 5283.841484755387, 5300.602454815904, 3632.3824949758605, 3787.8295476703615, 3820.0, 3869.5163104611925, 3875.4961854958674, 919.4253591505309, 1017.1862509991977, 1051.447989805343, 1054.8575942247699, 1070.25617196048, 893.4326702543931, 902.2331018634109, 934.6775362499926, 951.8905325887473, 967.065868263473, 6411.294757576663, 6420.7431702731365, 6485.70814313357, 6495.415412612888, 6550.148480697574, 975.5024937281895, 977.8692743180784, 980.9705708828747, 1003.9297249192487, 1016.517154351677, 3965.8413663453716, 4002.4996875390575, 4095.5699651536756, 4135.400366946776, 4176.916461670749, 13238.07333486666, 13619.0, 13620.727585448236, 13640.0, 13719.588412347646, 13197.802032969514, 13412.12821166621, 13473.124246923919, 13599.52401665937, 13635.590932272047, 9788.755281117887, 9876.654317098866, 9939.155171810333, 10055.547500362502, 10064.748381290527, 5997.190379298739, 6248.0003199487865, 6256.405641464054, 6415.133956915756, 6510.381513756186, 11645.243059201124, 11916.821247681119, 11969.820452693042, 11992.280463172237, 12118.394080296126, 12328.137030407785, 12401.176411943356, 12422.347841719318, 12490.0, 12576.496940122659, 8914.242289405473, 9024.232940200156, 9039.0, 9147.582124770672, 9153.993060763443, 9775.75560610962, 9849.852252216366, 9879.506024698592, 9961.601535938482, 9982.650607229003, 8725.0, 8730.0, 8735.650573977224, 8775.210231079234, 8810.383273171074, 7039.768040592978, 8380.24577788002, 8451.1548845115, 8666.696665616926, 8693.739187824376, 7375.483716139819, 7551.660175292015, 7634.160242373446, 7636.465447418627, 7726.266004729542, 5083.161278389024, 5749.511291540266, 5791.797287094932, 5821.650700957956, 5878.529717622494, 5697.686627235542, 5897.3807750185615, 5917.082852157922, 5963.4334738199805, 5985.910211346921], "value": 5040.8906498446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.8415086984552, 375.4499658001028, 377.9357509223432, 382.85642883918524, 390.0949796472184, 4545.454545454546, 5256.266741294525, 5273.811368282168, 5349.411564727887, 5445.156000819879, 4483.394741709868, 5277.973610131944, 5329.493698098684, 5341.359036915565, 5393.649412788166, 4408.294672852344, 4482.932756008662, 4530.864074077766, 4666.626669866419, 4692.0, 4135.808021748463, 4237.894052648693, 4637.188492013901, 4691.671582989192, 4706.2705280681375, 3673.5959044505084, 4183.807614829774, 4512.202434490604, 4722.693024953384, 4756.334113224151, 4550.726956382611, 4552.430946131733, 4649.232876575361, 4717.386739723848, 4762.761861906908, 4414.470263568361, 4427.203103441379, 4427.579379958931, 4472.932906006413, 4505.873531617096, 5074.4671809460215, 5273.6572122811895, 5289.788408463657, 5291.888703372297, 5315.681059136464, 5093.388793344822, 5173.508516690946, 5423.0, 5474.7262636868, 5530.695811730353, 4467.798949047301, 5377.704226267554, 5467.699276539789, 5848.298204215522, 5855.707214639251, 4304.634106100985, 4829.291440845148, 4994.350734404504, 5016.423111342206, 5041.672291301091, 5612.382637909826, 5820.417958204188, 6071.939280607226, 6114.694265286762, 6119.663418511936, 4618.769061546909, 4725.976370118127, 4945.738836596644, 5117.590592752571, 5154.170269554329, 4563.771811409482, 4996.4503904570465, 5292.841214763564, 5327.188755823039, 5334.866628334245, 5244.318238628954, 5405.972970135124, 5426.375966763872, 5435.0488664483555, 5449.891002180014, 5335.3864305604575, 5701.600887937804, 5705.773258749306, 5711.543076553866, 5801.7389217485315, 1608.463916959301, 1679.2863033210836, 1694.2803054899287, 1729.792424909019, 1746.91243963465, 1263.5388083349512, 1310.905393996024, 1354.627477443691, 1400.4748219417718, 1401.6916278418732, 4719.4897632757875, 5138.665986710852, 5154.561862241751, 5173.63784535079, 5211.013574615422, 5230.63385563007, 5674.00704876645, 5736.77052917887, 5737.827865164052, 5761.251037365116, 4574.74838883858, 4613.877616296541, 4736.64475164364, 5157.535821776059, 5172.250023746536, 2616.6336712860216, 2940.3237255430945, 2960.874867550317, 2964.0, 2969.5545668149516, 5878.794242201502, 5903.852403689943, 5945.821625351246, 5989.802039592099, 5994.0409534474275, 5012.423571289349, 5395.271638328775, 5570.860728481739, 5986.820395388146, 6086.178365920633, 6014.6391216527145, 6078.54410919174, 6094.0, 6109.969450152721, 6112.847178820565, 5229.816956406507, 5250.868728281824, 5886.470217680258, 5930.258717660293, 5933.795750294965, 4945.933521216869, 5413.458654134555, 5728.341240757366, 5767.68277744728, 5778.075507918713, 4916.680415772963, 6056.878862422639, 6119.2350956130485, 6190.7833225838685, 6201.472874805511, 4032.4152997814567, 4492.932606010847, 4520.32195170727, 4658.277966915133, 4694.8309890822875, 5008.547521218806, 5600.299962504687, 5718.770464350185, 5898.139402389923, 5900.734466948912, 5401.731272865484, 5609.018421776092, 5692.007797270922, 5725.656460612377, 5739.5695322850115, 4554.772261386851, 5245.344331958505, 5618.297712785902, 5702.830919661543, 5736.512396446182, 4696.906061878812, 5498.680316724038, 5571.777128914798, 5573.554115670818, 5624.943750562606, 5399.730013499231, 5407.540359069523, 5692.800751973827, 5697.23087383198, 5703.771849125988, 4375.759333236702, 5500.0, 5502.80740174092, 5557.888422315634, 5589.692566908859], "value": 4468.582131526356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.1661382529894, 726.3825748114102, 744.5346658338539, 770.445279398833, 791.4578513718103, 14817.629559261024, 15665.765013524791, 16521.504354869343, 17253.568660783487, 17370.913145434257, 16716.498505044838, 16831.663366732657, 16875.57811054724, 16880.83119168808, 16894.07082610456, 940.5203346293406, 955.0640372435, 980.4901451245383, 981.40134517944, 982.219135787051, 850.8468475674376, 974.7465658928694, 994.3834822410116, 995.3182070281844, 995.8655581496514, 1040.7866387390566, 1051.3113910388709, 1054.13560880078, 1054.6361505280704, 1054.6730513540788, 1017.6845177994845, 1033.2870319479534, 1047.287844265899, 1047.3035431438084, 1049.5486940615533, 707.3704403081261, 760.9467337286392, 780.1769133564088, 781.1173374087246, 781.2460975158942, 12591.433385497674, 15581.454649087227, 15908.522744317688, 16089.436869709622, 16167.353305867751, 14458.024043388048, 14606.488772892897, 14793.11241325524, 14810.037347572374, 14859.777103343458, 11675.474603642771, 11753.23603965748, 11875.620902046425, 11955.342456164908, 12228.816567751492, 3444.225049363898, 3445.3970555152746, 3470.514128022079, 3548.414511605582, 3577.94633080504, 100684.0, 104442.0, 105805.94194058038, 106020.0, 107920.4603976975, 10291.53688084038, 10982.0, 11143.832842507369, 11603.883961160449, 11755.529778808752, 24258.87870560636, 24314.392140196636, 24603.63094553583, 24664.13675521384, 24772.00911963501, 7153.964230178815, 7683.884741728879, 7733.994580704585, 7821.256980586835, 7909.920900791033, 4140.751554906655, 4216.241076606179, 4253.8723838284905, 4263.126059157865, 4291.592298731615, 3050.3746731919905, 3484.2160513884173, 3511.5786105667485, 3517.6306487818924, 3525.823708814549, 584.8450160707416, 620.0141774578442, 620.2340109964191, 636.1253276744476, 643.1092936283281, 600.8858316919772, 656.9310222426672, 678.9388954993975, 700.9824754381182, 721.8267615772177, 7654.617269136521, 8929.33030022751, 9138.949020862552, 9557.358559937187, 9721.416714997124, 530.3476723629957, 546.7157078319332, 555.6166245290817, 557.7378632042934, 578.7974209026806, 4403.911921761547, 4422.5356337584735, 4660.6970546914345, 4711.937836602522, 4766.666333356632, 94911.0, 95633.0, 96810.51594741982, 99531.0, 100576.49135262978, 85478.57260713782, 87405.0, 88494.67257991135, 89012.10987890168, 89589.0, 58840.82318353609, 60230.49592452193, 63906.36093639097, 67445.66277168583, 77175.0, 10633.574657013633, 10643.787124257626, 10742.624008159677, 10889.0, 10908.672739817817, 34665.82667086648, 42197.789011055975, 46011.53988460072, 47518.287225690954, 49680.75798105076, 55016.72491637676, 59526.404735952085, 59595.0, 66510.66978660497, 67297.0, 12501.93749031249, 12579.167496514492, 12628.621141365405, 12753.684042148923, 13067.147032697612, 11129.833052504056, 11538.94230528842, 11677.182597218112, 12014.879851201376, 13846.792298115335, 13154.539591114266, 13347.08808021598, 13547.390367433687, 13554.457821687021, 13996.0, 13133.146345487512, 13680.315984200552, 13806.171629701848, 13940.233287169303, 14115.0, 12753.362331883554, 13154.0, 13451.991100667685, 13950.302484875918, 14238.501652442115, 10230.407430193109, 10296.227782916161, 10905.509252083517, 11059.668209953714, 11575.536978520764, 9163.541822908695, 9507.809843802946, 11212.55149793999, 11231.382273974683, 11237.7752444949], "value": 7498.202808649287, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 121.0, 120.0, 120.0, 97.0, 115.0, 147.0, 111.0, 169.0, 171.0], "value": 129.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 127.0, 110.0, 145.0, 105.0, 143.0, 101.0, 104.0, 122.0, 119.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 216.0, 201.0, 195.0, 247.0, 199.0, 193.0, 198.0, 196.0, 196.0], "value": 205.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 77.0, 80.0, 98.0, 60.0, 66.0, 59.0, 63.0, 75.0, 78.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 110.0, 99.0, 98.0, 76.0, 81.0, 71.0, 70.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 105.0, 92.0, 125.0, 96.0, 126.0, 127.0, 90.0, 89.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 105.0, 97.0, 98.0, 96.0, 100.0, 112.0, 98.0, 97.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 124.0, 92.0, 139.0, 136.0, 90.0, 91.0, 92.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 66.0, 67.0, 66.0, 65.0, 67.0, 65.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 63.0, 65.0, 65.0, 59.0, 57.0, 56.0, 58.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 104.0, 104.0, 100.0, 88.0, 84.0, 93.0, 88.0, 86.0, 90.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 171.0, 150.0, 154.0, 147.0, 144.0, 148.0, 182.0, 150.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 171.0, 175.0, 176.0, 175.0, 175.0, 176.0, 173.0, 173.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 67.0, 69.0, 68.0, 67.0, 69.0, 66.0, 69.0], "value": 67.9, "unit": "score"}]}]} 06:49:48 INFO - Return code: 0 06:49:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:49:48 ERROR - # TBPL SUCCESS # 06:49:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:48 INFO - 2017-03-02 06:49:48.770 screenresolution[1668:10764] starting screenresolution argv=screenresolution get 06:49:48 INFO - 2017-03-02 06:49:48.772 screenresolution[1668:10764] Display 0: 1600x1200x32@60 06:49:48 INFO - 2017-03-02 06:49:48.779 screenresolution[1669:10767] starting screenresolution argv=screenresolution list 06:49:48 INFO - Available Modes on Display 0 06:49:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:49 INFO - Intel Iris: 06:49:49 INFO - Chipset Model: Intel Iris 06:49:49 INFO - Type: GPU 06:49:49 INFO - Bus: Built-In 06:49:49 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:49 INFO - Vendor: Intel (0x8086) 06:49:49 INFO - Device ID: 0x0a2e 06:49:49 INFO - Revision ID: 0x0009 06:49:49 INFO - Displays: 06:49:49 INFO - Display: 06:49:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:49 INFO - Display Serial Number: 1600x1200 60 06:49:49 INFO - Main Display: Yes 06:49:49 INFO - Mirror: Off 06:49:49 INFO - Online: Yes 06:49:49 INFO - Rotation: Supported 06:49:49 INFO - Return code: 0 06:49:49 INFO - Running post-action listener: _resource_record_post_action 06:49:49 INFO - [mozharness: 2017-03-02 14:49:49.012409Z] Finished run-tests step (success) 06:49:49 INFO - Running post-run listener: _resource_record_post_run 06:49:49 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38959616; Write bytes: 515895296; Read time: 247; Write time: 1614 06:49:49 INFO - TinderboxPrint: CPU usage
55.2% 06:49:49 INFO - TinderboxPrint: I/O read bytes / time
38,959,616 / 247 06:49:49 INFO - TinderboxPrint: I/O write bytes / time
515,895,296 / 1,614 06:49:49 INFO - TinderboxPrint: CPU idle
2,150.4 (44.8%) 06:49:49 INFO - TinderboxPrint: CPU system
974.3 (20.3%) 06:49:49 INFO - TinderboxPrint: CPU user
1,676.9 (34.9%) 06:49:49 INFO - TinderboxPrint: Swap in / out
250,028,032 / 0 06:49:49 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206338560; Write bytes: 203425792; Read time: 15633; Write time: 420 06:49:49 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 36936192; Write bytes: 301733888; Read time: 227; Write time: 1161 06:49:49 INFO - Running post-run listener: _upload_blobber_files 06:49:49 INFO - Blob upload gear active. 06:49:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:49:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:49:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:49:49 INFO - (blobuploader) - INFO - Open directory for files ... 06:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:51 INFO - (blobuploader) - INFO - Done attempting. 06:49:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:49:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:51 INFO - (blobuploader) - INFO - Done attempting. 06:49:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:49:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:52 INFO - (blobuploader) - INFO - Done attempting. 06:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:55 INFO - (blobuploader) - INFO - Done attempting. 06:49:55 INFO - (blobuploader) - INFO - Iteration through files over. 06:49:55 INFO - Return code: 0 06:49:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:49:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:49:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a70761a1eefd8b5c4eae076f27ea0c02ba8130951a251e533f8eb253c6ac0c9a5263f427f83325f041fe70b13bfea612f33cc06a23aee964ae027d33566487dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b78d218bbd410b824e18eb868bbe51c1c2991c14ec6451f5afb2020a1940ae9a02dfafab154253bac13fa648583057a14fc8049d2d0e5f372818b0406a3f95ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e34375f8adc731d19db10270967bbe8c4bffd2279d695844102d4792443afc944acba3dd86e12b03465cc3932d5e40f5dba43b76ab320756d355137b08ba6a86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fad2d613697f5f444e9c136c08655c4fddcba504c85eb940b043df114b39a824a3dde471fd83bd491cc5be87e1a414ccaeb1a95a5f1a36ada7433fac1459942a"} 06:49:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:49:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:49:55 INFO - Contents: 06:49:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a70761a1eefd8b5c4eae076f27ea0c02ba8130951a251e533f8eb253c6ac0c9a5263f427f83325f041fe70b13bfea612f33cc06a23aee964ae027d33566487dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b78d218bbd410b824e18eb868bbe51c1c2991c14ec6451f5afb2020a1940ae9a02dfafab154253bac13fa648583057a14fc8049d2d0e5f372818b0406a3f95ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e34375f8adc731d19db10270967bbe8c4bffd2279d695844102d4792443afc944acba3dd86e12b03465cc3932d5e40f5dba43b76ab320756d355137b08ba6a86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fad2d613697f5f444e9c136c08655c4fddcba504c85eb940b043df114b39a824a3dde471fd83bd491cc5be87e1a414ccaeb1a95a5f1a36ada7433fac1459942a"} 06:49:55 INFO - Running post-run listener: copy_logs_to_upload_dir 06:49:55 INFO - Copying logs to upload dir... 06:49:55 INFO - mkdir: /builds/slave/test/build/upload/logs 06:49:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.393766 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-02 06:49:55.374165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 06:49:55.377569) ========= 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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a70761a1eefd8b5c4eae076f27ea0c02ba8130951a251e533f8eb253c6ac0c9a5263f427f83325f041fe70b13bfea612f33cc06a23aee964ae027d33566487dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b78d218bbd410b824e18eb868bbe51c1c2991c14ec6451f5afb2020a1940ae9a02dfafab154253bac13fa648583057a14fc8049d2d0e5f372818b0406a3f95ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e34375f8adc731d19db10270967bbe8c4bffd2279d695844102d4792443afc944acba3dd86e12b03465cc3932d5e40f5dba43b76ab320756d355137b08ba6a86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fad2d613697f5f444e9c136c08655c4fddcba504c85eb940b043df114b39a824a3dde471fd83bd491cc5be87e1a414ccaeb1a95a5f1a36ada7433fac1459942a"} build_url:https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010829 build_url: 'https://queue.taskcluster.net/v1/task/JH0oU-v-Qfyv9KBHFfixnw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a70761a1eefd8b5c4eae076f27ea0c02ba8130951a251e533f8eb253c6ac0c9a5263f427f83325f041fe70b13bfea612f33cc06a23aee964ae027d33566487dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b78d218bbd410b824e18eb868bbe51c1c2991c14ec6451f5afb2020a1940ae9a02dfafab154253bac13fa648583057a14fc8049d2d0e5f372818b0406a3f95ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e34375f8adc731d19db10270967bbe8c4bffd2279d695844102d4792443afc944acba3dd86e12b03465cc3932d5e40f5dba43b76ab320756d355137b08ba6a86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fad2d613697f5f444e9c136c08655c4fddcba504c85eb940b043df114b39a824a3dde471fd83bd491cc5be87e1a414ccaeb1a95a5f1a36ada7433fac1459942a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 06:49:55.411707) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:49:55.412054) ========= 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.wxt8sAfLXo/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.Qm1pBXci0z/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.004985 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 06:49:55.461256) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 06:49:55.461603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 06:49:55.461941) ========= ========= Total master_lag: 0.18 =========