builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0246 starttime: 1493915849.14 results: success (0) buildid: 20170504080520 builduid: 0bfb350148674723b6f03a0744eb8853 revision: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.142125) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.142629) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.142903) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.173664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.174217) ========= 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.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-04 09:37:29-- 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% 157M=0s 2017-05-04 09:37:29 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125615 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.327823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.328385) ========= 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.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.027003 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:29.385134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 09:37:29.385478) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a --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 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-04 09:37:29,493 truncating revision to first 12 chars 2017-05-04 09:37:29,493 Setting DEBUG logging. 2017-05-04 09:37:29,493 attempt 1/10 2017-05-04 09:37:29,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6d4525b5aaa8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 09:37:30,567 unpacking tar archive at: mozilla-central-6d4525b5aaa8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.357592 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 09:37:30.768121) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.768611) ========= script_repo_revision: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.769062) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.769361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 09:37:30.829407) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-04 09:37:30.829800) ========= /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.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:37:31 INFO - MultiFileLogger online at 20170504 09:37:31 in /builds/slave/test 09:37:31 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 09:37:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:31 INFO - {'append_to_log': False, 09:37:31 INFO - 'base_work_dir': '/builds/slave/test', 09:37:31 INFO - 'blob_upload_branch': 'Firefox', 09:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:31 INFO - 'branch': 'Firefox', 09:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:31 INFO - 'config_files': ('talos/mac_config.py',), 09:37:31 INFO - 'default_actions': ('clobber', 09:37:31 INFO - 'read-buildbot-config', 09:37:31 INFO - 'download-and-extract', 09:37:31 INFO - 'populate-webroot', 09:37:31 INFO - 'create-virtualenv', 09:37:31 INFO - 'install', 09:37:31 INFO - 'run-tests'), 09:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:31 INFO - 'download_minidump_stackwalk': True, 09:37:31 INFO - 'download_symbols': 'ondemand', 09:37:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:31 INFO - 'gecko_profile': False, 09:37:31 INFO - 'gecko_profile_interval': 0, 09:37:31 INFO - 'installer_path': 'installer.exe', 09:37:31 INFO - 'log_level': 'info', 09:37:31 INFO - 'log_name': 'talos', 09:37:31 INFO - 'log_to_console': True, 09:37:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:37:31 INFO - 'opt_config_files': (), 09:37:31 INFO - 'pip_index': False, 09:37:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:31 INFO - 'cmd': ('bash', 09:37:31 INFO - '-c', 09:37:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:31 INFO - 'enabled': True, 09:37:31 INFO - 'halt_on_failure': False, 09:37:31 INFO - 'name': 'check_screen_resolution'},), 09:37:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:31 INFO - 'cmd': ('bash', 09:37:31 INFO - '-c', 09:37:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:31 INFO - 'enabled': True, 09:37:31 INFO - 'halt_on_failure': False, 09:37:31 INFO - 'name': 'check_screen_resolution'},), 09:37:31 INFO - 'run_cmd_checks_enabled': True, 09:37:31 INFO - 'suite': 'dromaeojs-e10s', 09:37:31 INFO - 'system_bits': '32', 09:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:31 INFO - 'title': 't-yosemite-r7-0246', 09:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:31 INFO - 'use_talos_json': True, 09:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:31 INFO - 'work_dir': 'build'} 09:37:31 INFO - [mozharness: 2017-05-04 16:37:31.065198Z] Running clobber step. 09:37:31 INFO - Running pre-action listener: _resource_record_pre_action 09:37:31 INFO - Running main action method: clobber 09:37:31 INFO - rmtree: /builds/slave/test/build 09:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:33 INFO - Running post-action listener: _resource_record_post_action 09:37:33 INFO - [mozharness: 2017-05-04 16:37:33.533575Z] Finished clobber step (success) 09:37:33 INFO - [mozharness: 2017-05-04 16:37:33.533740Z] Running read-buildbot-config step. 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: read_buildbot_config 09:37:33 INFO - Using buildbot properties: 09:37:33 INFO - { 09:37:33 INFO - "project": "", 09:37:33 INFO - "product": "firefox", 09:37:33 INFO - "script_repo_revision": "production", 09:37:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:37:33 INFO - "repository": "", 09:37:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:37:33 INFO - "buildid": "20170504080520", 09:37:33 INFO - "pgo_build": "False", 09:37:33 INFO - "basedir": "/builds/slave/test", 09:37:33 INFO - "buildnumber": 66, 09:37:33 INFO - "slavename": "t-yosemite-r7-0246", 09:37:33 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:37:33 INFO - "platform": "yosemite_r7", 09:37:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:37:33 INFO - "branch": "mozilla-central", 09:37:33 INFO - "repo_path": "mozilla-central", 09:37:33 INFO - "revision": "6d4525b5aaa834ea4db8d8eab8087d86e34cf57a", 09:37:33 INFO - "stage_platform": "macosx64", 09:37:33 INFO - "builduid": "0bfb350148674723b6f03a0744eb8853", 09:37:33 INFO - "slavebuilddir": "test" 09:37:33 INFO - } 09:37:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:37:33 INFO - Running post-action listener: _resource_record_post_action 09:37:33 INFO - [mozharness: 2017-05-04 16:37:33.537273Z] Finished read-buildbot-config step (success) 09:37:33 INFO - [mozharness: 2017-05-04 16:37:33.537386Z] Running download-and-extract step. 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: download_and_extract 09:37:33 INFO - mkdir: /builds/slave/test/build/tests 09:37:33 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')]} 09:37:33 INFO - trying https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:37:33 INFO - Downloading https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:37:34 INFO - Downloaded 1325 bytes. 09:37:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:37:34 INFO - Using the following test package requirements: 09:37:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:37:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:37:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:37:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:37:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'jsshell-mac.zip'], 09:37:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:37:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:37:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:37:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:37:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:37:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:37:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:37:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:36 INFO - Content-Length response header: 52700983 09:37:36 INFO - Bytes received: 52700983 09:37:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:37:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:52 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:54 INFO - Content-Length response header: 52700983 09:37:54 INFO - Bytes received: 52700983 09:38:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:38:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:38:10 INFO - Fetch https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:38:11 INFO - Content-Length response header: 14408809 09:38:11 INFO - Bytes received: 14408809 09:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:11 INFO - trying https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:11 INFO - Downloading https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:38:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:38:13 INFO - Downloaded 70878346 bytes. 09:38:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:13 INFO - mkdir: /builds/slave/test/properties 09:38:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:13 INFO - Contents: 09:38:13 INFO - build_url:https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:38:14 INFO - Running post-action listener: _resource_record_post_action 09:38:14 INFO - Running post-action listener: set_extra_try_arguments 09:38:14 INFO - [mozharness: 2017-05-04 16:38:14.584930Z] Finished download-and-extract step (success) 09:38:14 INFO - [mozharness: 2017-05-04 16:38:14.585079Z] Running populate-webroot step. 09:38:14 INFO - Running pre-action listener: _resource_record_pre_action 09:38:14 INFO - Running main action method: populate_webroot 09:38:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:14 INFO - '--tppagecycles', 09:38:14 INFO - '7'], 09:38:14 INFO - 'tests': ['tsvgm']}}, 09:38:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['damp', 'tps']}, 09:38:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'tests': ['damp', 'tps']}, 09:38:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['dromaeo_dom']}, 09:38:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:38:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:38:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['a11yr', 09:38:14 INFO - 'ts_paint', 09:38:14 INFO - 'tpaint', 09:38:14 INFO - 'sessionrestore', 09:38:14 INFO - 'sessionrestore_no_auto_restore', 09:38:14 INFO - 'tabpaint']}, 09:38:14 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:38:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:38:14 INFO - 'bloom_basic_ref']}, 09:38:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['tsvgx', 09:38:14 INFO - 'tsvgr_opacity', 09:38:14 INFO - 'tart', 09:38:14 INFO - 'tscrollx', 09:38:14 INFO - 'cart', 09:38:14 INFO - 'tsvg_static']}, 09:38:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:14 INFO - 'tsvgr_opacity', 09:38:14 INFO - 'tart', 09:38:14 INFO - 'tscrollx', 09:38:14 INFO - 'cart', 09:38:14 INFO - 'tsvg_static']}, 09:38:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'talos_options': ['--disable-e10s'], 09:38:14 INFO - 'tests': ['tp5o']}, 09:38:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:38:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'talos_options': ['--disable-e10s', 09:38:14 INFO - '--xperf_path', 09:38:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:14 INFO - 'tests': ['tp5n']}, 09:38:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:38:14 INFO - 'talos_options': ['--xperf_path', 09:38:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:14 INFO - 'tests': ['tp5n']}}, 09:38:14 INFO - 'talos.zip': {'path': '', 09:38:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:14 INFO - Running post-action listener: _resource_record_post_action 09:38:14 INFO - [mozharness: 2017-05-04 16:38:14.604813Z] Finished populate-webroot step (success) 09:38:14 INFO - [mozharness: 2017-05-04 16:38:14.604953Z] Running create-virtualenv step. 09:38:14 INFO - Running pre-action listener: _resource_record_pre_action 09:38:14 INFO - Running main action method: create_virtualenv 09:38:14 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:14 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 09:38:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:14 INFO - Using real prefix '/tools/python27' 09:38:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:18 INFO - Installing pip.................done. 09:38:18 INFO - Return code: 0 09:38:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:38:18 INFO - Reading from file tmpfile_stdout 09:38:18 INFO - Output received: 09:38:18 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) 09:38:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:18 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 09:38:18 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 09:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:18 INFO - 'HOME': '/Users/cltbld', 09:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:18 INFO - 'LOGNAME': 'cltbld', 09:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:18 INFO - 'MOZ_NO_REMOTE': '1', 09:38:18 INFO - 'NO_EM_RESTART': '1', 09:38:18 INFO - 'PAGER': '/bin/cat', 09:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:18 INFO - 'PWD': '/builds/slave/test', 09:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:18 INFO - 'SHELL': '/bin/bash', 09:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:18 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:18 INFO - 'USER': 'cltbld', 09:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:18 INFO - 'XPC_FLAGS': '0x0', 09:38:18 INFO - 'XPC_SERVICE_NAME': '0', 09:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38: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 09:38:19 INFO - Cleaning up... 09:38:19 INFO - Return code: 0 09:38:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38: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 09:38: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 09:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 INFO - Downloading/unpacking psutil>=3.1.1 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:22 INFO - Installing collected packages: psutil 09:38:22 INFO - Running setup.py install for psutil 09:38:22 INFO - building 'psutil._psutil_osx' extension 09:38:22 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 09:38: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 09:38: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 09:38: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 09:38:23 INFO - building 'psutil._psutil_posix' extension 09:38: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 09:38:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:23 INFO - ^ 09:38:23 INFO - 1 warning generated. 09:38:23 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 09:38:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:24 INFO - Successfully installed psutil 09:38:24 INFO - Cleaning up... 09:38:24 INFO - Return code: 0 09:38:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38: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 09:38: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 09:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:24 INFO - 'HOME': '/Users/cltbld', 09:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:24 INFO - 'LOGNAME': 'cltbld', 09:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:24 INFO - 'MOZ_NO_REMOTE': '1', 09:38:24 INFO - 'NO_EM_RESTART': '1', 09:38:24 INFO - 'PAGER': '/bin/cat', 09:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:24 INFO - 'PWD': '/builds/slave/test', 09:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:24 INFO - 'SHELL': '/bin/bash', 09:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:24 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:24 INFO - 'USER': 'cltbld', 09:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:24 INFO - 'XPC_FLAGS': '0x0', 09:38:24 INFO - 'XPC_SERVICE_NAME': '0', 09:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:25 INFO - Installing collected packages: mozsystemmonitor 09:38:25 INFO - Running setup.py install for mozsystemmonitor 09:38:25 INFO - Successfully installed mozsystemmonitor 09:38:25 INFO - Cleaning up... 09:38:25 INFO - Return code: 0 09:38:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:25 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')]} 09:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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')]} 09:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:25 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 09:38:25 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 09:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:25 INFO - 'HOME': '/Users/cltbld', 09:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PAGER': '/bin/cat', 09:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:25 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - 'XPC_FLAGS': '0x0', 09:38:25 INFO - 'XPC_SERVICE_NAME': '0', 09:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:26 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:26 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 09:38:26 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 09:38:26 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 09:38:26 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 09:38:26 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 09:38:26 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 09:38:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:27 INFO - warning: no files found matching '*.txt' 09:38:27 INFO - no previously-included directories found matching 'build' 09:38:27 INFO - no previously-included directories found matching 'dist' 09:38:27 INFO - no previously-included directories found matching '.git*' 09:38:27 INFO - Installing collected packages: jsonschema, functools32 09:38:27 INFO - Running setup.py install for functools32 09:38:27 INFO - warning: no files found matching '*.txt' 09:38:27 INFO - no previously-included directories found matching 'build' 09:38:27 INFO - no previously-included directories found matching 'dist' 09:38:27 INFO - no previously-included directories found matching '.git*' 09:38:28 INFO - Successfully installed jsonschema functools32 09:38:28 INFO - Cleaning up... 09:38:28 INFO - Return code: 0 09:38:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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', '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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:38: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 functools32==3.2.3-2 09:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:28 INFO - 'HOME': '/Users/cltbld', 09:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:28 INFO - 'LOGNAME': 'cltbld', 09:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:28 INFO - 'MOZ_NO_REMOTE': '1', 09:38:28 INFO - 'NO_EM_RESTART': '1', 09:38:28 INFO - 'PAGER': '/bin/cat', 09:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:28 INFO - 'PWD': '/builds/slave/test', 09:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:28 INFO - 'SHELL': '/bin/bash', 09:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:28 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:28 INFO - 'USER': 'cltbld', 09:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:28 INFO - 'XPC_FLAGS': '0x0', 09:38:28 INFO - 'XPC_SERVICE_NAME': '0', 09:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:38:28 INFO - Cleaning up... 09:38:28 INFO - Return code: 0 09:38:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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', '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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:38: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 blobuploader==1.2.4 09:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:28 INFO - 'HOME': '/Users/cltbld', 09:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:28 INFO - 'LOGNAME': 'cltbld', 09:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:28 INFO - 'MOZ_NO_REMOTE': '1', 09:38:28 INFO - 'NO_EM_RESTART': '1', 09:38:28 INFO - 'PAGER': '/bin/cat', 09:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:28 INFO - 'PWD': '/builds/slave/test', 09:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:28 INFO - 'SHELL': '/bin/bash', 09:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:28 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:28 INFO - 'USER': 'cltbld', 09:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:28 INFO - 'XPC_FLAGS': '0x0', 09:38:28 INFO - 'XPC_SERVICE_NAME': '0', 09:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Downloading docopt-0.6.1.tar.gz 09:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:30 INFO - Running setup.py install for blobuploader 09:38:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:30 INFO - Running setup.py install for requests 09:38:31 INFO - Running setup.py install for docopt 09:38:31 INFO - Successfully installed blobuploader requests docopt 09:38:31 INFO - Cleaning up... 09:38:31 INFO - Return code: 0 09:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:31 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:31 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 09:38:31 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 09:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:31 INFO - 'HOME': '/Users/cltbld', 09:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:31 INFO - 'LOGNAME': 'cltbld', 09:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:31 INFO - 'MOZ_NO_REMOTE': '1', 09:38:31 INFO - 'NO_EM_RESTART': '1', 09:38:31 INFO - 'PAGER': '/bin/cat', 09:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:31 INFO - 'PWD': '/builds/slave/test', 09:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:31 INFO - 'SHELL': '/bin/bash', 09:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:31 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:31 INFO - 'USER': 'cltbld', 09:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:31 INFO - 'XPC_FLAGS': '0x0', 09:38:31 INFO - 'XPC_SERVICE_NAME': '0', 09:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-4fE8ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ra7FvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Xnsssp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-IOvaIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-X_hFV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-9_nsLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-2SwgLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DMVy4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aAMVzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:32 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-P2PV2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-dq7j6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-dCHiOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-40jTJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-UmhG4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-VwuWEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-BKsTc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:33 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-sgvlTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:33 INFO - Running setup.py install for manifestparser 09:38:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:33 INFO - Running setup.py install for mozcrash 09:38:34 INFO - Running setup.py install for mozdebug 09:38:34 INFO - Running setup.py install for mozdevice 09:38:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozfile 09:38:34 INFO - Running setup.py install for mozhttpd 09:38:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozinfo 09:38:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozInstall 09:38:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:34 INFO - Running setup.py install for mozleak 09:38:34 INFO - Running setup.py install for mozlog 09:38:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for moznetwork 09:38:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozprocess 09:38:35 INFO - Running setup.py install for mozprofile 09:38:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozrunner 09:38:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:35 INFO - Running setup.py install for mozscreenshot 09:38:35 INFO - Running setup.py install for moztest 09:38:36 INFO - Running setup.py install for mozversion 09:38:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:36 INFO - Cleaning up... 09:38:36 INFO - Return code: 0 09:38:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:36 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')]} 09:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:36 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')]} 09:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:36 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:36 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 09:38:36 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 09:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:36 INFO - 'HOME': '/Users/cltbld', 09:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:36 INFO - 'LOGNAME': 'cltbld', 09:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:36 INFO - 'MOZ_NO_REMOTE': '1', 09:38:36 INFO - 'NO_EM_RESTART': '1', 09:38:36 INFO - 'PAGER': '/bin/cat', 09:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:36 INFO - 'PWD': '/builds/slave/test', 09:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:36 INFO - 'SHELL': '/bin/bash', 09:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:36 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:36 INFO - 'USER': 'cltbld', 09:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:36 INFO - 'XPC_FLAGS': '0x0', 09:38:36 INFO - 'XPC_SERVICE_NAME': '0', 09:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:36 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-FJ6qxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:36 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)) 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:36 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-p0CD5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:36 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)) 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:36 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nCHEPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:36 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)) 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:36 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-dg_FU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:36 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-5pk1RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-OLmjft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DPQZf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ft8xAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_Tqday-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-wTxe7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-So5JB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1UKZMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-28MGqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:37 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)) 09:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:37 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Fl5cEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:38 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)) 09:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:38 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-9CoZhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:38 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)) 09:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:38 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Fu5ntC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:38 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)) 09:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:38 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-2LO0Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:38 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)) 09:38:38 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)) 09:38:38 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)) 09:38:38 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)) 09:38:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:39 INFO - Downloading blessings-1.6.tar.gz 09:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:39 INFO - Installing collected packages: blessings 09:38:39 INFO - Running setup.py install for blessings 09:38:39 INFO - Successfully installed blessings 09:38:39 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:40 INFO - Reading from file tmpfile_stdout 09:38:40 INFO - Current package versions: 09:38:40 INFO - blessings == 1.6 09:38:40 INFO - blobuploader == 1.2.4 09:38:40 INFO - docopt == 0.6.1 09:38:40 INFO - functools32 == 3.2.3-2 09:38:40 INFO - jsonschema == 2.5.1 09:38:40 INFO - manifestparser == 1.1 09:38:40 INFO - mozInstall == 1.12 09:38:40 INFO - mozcrash == 1.0 09:38:40 INFO - mozdebug == 0.1 09:38:40 INFO - mozdevice == 0.50 09:38:40 INFO - mozfile == 1.2 09:38:40 INFO - mozhttpd == 0.7 09:38:40 INFO - mozinfo == 0.9 09:38:40 INFO - mozleak == 0.1 09:38:40 INFO - mozlog == 3.4 09:38:40 INFO - moznetwork == 0.27 09:38:40 INFO - mozprocess == 0.25 09:38:40 INFO - mozprofile == 0.28 09:38:40 INFO - mozrunner == 6.13 09:38:40 INFO - mozscreenshot == 0.1 09:38:40 INFO - mozsystemmonitor == 0.3 09:38:40 INFO - moztest == 0.8 09:38:40 INFO - mozversion == 1.4 09:38:40 INFO - psutil == 3.1.1 09:38:40 INFO - requests == 1.2.3 09:38:40 INFO - wsgiref == 0.1.2 09:38:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:40 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 09:38:40 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 09:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:40 INFO - 'HOME': '/Users/cltbld', 09:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:40 INFO - 'LOGNAME': 'cltbld', 09:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:40 INFO - 'MOZ_NO_REMOTE': '1', 09:38:40 INFO - 'NO_EM_RESTART': '1', 09:38:40 INFO - 'PAGER': '/bin/cat', 09:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:40 INFO - 'PWD': '/builds/slave/test', 09:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:40 INFO - 'SHELL': '/bin/bash', 09:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:40 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:40 INFO - 'USER': 'cltbld', 09:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:40 INFO - 'XPC_FLAGS': '0x0', 09:38:40 INFO - 'XPC_SERVICE_NAME': '0', 09:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 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)) 09:38:40 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:40 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 0x10c97db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c980768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1a0571a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, '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.6PPDBlrkIH/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.gu6QXBklRI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 09:38:40 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 09:38:40 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 09:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:40 INFO - 'HOME': '/Users/cltbld', 09:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:40 INFO - 'LOGNAME': 'cltbld', 09:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:40 INFO - 'MOZ_NO_REMOTE': '1', 09:38:40 INFO - 'NO_EM_RESTART': '1', 09:38:40 INFO - 'PAGER': '/bin/cat', 09:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:40 INFO - 'PWD': '/builds/slave/test', 09:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:40 INFO - 'SHELL': '/bin/bash', 09:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:38:40 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:40 INFO - 'USER': 'cltbld', 09:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:40 INFO - 'XPC_FLAGS': '0x0', 09:38:40 INFO - 'XPC_SERVICE_NAME': '0', 09:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:40 INFO - Cleaning up... 09:38:40 INFO - Return code: 0 09:38:40 INFO - Running post-action listener: _resource_record_post_action 09:38:40 INFO - Running post-action listener: _start_resource_monitoring 09:38:40 INFO - Starting resource monitoring. 09:38:40 INFO - [mozharness: 2017-05-04 16:38:40.873994Z] Finished create-virtualenv step (success) 09:38:40 INFO - [mozharness: 2017-05-04 16:38:40.874947Z] Running install step. 09:38:40 INFO - Running pre-action listener: _resource_record_pre_action 09:38:40 INFO - Running main action method: install 09:38:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:41 INFO - Reading from file tmpfile_stdout 09:38:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:41 INFO - Reading from file tmpfile_stdout 09:38:41 INFO - Output received: 09:38:41 INFO - Usage: mozinstall [options] installer 09:38:41 INFO - Options: 09:38:41 INFO - -h, --help show this help message and exit 09:38:41 INFO - -d DEST, --destination=DEST 09:38:41 INFO - Directory to install application into. [default: 09:38:41 INFO - "/builds/slave/test"] 09:38:41 INFO - --app=APP Application being installed. [default: firefox] 09:38:41 INFO - mkdir: /builds/slave/test/build/application 09:38:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:39:00 INFO - Reading from file tmpfile_stdout 09:39:00 INFO - Output received: 09:39:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:39:00 INFO - Running post-action listener: _resource_record_post_action 09:39:00 INFO - [mozharness: 2017-05-04 16:39:00.787739Z] Finished install step (success) 09:39:00 INFO - [mozharness: 2017-05-04 16:39:00.787855Z] Running run-tests step. 09:39:00 INFO - Running pre-action listener: _resource_record_pre_action 09:39:00 INFO - Running main action method: run_tests 09:39:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:00 INFO - 2017-05-04 09:39:00.802 screenresolution[1626:7836] starting screenresolution argv=screenresolution get 09:39:00 INFO - 2017-05-04 09:39:00.804 screenresolution[1626:7836] Display 0: 1600x1200x32@60 09:39:00 INFO - 2017-05-04 09:39:00.810 screenresolution[1627:7838] starting screenresolution argv=screenresolution list 09:39:00 INFO - Available Modes on Display 0 09:39:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:01 INFO - Intel Iris: 09:39:01 INFO - Chipset Model: Intel Iris 09:39:01 INFO - Type: GPU 09:39:01 INFO - Bus: Built-In 09:39:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:01 INFO - Vendor: Intel (0x8086) 09:39:01 INFO - Device ID: 0x0a2e 09:39:01 INFO - Revision ID: 0x0009 09:39:01 INFO - Displays: 09:39:01 INFO - Display: 09:39:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:01 INFO - Display Serial Number: 1600x1200 60 09:39:01 INFO - Main Display: Yes 09:39:01 INFO - Mirror: Off 09:39:01 INFO - Online: Yes 09:39:01 INFO - Rotation: Supported 09:39:01 INFO - Return code: 0 09:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:01 INFO - Python 2.7.3 09:39:01 INFO - Return code: 0 09:39:01 INFO - grabbing minidump binary from tooltool 09:39:01 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')]} 09:39:01 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 0x10c75bd30>, '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 0x10c681db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c750be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:01 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 09:39:01 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 09:39:01 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 09:39:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:01 INFO - Return code: 0 09:39:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:01 INFO - ENV: RUST_BACKTRACE is now 1 09:39:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 09:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 09:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6PPDBlrkIH/Render', 09:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:01 INFO - 'HOME': '/Users/cltbld', 09:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:01 INFO - 'LOGNAME': 'cltbld', 09:39:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:01 INFO - 'MOZ_NO_REMOTE': '1', 09:39:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:01 INFO - 'NO_EM_RESTART': '1', 09:39:01 INFO - 'PAGER': '/bin/cat', 09:39:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:01 INFO - 'PWD': '/builds/slave/test', 09:39:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:01 INFO - 'RUST_BACKTRACE': '1', 09:39:01 INFO - 'SHELL': '/bin/bash', 09:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gu6QXBklRI/Listeners', 09:39:01 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:01 INFO - 'USER': 'cltbld', 09:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:01 INFO - 'XPC_FLAGS': '0x0', 09:39:01 INFO - 'XPC_SERVICE_NAME': '0', 09:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:01 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/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 09:39:01 INFO - mozversion application_buildid: 20170504080520 09:39:01 INFO - mozversion application_changeset: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a 09:39:01 INFO - mozversion application_display_name: Nightly 09:39:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:01 INFO - mozversion application_name: Firefox 09:39:01 INFO - mozversion application_remotingname: firefox 09:39:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:39:01 INFO - mozversion application_vendor: Mozilla 09:39:01 INFO - mozversion application_version: 55.0a1 09:39:01 INFO - mozversion platform_buildid: 20170504080520 09:39:01 INFO - mozversion platform_changeset: 6d4525b5aaa834ea4db8d8eab8087d86e34cf57a 09:39:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:39:01 INFO - mozversion platform_version: 55.0a1 09:39:01 INFO - using testdate: 1493915941 09:39:01 INFO - actual date: 1493915941 09:39:01 INFO - starting webserver on 'localhost:49241' 09:39:01 INFO - SUITE-START | Running 2 tests 09:39:01 INFO - TEST-START | dromaeo_css 09:39:01 INFO - operating with platform_type : mac_ 09:39:01 INFO - Initialising browser for dromaeo_css test... 09:39:01 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpj5jmpB/profile http://localhost:49241/getInfo.html) 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.415 plugin-container[1639:8107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a47, name = 'com.apple.tsm.portname' 09:39:04 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.418 plugin-container[1639:8107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 09:39:04 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.418 plugin-container[1639:8107] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:04 INFO - PID 1637 | __metrics Screen width/height:1600/1200 09:39:04 INFO - PID 1637 | colorDepth:24 09:39:04 INFO - PID 1637 | Browser inner width/height: 1024/691 09:39:04 INFO - PID 1637 | __metrics 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.891 plugin-container[1641:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:39:04 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.891 plugin-container[1641:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 09:39:04 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:04 INFO - PID 1637 | 2017-05-04 09:39:04.891 plugin-container[1641:8159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:05 INFO - TEST-INFO | 1637: exit 0 09:39:05 INFO - Browser initialized. 09:39:05 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:05 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpj5jmpB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.001 plugin-container[1643:8271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:39:08 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.002 plugin-container[1643:8271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:39:08 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.002 plugin-container[1643:8271] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.420 plugin-container[1644:8315] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x912b, name = 'com.apple.tsm.portname' 09:39:08 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.420 plugin-container[1644:8315] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 09:39:08 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:08 INFO - PID 1642 | 2017-05-04 09:39:08.420 plugin-container[1644:8315] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:17 INFO - PID 1642 | RSS: Main: 233381888 09:39:17 INFO - PID 1642 | 09:42:22 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:42:23 INFO - PID 1642 | RSS: Main: 250048512 09:42:23 INFO - PID 1642 | 09:45:25 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:45:26 INFO - PID 1642 | RSS: Main: 258162688 09:45:26 INFO - PID 1642 | 09:48:27 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:48:28 INFO - PID 1642 | RSS: Main: 266067968 09:48:28 INFO - PID 1642 | 09:51:30 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:51:31 INFO - PID 1642 | RSS: Main: 259862528 09:51:31 INFO - PID 1642 | 09:54:33 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:54:34 INFO - PID 1642 | RSS: Main: 263409664 09:54:34 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:57:36 INFO - PID 1642 | RSS: Main: 269987840 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | __start_tp_report 09:57:36 INFO - PID 1642 | _x_x_mozilla_page_load 09:57:36 INFO - PID 1642 | _x_x_mozilla_page_load_details 09:57:36 INFO - PID 1642 | |i|pagename|runs| 09:57:36 INFO - PID 1642 | |0;dojo.html;3259.8753430066627;3428.8799892003785;3582.946255806163;3614.132608174039;3634.781913085215;6083;6164;6186.721597528111;6211.6583587902705;6255.874882502348;6105.126180223406;6128.411459112353;6146.524834039832;6171.475424588904;6221.720022598983;5419.701916394597;5426.375966763813;5434.83695489135;5451.700156491411;5463.426340234278;5456.8090116845915;5473.67157970521;5517.282753242073;5526.226328314039;5553.805616803413;5339;5392.91910621341;5486.423925487807;5486.561075114021;5527.474889885454;5388.676679399248;5444.972775136139;5482.616216864821;5492.148716948839;5500.917486237709;5058.317127187819;5398.2712333834825;5422.2951016368015;5430.076886929192;5438.972805135949;5137.743112844343;5991.700414979277;6262.624242545462;6269.59247648906;6270.529710271748;6370.076338930876;6465.935340646581;6578.407943285079;6648.501362397791;6658.234303055162;6791;6799.43612969015;6801.93198068018;6801.965990170018;6804.387605115563;6281.811545653638;6305.684715764193;6316.399942005502;6356.936430635635;6365.96817015912;6130.816075517741;6144.433169541739;6636.107615794762;6727.93272067273;6843;4872.439669437989;6284.685765711696;6358.3641635836975;6472.514561407913;6502.122213501211;6718.22740384862;6719.092922455504;6736.7642132525125;6739.326067393222;6752.358525940028;6471.5146364022885;6645.800625981228;6656.034874943203;6667.566608170453;6674.399304062657;6385.169927909435;6441.549091563639;6490.415862572391;6647.700853461606;6658.933410665928;5274.26160337553;5322.175062865262;5343.812966546152;5353.57171426285;5372;4673.392458980311;4689.718616882998;4700.224462963641;4727.858164255078;4753.857384278477;5156.20078887773;6143.082036032849;6333.334999825044;6343.111964324999;6359.459445947146;6548.345165483413;6654.534182607268;6711.530192886451;6736.6631668416385;6762.256151823295;6295.874082518324;6300.495960323163;6303.180586523723;6335.208098987627;6335.524835637346;4508.774561271924;5896.380880007623;5904.911426328608;5912.497437717756;5952.375000624958;5921.023031199804;6632.83417914556;6814;6895.75864844728;6956.40870526011;6755.560888542229;6777.220619628806;6788.524803263922;6807.965960170365;6819.727210911508;6937.202221744564;6949;6958.199807022055;6979.790606281819;6983.825404364931;6374.649394283359;6490.7079181436;6543.149390579194;6667.899981500378;6678.966605166943;6181.81454556364;6713.56361836479;6767.966160169168;6784.253732089367;6786.76246331376;5555.8888822224135;5982.401759824157;6195.628262304276;6251.468625166912;6524.739010439529;6448.065030570448;6517.315681853337;6521.706523206372;6523.64119973406;6545.705443254969;6193.535484838565;6243.157173781481;6279.372062793684;6292.4966002720585;6409.807705768834;6496.805095846943;6504.772332968323;6522.315156908457;6535.5425120241125;6547.639879806657;6357.7139028742895;6362.840928976812;6363.5545511813725;6364.045393190873;6381.234251889803 09:57:36 INFO - PID 1642 | |1;ext.html;780.7384526183728;812.7236739508568;1160.657606006228;1261.1046157228368;1271.7011502296964;37727.81136094319;38436.23127537448;38486.61513384865;38571.807140964294;38770;37340.25319493615;37573.87278381653;37681.434778478346;37904.62095379045;38510.84467465973;7766.883496747568;8294.875576866352;8486.957565212166;8487.74536763895;8495.150484951517;8454.154584541559;8476.78808029798;8542.145785421439;8551.74344769658;8581.184787445183;8461.450005749637;8521.10528394519;8539.573021348939;8575.742727718147;8592.8711069334;8513.02100258469;8516.659333626647;8548.658053677907;8553;8566.957165214135;8404.369672274604;8484.872726909101;8492.065872753992;8501.277391421736;8515.616797244138;37605.62394376076;37719.81140094282;37829.62170378288;37858;37912.43131353032;3621.9275614487565;3655.872044478457;3660.7437479376454;3663.9267214655556;3676.4301533262387;30448.695513044808;30650.38699226003;30799.846000770078;30807.229819254473;30833;2754.903578374747;3180.8091514508974;3221.532877732739;3223.4358987177384;3230.176305042199;285790;286928;287036;287295;289714;33113.50329745056;38066;46735;47748;48171.5182848174;41935.580644193775;49242;50380.74809626002;50606.49393506091;50627.98744025172;12084.516619335129;14111.647208819657;14281;14438;14463;9577.569009394454;9711.805763884682;11415.60045398407;11557.24877882935;11759.823602645967;13291.936645068372;13666.658333541742;13681.31593420325;13691.452341906412;13813.51652692151;5153.7165455900285;5863;5908.556858235649;5954.19618351517;5963;1002.1581871228203;1011.2920955331298;1017.4048181813722;1021.9948900255451;1056.5509658395151;23514.294571162893;24576.262712118663;24607.507849842903;24731.010759569417;24777.87611061969;1030.6598822388594;1044.2690116918188;1095.895889890459;1145.8338540911689;1146.6789298996293;9626.614935402646;12040.879591204151;12518.79868296801;12586.685332866751;12630.873691263154;325852.3707381448;329267;330694.6930530712;331420;332120;54711;54754.35736927899;54793;54927.450725493036;55095.72452137714;1314.8290722206052;1477.8448262932238;1534.1638806850294;1535.8233803112785;1546.404634215835;24201.91091400856;24466.51066978686;24476.75523244816;24488.897999589706;24523.38691532726;38757.09321800933;38819.805900970314;38874.80562597282;38912;38949.805250973564;279398.60300698364;282060;283506;284184;285506;34144.317113658086;34703.65296347004;35222.64777352194;35251.76618818329;35291.647083528835;43137.78431107824;44278;44689.32966005448;44846;44986.77506612577;29764;30651.693483065777;31274.687253127177;31299.84350078235;31633.367332653677;30590;30696.386072278878;31233.531497028016;31269.218269543442;31529.684703152674;37004.25991480209;38149.23701525899;38158.61841381661;38163.04592385213;38367.808160959015;29107.563386548794;31627.52558711575;31767.841160794047;31963;31981.200469988435;23776.762232377456;24518.877405612857;24615;24703.50592988166;24876.875615621808 09:57:36 INFO - PID 1642 | |2;jquery.html;419.8452738046888;422.461361763751;431.07964495801457;446.72526396266284;448.13957202171827;20606;20900.954952252385;21491.92540372983;21600;21797.891010544943;21628.35114946554;23330.766692333073;23357.18249861255;23650.17224397142;23765.287041388743;6018.3981601839905;6102.603330783485;6245.687715614224;6267.091271765591;6276.466500347476;5080.974595127038;6160.722767475474;6180.165677633507;6208;6215.968920155393;5201.609879259052;5380.946190538084;6141.754329826802;6150.96924515379;6186.474149697258;4713.622910167195;4900;6085.786997455112;6095.847603809896;6103.1455596216565;5312.787488500456;5390.137577987503;5422.810201642963;5427.457254274541;5452.863678408072;27621.86189069062;27875.442491150265;27889;28310.15069547917;28431.715682843114;2151.354593621923;2244.5847518209184;2249.2352600116037;2250.932472025842;2253.312739614417;17505.824941750674;17585.296588136458;17615.207315670825;18149;18269.269229230817;2133.306675330524;2142.282335417621;2169.5227050048966;2179.4333473297056;2184.0936011555186;154429;158941;161534;163370.9999999988;163856.3614363865;12668.98648108149;12680.048996325313;12926.41831117602;13021;13074.869251307555;16563.503094907173;16629.750553741702;16658;16668.83331166697;16699.74950375745;8601.096884827128;8954.23888969432;8981.326400519987;8987.910120898838;9098.909010909938;8380.832383352421;8409.285210757032;8431.620577074047;8586.570671466401;8604.827903441896;7836.686532538634;7866.192509237196;8015.238552337519;8056.758297251092;8064.661248238062;2172.8261739060836;2181.3019833653398;2182.6398644223527;2185.4208634711813;2340.8361414700807;929.330881765128;948.7912659214961;957.6600306890954;967.8410995309468;971.2715463402449;20773.480662983548;21413.143474260854;21537.353879383645;21614;21751.78248217529;966.4249771386014;974.5565767575736;998.9760495492179;1004.42747633848;1012.4937531234383;10729.570817167227;11030.724231894266;11198.776024479464;11226.438678066064;11243.718907027227;546485;557819;558229;576968;578345;95971;123448.14827777591;129950.35024825005;135680;139401.60598394088;1040.5889673579045;1119.1382635370883;1153.7865494883474;1175.2184797109996;1186.3771519951922;21775.891120544296;22566.435839104153;23037.88481057651;23106.422339440975;23120.4219894504;57679;58640;58956.11565826602;59564;59768;63961.72076558349;79814.60092699499;81225;81461;81526;7653.961730191313;8737.301015918838;8924.776880578038;9089.454632722058;9231.723048308562;14060.226687532284;14078.155310681392;14239.786403204167;14475.059121157092;14566.854331456974;9373.718788436357;11831.46758395857;12012.699682508008;12071.879281207075;12214.450349734105;10930.781384372427;11019.173561983043;11206.83189752138;11251.043661288883;11423;11875.940620296844;12002.699932501408;12306.384680766105;12311.19977201479;12434;8291.461055031403;8856.3800533962;8979.730608081769;9097.863532046893;9484;11536.480858361561;12025.879741202476;12271.263724176579;12282.508699651913;12294.323812190416 09:57:36 INFO - PID 1642 | |3;mootools.html;1374.917504949703;1423.402171088143;1431.1913768720672;1440.7262620102174;1450.7751298548728;11326.263792853459;11641.01051410631;11813.99581035611;11827.586034488795;11833.644990650284;11507.07943364531;11702.765944681123;11707.2390294631;11717.941410292937;11722.648320550397;2500.6499090127395;2560.5135024345313;2563.4873025394995;2568.627549005393;2569.704483984347;2354.1407386303963;2467.7532246775268;2507.122507122511;2541.3392517945376;2541.440883005736;2330.207729372013;2489.253224032792;2533.873306334685;2539.7968162546954;2541.898324067035;2466.4573793765344;2497.67530221071;2518.7229404765462;2532.696076470835;2534.037065914959;2369.6563998220163;2480.900763969439;2484.3168128764555;2531.632913227572;2541.682289713786;11831.881681183164;11855.94072029643;11865.110116741278;11879.58421455245;11896.88103118975;5334.866628334323;6591.11020012297;6802.4217941475035;6809.897851532231;6851.760188393382;3776.697864170887;4075.572064933199;4097.44684467596;4430.933535996963;4476.440444944382;1251.5494422008069;1254.7678679444332;1291.2123604601186;1306.875846794553;1315.486960085558;13827.585172444842;14007.369668364847;16235;16569.502914912573;16733.247003884855;5555.749991250323;5713.914291285634;5768.394318596571;5831.883362332815;5838;11823.704407389712;11912;11917.821232681517;11918.582849600223;11989.160758746804;6516.57642253252;6583.407493325623;6584.604923704497;6594.241662208811;6603.438707709898;5983.551233657493;6208.348123447064;6218.751249949952;6227.813165605039;6250.968745156336;3757.229767897574;3925;4136.730864581626;4407.889802754893;4429.092036132585;1096.6874440784338;1261.3945306252867;1262.0219330019308;1262.072376803049;1292.9030322725832;786.8347646994081;795.5823192823698;817.8446095241885;821.7165078048064;846.9110743371981;5596.496315331639;6189.535784816157;6257.217847769029;6360.777372792023;6442.549021568445;574.4542684449706;585.8828234353147;588.3910152991718;594.094006639869;601.1013534765553;3937.9212415751526;4153.958460415418;4263.87208383749;4348.108637729258;4405.339199120158;14448.638784030483;15199.848001519844;15230.543083707507;15262.618434539225;15266.618334541725;14445.78331325031;14965.551033469013;14965.850341496445;14995.625109372353;15057.472988445352;6268.4671802896355;8517.616707248064;8744.08187140341;8751.168638979414;8766.780830479289;6185.257769067741;6796.626185559841;7021.262767409348;7062.329078737512;7216.242294559206;12379.319137447526;12700.745985080062;12738.363081845686;12940.676483087998;12991;12961.027922906009;13028.153170043917;13067.803982940453;13188.736225275632;13382.330883455983;9304.255659547356;9483.336166468282;9505.524723763923;9510.286728495528;9527.618895244113;10696.251262411557;11006.064484518585;11125.777484450104;11149.383339415901;11166.553337866395;9117.407368521024;9178.816423671622;9321.067893210624;9382.765430864283;9397.201237894855;7601.809954751176;8826.249768769727;8924.464532128093;8978.147076027766;9052.728418147466;8053.959730201312;8175.591220439073;8231.876521852297;8232.300254478436;8245.093039765501;4933.407991041098;5965.164876917148;6039.184710064261;6063.181470501602;6136.570440069331;5226.8954620908125;5422.945770542402;6092.116643086639;6216.844578885564;6322.557420980487 09:57:36 INFO - PID 1642 | |4;prototype.html;351.9472079188123;380.824820582532;391.92553414851176;398.0307950141405;398.30098177698153;4962.925556116661;5121.231815227713;5324.520793128618;6147.538934579902;6372.617642941415;5601.439856014396;5950.285965684113;6064.9090263646085;6183.288921773998;6194.225721784777;3572.946405803902;4446.444194475691;4520.615747661444;4533.252013417782;4614.630829533629;4494.730316181023;4515.638748900096;4523.36672865799;4524.592786649201;4537.795799189028;4243.236217480852;4458.754768487748;4477.664175186857;4487.932681009787;4505.954940450602;4345.69580129391;4373.934390984138;4460.821567137311;4491.169133710274;4499.437570303712;3724.7578907370935;3827.7129215308964;3841.1741475582885;3841.2317536492533;3844.692424606025;6065.454109130155;6073.605215660968;6077.088436734481;6078.361772013919;6079.604825686316;4670.813167473297;4698.577128058491;5150.97424512875;5343.572514198855;5384.892302153974;5727.770889164429;6591.901121483182;6599.274079851212;6625.701843417058;6638.20341559016;4218.008767939532;4346.500152482474;4354.651627869763;4362;4373.409589705413;6848.623325717083;7048.506604537684;7082.681279342442;7090.093061844619;7147.462650758957;4387.692861499664;4392.604665580113;4398;4402.3396490526675;4431.446069241345;4483.775811209492;4487.708298960557;4491.438570178728;4500.504944456107;4502.549745025472;3897.142628621699;4703.529647035269;5050.191969284897;5246.396664383645;5341.050516561505;5154.226865970059;6504.0243963405865;6596.208454985433;6613.735450582019;6760.411303343711;1506.6610012747262;1519.080956021613;1520.7566789313657;1520.9771853422208;1530.8469153084825;953.2850362228329;996.5963784667222;998.0468652436905;1003.708924411927;1014.2899970021015;5497.862553436116;6376.266729326094;6401.015685137622;6403.96798016007;6419.2296924369375;6386.552941294065;6727.260001399842;6981.127359080115;7001.339490993491;7009.579425234502;5798.652080875161;6013.909791353133;6164.044573091178;6288.9056664150075;6354.586951848208;2881.481333359973;3316.8673253069605;3605.2789442111684;3607.9098022549647;3621.63783621641;6074.757009719562;6953.930460695429;6973;7100.715971361191;7205.819854503575;6097.3292937776805;6672.799816005528;7096.822579435556;7112.25321341262;7132.144142702805;6906.343897329745;7119.608421536762;7132.536385134949;7166.452605960083;7224.638768061576;6161.784337548164;6201.658908760061;6931.445484361138;6955.513114081966;6957.234704182637;5607.74765135578;6387.8722425550295;6807.421369183675;6821;6870.553414028072;6019.735855470401;6792.76225332111;6866.553674011173;7196.0645116134565;7222.566646001257;3702.981485092665;4390.507227542651;4421.867343979686;4426.919347906479;4451.6661250405705;5125.410577783454;5841.299044114563;6866.107406037183;6875.209350924508;6964.1643002837955;5561.998840208639;5763.971180144073;5901.819636072717;6382.808515744535;6621.569597976116;5638.830835074954;5969.7313620888035;6625.536212465081;6727.562708423937;6732.158480189976;6540.247871494648;6580;6606.933930660824;6608.669566521751;6609.4712423005085;5192.3769147702515;6559.819232538189;6710.4230505832065;6760.222574404006;6793.558418702769;5812.070068788974;6161.907571386522;6498.610083394822;6551.672416379257;6555.70499327541 09:57:36 INFO - PID 1642 | |5;yui.html;812.7724237213575;829.7261903571822;832.196929962586;844.248618729331;855.9786005349868;14166.29168541574;14793.186374749384;16219.513414597566;16224.837751622481;16692.66614667706;16473.83526164738;16939.237734301954;17198.484045478624;17221.91389043053;17482.56293592661;966.0822218892027;972.1008067537534;974.8001659659789;985.0346660272937;989.4261328429494;1009.530568285747;1012.7974405118969;1014.751385910453;1016.9491525423699;1023.355286169712;1035.4874337203057;1046.6022911293735;1047.9886909132686;1050.1283934334522;1051.5110473629761;1030.5156576409076;1044.791041791641;1047.1988928469727;1047.4867315015663;1051.285126114242;771.9925497226118;773.9690412383497;774.6746366526016;776.3207193705508;778.1712476212815;16248.268827902655;16848.410305639365;16977;17013.319467221296;17098.14509274531;16090.75863862043;16487.917560412243;16585;16797.91601041999;16821.32714691423;13498.987575931844;13902.860971390359;13931.721365572734;14050.718985620231;14074.296285185803;3334.4998250262656;3366.4950257461333;3379.594448666151;3498.7900725956524;3513.929721405583;108250;124248.37875810562;126311.42131472142;127048;127198.36400818029;13287.402066907012;13625.046246762631;13640.795388069188;13651.453941842414;13673.863261367458;24579.87710061474;25279.873600632247;25434;25761.742382575936;25792.871035645076;7946.841063178819;8073.354131669453;8102.473339232931;8113.107558168596;8138.186181381933;3878.4764056851964;3940.8817735467983;3941.586133456003;3953.5058117735284;3960.20795840833;4126.216018956388;4315.438992930899;4348.847790327323;4350.934735978963;4353.455818022747;908.8591268353416;935.0976307862945;944.5088553951906;953.9379940303858;954.6467806911487;638.6040654794058;663.4659099424913;671.1509939925998;674.8211723893172;686.2691233835949;10999.34003959765;11190.776184476264;11586.36275004866;11965.042796576254;12251.938740306241;614.6803662095774;624.5971348480205;632.7816903168399;634.8826066123655;654.5614438326332;5385.353757549119;5415.702136382537;5605.971970140124;5624.606277560531;5690.601657883908;68953.65523172452;97768.15622906329;101312;101580;112110.31834522489;89016.21967560613;89427.55286223527;89618;94253;95545.61135971657;85082.5745871279;85227.57386213029;85401;85437.14562854292;86367.13632863591;10078.546465409221;11862.584809531625;12520.311382873664;12559.497620095459;12560.874391256337;29164.541772910845;29448.85275573694;34887.825560872036;40061.79969100136;40916.79541602273;45749.31376029296;72509.6374518124;73746;74997.25002750121;75748;13849.653758656113;16339.836601634306;16422.343106275614;16581;16592.419265325654;18209.635807284045;18516.72224916654;18533.166007529424;18707.625847483247;18770;13444.193348399127;14760.409583616965;16954.152292385577;17086.829131708524;17437.866538674945;14199.64500887486;16372.918135409247;16905.408310709066;17487.912560437115;17513.03678297706;16528.338866445207;16544.586385340463;16702.997820130346;16911.91544042272;17000.48998530046;10157.796844063225;10211.540480678239;11876.228045177037;12075.939620301842;12099.939500302442;10884.564617415532;12214.389280536116;13156.539521116714;13198.868011319764;13687.247201404018 09:57:36 INFO - PID 1642 | __end_tp_report 09:57:36 INFO - PID 1642 | __start_cc_report 09:57:36 INFO - PID 1642 | _x_x_mozilla_cycle_collect,405 09:57:36 INFO - PID 1642 | __end_cc_report 09:57:36 INFO - PID 1642 | __startTimestamp1493917056510__endTimestamp 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | ------- Summary: start ------- 09:57:36 INFO - PID 1642 | Number of tests: 6 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6099.74 Median:6333.33 stddev:738.78 (11.7%) stddev-sans-first:706.66 09:57:36 INFO - PID 1642 | Values: 3259.9 3428.9 3582.9 3614.1 3634.8 6083.0 6164.0 6186.7 6211.7 6255.9 6105.1 6128.4 6146.5 6171.5 6221.7 5419.7 5426.4 5434.8 5451.7 5463.4 5456.8 5473.7 5517.3 5526.2 5553.8 5339.0 5392.9 5486.4 5486.6 5527.5 5388.7 5445.0 5482.6 5492.1 5500.9 5058.3 5398.3 5422.3 5430.1 5439.0 5137.7 5991.7 6262.6 6269.6 6270.5 6370.1 6465.9 6578.4 6648.5 6658.2 6791.0 6799.4 6801.9 6802.0 6804.4 6281.8 6305.7 6316.4 6356.9 6366.0 6130.8 6144.4 6636.1 6727.9 6843.0 4872.4 6284.7 6358.4 6472.5 6502.1 6718.2 6719.1 6736.8 6739.3 6752.4 6471.5 6645.8 6656.0 6667.6 6674.4 6385.2 6441.5 6490.4 6647.7 6658.9 5274.3 5322.2 5343.8 5353.6 5372.0 4673.4 4689.7 4700.2 4727.9 4753.9 5156.2 6143.1 6333.3 6343.1 6359.5 6548.3 6654.5 6711.5 6736.7 6762.3 6295.9 6300.5 6303.2 6335.2 6335.5 4508.8 5896.4 5904.9 5912.5 5952.4 5921.0 6632.8 6814.0 6895.8 6956.4 6755.6 6777.2 6788.5 6808.0 6819.7 6937.2 6949.0 6958.2 6979.8 6983.8 6374.6 6490.7 6543.1 6667.9 6679.0 6181.8 6713.6 6768.0 6784.3 6786.8 5555.9 5982.4 6195.6 6251.5 6524.7 6448.1 6517.3 6521.7 6523.6 6545.7 6193.5 6243.2 6279.4 6292.5 6409.8 6496.8 6504.8 6522.3 6535.5 6547.6 6357.7 6362.8 6363.6 6364.0 6381.2 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:45099.87 Median:183132.16 stddev:78861.03 (43.1%) stddev-sans-first:79012.05 09:57:36 INFO - PID 1642 | Values: 780.7 812.7 1160.7 1261.1 1271.7 37727.8 38436.2 38486.6 38571.8 38770.0 37340.3 37573.9 37681.4 37904.6 38510.8 7766.9 8294.9 8487.0 8487.7 8495.2 8454.2 8476.8 8542.1 8551.7 8581.2 8461.5 8521.1 8539.6 8575.7 8592.9 8513.0 8516.7 8548.7 8553.0 8567.0 8404.4 8484.9 8492.1 8501.3 8515.6 37605.6 37719.8 37829.6 37858.0 37912.4 3621.9 3655.9 3660.7 3663.9 3676.4 30448.7 30650.4 30799.8 30807.2 30833.0 2754.9 3180.8 3221.5 3223.4 3230.2 285790.0 286928.0 287036.0 287295.0 289714.0 33113.5 38066.0 46735.0 47748.0 48171.5 41935.6 49242.0 50380.7 50606.5 50628.0 12084.5 14111.6 14281.0 14438.0 14463.0 9577.6 9711.8 11415.6 11557.2 11759.8 13291.9 13666.7 13681.3 13691.5 13813.5 5153.7 5863.0 5908.6 5954.2 5963.0 1002.2 1011.3 1017.4 1022.0 1056.6 23514.3 24576.3 24607.5 24731.0 24777.9 1030.7 1044.3 1095.9 1145.8 1146.7 9626.6 12040.9 12518.8 12586.7 12630.9 325852.4 329267.0 330694.7 331420.0 332120.0 54711.0 54754.4 54793.0 54927.5 55095.7 1314.8 1477.8 1534.2 1535.8 1546.4 24201.9 24466.5 24476.8 24488.9 24523.4 38757.1 38819.8 38874.8 38912.0 38949.8 279398.6 282060.0 283506.0 284184.0 285506.0 34144.3 34703.7 35222.6 35251.8 35291.6 43137.8 44278.0 44689.3 44846.0 44986.8 29764.0 30651.7 31274.7 31299.8 31633.4 30590.0 30696.4 31233.5 31269.2 31529.7 37004.3 38149.2 38158.6 38163.0 38367.8 29107.6 31627.5 31767.8 31963.0 31981.2 23776.8 24518.9 24615.0 24703.5 24876.9 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:36215.33 Median:23343.97 stddev:95516.62 (409.2%) stddev-sans-first:95746.76 09:57:36 INFO - PID 1642 | Values: 419.8 422.5 431.1 446.7 448.1 20606.0 20901.0 21491.9 21600.0 21797.9 21628.4 23330.8 23357.2 23650.2 23765.3 6018.4 6102.6 6245.7 6267.1 6276.5 5081.0 6160.7 6180.2 6208.0 6216.0 5201.6 5380.9 6141.8 6151.0 6186.5 4713.6 4900.0 6085.8 6095.8 6103.1 5312.8 5390.1 5422.8 5427.5 5452.9 27621.9 27875.4 27889.0 28310.2 28431.7 2151.4 2244.6 2249.2 2250.9 2253.3 17505.8 17585.3 17615.2 18149.0 18269.3 2133.3 2142.3 2169.5 2179.4 2184.1 154429.0 158941.0 161534.0 163371.0 163856.4 12669.0 12680.0 12926.4 13021.0 13074.9 16563.5 16629.8 16658.0 16668.8 16699.7 8601.1 8954.2 8981.3 8987.9 9098.9 8380.8 8409.3 8431.6 8586.6 8604.8 7836.7 7866.2 8015.2 8056.8 8064.7 2172.8 2181.3 2182.6 2185.4 2340.8 929.3 948.8 957.7 967.8 971.3 20773.5 21413.1 21537.4 21614.0 21751.8 966.4 974.6 999.0 1004.4 1012.5 10729.6 11030.7 11198.8 11226.4 11243.7 546485.0 557819.0 558229.0 576968.0 578345.0 95971.0 123448.1 129950.4 135680.0 139401.6 1040.6 1119.1 1153.8 1175.2 1186.4 21775.9 22566.4 23037.9 23106.4 23120.4 57679.0 58640.0 58956.1 59564.0 59768.0 63961.7 79814.6 81225.0 81461.0 81526.0 7654.0 8737.3 8924.8 9089.5 9231.7 14060.2 14078.2 14239.8 14475.1 14566.9 9373.7 11831.5 12012.7 12071.9 12214.5 10930.8 11019.2 11206.8 11251.0 11423.0 11875.9 12002.7 12306.4 12311.2 12434.0 8291.5 8856.4 8979.7 9097.9 9484.0 11536.5 12025.9 12271.3 12282.5 12294.3 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7038.10 Median:3766.96 stddev:4442.66 (117.9%) stddev-sans-first:4434.74 09:57:36 INFO - PID 1642 | Values: 1374.9 1423.4 1431.2 1440.7 1450.8 11326.3 11641.0 11814.0 11827.6 11833.6 11507.1 11702.8 11707.2 11717.9 11722.6 2500.6 2560.5 2563.5 2568.6 2569.7 2354.1 2467.8 2507.1 2541.3 2541.4 2330.2 2489.3 2533.9 2539.8 2541.9 2466.5 2497.7 2518.7 2532.7 2534.0 2369.7 2480.9 2484.3 2531.6 2541.7 11831.9 11855.9 11865.1 11879.6 11896.9 5334.9 6591.1 6802.4 6809.9 6851.8 3776.7 4075.6 4097.4 4430.9 4476.4 1251.5 1254.8 1291.2 1306.9 1315.5 13827.6 14007.4 16235.0 16569.5 16733.2 5555.7 5713.9 5768.4 5831.9 5838.0 11823.7 11912.0 11917.8 11918.6 11989.2 6516.6 6583.4 6584.6 6594.2 6603.4 5983.6 6208.3 6218.8 6227.8 6251.0 3757.2 3925.0 4136.7 4407.9 4429.1 1096.7 1261.4 1262.0 1262.1 1292.9 786.8 795.6 817.8 821.7 846.9 5596.5 6189.5 6257.2 6360.8 6442.5 574.5 585.9 588.4 594.1 601.1 3937.9 4154.0 4263.9 4348.1 4405.3 14448.6 15199.8 15230.5 15262.6 15266.6 14445.8 14965.6 14965.9 14995.6 15057.5 6268.5 8517.6 8744.1 8751.2 8766.8 6185.3 6796.6 7021.3 7062.3 7216.2 12379.3 12700.7 12738.4 12940.7 12991.0 12961.0 13028.2 13067.8 13188.7 13382.3 9304.3 9483.3 9505.5 9510.3 9527.6 10696.3 11006.1 11125.8 11149.4 11166.6 9117.4 9178.8 9321.1 9382.8 9397.2 7601.8 8826.2 8924.5 8978.1 9052.7 8054.0 8175.6 8231.9 8232.3 8245.1 4933.4 5965.2 6039.2 6063.2 6136.6 5226.9 5422.9 6092.1 6216.8 6322.6 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5296.11 Median:5991.82 stddev:1705.67 (28.5%) stddev-sans-first:1669.60 09:57:36 INFO - PID 1642 | Values: 351.9 380.8 391.9 398.0 398.3 4962.9 5121.2 5324.5 6147.5 6372.6 5601.4 5950.3 6064.9 6183.3 6194.2 3572.9 4446.4 4520.6 4533.3 4614.6 4494.7 4515.6 4523.4 4524.6 4537.8 4243.2 4458.8 4477.7 4487.9 4506.0 4345.7 4373.9 4460.8 4491.2 4499.4 3724.8 3827.7 3841.2 3841.2 3844.7 6065.5 6073.6 6077.1 6078.4 6079.6 4670.8 4698.6 5151.0 5343.6 5384.9 5727.8 6591.9 6599.3 6625.7 6638.2 4218.0 4346.5 4354.7 4362.0 4373.4 6848.6 7048.5 7082.7 7090.1 7147.5 4387.7 4392.6 4398.0 4402.3 4431.4 4483.8 4487.7 4491.4 4500.5 4502.5 3897.1 4703.5 5050.2 5246.4 5341.1 5154.2 6504.0 6596.2 6613.7 6760.4 1506.7 1519.1 1520.8 1521.0 1530.8 953.3 996.6 998.0 1003.7 1014.3 5497.9 6376.3 6401.0 6404.0 6419.2 6386.6 6727.3 6981.1 7001.3 7009.6 5798.7 6013.9 6164.0 6288.9 6354.6 2881.5 3316.9 3605.3 3607.9 3621.6 6074.8 6953.9 6973.0 7100.7 7205.8 6097.3 6672.8 7096.8 7112.3 7132.1 6906.3 7119.6 7132.5 7166.5 7224.6 6161.8 6201.7 6931.4 6955.5 6957.2 5607.7 6387.9 6807.4 6821.0 6870.6 6019.7 6792.8 6866.6 7196.1 7222.6 3703.0 4390.5 4421.9 4426.9 4451.7 5125.4 5841.3 6866.1 6875.2 6964.2 5562.0 5764.0 5901.8 6382.8 6621.6 5638.8 5969.7 6625.5 6727.6 6732.2 6540.2 6580.0 6606.9 6608.7 6609.5 5192.4 6559.8 6710.4 6760.2 6793.6 5812.1 6161.9 6498.6 6551.7 6555.7 09:57:36 INFO - PID 1642 | 09:57:36 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:21741.57 Median:17485.24 stddev:30626.31 (175.2%) stddev-sans-first:30671.91 09:57:36 INFO - PID 1642 | Values: 812.8 829.7 832.2 844.2 856.0 14166.3 14793.2 16219.5 16224.8 16692.7 16473.8 16939.2 17198.5 17221.9 17482.6 966.1 972.1 974.8 985.0 989.4 1009.5 1012.8 1014.8 1016.9 1023.4 1035.5 1046.6 1048.0 1050.1 1051.5 1030.5 1044.8 1047.2 1047.5 1051.3 772.0 774.0 774.7 776.3 778.2 16248.3 16848.4 16977.0 17013.3 17098.1 16090.8 16487.9 16585.0 16797.9 16821.3 13499.0 13902.9 13931.7 14050.7 14074.3 3334.5 3366.5 3379.6 3498.8 3513.9 108250.0 124248.4 126311.4 127048.0 127198.4 13287.4 13625.0 13640.8 13651.5 13673.9 24579.9 25279.9 25434.0 25761.7 25792.9 7946.8 8073.4 8102.5 8113.1 8138.2 3878.5 3940.9 3941.6 3953.5 3960.2 4126.2 4315.4 4348.8 4350.9 4353.5 908.9 935.1 944.5 953.9 954.6 638.6 663.5 671.2 674.8 686.3 10999.3 11190.8 11586.4 11965.0 12251.9 614.7 624.6 632.8 634.9 654.6 5385.4 5415.7 5606.0 5624.6 5690.6 68953.7 97768.2 101312.0 101580.0 112110.3 89016.2 89427.6 89618.0 94253.0 95545.6 85082.6 85227.6 85401.0 85437.1 86367.1 10078.5 11862.6 12520.3 12559.5 12560.9 29164.5 29448.9 34887.8 40061.8 40916.8 45749.3 72509.6 73746.0 74997.3 75748.0 13849.7 16339.8 16422.3 16581.0 16592.4 18209.6 18516.7 18533.2 18707.6 18770.0 13444.2 14760.4 16954.2 17086.8 17437.9 14199.6 16372.9 16905.4 17487.9 17513.0 16528.3 16544.6 16703.0 16911.9 17000.5 10157.8 10211.5 11876.2 12075.9 12099.9 10884.6 12214.4 13156.5 13198.9 13687.2 09:57:36 INFO - PID 1642 | -------- Summary: end -------- 09:57:36 INFO - PID 1642 | 09:57:37 INFO - TEST-INFO | 1642: exit 0 09:57:37 INFO - TEST-OK | dromaeo_css | took 1115717ms 09:57:37 INFO - TEST-START | kraken 09:57:37 INFO - operating with platform_type : mac_ 09:57:37 INFO - Initialising browser for kraken test... 09:57:37 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDrsoRz/profile http://localhost:49241/getInfo.html) 09:57:39 INFO - PID 1651 | 2017-05-04 09:57:39.827 plugin-container[1652:10470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9337, name = 'com.apple.tsm.portname' 09:57:39 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:39 INFO - PID 1651 | 2017-05-04 09:57:39.832 plugin-container[1652:10470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 09:57:39 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:39 INFO - PID 1651 | 2017-05-04 09:57:39.832 plugin-container[1652:10470] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:39 INFO - PID 1651 | __metrics Screen width/height:1600/1200 09:57:39 INFO - PID 1651 | colorDepth:24 09:57:39 INFO - PID 1651 | Browser inner width/height: 1024/691 09:57:39 INFO - PID 1651 | __metrics 09:57:41 INFO - TEST-INFO | 1651: exit 0 09:57:41 INFO - Browser initialized. 09:57:41 INFO - Running cycle 1/1 for kraken test... 09:57:41 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDrsoRz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.284 plugin-container[1654:10596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 09:57:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.285 plugin-container[1654:10596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:57:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.285 plugin-container[1654:10596] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.770 plugin-container[1655:10639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:57:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.771 plugin-container[1655:10639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951f, name = 'com.apple.CFPasteboardClient' 09:57:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:43 INFO - PID 1653 | 2017-05-04 09:57:43.771 plugin-container[1655:10639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:53 INFO - PID 1653 | RSS: Main: 250068992 09:57:53 INFO - PID 1653 | 09:57:55 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:56 INFO - PID 1653 | RSS: Main: 258375680 09:57:56 INFO - PID 1653 | 09:57:59 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:57:59 INFO - PID 1653 | RSS: Main: 265179136 09:57:59 INFO - PID 1653 | 09:58:03 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:58:04 INFO - PID 1653 | RSS: Main: 260104192 09:58:04 INFO - PID 1653 | 09:58:06 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:58:06 INFO - PID 1653 | RSS: Main: 262287360 09:58:06 INFO - PID 1653 | 09:58:08 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:09 INFO - PID 1653 | RSS: Main: 263073792 09:58:09 INFO - PID 1653 | 09:58:12 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:13 INFO - PID 1653 | RSS: Main: 269205504 09:58:13 INFO - PID 1653 | 09:58:16 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:17 INFO - PID 1653 | RSS: Main: 266633216 09:58:17 INFO - PID 1653 | 09:58:20 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:58:20 INFO - PID 1653 | RSS: Main: 267911168 09:58:20 INFO - PID 1653 | 09:58:22 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:23 INFO - PID 1653 | RSS: Main: 268148736 09:58:23 INFO - PID 1653 | 09:58:24 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:25 INFO - PID 1653 | RSS: Main: 268251136 09:58:25 INFO - PID 1653 | 09:58:27 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:28 INFO - PID 1653 | RSS: Main: 268283904 09:58:28 INFO - PID 1653 | 09:58:31 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:32 INFO - PID 1653 | RSS: Main: 268619776 09:58:32 INFO - PID 1653 | 09:58:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:35 INFO - PID 1653 | RSS: Main: 268185600 09:58:35 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:58:37 INFO - PID 1653 | RSS: Main: 270012416 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | __start_tp_report 09:58:37 INFO - PID 1653 | _x_x_mozilla_page_load 09:58:37 INFO - PID 1653 | _x_x_mozilla_page_load_details 09:58:37 INFO - PID 1653 | |i|pagename|runs| 09:58:37 INFO - PID 1653 | |0;ai-astar;102;117;115;115;113;115;116;115;115;114 09:58:37 INFO - PID 1653 | |1;audio-beat-detection;155;144;111;154;106;110;108;112;139;109 09:58:37 INFO - PID 1653 | |2;audio-dft;197;192;198;227;256;284;281;207;197;215 09:58:37 INFO - PID 1653 | |3;audio-fft;87;47;61;100;85;83;81;58;77;55 09:58:37 INFO - PID 1653 | |4;audio-oscillator;91;70;84;86;86;69;86;73;73;90 09:58:37 INFO - PID 1653 | |5;imaging-gaussian-blur;101;134;87;136;98;77;122;135;104;90 09:58:37 INFO - PID 1653 | |6;imaging-darkroom;155;116;95;149;155;98;110;153;107;152 09:58:37 INFO - PID 1653 | |7;imaging-desaturate;90;92;91;94;92;91;75;149;147;90 09:58:37 INFO - PID 1653 | |8;json-parse-financial;52;49;51;51;53;50;51;49;52;52 09:58:37 INFO - PID 1653 | |9;json-stringify-tinderbox;55;64;65;49;70;77;68;57;66;51 09:58:37 INFO - PID 1653 | |10;stanford-crypto-aes;84;82;87;96;76;98;103;106;104;83 09:58:37 INFO - PID 1653 | |11;stanford-crypto-ccm;151;158;147;174;152;153;139;137;187;142 09:58:37 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;182;215;234;230;187;183;186;187;184;185 09:58:37 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;66;69;69;70;66;68;66;69;67;68 09:58:37 INFO - PID 1653 | __end_tp_report 09:58:37 INFO - PID 1653 | __start_cc_report 09:58:37 INFO - PID 1653 | _x_x_mozilla_cycle_collect,993 09:58:37 INFO - PID 1653 | __end_cc_report 09:58:37 INFO - PID 1653 | __startTimestamp1493917117839__endTimestamp 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | ------- Summary: start ------- 09:58:37 INFO - PID 1653 | Number of tests: 14 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:4.24 (3.7%) stddev-sans-first:1.12 09:58:37 INFO - PID 1653 | Values: 102.0 117.0 115.0 115.0 113.0 115.0 116.0 115.0 115.0 114.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:125.50 stddev:20.53 (16.4%) stddev-sans-first:18.64 09:58:37 INFO - PID 1653 | Values: 155.0 144.0 111.0 154.0 106.0 110.0 108.0 112.0 139.0 109.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:225.40 Median:221.00 stddev:35.58 (16.1%) stddev-sans-first:36.22 09:58:37 INFO - PID 1653 | Values: 197.0 192.0 198.0 227.0 256.0 284.0 281.0 207.0 197.0 215.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:73.40 Median:79.00 stddev:17.05 (21.6%) stddev-sans-first:17.36 09:58:37 INFO - PID 1653 | Values: 87.0 47.0 61.0 100.0 85.0 83.0 81.0 58.0 77.0 55.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:86.00 stddev:8.55 (9.9%) stddev-sans-first:8.23 09:58:37 INFO - PID 1653 | Values: 91.0 70.0 84.0 86.0 86.0 69.0 86.0 73.0 73.0 90.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:108.40 Median:106.50 stddev:21.79 (20.5%) stddev-sans-first:22.95 09:58:37 INFO - PID 1653 | Values: 101.0 134.0 87.0 136.0 98.0 77.0 122.0 135.0 104.0 90.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:129.00 Median:154.00 stddev:25.79 (16.7%) stddev-sans-first:25.59 09:58:37 INFO - PID 1653 | Values: 155.0 116.0 95.0 149.0 155.0 98.0 110.0 153.0 107.0 152.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:91.00 stddev:25.27 (27.8%) stddev-sans-first:26.49 09:58:37 INFO - PID 1653 | Values: 90.0 92.0 91.0 94.0 92.0 91.0 75.0 149.0 147.0 90.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.50 stddev:1.33 (2.6%) stddev-sans-first:1.36 09:58:37 INFO - PID 1653 | Values: 52.0 49.0 51.0 51.0 53.0 50.0 51.0 49.0 52.0 52.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:65.50 stddev:8.93 (13.6%) stddev-sans-first:9.08 09:58:37 INFO - PID 1653 | Values: 55.0 64.0 65.0 49.0 70.0 77.0 68.0 57.0 66.0 51.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:83.50 stddev:10.74 (12.9%) stddev-sans-first:11.01 09:58:37 INFO - PID 1653 | Values: 84.0 82.0 87.0 96.0 76.0 98.0 103.0 106.0 104.0 83.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:152.50 stddev:15.73 (10.3%) stddev-sans-first:16.64 09:58:37 INFO - PID 1653 | Values: 151.0 158.0 147.0 174.0 152.0 153.0 139.0 137.0 187.0 142.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.30 Median:187.00 stddev:20.65 (11.0%) stddev-sans-first:21.14 09:58:37 INFO - PID 1653 | Values: 182.0 215.0 234.0 230.0 187.0 183.0 186.0 187.0 184.0 185.0 09:58:37 INFO - PID 1653 | 09:58:37 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.50 stddev:1.48 (2.2%) stddev-sans-first:1.41 09:58:37 INFO - PID 1653 | Values: 66.0 69.0 69.0 70.0 66.0 68.0 66.0 69.0 67.0 68.0 09:58:37 INFO - PID 1653 | -------- Summary: end -------- 09:58:37 INFO - PID 1653 | 09:58:38 INFO - TEST-INFO | 1653: exit 0 09:58:38 INFO - TEST-OK | kraken | took 61802ms 09:58:38 INFO - SUITE-END | took 1177s 09:58:39 INFO - Completed test suite (00:19:37) 09:58:39 INFO - javascript benchmark 09:58:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7853.040732851522, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.8753430066627, 3428.8799892003785, 3582.946255806163, 3614.132608174039, 3634.781913085215, 6083.0, 6164.0, 6186.721597528111, 6211.6583587902705, 6255.874882502348, 6105.126180223406, 6128.411459112353, 6146.524834039832, 6171.475424588904, 6221.720022598983, 5419.701916394597, 5426.375966763813, 5434.83695489135, 5451.700156491411, 5463.426340234278, 5456.8090116845915, 5473.67157970521, 5517.282753242073, 5526.226328314039, 5553.805616803413, 5339.0, 5392.91910621341, 5486.423925487807, 5486.561075114021, 5527.474889885454, 5388.676679399248, 5444.972775136139, 5482.616216864821, 5492.148716948839, 5500.917486237709, 5058.317127187819, 5398.2712333834825, 5422.2951016368015, 5430.076886929192, 5438.972805135949, 5137.743112844343, 5991.700414979277, 6262.624242545462, 6269.59247648906, 6270.529710271748, 6370.076338930876, 6465.935340646581, 6578.407943285079, 6648.501362397791, 6658.234303055162, 6791.0, 6799.43612969015, 6801.93198068018, 6801.965990170018, 6804.387605115563, 6281.811545653638, 6305.684715764193, 6316.399942005502, 6356.936430635635, 6365.96817015912, 6130.816075517741, 6144.433169541739, 6636.107615794762, 6727.93272067273, 6843.0, 4872.439669437989, 6284.685765711696, 6358.3641635836975, 6472.514561407913, 6502.122213501211, 6718.22740384862, 6719.092922455504, 6736.7642132525125, 6739.326067393222, 6752.358525940028, 6471.5146364022885, 6645.800625981228, 6656.034874943203, 6667.566608170453, 6674.399304062657, 6385.169927909435, 6441.549091563639, 6490.415862572391, 6647.700853461606, 6658.933410665928, 5274.26160337553, 5322.175062865262, 5343.812966546152, 5353.57171426285, 5372.0, 4673.392458980311, 4689.718616882998, 4700.224462963641, 4727.858164255078, 4753.857384278477, 5156.20078887773, 6143.082036032849, 6333.334999825044, 6343.111964324999, 6359.459445947146, 6548.345165483413, 6654.534182607268, 6711.530192886451, 6736.6631668416385, 6762.256151823295, 6295.874082518324, 6300.495960323163, 6303.180586523723, 6335.208098987627, 6335.524835637346, 4508.774561271924, 5896.380880007623, 5904.911426328608, 5912.497437717756, 5952.375000624958, 5921.023031199804, 6632.83417914556, 6814.0, 6895.75864844728, 6956.40870526011, 6755.560888542229, 6777.220619628806, 6788.524803263922, 6807.965960170365, 6819.727210911508, 6937.202221744564, 6949.0, 6958.199807022055, 6979.790606281819, 6983.825404364931, 6374.649394283359, 6490.7079181436, 6543.149390579194, 6667.899981500378, 6678.966605166943, 6181.81454556364, 6713.56361836479, 6767.966160169168, 6784.253732089367, 6786.76246331376, 5555.8888822224135, 5982.401759824157, 6195.628262304276, 6251.468625166912, 6524.739010439529, 6448.065030570448, 6517.315681853337, 6521.706523206372, 6523.64119973406, 6545.705443254969, 6193.535484838565, 6243.157173781481, 6279.372062793684, 6292.4966002720585, 6409.807705768834, 6496.805095846943, 6504.772332968323, 6522.315156908457, 6535.5425120241125, 6547.639879806657, 6357.7139028742895, 6362.840928976812, 6363.5545511813725, 6364.045393190873, 6381.234251889803], "value": 6051.618782641433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.7384526183728, 812.7236739508568, 1160.657606006228, 1261.1046157228368, 1271.7011502296964, 37727.81136094319, 38436.23127537448, 38486.61513384865, 38571.807140964294, 38770.0, 37340.25319493615, 37573.87278381653, 37681.434778478346, 37904.62095379045, 38510.84467465973, 7766.883496747568, 8294.875576866352, 8486.957565212166, 8487.74536763895, 8495.150484951517, 8454.154584541559, 8476.78808029798, 8542.145785421439, 8551.74344769658, 8581.184787445183, 8461.450005749637, 8521.10528394519, 8539.573021348939, 8575.742727718147, 8592.8711069334, 8513.02100258469, 8516.659333626647, 8548.658053677907, 8553.0, 8566.957165214135, 8404.369672274604, 8484.872726909101, 8492.065872753992, 8501.277391421736, 8515.616797244138, 37605.62394376076, 37719.81140094282, 37829.62170378288, 37858.0, 37912.43131353032, 3621.9275614487565, 3655.872044478457, 3660.7437479376454, 3663.9267214655556, 3676.4301533262387, 30448.695513044808, 30650.38699226003, 30799.846000770078, 30807.229819254473, 30833.0, 2754.903578374747, 3180.8091514508974, 3221.532877732739, 3223.4358987177384, 3230.176305042199, 285790.0, 286928.0, 287036.0, 287295.0, 289714.0, 33113.50329745056, 38066.0, 46735.0, 47748.0, 48171.5182848174, 41935.580644193775, 49242.0, 50380.74809626002, 50606.49393506091, 50627.98744025172, 12084.516619335129, 14111.647208819657, 14281.0, 14438.0, 14463.0, 9577.569009394454, 9711.805763884682, 11415.60045398407, 11557.24877882935, 11759.823602645967, 13291.936645068372, 13666.658333541742, 13681.31593420325, 13691.452341906412, 13813.51652692151, 5153.7165455900285, 5863.0, 5908.556858235649, 5954.19618351517, 5963.0, 1002.1581871228203, 1011.2920955331298, 1017.4048181813722, 1021.9948900255451, 1056.5509658395151, 23514.294571162893, 24576.262712118663, 24607.507849842903, 24731.010759569417, 24777.87611061969, 1030.6598822388594, 1044.2690116918188, 1095.895889890459, 1145.8338540911689, 1146.6789298996293, 9626.614935402646, 12040.879591204151, 12518.79868296801, 12586.685332866751, 12630.873691263154, 325852.3707381448, 329267.0, 330694.6930530712, 331420.0, 332120.0, 54711.0, 54754.35736927899, 54793.0, 54927.450725493036, 55095.72452137714, 1314.8290722206052, 1477.8448262932238, 1534.1638806850294, 1535.8233803112785, 1546.404634215835, 24201.91091400856, 24466.51066978686, 24476.75523244816, 24488.897999589706, 24523.38691532726, 38757.09321800933, 38819.805900970314, 38874.80562597282, 38912.0, 38949.805250973564, 279398.60300698364, 282060.0, 283506.0, 284184.0, 285506.0, 34144.317113658086, 34703.65296347004, 35222.64777352194, 35251.76618818329, 35291.647083528835, 43137.78431107824, 44278.0, 44689.32966005448, 44846.0, 44986.77506612577, 29764.0, 30651.693483065777, 31274.687253127177, 31299.84350078235, 31633.367332653677, 30590.0, 30696.386072278878, 31233.531497028016, 31269.218269543442, 31529.684703152674, 37004.25991480209, 38149.23701525899, 38158.61841381661, 38163.04592385213, 38367.808160959015, 29107.563386548794, 31627.52558711575, 31767.841160794047, 31963.0, 31981.200469988435, 23776.762232377456, 24518.877405612857, 24615.0, 24703.50592988166, 24876.875615621808], "value": 17560.341626069545, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.8452738046888, 422.461361763751, 431.07964495801457, 446.72526396266284, 448.13957202171827, 20606.0, 20900.954952252385, 21491.92540372983, 21600.0, 21797.891010544943, 21628.35114946554, 23330.766692333073, 23357.18249861255, 23650.17224397142, 23765.287041388743, 6018.3981601839905, 6102.603330783485, 6245.687715614224, 6267.091271765591, 6276.466500347476, 5080.974595127038, 6160.722767475474, 6180.165677633507, 6208.0, 6215.968920155393, 5201.609879259052, 5380.946190538084, 6141.754329826802, 6150.96924515379, 6186.474149697258, 4713.622910167195, 4900.0, 6085.786997455112, 6095.847603809896, 6103.1455596216565, 5312.787488500456, 5390.137577987503, 5422.810201642963, 5427.457254274541, 5452.863678408072, 27621.86189069062, 27875.442491150265, 27889.0, 28310.15069547917, 28431.715682843114, 2151.354593621923, 2244.5847518209184, 2249.2352600116037, 2250.932472025842, 2253.312739614417, 17505.824941750674, 17585.296588136458, 17615.207315670825, 18149.0, 18269.269229230817, 2133.306675330524, 2142.282335417621, 2169.5227050048966, 2179.4333473297056, 2184.0936011555186, 154429.0, 158941.0, 161534.0, 163370.9999999988, 163856.3614363865, 12668.98648108149, 12680.048996325313, 12926.41831117602, 13021.0, 13074.869251307555, 16563.503094907173, 16629.750553741702, 16658.0, 16668.83331166697, 16699.74950375745, 8601.096884827128, 8954.23888969432, 8981.326400519987, 8987.910120898838, 9098.909010909938, 8380.832383352421, 8409.285210757032, 8431.620577074047, 8586.570671466401, 8604.827903441896, 7836.686532538634, 7866.192509237196, 8015.238552337519, 8056.758297251092, 8064.661248238062, 2172.8261739060836, 2181.3019833653398, 2182.6398644223527, 2185.4208634711813, 2340.8361414700807, 929.330881765128, 948.7912659214961, 957.6600306890954, 967.8410995309468, 971.2715463402449, 20773.480662983548, 21413.143474260854, 21537.353879383645, 21614.0, 21751.78248217529, 966.4249771386014, 974.5565767575736, 998.9760495492179, 1004.42747633848, 1012.4937531234383, 10729.570817167227, 11030.724231894266, 11198.776024479464, 11226.438678066064, 11243.718907027227, 546485.0, 557819.0, 558229.0, 576968.0, 578345.0, 95971.0, 123448.14827777591, 129950.35024825005, 135680.0, 139401.60598394088, 1040.5889673579045, 1119.1382635370883, 1153.7865494883474, 1175.2184797109996, 1186.3771519951922, 21775.891120544296, 22566.435839104153, 23037.88481057651, 23106.422339440975, 23120.4219894504, 57679.0, 58640.0, 58956.11565826602, 59564.0, 59768.0, 63961.72076558349, 79814.60092699499, 81225.0, 81461.0, 81526.0, 7653.961730191313, 8737.301015918838, 8924.776880578038, 9089.454632722058, 9231.723048308562, 14060.226687532284, 14078.155310681392, 14239.786403204167, 14475.059121157092, 14566.854331456974, 9373.718788436357, 11831.46758395857, 12012.699682508008, 12071.879281207075, 12214.450349734105, 10930.781384372427, 11019.173561983043, 11206.83189752138, 11251.043661288883, 11423.0, 11875.940620296844, 12002.699932501408, 12306.384680766105, 12311.19977201479, 12434.0, 8291.461055031403, 8856.3800533962, 8979.730608081769, 9097.863532046893, 9484.0, 11536.480858361561, 12025.879741202476, 12271.263724176579, 12282.508699651913, 12294.323812190416], "value": 10511.828516056241, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.917504949703, 1423.402171088143, 1431.1913768720672, 1440.7262620102174, 1450.7751298548728, 11326.263792853459, 11641.01051410631, 11813.99581035611, 11827.586034488795, 11833.644990650284, 11507.07943364531, 11702.765944681123, 11707.2390294631, 11717.941410292937, 11722.648320550397, 2500.6499090127395, 2560.5135024345313, 2563.4873025394995, 2568.627549005393, 2569.704483984347, 2354.1407386303963, 2467.7532246775268, 2507.122507122511, 2541.3392517945376, 2541.440883005736, 2330.207729372013, 2489.253224032792, 2533.873306334685, 2539.7968162546954, 2541.898324067035, 2466.4573793765344, 2497.67530221071, 2518.7229404765462, 2532.696076470835, 2534.037065914959, 2369.6563998220163, 2480.900763969439, 2484.3168128764555, 2531.632913227572, 2541.682289713786, 11831.881681183164, 11855.94072029643, 11865.110116741278, 11879.58421455245, 11896.88103118975, 5334.866628334323, 6591.11020012297, 6802.4217941475035, 6809.897851532231, 6851.760188393382, 3776.697864170887, 4075.572064933199, 4097.44684467596, 4430.933535996963, 4476.440444944382, 1251.5494422008069, 1254.7678679444332, 1291.2123604601186, 1306.875846794553, 1315.486960085558, 13827.585172444842, 14007.369668364847, 16235.0, 16569.502914912573, 16733.247003884855, 5555.749991250323, 5713.914291285634, 5768.394318596571, 5831.883362332815, 5838.0, 11823.704407389712, 11912.0, 11917.821232681517, 11918.582849600223, 11989.160758746804, 6516.57642253252, 6583.407493325623, 6584.604923704497, 6594.241662208811, 6603.438707709898, 5983.551233657493, 6208.348123447064, 6218.751249949952, 6227.813165605039, 6250.968745156336, 3757.229767897574, 3925.0, 4136.730864581626, 4407.889802754893, 4429.092036132585, 1096.6874440784338, 1261.3945306252867, 1262.0219330019308, 1262.072376803049, 1292.9030322725832, 786.8347646994081, 795.5823192823698, 817.8446095241885, 821.7165078048064, 846.9110743371981, 5596.496315331639, 6189.535784816157, 6257.217847769029, 6360.777372792023, 6442.549021568445, 574.4542684449706, 585.8828234353147, 588.3910152991718, 594.094006639869, 601.1013534765553, 3937.9212415751526, 4153.958460415418, 4263.87208383749, 4348.108637729258, 4405.339199120158, 14448.638784030483, 15199.848001519844, 15230.543083707507, 15262.618434539225, 15266.618334541725, 14445.78331325031, 14965.551033469013, 14965.850341496445, 14995.625109372353, 15057.472988445352, 6268.4671802896355, 8517.616707248064, 8744.08187140341, 8751.168638979414, 8766.780830479289, 6185.257769067741, 6796.626185559841, 7021.262767409348, 7062.329078737512, 7216.242294559206, 12379.319137447526, 12700.745985080062, 12738.363081845686, 12940.676483087998, 12991.0, 12961.027922906009, 13028.153170043917, 13067.803982940453, 13188.736225275632, 13382.330883455983, 9304.255659547356, 9483.336166468282, 9505.524723763923, 9510.286728495528, 9527.618895244113, 10696.251262411557, 11006.064484518585, 11125.777484450104, 11149.383339415901, 11166.553337866395, 9117.407368521024, 9178.816423671622, 9321.067893210624, 9382.765430864283, 9397.201237894855, 7601.809954751176, 8826.249768769727, 8924.464532128093, 8978.147076027766, 9052.728418147466, 8053.959730201312, 8175.591220439073, 8231.876521852297, 8232.300254478436, 8245.093039765501, 4933.407991041098, 5965.164876917148, 6039.184710064261, 6063.181470501602, 6136.570440069331, 5226.8954620908125, 5422.945770542402, 6092.116643086639, 6216.844578885564, 6322.557420980487], "value": 5285.178546404489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9472079188123, 380.824820582532, 391.92553414851176, 398.0307950141405, 398.30098177698153, 4962.925556116661, 5121.231815227713, 5324.520793128618, 6147.538934579902, 6372.617642941415, 5601.439856014396, 5950.285965684113, 6064.9090263646085, 6183.288921773998, 6194.225721784777, 3572.946405803902, 4446.444194475691, 4520.615747661444, 4533.252013417782, 4614.630829533629, 4494.730316181023, 4515.638748900096, 4523.36672865799, 4524.592786649201, 4537.795799189028, 4243.236217480852, 4458.754768487748, 4477.664175186857, 4487.932681009787, 4505.954940450602, 4345.69580129391, 4373.934390984138, 4460.821567137311, 4491.169133710274, 4499.437570303712, 3724.7578907370935, 3827.7129215308964, 3841.1741475582885, 3841.2317536492533, 3844.692424606025, 6065.454109130155, 6073.605215660968, 6077.088436734481, 6078.361772013919, 6079.604825686316, 4670.813167473297, 4698.577128058491, 5150.97424512875, 5343.572514198855, 5384.892302153974, 5727.770889164429, 6591.901121483182, 6599.274079851212, 6625.701843417058, 6638.20341559016, 4218.008767939532, 4346.500152482474, 4354.651627869763, 4362.0, 4373.409589705413, 6848.623325717083, 7048.506604537684, 7082.681279342442, 7090.093061844619, 7147.462650758957, 4387.692861499664, 4392.604665580113, 4398.0, 4402.3396490526675, 4431.446069241345, 4483.775811209492, 4487.708298960557, 4491.438570178728, 4500.504944456107, 4502.549745025472, 3897.142628621699, 4703.529647035269, 5050.191969284897, 5246.396664383645, 5341.050516561505, 5154.226865970059, 6504.0243963405865, 6596.208454985433, 6613.735450582019, 6760.411303343711, 1506.6610012747262, 1519.080956021613, 1520.7566789313657, 1520.9771853422208, 1530.8469153084825, 953.2850362228329, 996.5963784667222, 998.0468652436905, 1003.708924411927, 1014.2899970021015, 5497.862553436116, 6376.266729326094, 6401.015685137622, 6403.96798016007, 6419.2296924369375, 6386.552941294065, 6727.260001399842, 6981.127359080115, 7001.339490993491, 7009.579425234502, 5798.652080875161, 6013.909791353133, 6164.044573091178, 6288.9056664150075, 6354.586951848208, 2881.481333359973, 3316.8673253069605, 3605.2789442111684, 3607.9098022549647, 3621.63783621641, 6074.757009719562, 6953.930460695429, 6973.0, 7100.715971361191, 7205.819854503575, 6097.3292937776805, 6672.799816005528, 7096.822579435556, 7112.25321341262, 7132.144142702805, 6906.343897329745, 7119.608421536762, 7132.536385134949, 7166.452605960083, 7224.638768061576, 6161.784337548164, 6201.658908760061, 6931.445484361138, 6955.513114081966, 6957.234704182637, 5607.74765135578, 6387.8722425550295, 6807.421369183675, 6821.0, 6870.553414028072, 6019.735855470401, 6792.76225332111, 6866.553674011173, 7196.0645116134565, 7222.566646001257, 3702.981485092665, 4390.507227542651, 4421.867343979686, 4426.919347906479, 4451.6661250405705, 5125.410577783454, 5841.299044114563, 6866.107406037183, 6875.209350924508, 6964.1643002837955, 5561.998840208639, 5763.971180144073, 5901.819636072717, 6382.808515744535, 6621.569597976116, 5638.830835074954, 5969.7313620888035, 6625.536212465081, 6727.562708423937, 6732.158480189976, 6540.247871494648, 6580.0, 6606.933930660824, 6608.669566521751, 6609.4712423005085, 5192.3769147702515, 6559.819232538189, 6710.4230505832065, 6760.222574404006, 6793.558418702769, 5812.070068788974, 6161.907571386522, 6498.610083394822, 6551.672416379257, 6555.70499327541], "value": 4761.953609360437, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.7724237213575, 829.7261903571822, 832.196929962586, 844.248618729331, 855.9786005349868, 14166.29168541574, 14793.186374749384, 16219.513414597566, 16224.837751622481, 16692.66614667706, 16473.83526164738, 16939.237734301954, 17198.484045478624, 17221.91389043053, 17482.56293592661, 966.0822218892027, 972.1008067537534, 974.8001659659789, 985.0346660272937, 989.4261328429494, 1009.530568285747, 1012.7974405118969, 1014.751385910453, 1016.9491525423699, 1023.355286169712, 1035.4874337203057, 1046.6022911293735, 1047.9886909132686, 1050.1283934334522, 1051.5110473629761, 1030.5156576409076, 1044.791041791641, 1047.1988928469727, 1047.4867315015663, 1051.285126114242, 771.9925497226118, 773.9690412383497, 774.6746366526016, 776.3207193705508, 778.1712476212815, 16248.268827902655, 16848.410305639365, 16977.0, 17013.319467221296, 17098.14509274531, 16090.75863862043, 16487.917560412243, 16585.0, 16797.91601041999, 16821.32714691423, 13498.987575931844, 13902.860971390359, 13931.721365572734, 14050.718985620231, 14074.296285185803, 3334.4998250262656, 3366.4950257461333, 3379.594448666151, 3498.7900725956524, 3513.929721405583, 108250.0, 124248.37875810562, 126311.42131472142, 127048.0, 127198.36400818029, 13287.402066907012, 13625.046246762631, 13640.795388069188, 13651.453941842414, 13673.863261367458, 24579.87710061474, 25279.873600632247, 25434.0, 25761.742382575936, 25792.871035645076, 7946.841063178819, 8073.354131669453, 8102.473339232931, 8113.107558168596, 8138.186181381933, 3878.4764056851964, 3940.8817735467983, 3941.586133456003, 3953.5058117735284, 3960.20795840833, 4126.216018956388, 4315.438992930899, 4348.847790327323, 4350.934735978963, 4353.455818022747, 908.8591268353416, 935.0976307862945, 944.5088553951906, 953.9379940303858, 954.6467806911487, 638.6040654794058, 663.4659099424913, 671.1509939925998, 674.8211723893172, 686.2691233835949, 10999.34003959765, 11190.776184476264, 11586.36275004866, 11965.042796576254, 12251.938740306241, 614.6803662095774, 624.5971348480205, 632.7816903168399, 634.8826066123655, 654.5614438326332, 5385.353757549119, 5415.702136382537, 5605.971970140124, 5624.606277560531, 5690.601657883908, 68953.65523172452, 97768.15622906329, 101312.0, 101580.0, 112110.31834522489, 89016.21967560613, 89427.55286223527, 89618.0, 94253.0, 95545.61135971657, 85082.5745871279, 85227.57386213029, 85401.0, 85437.14562854292, 86367.13632863591, 10078.546465409221, 11862.584809531625, 12520.311382873664, 12559.497620095459, 12560.874391256337, 29164.541772910845, 29448.85275573694, 34887.825560872036, 40061.79969100136, 40916.79541602273, 45749.31376029296, 72509.6374518124, 73746.0, 74997.25002750121, 75748.0, 13849.653758656113, 16339.836601634306, 16422.343106275614, 16581.0, 16592.419265325654, 18209.635807284045, 18516.72224916654, 18533.166007529424, 18707.625847483247, 18770.0, 13444.193348399127, 14760.409583616965, 16954.152292385577, 17086.829131708524, 17437.866538674945, 14199.64500887486, 16372.918135409247, 16905.408310709066, 17487.912560437115, 17513.03678297706, 16528.338866445207, 16544.586385340463, 16702.997820130346, 16911.91544042272, 17000.48998530046, 10157.796844063225, 10211.540480678239, 11876.228045177037, 12075.939620301842, 12099.939500302442, 10884.564617415532, 12214.389280536116, 13156.539521116714, 13198.868011319764, 13687.247201404018], "value": 8341.959270383479, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 115.0, 115.0, 113.0, 115.0, 116.0, 115.0, 115.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 144.0, 111.0, 154.0, 106.0, 110.0, 108.0, 112.0, 139.0, 109.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 192.0, 198.0, 227.0, 256.0, 284.0, 281.0, 207.0, 197.0, 215.0], "value": 225.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 47.0, 61.0, 100.0, 85.0, 83.0, 81.0, 58.0, 77.0, 55.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 84.0, 86.0, 86.0, 69.0, 86.0, 73.0, 73.0, 90.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 134.0, 87.0, 136.0, 98.0, 77.0, 122.0, 135.0, 104.0, 90.0], "value": 108.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 95.0, 149.0, 155.0, 98.0, 110.0, 153.0, 107.0, 152.0], "value": 129.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 94.0, 92.0, 91.0, 75.0, 149.0, 147.0, 90.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 51.0, 53.0, 50.0, 51.0, 49.0, 52.0, 52.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 65.0, 49.0, 70.0, 77.0, 68.0, 57.0, 66.0, 51.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 87.0, 96.0, 76.0, 98.0, 103.0, 106.0, 104.0, 83.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 158.0, 147.0, 174.0, 152.0, 153.0, 139.0, 137.0, 187.0, 142.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 215.0, 234.0, 230.0, 187.0, 183.0, 186.0, 187.0, 184.0, 185.0], "value": 197.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 70.0, 66.0, 68.0, 66.0, 69.0, 67.0, 68.0], "value": 67.8, "unit": "score"}]}]} 09:58:39 INFO - Return code: 0 09:58:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:39 INFO - # TBPL SUCCESS # 09:58:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:39 INFO - 2017-05-04 09:58:39.175 screenresolution[1660:11024] starting screenresolution argv=screenresolution get 09:58:39 INFO - 2017-05-04 09:58:39.178 screenresolution[1660:11024] Display 0: 1600x1200x32@60 09:58:39 INFO - 2017-05-04 09:58:39.185 screenresolution[1661:11027] starting screenresolution argv=screenresolution list 09:58:39 INFO - Available Modes on Display 0 09:58:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:39 INFO - Intel Iris: 09:58:39 INFO - Chipset Model: Intel Iris 09:58:39 INFO - Type: GPU 09:58:39 INFO - Bus: Built-In 09:58:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:39 INFO - Vendor: Intel (0x8086) 09:58:39 INFO - Device ID: 0x0a2e 09:58:39 INFO - Revision ID: 0x0009 09:58:39 INFO - Displays: 09:58:39 INFO - Display: 09:58:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:39 INFO - Display Serial Number: 1600x1200 60 09:58:39 INFO - Main Display: Yes 09:58:39 INFO - Mirror: Off 09:58:39 INFO - Online: Yes 09:58:39 INFO - Rotation: Supported 09:58:39 INFO - Return code: 0 09:58:39 INFO - Running post-action listener: _resource_record_post_action 09:58:39 INFO - [mozharness: 2017-05-04 16:58:39.427852Z] Finished run-tests step (success) 09:58:39 INFO - Running post-run listener: _resource_record_post_run 09:58:39 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 33821696; Write bytes: 511792128; Read time: 206; Write time: 1579 09:58:39 INFO - TinderboxPrint: CPU usage
54.3% 09:58:39 INFO - TinderboxPrint: I/O read bytes / time
33,821,696 / 206 09:58:39 INFO - TinderboxPrint: I/O write bytes / time
511,792,128 / 1,579 09:58:39 INFO - TinderboxPrint: CPU idle
2,191.4 (45.7%) 09:58:39 INFO - TinderboxPrint: CPU system
969.2 (20.2%) 09:58:39 INFO - TinderboxPrint: CPU user
1,633.3 (34.1%) 09:58:39 INFO - TinderboxPrint: Swap in / out
238,448,640 / 0 09:58:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194824704; Write bytes: 195700736; Read time: 15026; Write time: 399 09:58:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32859136; Write bytes: 299211776; Read time: 193; Write time: 1126 09:58:39 INFO - Running post-run listener: _upload_blobber_files 09:58:39 INFO - Blob upload gear active. 09:58:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:39 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 09:58:39 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'] 09:58:39 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 09:58:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:58:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:58:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:41 INFO - (blobuploader) - INFO - Done attempting. 09:58:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:58:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:42 INFO - (blobuploader) - INFO - Done attempting. 09:58:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:43 INFO - (blobuploader) - INFO - Done attempting. 09:58:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:45 INFO - (blobuploader) - INFO - Done attempting. 09:58:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:45 INFO - Return code: 0 09:58:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1a6ffb51858b10ba2e308576dd1f83762326742f878c8f6ae0ec22927db3fab15dbce74ff2ea37b2138b8510a607ab105b123c0c7f91b15642edaf3d554ba0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1840ccc83b7739e73536cf9c93448573a33f8f16151d2d49f2156b7587563d183c4b0f881e708d1c1cd87bfeeb688afe27343dc76dd11fa77f64296a9bb98fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9789d8de3f9769a1c77e59df6e2acd0bb1b2d0754d028b228990650469b37b0acfb337964f4a0600010a415297768a514a2d773b88b9c00d1907b7f2ea42d8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee609d28f70c26029c339a1da8dc534c2e14f6dc869cd7cd18c29efbe9cdbd037925c735ab54d7d97dc6d3f88fb3bc8d0b031e36567306f5df527e7363785d61"} 09:58:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:45 INFO - Contents: 09:58:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1a6ffb51858b10ba2e308576dd1f83762326742f878c8f6ae0ec22927db3fab15dbce74ff2ea37b2138b8510a607ab105b123c0c7f91b15642edaf3d554ba0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1840ccc83b7739e73536cf9c93448573a33f8f16151d2d49f2156b7587563d183c4b0f881e708d1c1cd87bfeeb688afe27343dc76dd11fa77f64296a9bb98fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9789d8de3f9769a1c77e59df6e2acd0bb1b2d0754d028b228990650469b37b0acfb337964f4a0600010a415297768a514a2d773b88b9c00d1907b7f2ea42d8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee609d28f70c26029c339a1da8dc534c2e14f6dc869cd7cd18c29efbe9cdbd037925c735ab54d7d97dc6d3f88fb3bc8d0b031e36567306f5df527e7363785d61"} 09:58:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:45 INFO - Copying logs to upload dir... 09:58:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.097639 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-04 09:58:45.995169) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:45.998879) ========= 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.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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/5c1a6ffb51858b10ba2e308576dd1f83762326742f878c8f6ae0ec22927db3fab15dbce74ff2ea37b2138b8510a607ab105b123c0c7f91b15642edaf3d554ba0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1840ccc83b7739e73536cf9c93448573a33f8f16151d2d49f2156b7587563d183c4b0f881e708d1c1cd87bfeeb688afe27343dc76dd11fa77f64296a9bb98fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9789d8de3f9769a1c77e59df6e2acd0bb1b2d0754d028b228990650469b37b0acfb337964f4a0600010a415297768a514a2d773b88b9c00d1907b7f2ea42d8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee609d28f70c26029c339a1da8dc534c2e14f6dc869cd7cd18c29efbe9cdbd037925c735ab54d7d97dc6d3f88fb3bc8d0b031e36567306f5df527e7363785d61"} build_url:https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/KpWj0fmlR7CmzeLUMT9I6g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1a6ffb51858b10ba2e308576dd1f83762326742f878c8f6ae0ec22927db3fab15dbce74ff2ea37b2138b8510a607ab105b123c0c7f91b15642edaf3d554ba0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1840ccc83b7739e73536cf9c93448573a33f8f16151d2d49f2156b7587563d183c4b0f881e708d1c1cd87bfeeb688afe27343dc76dd11fa77f64296a9bb98fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9789d8de3f9769a1c77e59df6e2acd0bb1b2d0754d028b228990650469b37b0acfb337964f4a0600010a415297768a514a2d773b88b9c00d1907b7f2ea42d8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee609d28f70c26029c339a1da8dc534c2e14f6dc869cd7cd18c29efbe9cdbd037925c735ab54d7d97dc6d3f88fb3bc8d0b031e36567306f5df527e7363785d61"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:46.040658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:46.041471) ========= 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.6PPDBlrkIH/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.gu6QXBklRI/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005335 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:58:46.092789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:58:46.093196) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:58:46.093609) ========= ========= Total master_lag: 0.23 =========