builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0147 starttime: 1490162221.39 results: success (0) buildid: 20170321204834 builduid: c07d158701bf4f3182c5462409fe5ca3 revision: 201231223cd4354a450c3e5d80959f35b8e4cf0c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.386290) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.386768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.387082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.418179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.418548) ========= 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.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 22:57:01-- 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% 9.13M=0.001s 2017-03-21 22:57:01 (9.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090376 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.537205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.537972) ========= 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.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.027673 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:01.589672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 22:57:01.590067) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 201231223cd4354a450c3e5d80959f35b8e4cf0c --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 201231223cd4354a450c3e5d80959f35b8e4cf0c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 22:57:01,736 truncating revision to first 12 chars 2017-03-21 22:57:01,736 Setting DEBUG logging. 2017-03-21 22:57:01,736 attempt 1/10 2017-03-21 22:57:01,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/201231223cd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 22:57:02,703 unpacking tar archive at: mozilla-central-201231223cd4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.257454 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 22:57:02.874658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:02.875437) ========= script_repo_revision: 201231223cd4354a450c3e5d80959f35b8e4cf0c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:02.876383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:02.877093) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 22:57:02.899340) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-21 22:57:02.900047) ========= /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.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:57:03 INFO - MultiFileLogger online at 20170321 22:57:03 in /builds/slave/test 22:57:03 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 22:57:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:57:03 INFO - {'append_to_log': False, 22:57:03 INFO - 'base_work_dir': '/builds/slave/test', 22:57:03 INFO - 'blob_upload_branch': 'Firefox', 22:57:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:57:03 INFO - 'branch': 'Firefox', 22:57:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:57:03 INFO - 'config_files': ('talos/mac_config.py',), 22:57:03 INFO - 'default_actions': ('clobber', 22:57:03 INFO - 'read-buildbot-config', 22:57:03 INFO - 'download-and-extract', 22:57:03 INFO - 'populate-webroot', 22:57:03 INFO - 'create-virtualenv', 22:57:03 INFO - 'install', 22:57:03 INFO - 'run-tests'), 22:57:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:57:03 INFO - 'download_minidump_stackwalk': True, 22:57:03 INFO - 'download_symbols': 'ondemand', 22:57:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:57:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:57:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:57:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:57:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:57:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:57:03 INFO - 'gecko_profile': False, 22:57:03 INFO - 'gecko_profile_interval': 0, 22:57:03 INFO - 'installer_path': 'installer.exe', 22:57:03 INFO - 'log_level': 'info', 22:57:03 INFO - 'log_name': 'talos', 22:57:03 INFO - 'log_to_console': True, 22:57:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:57:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:57:03 INFO - 'opt_config_files': (), 22:57:03 INFO - 'pip_index': False, 22:57:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:57:03 INFO - 'cmd': ('bash', 22:57:03 INFO - '-c', 22:57:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:57:03 INFO - 'enabled': True, 22:57:03 INFO - 'halt_on_failure': False, 22:57:03 INFO - 'name': 'check_screen_resolution'},), 22:57:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:57:03 INFO - 'cmd': ('bash', 22:57:03 INFO - '-c', 22:57:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:57:03 INFO - 'enabled': True, 22:57:03 INFO - 'halt_on_failure': False, 22:57:03 INFO - 'name': 'check_screen_resolution'},), 22:57:03 INFO - 'run_cmd_checks_enabled': True, 22:57:03 INFO - 'suite': 'dromaeojs-e10s', 22:57:03 INFO - 'system_bits': '32', 22:57:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:57:03 INFO - 'title': 't-yosemite-r7-0147', 22:57:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:57:03 INFO - 'use_talos_json': True, 22:57:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:57:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:57:03 INFO - 'work_dir': 'build'} 22:57:03 INFO - [mozharness: 2017-03-22 05:57:03.161109Z] Running clobber step. 22:57:03 INFO - Running pre-action listener: _resource_record_pre_action 22:57:03 INFO - Running main action method: clobber 22:57:03 INFO - rmtree: /builds/slave/test/build 22:57:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:57:05 INFO - Running post-action listener: _resource_record_post_action 22:57:05 INFO - [mozharness: 2017-03-22 05:57:05.625012Z] Finished clobber step (success) 22:57:05 INFO - [mozharness: 2017-03-22 05:57:05.625167Z] Running read-buildbot-config step. 22:57:05 INFO - Running pre-action listener: _resource_record_pre_action 22:57:05 INFO - Running main action method: read_buildbot_config 22:57:05 INFO - Using buildbot properties: 22:57:05 INFO - { 22:57:05 INFO - "project": "", 22:57:05 INFO - "product": "firefox", 22:57:05 INFO - "script_repo_revision": "production", 22:57:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:57:05 INFO - "repository": "", 22:57:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:57:05 INFO - "buildid": "20170321204834", 22:57:05 INFO - "pgo_build": "False", 22:57:05 INFO - "basedir": "/builds/slave/test", 22:57:05 INFO - "buildnumber": 130, 22:57:05 INFO - "slavename": "t-yosemite-r7-0147", 22:57:05 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:57:05 INFO - "platform": "yosemite_r7", 22:57:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:57:05 INFO - "branch": "mozilla-central", 22:57:05 INFO - "repo_path": "mozilla-central", 22:57:05 INFO - "revision": "201231223cd4354a450c3e5d80959f35b8e4cf0c", 22:57:05 INFO - "stage_platform": "macosx64", 22:57:05 INFO - "builduid": "c07d158701bf4f3182c5462409fe5ca3", 22:57:05 INFO - "slavebuilddir": "test" 22:57:05 INFO - } 22:57:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:57:05 INFO - Running post-action listener: _resource_record_post_action 22:57:05 INFO - [mozharness: 2017-03-22 05:57:05.628601Z] Finished read-buildbot-config step (success) 22:57:05 INFO - [mozharness: 2017-03-22 05:57:05.628733Z] Running download-and-extract step. 22:57:05 INFO - Running pre-action listener: _resource_record_pre_action 22:57:05 INFO - Running main action method: download_and_extract 22:57:05 INFO - mkdir: /builds/slave/test/build/tests 22:57:05 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')]} 22:57:05 INFO - trying https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:57:05 INFO - Downloading https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/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 22:57:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/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 22:57:06 INFO - Downloaded 1325 bytes. 22:57:06 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:57:06 INFO - Using the following test package requirements: 22:57:06 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:57:06 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:57:06 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:57:06 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:57:06 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'jsshell-mac.zip'], 22:57:06 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:57:06 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:57:06 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:57:06 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:57:06 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:57:06 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:57:06 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:57:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:57:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:57:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:57:06 INFO - Fetch https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:57:08 INFO - Expected file size: 52476217 22:57:08 INFO - Obtained file size: 52476217 22:57:25 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 22:57:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:57:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:57:25 INFO - Fetch https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:57:27 INFO - Expected file size: 52476217 22:57:27 INFO - Obtained file size: 52476217 22:57:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:57:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:57:42 INFO - Fetch https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:57:43 INFO - Expected file size: 14404171 22:57:43 INFO - Obtained file size: 14404171 22:57:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:43 INFO - trying https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:57:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:57:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/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 22:57:46 INFO - Downloaded 75278845 bytes. 22:57:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:57:46 INFO - mkdir: /builds/slave/test/properties 22:57:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:57:46 INFO - Writing to file /builds/slave/test/properties/build_url 22:57:46 INFO - Contents: 22:57:46 INFO - build_url:https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:57:46 INFO - Running post-action listener: _resource_record_post_action 22:57:46 INFO - Running post-action listener: set_extra_try_arguments 22:57:46 INFO - [mozharness: 2017-03-22 05:57:46.843521Z] Finished download-and-extract step (success) 22:57:46 INFO - [mozharness: 2017-03-22 05:57:46.843641Z] Running populate-webroot step. 22:57:46 INFO - Running pre-action listener: _resource_record_pre_action 22:57:46 INFO - Running main action method: populate_webroot 22:57:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:57:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:57:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:57:46 INFO - '--tppagecycles', 22:57:46 INFO - '7'], 22:57:46 INFO - 'tests': ['tsvgm']}}, 22:57:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:57:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:57:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:57:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:57:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['damp', 'tps']}, 22:57:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'tests': ['damp', 'tps']}, 22:57:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['dromaeo_dom']}, 22:57:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:57:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['basic_compositor_video']}, 22:57:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:57:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'other-e10s': {'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['a11yr', 22:57:46 INFO - 'ts_paint', 22:57:46 INFO - 'tpaint', 22:57:46 INFO - 'sessionrestore', 22:57:46 INFO - 'sessionrestore_no_auto_restore', 22:57:46 INFO - 'tabpaint']}, 22:57:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['tsvgx', 22:57:46 INFO - 'tsvgr_opacity', 22:57:46 INFO - 'tart', 22:57:46 INFO - 'tscrollx', 22:57:46 INFO - 'cart', 22:57:46 INFO - 'tsvg_static']}, 22:57:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:57:46 INFO - 'tsvgr_opacity', 22:57:46 INFO - 'tart', 22:57:46 INFO - 'tscrollx', 22:57:46 INFO - 'cart', 22:57:46 INFO - 'tsvg_static']}, 22:57:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'talos_options': ['--disable-e10s'], 22:57:46 INFO - 'tests': ['tp5o']}, 22:57:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:57:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'talos_options': ['--disable-e10s', 22:57:46 INFO - '--xperf_path', 22:57:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:46 INFO - 'tests': ['tp5n']}, 22:57:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:57:46 INFO - 'talos_options': ['--xperf_path', 22:57:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:46 INFO - 'tests': ['tp5n']}}, 22:57:46 INFO - 'talos.zip': {'path': '', 22:57:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:57:46 INFO - Running post-action listener: _resource_record_post_action 22:57:46 INFO - [mozharness: 2017-03-22 05:57:46.861793Z] Finished populate-webroot step (success) 22:57:46 INFO - [mozharness: 2017-03-22 05:57:46.861897Z] Running create-virtualenv step. 22:57:46 INFO - Running pre-action listener: _resource_record_pre_action 22:57:46 INFO - Running main action method: create_virtualenv 22:57:46 INFO - Creating virtualenv /builds/slave/test/build/venv 22:57:46 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 22:57:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:57:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:57:46 INFO - Using real prefix '/tools/python27' 22:57:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:57:47 INFO - Installing distribute.............................................................................................................................................................................................done. 22:57:50 INFO - Installing pip.................done. 22:57:50 INFO - Return code: 0 22:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:57:50 INFO - Reading from file tmpfile_stdout 22:57:50 INFO - Output received: 22:57:50 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) 22:57:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:50 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:57:50 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 22:57:50 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 22:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:50 INFO - 'HOME': '/Users/cltbld', 22:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:50 INFO - 'LOGNAME': 'cltbld', 22:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:50 INFO - 'MOZ_NO_REMOTE': '1', 22:57:50 INFO - 'NO_EM_RESTART': '1', 22:57:50 INFO - 'PAGER': '/bin/cat', 22:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:50 INFO - 'PWD': '/builds/slave/test', 22:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:50 INFO - 'SHELL': '/bin/bash', 22:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:57:50 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:50 INFO - 'USER': 'cltbld', 22:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:50 INFO - 'XPC_FLAGS': '0x0', 22:57:50 INFO - 'XPC_SERVICE_NAME': '0', 22:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:51 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 22:57:51 INFO - Cleaning up... 22:57:51 INFO - Return code: 0 22:57:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:51 INFO - 'HOME': '/Users/cltbld', 22:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:51 INFO - 'LOGNAME': 'cltbld', 22:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:51 INFO - 'MOZ_NO_REMOTE': '1', 22:57:51 INFO - 'NO_EM_RESTART': '1', 22:57:51 INFO - 'PAGER': '/bin/cat', 22:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:51 INFO - 'PWD': '/builds/slave/test', 22:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:51 INFO - 'SHELL': '/bin/bash', 22:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:57:51 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:51 INFO - 'USER': 'cltbld', 22:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:51 INFO - 'XPC_FLAGS': '0x0', 22:57:51 INFO - 'XPC_SERVICE_NAME': '0', 22:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:51 INFO - Downloading/unpacking psutil>=3.1.1 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:57:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:54 INFO - Installing collected packages: psutil 22:57:54 INFO - Running setup.py install for psutil 22:57:55 INFO - building 'psutil._psutil_osx' extension 22:57:55 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 22:57:55 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 22:57:55 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 22:57:55 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 22:57:55 INFO - building 'psutil._psutil_posix' extension 22:57:55 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 22:57:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:57:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:57:55 INFO - ^ 22:57:55 INFO - 1 warning generated. 22:57:55 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 22:57:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:56 INFO - Successfully installed psutil 22:57:56 INFO - Cleaning up... 22:57:56 INFO - Return code: 0 22:57:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:57:56 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')]} 22:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:56 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')]} 22:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:56 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:57:56 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 22:57:56 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 22:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:56 INFO - 'HOME': '/Users/cltbld', 22:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:56 INFO - 'LOGNAME': 'cltbld', 22:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:56 INFO - 'MOZ_NO_REMOTE': '1', 22:57:56 INFO - 'NO_EM_RESTART': '1', 22:57:56 INFO - 'PAGER': '/bin/cat', 22:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:56 INFO - 'PWD': '/builds/slave/test', 22:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:56 INFO - 'SHELL': '/bin/bash', 22:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:57:56 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:56 INFO - 'USER': 'cltbld', 22:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:56 INFO - 'XPC_FLAGS': '0x0', 22:57:56 INFO - 'XPC_SERVICE_NAME': '0', 22:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:57:56 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 22:57:56 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 22:57:56 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 22:57:56 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 22:57:56 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 22:57:56 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 22:57:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:57:59 INFO - Installing collected packages: mozsystemmonitor 22:57:59 INFO - Running setup.py install for mozsystemmonitor 22:57:59 INFO - Successfully installed mozsystemmonitor 22:57:59 INFO - Cleaning up... 22:57:59 INFO - Return code: 0 22:57:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:00 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:00 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 22:58:00 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 22:58:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:00 INFO - 'HOME': '/Users/cltbld', 22:58:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:00 INFO - 'LOGNAME': 'cltbld', 22:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:00 INFO - 'MOZ_NO_REMOTE': '1', 22:58:00 INFO - 'NO_EM_RESTART': '1', 22:58:00 INFO - 'PAGER': '/bin/cat', 22:58:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:00 INFO - 'PWD': '/builds/slave/test', 22:58:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:00 INFO - 'SHELL': '/bin/bash', 22:58:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:00 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:00 INFO - 'USER': 'cltbld', 22:58:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:00 INFO - 'XPC_FLAGS': '0x0', 22:58:00 INFO - 'XPC_SERVICE_NAME': '0', 22:58:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:00 INFO - Downloading/unpacking jsonschema==2.5.1 22:58:00 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 22:58:00 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 22:58:00 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 22:58:00 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 22:58:00 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 22:58:00 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 22:58:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:58:03 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 22:58:03 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 22:58:03 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 22:58:03 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 22:58:03 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 22:58:03 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 22:58:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:03 INFO - warning: no files found matching '*.txt' 22:58:03 INFO - no previously-included directories found matching 'build' 22:58:03 INFO - no previously-included directories found matching 'dist' 22:58:03 INFO - no previously-included directories found matching '.git*' 22:58:03 INFO - Installing collected packages: jsonschema, functools32 22:58:03 INFO - Running setup.py install for functools32 22:58:04 INFO - warning: no files found matching '*.txt' 22:58:04 INFO - no previously-included directories found matching 'build' 22:58:04 INFO - no previously-included directories found matching 'dist' 22:58:04 INFO - no previously-included directories found matching '.git*' 22:58:04 INFO - Successfully installed jsonschema functools32 22:58:04 INFO - Cleaning up... 22:58:04 INFO - Return code: 0 22:58:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:04 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 22:58:04 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 22:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:04 INFO - 'HOME': '/Users/cltbld', 22:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:04 INFO - 'LOGNAME': 'cltbld', 22:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:04 INFO - 'MOZ_NO_REMOTE': '1', 22:58:04 INFO - 'NO_EM_RESTART': '1', 22:58:04 INFO - 'PAGER': '/bin/cat', 22:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:04 INFO - 'PWD': '/builds/slave/test', 22:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:04 INFO - 'SHELL': '/bin/bash', 22:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:04 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:04 INFO - 'USER': 'cltbld', 22:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:04 INFO - 'XPC_FLAGS': '0x0', 22:58:04 INFO - 'XPC_SERVICE_NAME': '0', 22:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:58:04 INFO - Cleaning up... 22:58:04 INFO - Return code: 0 22:58:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:04 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 22:58:04 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 22:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:04 INFO - 'HOME': '/Users/cltbld', 22:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:04 INFO - 'LOGNAME': 'cltbld', 22:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:04 INFO - 'MOZ_NO_REMOTE': '1', 22:58:04 INFO - 'NO_EM_RESTART': '1', 22:58:04 INFO - 'PAGER': '/bin/cat', 22:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:04 INFO - 'PWD': '/builds/slave/test', 22:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:04 INFO - 'SHELL': '/bin/bash', 22:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:04 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:04 INFO - 'USER': 'cltbld', 22:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:04 INFO - 'XPC_FLAGS': '0x0', 22:58:04 INFO - 'XPC_SERVICE_NAME': '0', 22:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:04 INFO - Downloading/unpacking blobuploader==1.2.4 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:07 INFO - Downloading blobuploader-1.2.4.tar.gz 22:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:58:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:58:07 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 22:58:07 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 22:58:07 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 22:58:07 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 22:58:07 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 22:58:07 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 22:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:58:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:58:08 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 22:58:08 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 22:58:08 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 22:58:08 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 22:58:08 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 22:58:08 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 22:58:08 INFO - Downloading docopt-0.6.1.tar.gz 22:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:58:08 INFO - Installing collected packages: blobuploader, requests, docopt 22:58:08 INFO - Running setup.py install for blobuploader 22:58:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:58:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:58:08 INFO - Running setup.py install for requests 22:58:09 INFO - Running setup.py install for docopt 22:58:09 INFO - Successfully installed blobuploader requests docopt 22:58:09 INFO - Cleaning up... 22:58:09 INFO - Return code: 0 22:58:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:09 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')]} 22:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:09 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')]} 22:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:09 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:09 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 22:58:09 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 22:58:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:09 INFO - 'HOME': '/Users/cltbld', 22:58:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:09 INFO - 'LOGNAME': 'cltbld', 22:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:09 INFO - 'MOZ_NO_REMOTE': '1', 22:58:09 INFO - 'NO_EM_RESTART': '1', 22:58:09 INFO - 'PAGER': '/bin/cat', 22:58:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:09 INFO - 'PWD': '/builds/slave/test', 22:58:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:09 INFO - 'SHELL': '/bin/bash', 22:58:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:09 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:09 INFO - 'USER': 'cltbld', 22:58:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:09 INFO - 'XPC_FLAGS': '0x0', 22:58:09 INFO - 'XPC_SERVICE_NAME': '0', 22:58:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HwpObX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hCl0rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_2Q_Zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BQ3jOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-K5QsEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hb9bPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bxvQpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nepqeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-k5eUVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HNtwcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:10 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-A2DfKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-MTqkHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zaGrIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fuPe0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-8xWfCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WTAJoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:11 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-tZWylx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:58:11 INFO - Running setup.py install for manifestparser 22:58:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:58:11 INFO - Running setup.py install for mozcrash 22:58:11 INFO - Running setup.py install for mozdebug 22:58:12 INFO - Running setup.py install for mozdevice 22:58:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Running setup.py install for mozfile 22:58:12 INFO - Running setup.py install for mozhttpd 22:58:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Running setup.py install for mozinfo 22:58:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Running setup.py install for mozInstall 22:58:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:58:12 INFO - Running setup.py install for mozleak 22:58:12 INFO - Running setup.py install for mozlog 22:58:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Running setup.py install for moznetwork 22:58:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Running setup.py install for mozprocess 22:58:13 INFO - Running setup.py install for mozprofile 22:58:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Running setup.py install for mozrunner 22:58:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:58:13 INFO - Running setup.py install for mozscreenshot 22:58:13 INFO - Running setup.py install for moztest 22:58:14 INFO - Running setup.py install for mozversion 22:58:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:58:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:58:14 INFO - Cleaning up... 22:58:14 INFO - Return code: 0 22:58:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:14 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:14 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 22:58:14 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 22:58:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:14 INFO - 'HOME': '/Users/cltbld', 22:58:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:14 INFO - 'LOGNAME': 'cltbld', 22:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:14 INFO - 'MOZ_NO_REMOTE': '1', 22:58:14 INFO - 'NO_EM_RESTART': '1', 22:58:14 INFO - 'PAGER': '/bin/cat', 22:58:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:14 INFO - 'PWD': '/builds/slave/test', 22:58:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:14 INFO - 'SHELL': '/bin/bash', 22:58:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:14 INFO - 'USER': 'cltbld', 22:58:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:14 INFO - 'XPC_FLAGS': '0x0', 22:58:14 INFO - 'XPC_SERVICE_NAME': '0', 22:58:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:14 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-H5syYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:14 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)) 22:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:14 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XqK6s2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:14 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)) 22:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:14 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-s8dsr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:14 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)) 22:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:14 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-wJQpVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:14 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-DEs5WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2faJoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-AiHIJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qF_IVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ipUJeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9IGRC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_lmLku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2aQ2eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-KBVLc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:15 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)) 22:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-pkURr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:16 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)) 22:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cUgW81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:16 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)) 22:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WZCz1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:16 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)) 22:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-aeWD1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:16 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)) 22:58:16 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)) 22:58:16 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)) 22:58:16 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)) 22:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:58:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:18 INFO - Downloading blessings-1.6.tar.gz 22:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:19 INFO - Installing collected packages: blessings 22:58:19 INFO - Running setup.py install for blessings 22:58:19 INFO - Successfully installed blessings 22:58:19 INFO - Cleaning up... 22:58:19 INFO - Return code: 0 22:58:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:58:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:19 INFO - Reading from file tmpfile_stdout 22:58:19 INFO - Current package versions: 22:58:19 INFO - blessings == 1.6 22:58:19 INFO - blobuploader == 1.2.4 22:58:19 INFO - docopt == 0.6.1 22:58:19 INFO - functools32 == 3.2.3-2 22:58:19 INFO - jsonschema == 2.5.1 22:58:19 INFO - manifestparser == 1.1 22:58:19 INFO - mozInstall == 1.12 22:58:19 INFO - mozcrash == 1.0 22:58:19 INFO - mozdebug == 0.1 22:58:19 INFO - mozdevice == 0.49 22:58:19 INFO - mozfile == 1.2 22:58:19 INFO - mozhttpd == 0.7 22:58:19 INFO - mozinfo == 0.9 22:58:19 INFO - mozleak == 0.1 22:58:19 INFO - mozlog == 3.4 22:58:19 INFO - moznetwork == 0.27 22:58:19 INFO - mozprocess == 0.25 22:58:19 INFO - mozprofile == 0.28 22:58:19 INFO - mozrunner == 6.13 22:58:19 INFO - mozscreenshot == 0.1 22:58:19 INFO - mozsystemmonitor == 0.3 22:58:19 INFO - moztest == 0.8 22:58:19 INFO - mozversion == 1.4 22:58:19 INFO - psutil == 3.1.1 22:58:19 INFO - requests == 1.2.3 22:58:19 INFO - wsgiref == 0.1.2 22:58:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:19 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 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58:19 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 22:58:19 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 22:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:19 INFO - 'HOME': '/Users/cltbld', 22:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:19 INFO - 'LOGNAME': 'cltbld', 22:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:19 INFO - 'MOZ_NO_REMOTE': '1', 22:58:19 INFO - 'NO_EM_RESTART': '1', 22:58:19 INFO - 'PAGER': '/bin/cat', 22:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:19 INFO - 'PWD': '/builds/slave/test', 22:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:19 INFO - 'SHELL': '/bin/bash', 22:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:19 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:19 INFO - 'USER': 'cltbld', 22:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:19 INFO - 'XPC_FLAGS': '0x0', 22:58:19 INFO - 'XPC_SERVICE_NAME': '0', 22:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 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)) 22:58:19 INFO - Cleaning up... 22:58:19 INFO - Return code: 0 22:58:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e109768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa7bd77830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, '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.ADiCvhMtPh/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.tRg9qssf2P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 22:58: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', 'jsonschema'] in /builds/slave/test/build 22:58: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 jsonschema 22:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:19 INFO - 'HOME': '/Users/cltbld', 22:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:19 INFO - 'LOGNAME': 'cltbld', 22:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:19 INFO - 'MOZ_NO_REMOTE': '1', 22:58:19 INFO - 'NO_EM_RESTART': '1', 22:58:19 INFO - 'PAGER': '/bin/cat', 22:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:19 INFO - 'PWD': '/builds/slave/test', 22:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:19 INFO - 'SHELL': '/bin/bash', 22:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:19 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:19 INFO - 'USER': 'cltbld', 22:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:19 INFO - 'XPC_FLAGS': '0x0', 22:58:19 INFO - 'XPC_SERVICE_NAME': '0', 22:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:58:20 INFO - Cleaning up... 22:58:20 INFO - Return code: 0 22:58:20 INFO - Running post-action listener: _resource_record_post_action 22:58:20 INFO - Running post-action listener: _start_resource_monitoring 22:58:20 INFO - Starting resource monitoring. 22:58:20 INFO - [mozharness: 2017-03-22 05:58:20.113401Z] Finished create-virtualenv step (success) 22:58:20 INFO - [mozharness: 2017-03-22 05:58:20.114277Z] Running install step. 22:58:20 INFO - Running pre-action listener: _resource_record_pre_action 22:58:20 INFO - Running main action method: install 22:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:20 INFO - Reading from file tmpfile_stdout 22:58:20 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:58:20 INFO - Reading from file tmpfile_stdout 22:58:20 INFO - Output received: 22:58:20 INFO - Usage: mozinstall [options] installer 22:58:20 INFO - Options: 22:58:20 INFO - -h, --help show this help message and exit 22:58:20 INFO - -d DEST, --destination=DEST 22:58:20 INFO - Directory to install application into. [default: 22:58:20 INFO - "/builds/slave/test"] 22:58:20 INFO - --app=APP Application being installed. [default: firefox] 22:58:20 INFO - mkdir: /builds/slave/test/build/application 22:58:20 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'] 22:58:20 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 22:58:41 INFO - Reading from file tmpfile_stdout 22:58:41 INFO - Output received: 22:58:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:58:41 INFO - Running post-action listener: _resource_record_post_action 22:58:41 INFO - [mozharness: 2017-03-22 05:58:41.024363Z] Finished install step (success) 22:58:41 INFO - [mozharness: 2017-03-22 05:58:41.024530Z] Running run-tests step. 22:58:41 INFO - Running pre-action listener: _resource_record_pre_action 22:58:41 INFO - Running main action method: run_tests 22:58:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:58:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:58:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:58:41 INFO - 2017-03-21 22:58:41.039 screenresolution[1623:7547] starting screenresolution argv=screenresolution get 22:58:41 INFO - 2017-03-21 22:58:41.042 screenresolution[1623:7547] Display 0: 1600x1200x32@60 22:58:41 INFO - 2017-03-21 22:58:41.048 screenresolution[1624:7549] starting screenresolution argv=screenresolution list 22:58:41 INFO - Available Modes on Display 0 22:58:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:58:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:58:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:58:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:58:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:58:41 INFO - Intel Iris: 22:58:41 INFO - Chipset Model: Intel Iris 22:58:41 INFO - Type: GPU 22:58:41 INFO - Bus: Built-In 22:58:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:58:41 INFO - Vendor: Intel (0x8086) 22:58:41 INFO - Device ID: 0x0a2e 22:58:41 INFO - Revision ID: 0x0009 22:58:41 INFO - Displays: 22:58:41 INFO - Display: 22:58:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:58:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:58:41 INFO - Display Serial Number: 1600x1200 60 22:58:41 INFO - Main Display: Yes 22:58:41 INFO - Mirror: Off 22:58:41 INFO - Online: Yes 22:58:41 INFO - Rotation: Supported 22:58:41 INFO - Return code: 0 22:58:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:58:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:58:41 INFO - Python 2.7.3 22:58:41 INFO - Return code: 0 22:58:41 INFO - grabbing minidump binary from tooltool 22:58:41 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')]} 22:58:41 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 0x10dee4d30>, '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 0x10de0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ded9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:58:41 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 22:58:41 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 22:58:41 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 22:58:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:58:41 INFO - Return code: 0 22:58:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:58:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:58:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:58:41 INFO - ENV: RUST_BACKTRACE is now 1 22:58:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:58:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:58:41 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/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 22:58:41 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/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 22:58:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADiCvhMtPh/Render', 22:58:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:41 INFO - 'HOME': '/Users/cltbld', 22:58:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:41 INFO - 'LOGNAME': 'cltbld', 22:58:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:58:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:41 INFO - 'MOZ_NO_REMOTE': '1', 22:58:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:58:41 INFO - 'NO_EM_RESTART': '1', 22:58:41 INFO - 'PAGER': '/bin/cat', 22:58:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:41 INFO - 'PWD': '/builds/slave/test', 22:58:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:58:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:41 INFO - 'RUST_BACKTRACE': '1', 22:58:41 INFO - 'SHELL': '/bin/bash', 22:58:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tRg9qssf2P/Listeners', 22:58:41 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 22:58:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:41 INFO - 'USER': 'cltbld', 22:58:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:41 INFO - 'XPC_FLAGS': '0x0', 22:58:41 INFO - 'XPC_SERVICE_NAME': '0', 22:58:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:41 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/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 22:58:41 INFO - mozversion application_buildid: 20170321204834 22:58:41 INFO - mozversion application_changeset: 201231223cd4354a450c3e5d80959f35b8e4cf0c 22:58:41 INFO - mozversion application_display_name: Nightly 22:58:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:58:41 INFO - mozversion application_name: Firefox 22:58:41 INFO - mozversion application_remotingname: firefox 22:58:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:58:41 INFO - mozversion application_vendor: Mozilla 22:58:41 INFO - mozversion application_version: 55.0a1 22:58:41 INFO - mozversion platform_buildid: 20170321204834 22:58:41 INFO - mozversion platform_changeset: 201231223cd4354a450c3e5d80959f35b8e4cf0c 22:58:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:58:41 INFO - mozversion platform_version: 55.0a1 22:58:41 INFO - using testdate: 1490162321 22:58:41 INFO - actual date: 1490162321 22:58:41 INFO - starting webserver on 'localhost:49242' 22:58:41 INFO - SUITE-START | Running 2 tests 22:58:41 INFO - TEST-START | dromaeo_css 22:58:41 INFO - operating with platform_type : mac_ 22:58:41 INFO - Initialising browser for dromaeo_css test... 22:58:41 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpukcIuu/profile http://localhost:49242/getInfo.html) 22:58:44 INFO - PID 1634 | 2017-03-21 22:58:44.518 plugin-container[1636:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 22:58:44 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:58:44 INFO - PID 1634 | 2017-03-21 22:58:44.521 plugin-container[1636:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 22:58:44 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:58:44 INFO - PID 1634 | 2017-03-21 22:58:44.521 plugin-container[1636:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:58:44 INFO - PID 1634 | __metrics Screen width/height:1600/1200 22:58:44 INFO - PID 1634 | colorDepth:24 22:58:44 INFO - PID 1634 | Browser inner width/height: 1024/689 22:58:44 INFO - PID 1634 | __metrics 22:58:44 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 22:58:45 INFO - TEST-INFO | 1634: exit 0 22:58:45 INFO - Browser initialized. 22:58:45 INFO - Running cycle 1/1 for dromaeo_css test... 22:58:45 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpukcIuu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:58:47 INFO - PID 1637 | 2017-03-21 22:58:47.925 plugin-container[1638:7865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 22:58:47 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:58:47 INFO - PID 1637 | 2017-03-21 22:58:47.926 plugin-container[1638:7865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:58:47 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:58:47 INFO - PID 1637 | 2017-03-21 22:58:47.926 plugin-container[1638:7865] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:58:57 INFO - PID 1637 | RSS: Main: 227561472 22:58:57 INFO - PID 1637 | 23:02:03 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 23:02:04 INFO - PID 1637 | RSS: Main: 254283776 23:02:04 INFO - PID 1637 | 23:03:45 INFO - PID 1637 | ************************* 23:03:45 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 23:03:45 INFO - PID 1637 | 23:03:45 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:03:45 INFO - PID 1637 | Full stack: 23:03:45 INFO - PID 1637 | ************************* 23:05:06 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 23:05:07 INFO - PID 1637 | RSS: Main: 260554752 23:05:07 INFO - PID 1637 | 23:08:09 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 23:08:10 INFO - PID 1637 | RSS: Main: 271704064 23:08:10 INFO - PID 1637 | 23:11:12 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 23:11:12 INFO - PID 1637 | RSS: Main: 263979008 23:11:12 INFO - PID 1637 | 23:14:15 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 23:14:16 INFO - PID 1637 | RSS: Main: 274182144 23:14:16 INFO - PID 1637 | 23:17:18 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 23:17:19 INFO - PID 1637 | RSS: Main: 268894208 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | __start_tp_report 23:17:19 INFO - PID 1637 | _x_x_mozilla_page_load 23:17:19 INFO - PID 1637 | _x_x_mozilla_page_load_details 23:17:19 INFO - PID 1637 | |i|pagename|runs| 23:17:19 INFO - PID 1637 | |0;dojo.html;3493.091796133006;3521.8591256349746;3537.964620353796;3539.893803185904;3552.0231936217538;5481.780728770845;5507.779688812453;6030.396960303968;6060.484858787008;6166.6916654167335;6092.20801295832;6138.017917133259;6159.9528080226355;6181.536384771138;6183.783567575136;4436.600705936465;5443.591730620199;5480.890382192374;5573.052581061229;5693.202951586781;5512.503874651281;5564.276644036269;5631.352394474647;5634.9718251408685;5701.372848986608;5396.946030539683;5620.662760234358;5653.547716182696;5674;5677.57418193635;4704.882377940545;5441.020616289066;5508.173773933942;5569.0254205513875;5612.663240205601;5443.237946687467;5452.073147564924;5461.344638643349;5463.617546771728;5474.972625136888;4512.278035514335;5290.259363689087;6179.5056395488255;6194.938050619481;6215.658138802364;6307.369263073701;6372.426481616677;6453;6541.607503549754;6542.803715888531;6693.163354580677;6697.966510167466;6699.966500167517;6703.262641109474;6714.060031595581;6237.812865614039;6270.529710271702;6290.339514350927;6295.401936815995;6300.873982520324;6498.057781621638;6627.900581491282;6712.463002959751;6731.629760363129;6746.83132921671;5275.762590683428;6331.651759153198;6399.8080057598345;6510.08858759774;6521.902171467432;5694.943050569441;6094.451499365078;6406.071119687619;6430.7749228776765;6637.137172167684;6433.163688720437;6523.543351965412;6545.705443255065;6552.63960482178;6577.440917522064;5600.159976003632;6254.437100660963;6309.589876658002;6558.704858281389;6562.737490500326;5196.532312091855;5216.556592689664;5224.817131400383;5279;5291.497307755757;4694.882627934329;4709.95290047102;4731.266653668687;4747.382840230817;4774.045190961821;6230;6268.310485846553;6284.497240220771;6286;6299.433051025429;6402.199725034371;6450.741970321228;6638.5684930479365;6639.468842492589;6692.86614267712;5290.206469029677;6181.690915454299;6203.534734894901;6211.875762484725;6236.532260080421;5832.096025116114;5848.824535263949;5869.972754767984;5876.206712093898;5881.264841894763;5972.074328479092;6485.189351331083;6817.420519255918;6843.623600702009;6891.207511136284;5642.040853054908;6039.879202416015;6450.645214513247;6465.482761379172;6524.510661700297;6764.086848275617;6836.863262734817;6854.657267136723;6863.759768408082;6881.827954301182;5505.779768809202;6531.248906375828;6542.607443553402;6560.3111673273615;6604.306547812411;6414.871702566016;6484.675766211765;6658.966705166443;6675.265720770809;6689.665516724047;6371.96814015927;6410.679466026773;6447.903281450688;6479.287278399466;6504.089427480251;6235.501159907106;6288.654124023223;6327.651979141191;6381.3937675920715;6462.289148193789;5312.867178320573;6152.50779937595;6320.462760665211;6349.809505714836;6355.205599300088;4776.450708168605;5892.762519870875;6394.200724909387;6476.805695829133;6493.675316234264;6222.533310001678;6226.439620434183;6228.439440450381;6243.781467648792;6287.96856015717 23:17:19 INFO - PID 1637 | |1;ext.html;735.955842649441;794.551078640568;815.5636734347122;1254.8996080313575;1259.691375612975;31770.68229317712;37026.629733702655;37973.81013094934;38025;38170.42744358837;37509.624903750955;37848.053798654975;37894;38037.809810950974;38091.23817523648;7813.374930005586;7964.283214510693;8396.664133434686;8566.271866891322;8630.439021463617;8398.916010839905;8493.02330232025;8588.895720548444;8614.095519970408;8627.61175747093;8534.530600816952;8544.401891867572;8560.443571167854;8604.35467339952;8619.827603447928;8565.957170214171;8608.827823443558;8618.87071693922;8620.224179823846;8632.913670863336;7452.329290363839;8413.200745929127;8459.323254139654;8526.275266602346;8530.701425450141;37825.43261851074;37855.432168517495;37930.051748706224;37941.29264183118;38093;2824.5904343870025;2886.975123831052;3032.586832503992;3051.649060358065;3054.526548385004;27319.907203711828;27448.39240272171;27471.313217169532;27619.30951726223;28565.857170714222;2771.9029833955715;2788.5817127430846;2801.5097357962277;2807.101727447218;2812.7749780017753;309430;311559;312720;314298.4285078583;315648;48028.79928001758;48269.5173048265;48333;48361.7909552257;48412.03175936462;50718.239226411635;51079.48920510747;51082.74458627683;51130;51593;14125;14351.569452916428;14447.349869255908;14518.927405362905;14542;11734.413279335999;11750;11755.177137600456;11772.941135294268;11826.349550774617;12882.613521594367;13307.933460332637;13380.732385352238;13403.597892063253;13404.262765547988;5846.210761547137;5849.947009538324;5901.527877769854;5907.911381329283;5911.113333000085;826.7189155686975;947.3084648206848;961.2166084640995;990.9058639429387;995.1789773436943;23209.071637134664;23376.532469350517;23981.280561583182;24023.27930162098;24042.75957240405;928.744595236302;1055.2665897201525;1079.2714917430703;1095.058249905081;1102.818035024228;10366;10481;11543.595974141033;11788.587399440978;12292.955098816521;420668;422746;423028;424719;427582;45218.77390613026;51527.22709159365;52556.21165682518;53975;54225.72887135539;1381.9723605527834;1399.951001714935;1437.152080272644;1465.421158642309;1473.8894582906325;28048.57927131054;28119.57820632651;28128.29679258035;28378.2905427366;28823.991160309288;36748.63251367452;36787.44818827666;36841.815790920875;36951.630483695895;37011.07472313214;343425;346075.20135184773;346823;346852;349326;31061.378772424876;31263.68736312608;31282.68717312798;31359.37281254408;31533.684663153992;40897.795511022254;42113;42212;42309.788451057546;42342;32187.67812321847;34341.828290858386;35168;35298.64701352954;36821.26357472782;32231.033069007968;34830.65169348376;35224.119397014256;35599.46600800938;36325.81837090903;36017.81991090133;36238.91283261401;36703.81648091849;36838.26323473569;37109.44335835019;30528.694713052584;31973.36053278875;32074.839625802655;32167.678323216467;32187.51718724268;20616.690749639067;21955.67066493972;22687.546249074596;22744.43138921474;24357.634635480837 23:17:19 INFO - PID 1637 | |2;jquery.html;425.0734284828716;427.741216563979;432.82686925229916;444.6842741653426;450.7993942695498;20688.27591034314;21233.15067397302;21431.035603397864;21483;22140.446488837788;20022;20379.082941267643;21905.561888762215;22748.20381286655;22988.425289367773;5630.380658127603;6034.8793024139495;6218.409251121137;6228.314885362606;6245.843853903643;5863.941360586404;6157.261128664544;6171.907421388682;6193.955994521797;6234.906476402857;5926.496247818941;6122.173506576622;6186.443220110189;6190.597611155283;6202.131701561762;6033.577649564532;6039.486643635274;6079.969600151971;6087.847803804918;6101.206843110367;4877.439094504142;5155.226715992631;5333.493318134771;5389.218563308337;5406.540234136761;25838.87080564604;25945.091921782834;25987.350316242057;26185.73814261871;26239;2036.9898150509152;2038.6941958706163;2049.2622655843884;2057.2285392977633;2128.265748316828;15620;15665.21673916311;15777.29002194904;16092.517224483285;16479.423220187236;1590.5148929576474;1661.534770264328;1678.3549740608894;1710.598009467774;1719.5271300392492;129416;138556;139953.60046399507;141362.58637413598;141512.2924385382;9902.009799020185;12514.249145051326;12672.986161107274;12716.17344872599;12893.484260629657;16114.838851611568;16123.355065797237;16149.757753633705;16164.838351616569;16223;7656.272654097852;8595.269402100892;8919.286457083417;8934.597943092576;9013.594388252544;8424.747257582283;8518.787030324167;8549.615267312985;8658.567071646392;8718.215360617574;7721.03487064117;7861.842763144705;7889.960550197212;7917.366610671133;7918.881216781753;2140.6467932790915;2147.602693501707;2147.9248226312316;2150.731158605174;2313.6298192289155;914.2320450821342;918.3204428722697;918.7335672654855;924.6347692661495;956.583886009588;15509.689806204038;19415.708764368545;20223.8876861774;20339.491512712302;20684;896.7175339767953;930.7899730350517;949.7055912667096;976.0479041916168;976.799756050008;8734.38859279857;10624.521896514521;10660.786784264272;10695.732606684895;11033.6138235163;617304.9134754298;633896;636883;641229;641503;91495;98635;112622;114630.4268478669;128621;1120.5797825815318;1178.6110583507593;1180.0854337888206;1181.6573193773709;1184.567632814034;19900.402987910387;20890.895545522177;21128.471788204803;21147.577048459254;21451.356459306247;56605.71697141653;58203.12695309489;59540.80918381522;59890.40109598848;60231.79536409335;81819;82307.17692823235;82527.58736206281;83223.5838820802;83809.58095209485;8418.494890306602;8447.535385553854;8552.315814734682;8967.551622418983;9013.954930225307;10329.018743219382;10779.784404311713;12484.375781211085;13523.932380338429;13789.655258618615;11657.242279251821;11772.17594768358;11799.823002654795;11836.349000805038;11995.520179192734;11429;11489.885101148882;11690.649280521598;11950.701232469259;12033;10330.586776529155;11949.820752688544;12238.877611223774;12258.693532661755;12309.569165079538;6268.27914789805;8655;8688.304935605263;8710.172533609297;8787;11471.4264286787;11526.654200374001;11843.526258949545;11866.703332416759;11892.821607676065 23:17:19 INFO - PID 1637 | |3;mootools.html;1321.537461888339;1346.4210389532502;1359.109783092075;1367.8221831161948;1385.0512399006682;9456.763580910481;9762.804743905126;9780.804383912318;10539.73650658734;10932.016118549329;10738.463076846165;10749.731256718585;10867.076298514616;10877.6736697899;11046.668599941995;2336.941576460589;2375.70303712036;2387.3434805428474;2388.6894703688495;2390.6892104026538;2349.694539709844;2404.663347131403;2404.7955923746504;2405.0860672944254;2410.035985605763;2350.4476448034784;2380.4048987753063;2393;2397.30478161333;2397.7482364351667;2342.227065068523;2358.469344397514;2374.323317854412;2377.3343463830156;2379.5478859016816;2326.592846251899;2338.543983923136;2404.158544509408;2405.87970601471;2430.1130087518104;10551.683449496451;10636.149108071335;10644.787104257948;10661.946690266499;10673.83989240162;6132.540059495555;6352.46004089653;6372.0123380875575;6372.490200783926;6380.712867920908;3883.262180185784;3895.5714871364125;3910.335243008695;3963.1677347757004;4019.839206431739;1160.7214268575565;1462.7659574468141;1503.4304464552097;1560.0405750463497;1623.115402105855;13438.395272212774;13695.246761428118;13839.723205535933;14570.854291457055;14696.338664760004;5180.7150606717;5379.4351593082165;5386.461353864661;5403.7838486460105;5404.32445944257;11023.944880275707;11084.168687348481;11114;11133;11147.777044459228;6037.63774173551;6072.301685306246;6159.260888693296;6215.564910456224;6220.066989951453;5602.215689803431;5717.971410143005;5758.2514273144225;5796.043652797325;5804.651720896675;4087.5708050654252;4286.099919017041;4316.223079845595;4349.238883195491;4352.717073390219;1252.0859772366223;1271.065766661503;1273.4718337994443;1287.452832546183;1288.4974859804684;744.9590272535063;753.5629334985695;780.4575819805294;780.6331024418514;783.4124406694979;5664.68844213564;5913.76344946206;6343.460805831556;6895.896561551581;6907.758228461979;854.7136709202487;867.7049803066988;911.0980129671577;914.7804526913493;927.9025702301296;4207.5161356444405;4235.449391579136;4248.320268756984;4328.978355108205;4394.648428125743;12798.040146988827;12968.610941671575;13344.799828002588;13708.725825483434;13859.584212473643;12917.870821291666;13320.93339533296;13331.600051998455;13336.333183340794;13370.39833207508;8169.754907352789;9960.601575937026;10013.699589012342;10029.097381235724;10081.798364032678;6069.5751297410525;6305.684715764285;6385.936140638534;6607.141071660653;6623.536352455282;12114.091443141624;12251.387430628254;12255.69360765988;12453.501859925502;12479.5008199671;12375.257484550955;12381.814272786096;12642.620721378373;12645.304508252135;12801.29592872401;8664.263537599374;9045.321600880092;9058.094190580889;9214.308926830381;9270.44377337362;10095.949520252352;10119.949400252952;10135.087842094246;10251.794964100825;10261.17910567138;8685.609147588248;8787.560621968749;8965.058668839678;9007.594658240527;9111.681091161778;8610.268127209258;8618.913810861812;8639.438436501607;8717.869231961398;8753.47479151253;7636.770896873103;7711.6915323388685;7782;7788.493747906368;7806.219378062148;5297.178937264576;5934.851628709317;5969.582129250911;6012.759489620541;6024.518038556993;5815.534757219328;5873.295204575478;6028.608140470856;6146.139540464428;6162.445379915829 23:17:19 INFO - PID 1637 | |4;prototype.html;374.54866885403106;380.0517708317748;388.6579809767404;394.964453199212;399.53254692010336;5925.666899790191;5986.01230796918;6006.7897623583185;6016.8796624067545;6030.457258846703;4493.472219445388;5606.635568688032;5785.363610002896;5861.589688721791;5879.382664820186;4545.659075569329;4548.295014272793;4563.201439748047;4569.428821397325;4595.4255718035165;4565.520620334867;4581.3128030795315;4594;4597.793099310522;4618.1687296286655;3292.967070329302;3417.299453612016;4442.422485076935;4486.55134486552;4564.334018083392;3761.6990640748672;4137.524184718765;4504.031633198845;4506.616937560311;4564.84023059193;3347.577279656161;3980.6218409251073;3981.7013723970526;3999.9400008999887;4014.3175660137554;4722.551357621055;5506.64206826559;5618.595351162209;5698.886022279573;5766.682832444214;5320.893582128336;5326.174442961347;5332.386775520826;5337.439568845254;5359.812406565751;6025.578209525335;6321.336259692758;6329.715162817638;6331.986882098842;6399.584027038274;3704.518412606371;3742.9438558421643;4328.199283132631;4394.309369366678;4472.195004899117;6045.788397406113;6389.5527313088105;6533.705983230766;6590.999999999952;6639.435647969928;3804.3912973924594;4585.151746926829;4754.833580824655;4789.473157952622;4796.904061918812;4446.755428451466;4791.292919500471;4804.471508134103;4810.2683034107695;4812.446568644651;5261.026710058652;5399.298091248191;5461.508464238238;5497.587680923947;5525.419830917695;6215.81352559424;6304.865124277674;6506.869862602816;6513.208867561364;6548.70530826114;1342.8724271194221;1573.6144644562155;1653.2477722636172;1664.716998110324;1671.5236157694999;963.9277054220961;1012.9465356029793;1088.9509972051276;1130.1241537808264;1167.3346192670097;4723.291506274087;5711.286089238845;5931.822045338647;5954.600668842819;5965;6038.879222415615;6459.709313080923;6536.705848236841;6583.539152259296;6627.5029372797235;4829.492903245179;5883.8232353529465;5892.086726557391;5975.880482390327;6000.879982400327;2659.1889473710316;2876.295307649639;3290.555774970396;3348.464245720673;3421.4012547804036;5843.5909486336395;6285.5914365567005;6418.84460797061;6819.748793429032;6838.692258848364;5376.032314183406;6789.456843452512;6842.281560436082;6850.725970961105;6880;5936.910946335808;6190.461910616716;6725.899111513331;6793.16186354708;6878.931210687929;6597.0764093026055;6685;6699.387238976706;6699.631520266432;6742.393184613408;5546.362168350692;6571.3757193066585;6633.867322653617;6640;6661.300563440963;6802.523823332319;6814.182298124257;6814.829629259309;6848.554843935328;6859.725610975505;3673.8163091844767;3690.1512652090705;4339.457567804025;4360.847370342023;4365.410669559569;5557.3331200256225;6387.594729159578;6571.934280657323;6634.734610615521;6651.7006734698025;5940.049592065248;6078.908816367846;6319.873602527831;6332.810015699536;6342.7145778441;5150.716710580954;6083.908741368971;6336.017917222838;6396.744130234924;6399.64801935898;5960.910586341121;6176.474999624902;6193.566450348432;6218.471429928507;6236.563440559117;5589.469000444951;6366.172397588284;6378.585391949508;6400.743970241138;6427.389398007369;5627;5917.467427931507;6184.721687524162;6201.627902325875;6234.470070043915 23:17:19 INFO - PID 1637 | |5;yui.html;700.8072779985506;712.6151877985887;736.0799001248439;738.5273425007996;742.881139017757;12671.61985140445;12728.299943503103;13260.138091024077;14490.492832750855;15396.153211573359;14064.789028164585;14086.366113524891;14243.287835608204;14259.429622815102;14457.915656325766;783.2715574515684;921.3044151665483;929.5445231836385;964.4406244378243;966.1401352796017;954.6324665003954;966.9903300967006;969.2730452160879;969.8981606931277;971.7910649210419;992.8411454167334;999.035930327234;1001.7345403468087;1001.8797744270697;1001.9348742331725;985.0543478260878;995.6863587969839;996.9451680157553;998.8601595776524;999.3753903810118;735.0995031086929;737.6164394514876;740.9481336306461;741.7515132430642;741.8775901976168;16050.197490125447;16351.182440878027;16380.590485237844;16497.91751041249;16602.916985415115;14299.928500357535;14467.30363568681;14542.418303267963;14543.200123993176;14697.632559185999;11681.182317237797;11685.357305348203;11872.014043346859;11919.821202681967;12224.572139975147;3021.531662592302;3051.969480305213;3052.1759125036338;3140.2149462634343;3145.3237553926015;96900;98607.50696246544;98963.50518247434;99539;99958.00083998352;9162.679306224383;10219.774226972751;11448.656540303804;11604.94197529007;11885.346305953255;22889.198878039188;22959.540809184058;23176.65235021476;23285.417864553183;23354.949027293813;6983.790486285419;7593;7961.283484486424;7981.640826162837;8177.71378001779;4090.263752524574;4172.5410204877435;4173.123644034726;4179.352200408942;4188.874333769992;4025.295573274665;4057.736247143985;4078.1028173801715;4106.3840423936645;4124.7731374774075;817.166490180013;838.1660248053226;858.0947100808582;865.7143142762873;867.1458613265928;553.1619596311591;557.3005877623638;561.4694114062172;569.8148101866827;588.6026931821003;9717.61129554824;9781.951090244504;10244.743881403025;10347.793044139074;10473.423961682181;541.3720084701727;578.259827420902;579.8695293558916;592.3809618948123;592.4963780786297;4597.287420449836;4812.398450193726;4822.9871726937035;4833.589144922651;4855.757212139379;96218;98797;99705.00294996957;100907.49546252369;101246.49376753069;87053;87316;87632;88054.55972720095;89070.55464722765;48794.756026220355;50782.74608626933;52110.95778084417;61316.69341653263;61986.38013619806;10361.533730981973;10758.73103172427;10844.349339039367;10952.34285942846;11079.390633515233;36223.551057958444;36265.19756838999;36532.452013220354;36665.26669466542;36963.07592310214;38486.80756596199;48595.75702121467;49059.52821415363;49275.50724492709;49792.75103624459;11239.438028098399;12838.486460541473;13148.703212861277;13182.274974876475;13285.66785830362;14035.789463158266;14140.717185656435;14153.858461415666;14247.643808904446;14330.57008289753;12583.307918064229;13212.537561185312;13499.932500337436;13549.187048777105;13939.651508712363;12120.515179393078;12380.195287306296;12969;13209.867901321248;13917.58247252584;11448.484818183035;12733.29966851832;12925.870741292843;13041.478340866639;13485.86514134846;10787.946060269649;10851.348919064565;11005.669829905115;11025;11785.882141178821;7967.760967170994;9357.298202634693;9619.326647134634;11226.158038147334;11241.437928103398 23:17:19 INFO - PID 1637 | __end_tp_report 23:17:19 INFO - PID 1637 | __start_cc_report 23:17:19 INFO - PID 1637 | _x_x_mozilla_cycle_collect,427 23:17:19 INFO - PID 1637 | __end_cc_report 23:17:19 INFO - PID 1637 | __startTimestamp1490163439178__endTimestamp 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | ------- Summary: start ------- 23:17:19 INFO - PID 1637 | Number of tests: 6 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6010.05 Median:6237.81 stddev:708.35 (11.4%) stddev-sans-first:682.45 23:17:19 INFO - PID 1637 | Values: 3493.1 3521.9 3538.0 3539.9 3552.0 5481.8 5507.8 6030.4 6060.5 6166.7 6092.2 6138.0 6160.0 6181.5 6183.8 4436.6 5443.6 5480.9 5573.1 5693.2 5512.5 5564.3 5631.4 5635.0 5701.4 5396.9 5620.7 5653.5 5674.0 5677.6 4704.9 5441.0 5508.2 5569.0 5612.7 5443.2 5452.1 5461.3 5463.6 5475.0 4512.3 5290.3 6179.5 6194.9 6215.7 6307.4 6372.4 6453.0 6541.6 6542.8 6693.2 6698.0 6700.0 6703.3 6714.1 6237.8 6270.5 6290.3 6295.4 6300.9 6498.1 6627.9 6712.5 6731.6 6746.8 5275.8 6331.7 6399.8 6510.1 6521.9 5694.9 6094.5 6406.1 6430.8 6637.1 6433.2 6523.5 6545.7 6552.6 6577.4 5600.2 6254.4 6309.6 6558.7 6562.7 5196.5 5216.6 5224.8 5279.0 5291.5 4694.9 4710.0 4731.3 4747.4 4774.0 6230.0 6268.3 6284.5 6286.0 6299.4 6402.2 6450.7 6638.6 6639.5 6692.9 5290.2 6181.7 6203.5 6211.9 6236.5 5832.1 5848.8 5870.0 5876.2 5881.3 5972.1 6485.2 6817.4 6843.6 6891.2 5642.0 6039.9 6450.6 6465.5 6524.5 6764.1 6836.9 6854.7 6863.8 6881.8 5505.8 6531.2 6542.6 6560.3 6604.3 6414.9 6484.7 6659.0 6675.3 6689.7 6372.0 6410.7 6447.9 6479.3 6504.1 6235.5 6288.7 6327.7 6381.4 6462.3 5312.9 6152.5 6320.5 6349.8 6355.2 4776.5 5892.8 6394.2 6476.8 6493.7 6222.5 6226.4 6228.4 6243.8 6288.0 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:50156.77 Median:36128.37 stddev:96195.63 (266.3%) stddev-sans-first:96393.92 23:17:19 INFO - PID 1637 | Values: 736.0 794.6 815.6 1254.9 1259.7 31770.7 37026.6 37973.8 38025.0 38170.4 37509.6 37848.1 37894.0 38037.8 38091.2 7813.4 7964.3 8396.7 8566.3 8630.4 8398.9 8493.0 8588.9 8614.1 8627.6 8534.5 8544.4 8560.4 8604.4 8619.8 8566.0 8608.8 8618.9 8620.2 8632.9 7452.3 8413.2 8459.3 8526.3 8530.7 37825.4 37855.4 37930.1 37941.3 38093.0 2824.6 2887.0 3032.6 3051.6 3054.5 27319.9 27448.4 27471.3 27619.3 28565.9 2771.9 2788.6 2801.5 2807.1 2812.8 309430.0 311559.0 312720.0 314298.4 315648.0 48028.8 48269.5 48333.0 48361.8 48412.0 50718.2 51079.5 51082.7 51130.0 51593.0 14125.0 14351.6 14447.3 14518.9 14542.0 11734.4 11750.0 11755.2 11772.9 11826.3 12882.6 13307.9 13380.7 13403.6 13404.3 5846.2 5849.9 5901.5 5907.9 5911.1 826.7 947.3 961.2 990.9 995.2 23209.1 23376.5 23981.3 24023.3 24042.8 928.7 1055.3 1079.3 1095.1 1102.8 10366.0 10481.0 11543.6 11788.6 12293.0 420668.0 422746.0 423028.0 424719.0 427582.0 45218.8 51527.2 52556.2 53975.0 54225.7 1382.0 1400.0 1437.2 1465.4 1473.9 28048.6 28119.6 28128.3 28378.3 28824.0 36748.6 36787.4 36841.8 36951.6 37011.1 343425.0 346075.2 346823.0 346852.0 349326.0 31061.4 31263.7 31282.7 31359.4 31533.7 40897.8 42113.0 42212.0 42309.8 42342.0 32187.7 34341.8 35168.0 35298.6 36821.3 32231.0 34830.7 35224.1 35599.5 36325.8 36017.8 36238.9 36703.8 36838.3 37109.4 30528.7 31973.4 32074.8 32167.7 32187.5 20616.7 21955.7 22687.5 22744.4 24357.6 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:36864.85 Median:14076.25 stddev:105607.10 (750.3%) stddev-sans-first:105867.91 23:17:19 INFO - PID 1637 | Values: 425.1 427.7 432.8 444.7 450.8 20688.3 21233.2 21431.0 21483.0 22140.4 20022.0 20379.1 21905.6 22748.2 22988.4 5630.4 6034.9 6218.4 6228.3 6245.8 5863.9 6157.3 6171.9 6194.0 6234.9 5926.5 6122.2 6186.4 6190.6 6202.1 6033.6 6039.5 6080.0 6087.8 6101.2 4877.4 5155.2 5333.5 5389.2 5406.5 25838.9 25945.1 25987.4 26185.7 26239.0 2037.0 2038.7 2049.3 2057.2 2128.3 15620.0 15665.2 15777.3 16092.5 16479.4 1590.5 1661.5 1678.4 1710.6 1719.5 129416.0 138556.0 139953.6 141362.6 141512.3 9902.0 12514.2 12673.0 12716.2 12893.5 16114.8 16123.4 16149.8 16164.8 16223.0 7656.3 8595.3 8919.3 8934.6 9013.6 8424.7 8518.8 8549.6 8658.6 8718.2 7721.0 7861.8 7890.0 7917.4 7918.9 2140.6 2147.6 2147.9 2150.7 2313.6 914.2 918.3 918.7 924.6 956.6 15509.7 19415.7 20223.9 20339.5 20684.0 896.7 930.8 949.7 976.0 976.8 8734.4 10624.5 10660.8 10695.7 11033.6 617304.9 633896.0 636883.0 641229.0 641503.0 91495.0 98635.0 112622.0 114630.4 128621.0 1120.6 1178.6 1180.1 1181.7 1184.6 19900.4 20890.9 21128.5 21147.6 21451.4 56605.7 58203.1 59540.8 59890.4 60231.8 81819.0 82307.2 82527.6 83223.6 83809.6 8418.5 8447.5 8552.3 8967.6 9014.0 10329.0 10779.8 12484.4 13523.9 13789.7 11657.2 11772.2 11799.8 11836.3 11995.5 11429.0 11489.9 11690.6 11950.7 12033.0 10330.6 11949.8 12238.9 12258.7 12309.6 6268.3 8655.0 8688.3 8710.2 8787.0 11471.4 11526.7 11843.5 11866.7 11892.8 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6686.20 Median:3889.42 stddev:4025.77 (103.5%) stddev-sans-first:4016.87 23:17:19 INFO - PID 1637 | Values: 1321.5 1346.4 1359.1 1367.8 1385.1 9456.8 9762.8 9780.8 10539.7 10932.0 10738.5 10749.7 10867.1 10877.7 11046.7 2336.9 2375.7 2387.3 2388.7 2390.7 2349.7 2404.7 2404.8 2405.1 2410.0 2350.4 2380.4 2393.0 2397.3 2397.7 2342.2 2358.5 2374.3 2377.3 2379.5 2326.6 2338.5 2404.2 2405.9 2430.1 10551.7 10636.1 10644.8 10661.9 10673.8 6132.5 6352.5 6372.0 6372.5 6380.7 3883.3 3895.6 3910.3 3963.2 4019.8 1160.7 1462.8 1503.4 1560.0 1623.1 13438.4 13695.2 13839.7 14570.9 14696.3 5180.7 5379.4 5386.5 5403.8 5404.3 11023.9 11084.2 11114.0 11133.0 11147.8 6037.6 6072.3 6159.3 6215.6 6220.1 5602.2 5718.0 5758.3 5796.0 5804.7 4087.6 4286.1 4316.2 4349.2 4352.7 1252.1 1271.1 1273.5 1287.5 1288.5 745.0 753.6 780.5 780.6 783.4 5664.7 5913.8 6343.5 6895.9 6907.8 854.7 867.7 911.1 914.8 927.9 4207.5 4235.4 4248.3 4329.0 4394.6 12798.0 12968.6 13344.8 13708.7 13859.6 12917.9 13320.9 13331.6 13336.3 13370.4 8169.8 9960.6 10013.7 10029.1 10081.8 6069.6 6305.7 6385.9 6607.1 6623.5 12114.1 12251.4 12255.7 12453.5 12479.5 12375.3 12381.8 12642.6 12645.3 12801.3 8664.3 9045.3 9058.1 9214.3 9270.4 10095.9 10119.9 10135.1 10251.8 10261.2 8685.6 8787.6 8965.1 9007.6 9111.7 8610.3 8618.9 8639.4 8717.9 8753.5 7636.8 7711.7 7782.0 7788.5 7806.2 5297.2 5934.9 5969.6 6012.8 6024.5 5815.5 5873.3 6028.6 6146.1 6162.4 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5172.09 Median:5814.48 stddev:1611.55 (27.7%) stddev-sans-first:1575.33 23:17:19 INFO - PID 1637 | Values: 374.5 380.1 388.7 395.0 399.5 5925.7 5986.0 6006.8 6016.9 6030.5 4493.5 5606.6 5785.4 5861.6 5879.4 4545.7 4548.3 4563.2 4569.4 4595.4 4565.5 4581.3 4594.0 4597.8 4618.2 3293.0 3417.3 4442.4 4486.6 4564.3 3761.7 4137.5 4504.0 4506.6 4564.8 3347.6 3980.6 3981.7 3999.9 4014.3 4722.6 5506.6 5618.6 5698.9 5766.7 5320.9 5326.2 5332.4 5337.4 5359.8 6025.6 6321.3 6329.7 6332.0 6399.6 3704.5 3742.9 4328.2 4394.3 4472.2 6045.8 6389.6 6533.7 6591.0 6639.4 3804.4 4585.2 4754.8 4789.5 4796.9 4446.8 4791.3 4804.5 4810.3 4812.4 5261.0 5399.3 5461.5 5497.6 5525.4 6215.8 6304.9 6506.9 6513.2 6548.7 1342.9 1573.6 1653.2 1664.7 1671.5 963.9 1012.9 1089.0 1130.1 1167.3 4723.3 5711.3 5931.8 5954.6 5965.0 6038.9 6459.7 6536.7 6583.5 6627.5 4829.5 5883.8 5892.1 5975.9 6000.9 2659.2 2876.3 3290.6 3348.5 3421.4 5843.6 6285.6 6418.8 6819.7 6838.7 5376.0 6789.5 6842.3 6850.7 6880.0 5936.9 6190.5 6725.9 6793.2 6878.9 6597.1 6685.0 6699.4 6699.6 6742.4 5546.4 6571.4 6633.9 6640.0 6661.3 6802.5 6814.2 6814.8 6848.6 6859.7 3673.8 3690.2 4339.5 4360.8 4365.4 5557.3 6387.6 6571.9 6634.7 6651.7 5940.0 6078.9 6319.9 6332.8 6342.7 5150.7 6083.9 6336.0 6396.7 6399.6 5960.9 6176.5 6193.6 6218.5 6236.6 5589.5 6366.2 6378.6 6400.7 6427.4 5627.0 5917.5 6184.7 6201.6 6234.5 23:17:19 INFO - PID 1637 | 23:17:19 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:18562.32 Median:4041.52 stddev:26257.65 (649.7%) stddev-sans-first:26297.06 23:17:19 INFO - PID 1637 | Values: 700.8 712.6 736.1 738.5 742.9 12671.6 12728.3 13260.1 14490.5 15396.2 14064.8 14086.4 14243.3 14259.4 14457.9 783.3 921.3 929.5 964.4 966.1 954.6 967.0 969.3 969.9 971.8 992.8 999.0 1001.7 1001.9 1001.9 985.1 995.7 996.9 998.9 999.4 735.1 737.6 740.9 741.8 741.9 16050.2 16351.2 16380.6 16497.9 16602.9 14299.9 14467.3 14542.4 14543.2 14697.6 11681.2 11685.4 11872.0 11919.8 12224.6 3021.5 3052.0 3052.2 3140.2 3145.3 96900.0 98607.5 98963.5 99539.0 99958.0 9162.7 10219.8 11448.7 11604.9 11885.3 22889.2 22959.5 23176.7 23285.4 23354.9 6983.8 7593.0 7961.3 7981.6 8177.7 4090.3 4172.5 4173.1 4179.4 4188.9 4025.3 4057.7 4078.1 4106.4 4124.8 817.2 838.2 858.1 865.7 867.1 553.2 557.3 561.5 569.8 588.6 9717.6 9782.0 10244.7 10347.8 10473.4 541.4 578.3 579.9 592.4 592.5 4597.3 4812.4 4823.0 4833.6 4855.8 96218.0 98797.0 99705.0 100907.5 101246.5 87053.0 87316.0 87632.0 88054.6 89070.6 48794.8 50782.7 52111.0 61316.7 61986.4 10361.5 10758.7 10844.3 10952.3 11079.4 36223.6 36265.2 36532.5 36665.3 36963.1 38486.8 48595.8 49059.5 49275.5 49792.8 11239.4 12838.5 13148.7 13182.3 13285.7 14035.8 14140.7 14153.9 14247.6 14330.6 12583.3 13212.5 13499.9 13549.2 13939.7 12120.5 12380.2 12969.0 13209.9 13917.6 11448.5 12733.3 12925.9 13041.5 13485.9 10787.9 10851.3 11005.7 11025.0 11785.9 7967.8 9357.3 9619.3 11226.2 11241.4 23:17:19 INFO - PID 1637 | -------- Summary: end -------- 23:17:19 INFO - PID 1637 | 23:17:19 INFO - TEST-INFO | 1637: exit 0 23:17:19 INFO - TEST-OK | dromaeo_css | took 1118277ms 23:17:19 INFO - TEST-START | kraken 23:17:19 INFO - operating with platform_type : mac_ 23:17:19 INFO - Initialising browser for kraken test... 23:17:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpMmV0dM/profile http://localhost:49242/getInfo.html) 23:17:22 INFO - PID 1648 | 2017-03-21 23:17:22.644 plugin-container[1649:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 23:17:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:17:22 INFO - PID 1648 | 2017-03-21 23:17:22.649 plugin-container[1649:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 23:17:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:17:22 INFO - PID 1648 | 2017-03-21 23:17:22.649 plugin-container[1649:10269] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:17:22 INFO - PID 1648 | __metrics Screen width/height:1600/1200 23:17:22 INFO - PID 1648 | colorDepth:24 23:17:22 INFO - PID 1648 | Browser inner width/height: 1024/689 23:17:22 INFO - PID 1648 | __metrics 23:17:22 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 23:17:23 INFO - TEST-INFO | 1648: exit 0 23:17:23 INFO - Browser initialized. 23:17:23 INFO - Running cycle 1/1 for kraken test... 23:17:23 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpMmV0dM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:26 INFO - PID 1650 | 2017-03-21 23:17:26.214 plugin-container[1651:10390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 23:17:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:17:26 INFO - PID 1650 | 2017-03-21 23:17:26.215 plugin-container[1651:10390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 23:17:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:17:26 INFO - PID 1650 | 2017-03-21 23:17:26.215 plugin-container[1651:10390] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:17:35 INFO - PID 1650 | RSS: Main: 227864576 23:17:35 INFO - PID 1650 | 23:17:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 23:17:39 INFO - PID 1650 | RSS: Main: 241713152 23:17:39 INFO - PID 1650 | 23:17:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 23:17:43 INFO - PID 1650 | RSS: Main: 250089472 23:17:43 INFO - PID 1650 | 23:17:46 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 23:17:47 INFO - PID 1650 | RSS: Main: 252862464 23:17:47 INFO - PID 1650 | 23:17:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 23:17:49 INFO - PID 1650 | RSS: Main: 255004672 23:17:49 INFO - PID 1650 | 23:17:51 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:17:52 INFO - PID 1650 | RSS: Main: 255647744 23:17:52 INFO - PID 1650 | 23:17:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 23:17:55 INFO - PID 1650 | RSS: Main: 260886528 23:17:55 INFO - PID 1650 | 23:17:59 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 23:17:59 INFO - PID 1650 | RSS: Main: 261603328 23:17:59 INFO - PID 1650 | 23:18:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 23:18:03 INFO - PID 1650 | RSS: Main: 261349376 23:18:03 INFO - PID 1650 | 23:18:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:18:06 INFO - PID 1650 | RSS: Main: 260993024 23:18:06 INFO - PID 1650 | 23:18:08 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:18:08 INFO - PID 1650 | RSS: Main: 260931584 23:18:08 INFO - PID 1650 | 23:18:10 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:18:11 INFO - PID 1650 | RSS: Main: 261013504 23:18:11 INFO - PID 1650 | 23:18:14 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:18:15 INFO - PID 1650 | RSS: Main: 261386240 23:18:15 INFO - PID 1650 | 23:18:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:18:19 INFO - PID 1650 | RSS: Main: 269705216 23:18:19 INFO - PID 1650 | 23:18:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 23:18:21 INFO - PID 1650 | RSS: Main: 272216064 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | __start_tp_report 23:18:21 INFO - PID 1650 | _x_x_mozilla_page_load 23:18:21 INFO - PID 1650 | _x_x_mozilla_page_load_details 23:18:21 INFO - PID 1650 | |i|pagename|runs| 23:18:21 INFO - PID 1650 | |0;ai-astar;106;121;117;116;118;117;116;119;130;162 23:18:21 INFO - PID 1650 | |1;audio-beat-detection;167;138;114;152;149;149;148;117;135;122 23:18:21 INFO - PID 1650 | |2;audio-dft;199;207;212;217;259;277;201;197;199;197 23:18:21 INFO - PID 1650 | |3;audio-fft;76;59;63;85;61;60;63;67;103;60 23:18:21 INFO - PID 1650 | |4;audio-oscillator;87;72;84;71;70;74;87;71;74;84 23:18:21 INFO - PID 1650 | |5;imaging-gaussian-blur;92;94;92;95;94;92;78;136;94;133 23:18:21 INFO - PID 1650 | |6;imaging-darkroom;151;113;112;152;151;100;99;100;146;150 23:18:21 INFO - PID 1650 | |7;imaging-desaturate;92;92;130;100;141;89;80;146;146;93 23:18:21 INFO - PID 1650 | |8;json-parse-financial;65;67;67;70;68;67;68;69;70;69 23:18:21 INFO - PID 1650 | |9;json-stringify-tinderbox;56;63;60;64;77;83;79;58;59;59 23:18:21 INFO - PID 1650 | |10;stanford-crypto-aes;86;107;107;103;94;83;85;84;83;83 23:18:21 INFO - PID 1650 | |11;stanford-crypto-ccm;140;139;143;144;161;154;137;142;232;143 23:18:21 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;192;189;190;200;231;200;231;202;191;188 23:18:21 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;72;69;55;84;87;90;81;70;71;73 23:18:21 INFO - PID 1650 | __end_tp_report 23:18:21 INFO - PID 1650 | __start_cc_report 23:18:21 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1000 23:18:21 INFO - PID 1650 | __end_cc_report 23:18:21 INFO - PID 1650 | __startTimestamp1490163501208__endTimestamp 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | ------- Summary: start ------- 23:18:21 INFO - PID 1650 | Number of tests: 14 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:122.20 Median:118.50 stddev:15.16 (12.8%) stddev-sans-first:14.90 23:18:21 INFO - PID 1650 | Values: 106.0 121.0 117.0 116.0 118.0 117.0 116.0 119.0 130.0 162.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:148.50 stddev:17.16 (11.6%) stddev-sans-first:14.93 23:18:21 INFO - PID 1650 | Values: 167.0 138.0 114.0 152.0 149.0 149.0 148.0 117.0 135.0 122.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:216.50 Median:209.50 stddev:28.27 (13.5%) stddev-sans-first:29.26 23:18:21 INFO - PID 1650 | Values: 199.0 207.0 212.0 217.0 259.0 277.0 201.0 197.0 199.0 197.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:69.70 Median:63.00 stddev:14.37 (22.8%) stddev-sans-first:15.06 23:18:21 INFO - PID 1650 | Values: 76.0 59.0 63.0 85.0 61.0 60.0 63.0 67.0 103.0 60.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:7.15 (9.1%) stddev-sans-first:6.69 23:18:21 INFO - PID 1650 | Values: 87.0 72.0 84.0 71.0 70.0 74.0 87.0 71.0 74.0 84.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:93.00 stddev:18.84 (20.3%) stddev-sans-first:19.76 23:18:21 INFO - PID 1650 | Values: 92.0 94.0 92.0 95.0 94.0 92.0 78.0 136.0 94.0 133.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:127.40 Median:150.50 stddev:24.33 (16.2%) stddev-sans-first:24.26 23:18:21 INFO - PID 1650 | Values: 151.0 113.0 112.0 152.0 151.0 100.0 99.0 100.0 146.0 150.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:110.90 Median:84.50 stddev:26.51 (31.4%) stddev-sans-first:27.22 23:18:21 INFO - PID 1650 | Values: 92.0 92.0 130.0 100.0 141.0 89.0 80.0 146.0 146.0 93.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:68.00 Median:68.50 stddev:1.56 (2.3%) stddev-sans-first:1.22 23:18:21 INFO - PID 1650 | Values: 65.0 67.0 67.0 70.0 68.0 67.0 68.0 69.0 70.0 69.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:63.50 stddev:9.94 (15.7%) stddev-sans-first:9.89 23:18:21 INFO - PID 1650 | Values: 56.0 63.0 60.0 64.0 77.0 83.0 79.0 58.0 59.0 59.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:83.50 stddev:10.35 (12.4%) stddev-sans-first:10.79 23:18:21 INFO - PID 1650 | Values: 86.0 107.0 107.0 103.0 94.0 83.0 85.0 84.0 83.0 83.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:143.50 stddev:28.53 (19.9%) stddev-sans-first:29.84 23:18:21 INFO - PID 1650 | Values: 140.0 139.0 143.0 144.0 161.0 154.0 137.0 142.0 232.0 143.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.40 Median:200.00 stddev:16.39 (8.2%) stddev-sans-first:17.02 23:18:21 INFO - PID 1650 | Values: 192.0 189.0 190.0 200.0 231.0 200.0 231.0 202.0 191.0 188.0 23:18:21 INFO - PID 1650 | 23:18:21 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:77.00 stddev:10.41 (13.5%) stddev-sans-first:10.98 23:18:21 INFO - PID 1650 | Values: 72.0 69.0 55.0 84.0 87.0 90.0 81.0 70.0 71.0 73.0 23:18:21 INFO - PID 1650 | -------- Summary: end -------- 23:18:21 INFO - PID 1650 | 23:18:22 INFO - TEST-INFO | 1650: exit 0 23:18:22 INFO - TEST-OK | kraken | took 62347ms 23:18:22 INFO - SUITE-END | took 1180s 23:18:22 INFO - Completed test suite (00:19:40) 23:18:22 INFO - javascript benchmark 23:18:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7569.2708653796935, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3493.091796133006, 3521.8591256349746, 3537.964620353796, 3539.893803185904, 3552.0231936217538, 5481.780728770845, 5507.779688812453, 6030.396960303968, 6060.484858787008, 6166.6916654167335, 6092.20801295832, 6138.017917133259, 6159.9528080226355, 6181.536384771138, 6183.783567575136, 4436.600705936465, 5443.591730620199, 5480.890382192374, 5573.052581061229, 5693.202951586781, 5512.503874651281, 5564.276644036269, 5631.352394474647, 5634.9718251408685, 5701.372848986608, 5396.946030539683, 5620.662760234358, 5653.547716182696, 5674.0, 5677.57418193635, 4704.882377940545, 5441.020616289066, 5508.173773933942, 5569.0254205513875, 5612.663240205601, 5443.237946687467, 5452.073147564924, 5461.344638643349, 5463.617546771728, 5474.972625136888, 4512.278035514335, 5290.259363689087, 6179.5056395488255, 6194.938050619481, 6215.658138802364, 6307.369263073701, 6372.426481616677, 6453.0, 6541.607503549754, 6542.803715888531, 6693.163354580677, 6697.966510167466, 6699.966500167517, 6703.262641109474, 6714.060031595581, 6237.812865614039, 6270.529710271702, 6290.339514350927, 6295.401936815995, 6300.873982520324, 6498.057781621638, 6627.900581491282, 6712.463002959751, 6731.629760363129, 6746.83132921671, 5275.762590683428, 6331.651759153198, 6399.8080057598345, 6510.08858759774, 6521.902171467432, 5694.943050569441, 6094.451499365078, 6406.071119687619, 6430.7749228776765, 6637.137172167684, 6433.163688720437, 6523.543351965412, 6545.705443255065, 6552.63960482178, 6577.440917522064, 5600.159976003632, 6254.437100660963, 6309.589876658002, 6558.704858281389, 6562.737490500326, 5196.532312091855, 5216.556592689664, 5224.817131400383, 5279.0, 5291.497307755757, 4694.882627934329, 4709.95290047102, 4731.266653668687, 4747.382840230817, 4774.045190961821, 6230.0, 6268.310485846553, 6284.497240220771, 6286.0, 6299.433051025429, 6402.199725034371, 6450.741970321228, 6638.5684930479365, 6639.468842492589, 6692.86614267712, 5290.206469029677, 6181.690915454299, 6203.534734894901, 6211.875762484725, 6236.532260080421, 5832.096025116114, 5848.824535263949, 5869.972754767984, 5876.206712093898, 5881.264841894763, 5972.074328479092, 6485.189351331083, 6817.420519255918, 6843.623600702009, 6891.207511136284, 5642.040853054908, 6039.879202416015, 6450.645214513247, 6465.482761379172, 6524.510661700297, 6764.086848275617, 6836.863262734817, 6854.657267136723, 6863.759768408082, 6881.827954301182, 5505.779768809202, 6531.248906375828, 6542.607443553402, 6560.3111673273615, 6604.306547812411, 6414.871702566016, 6484.675766211765, 6658.966705166443, 6675.265720770809, 6689.665516724047, 6371.96814015927, 6410.679466026773, 6447.903281450688, 6479.287278399466, 6504.089427480251, 6235.501159907106, 6288.654124023223, 6327.651979141191, 6381.3937675920715, 6462.289148193789, 5312.867178320573, 6152.50779937595, 6320.462760665211, 6349.809505714836, 6355.205599300088, 4776.450708168605, 5892.762519870875, 6394.200724909387, 6476.805695829133, 6493.675316234264, 6222.533310001678, 6226.439620434183, 6228.439440450381, 6243.781467648792, 6287.96856015717], "value": 5968.293771624574, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.955842649441, 794.551078640568, 815.5636734347122, 1254.8996080313575, 1259.691375612975, 31770.68229317712, 37026.629733702655, 37973.81013094934, 38025.0, 38170.42744358837, 37509.624903750955, 37848.053798654975, 37894.0, 38037.809810950974, 38091.23817523648, 7813.374930005586, 7964.283214510693, 8396.664133434686, 8566.271866891322, 8630.439021463617, 8398.916010839905, 8493.02330232025, 8588.895720548444, 8614.095519970408, 8627.61175747093, 8534.530600816952, 8544.401891867572, 8560.443571167854, 8604.35467339952, 8619.827603447928, 8565.957170214171, 8608.827823443558, 8618.87071693922, 8620.224179823846, 8632.913670863336, 7452.329290363839, 8413.200745929127, 8459.323254139654, 8526.275266602346, 8530.701425450141, 37825.43261851074, 37855.432168517495, 37930.051748706224, 37941.29264183118, 38093.0, 2824.5904343870025, 2886.975123831052, 3032.586832503992, 3051.649060358065, 3054.526548385004, 27319.907203711828, 27448.39240272171, 27471.313217169532, 27619.30951726223, 28565.857170714222, 2771.9029833955715, 2788.5817127430846, 2801.5097357962277, 2807.101727447218, 2812.7749780017753, 309430.0, 311559.0, 312720.0, 314298.4285078583, 315648.0, 48028.79928001758, 48269.5173048265, 48333.0, 48361.7909552257, 48412.03175936462, 50718.239226411635, 51079.48920510747, 51082.74458627683, 51130.0, 51593.0, 14125.0, 14351.569452916428, 14447.349869255908, 14518.927405362905, 14542.0, 11734.413279335999, 11750.0, 11755.177137600456, 11772.941135294268, 11826.349550774617, 12882.613521594367, 13307.933460332637, 13380.732385352238, 13403.597892063253, 13404.262765547988, 5846.210761547137, 5849.947009538324, 5901.527877769854, 5907.911381329283, 5911.113333000085, 826.7189155686975, 947.3084648206848, 961.2166084640995, 990.9058639429387, 995.1789773436943, 23209.071637134664, 23376.532469350517, 23981.280561583182, 24023.27930162098, 24042.75957240405, 928.744595236302, 1055.2665897201525, 1079.2714917430703, 1095.058249905081, 1102.818035024228, 10366.0, 10481.0, 11543.595974141033, 11788.587399440978, 12292.955098816521, 420668.0, 422746.0, 423028.0, 424719.0, 427582.0, 45218.77390613026, 51527.22709159365, 52556.21165682518, 53975.0, 54225.72887135539, 1381.9723605527834, 1399.951001714935, 1437.152080272644, 1465.421158642309, 1473.8894582906325, 28048.57927131054, 28119.57820632651, 28128.29679258035, 28378.2905427366, 28823.991160309288, 36748.63251367452, 36787.44818827666, 36841.815790920875, 36951.630483695895, 37011.07472313214, 343425.0, 346075.20135184773, 346823.0, 346852.0, 349326.0, 31061.378772424876, 31263.68736312608, 31282.68717312798, 31359.37281254408, 31533.684663153992, 40897.795511022254, 42113.0, 42212.0, 42309.788451057546, 42342.0, 32187.67812321847, 34341.828290858386, 35168.0, 35298.64701352954, 36821.26357472782, 32231.033069007968, 34830.65169348376, 35224.119397014256, 35599.46600800938, 36325.81837090903, 36017.81991090133, 36238.91283261401, 36703.81648091849, 36838.26323473569, 37109.44335835019, 30528.694713052584, 31973.36053278875, 32074.839625802655, 32167.678323216467, 32187.51718724268, 20616.690749639067, 21955.67066493972, 22687.546249074596, 22744.43138921474, 24357.634635480837], "value": 17571.88117648704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.0734284828716, 427.741216563979, 432.82686925229916, 444.6842741653426, 450.7993942695498, 20688.27591034314, 21233.15067397302, 21431.035603397864, 21483.0, 22140.446488837788, 20022.0, 20379.082941267643, 21905.561888762215, 22748.20381286655, 22988.425289367773, 5630.380658127603, 6034.8793024139495, 6218.409251121137, 6228.314885362606, 6245.843853903643, 5863.941360586404, 6157.261128664544, 6171.907421388682, 6193.955994521797, 6234.906476402857, 5926.496247818941, 6122.173506576622, 6186.443220110189, 6190.597611155283, 6202.131701561762, 6033.577649564532, 6039.486643635274, 6079.969600151971, 6087.847803804918, 6101.206843110367, 4877.439094504142, 5155.226715992631, 5333.493318134771, 5389.218563308337, 5406.540234136761, 25838.87080564604, 25945.091921782834, 25987.350316242057, 26185.73814261871, 26239.0, 2036.9898150509152, 2038.6941958706163, 2049.2622655843884, 2057.2285392977633, 2128.265748316828, 15620.0, 15665.21673916311, 15777.29002194904, 16092.517224483285, 16479.423220187236, 1590.5148929576474, 1661.534770264328, 1678.3549740608894, 1710.598009467774, 1719.5271300392492, 129416.0, 138556.0, 139953.60046399507, 141362.58637413598, 141512.2924385382, 9902.009799020185, 12514.249145051326, 12672.986161107274, 12716.17344872599, 12893.484260629657, 16114.838851611568, 16123.355065797237, 16149.757753633705, 16164.838351616569, 16223.0, 7656.272654097852, 8595.269402100892, 8919.286457083417, 8934.597943092576, 9013.594388252544, 8424.747257582283, 8518.787030324167, 8549.615267312985, 8658.567071646392, 8718.215360617574, 7721.03487064117, 7861.842763144705, 7889.960550197212, 7917.366610671133, 7918.881216781753, 2140.6467932790915, 2147.602693501707, 2147.9248226312316, 2150.731158605174, 2313.6298192289155, 914.2320450821342, 918.3204428722697, 918.7335672654855, 924.6347692661495, 956.583886009588, 15509.689806204038, 19415.708764368545, 20223.8876861774, 20339.491512712302, 20684.0, 896.7175339767953, 930.7899730350517, 949.7055912667096, 976.0479041916168, 976.799756050008, 8734.38859279857, 10624.521896514521, 10660.786784264272, 10695.732606684895, 11033.6138235163, 617304.9134754298, 633896.0, 636883.0, 641229.0, 641503.0, 91495.0, 98635.0, 112622.0, 114630.4268478669, 128621.0, 1120.5797825815318, 1178.6110583507593, 1180.0854337888206, 1181.6573193773709, 1184.567632814034, 19900.402987910387, 20890.895545522177, 21128.471788204803, 21147.577048459254, 21451.356459306247, 56605.71697141653, 58203.12695309489, 59540.80918381522, 59890.40109598848, 60231.79536409335, 81819.0, 82307.17692823235, 82527.58736206281, 83223.5838820802, 83809.58095209485, 8418.494890306602, 8447.535385553854, 8552.315814734682, 8967.551622418983, 9013.954930225307, 10329.018743219382, 10779.784404311713, 12484.375781211085, 13523.932380338429, 13789.655258618615, 11657.242279251821, 11772.17594768358, 11799.823002654795, 11836.349000805038, 11995.520179192734, 11429.0, 11489.885101148882, 11690.649280521598, 11950.701232469259, 12033.0, 10330.586776529155, 11949.820752688544, 12238.877611223774, 12258.693532661755, 12309.569165079538, 6268.27914789805, 8655.0, 8688.304935605263, 8710.172533609297, 8787.0, 11471.4264286787, 11526.654200374001, 11843.526258949545, 11866.703332416759, 11892.821607676065], "value": 10153.018284704145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.537461888339, 1346.4210389532502, 1359.109783092075, 1367.8221831161948, 1385.0512399006682, 9456.763580910481, 9762.804743905126, 9780.804383912318, 10539.73650658734, 10932.016118549329, 10738.463076846165, 10749.731256718585, 10867.076298514616, 10877.6736697899, 11046.668599941995, 2336.941576460589, 2375.70303712036, 2387.3434805428474, 2388.6894703688495, 2390.6892104026538, 2349.694539709844, 2404.663347131403, 2404.7955923746504, 2405.0860672944254, 2410.035985605763, 2350.4476448034784, 2380.4048987753063, 2393.0, 2397.30478161333, 2397.7482364351667, 2342.227065068523, 2358.469344397514, 2374.323317854412, 2377.3343463830156, 2379.5478859016816, 2326.592846251899, 2338.543983923136, 2404.158544509408, 2405.87970601471, 2430.1130087518104, 10551.683449496451, 10636.149108071335, 10644.787104257948, 10661.946690266499, 10673.83989240162, 6132.540059495555, 6352.46004089653, 6372.0123380875575, 6372.490200783926, 6380.712867920908, 3883.262180185784, 3895.5714871364125, 3910.335243008695, 3963.1677347757004, 4019.839206431739, 1160.7214268575565, 1462.7659574468141, 1503.4304464552097, 1560.0405750463497, 1623.115402105855, 13438.395272212774, 13695.246761428118, 13839.723205535933, 14570.854291457055, 14696.338664760004, 5180.7150606717, 5379.4351593082165, 5386.461353864661, 5403.7838486460105, 5404.32445944257, 11023.944880275707, 11084.168687348481, 11114.0, 11133.0, 11147.777044459228, 6037.63774173551, 6072.301685306246, 6159.260888693296, 6215.564910456224, 6220.066989951453, 5602.215689803431, 5717.971410143005, 5758.2514273144225, 5796.043652797325, 5804.651720896675, 4087.5708050654252, 4286.099919017041, 4316.223079845595, 4349.238883195491, 4352.717073390219, 1252.0859772366223, 1271.065766661503, 1273.4718337994443, 1287.452832546183, 1288.4974859804684, 744.9590272535063, 753.5629334985695, 780.4575819805294, 780.6331024418514, 783.4124406694979, 5664.68844213564, 5913.76344946206, 6343.460805831556, 6895.896561551581, 6907.758228461979, 854.7136709202487, 867.7049803066988, 911.0980129671577, 914.7804526913493, 927.9025702301296, 4207.5161356444405, 4235.449391579136, 4248.320268756984, 4328.978355108205, 4394.648428125743, 12798.040146988827, 12968.610941671575, 13344.799828002588, 13708.725825483434, 13859.584212473643, 12917.870821291666, 13320.93339533296, 13331.600051998455, 13336.333183340794, 13370.39833207508, 8169.754907352789, 9960.601575937026, 10013.699589012342, 10029.097381235724, 10081.798364032678, 6069.5751297410525, 6305.684715764285, 6385.936140638534, 6607.141071660653, 6623.536352455282, 12114.091443141624, 12251.387430628254, 12255.69360765988, 12453.501859925502, 12479.5008199671, 12375.257484550955, 12381.814272786096, 12642.620721378373, 12645.304508252135, 12801.29592872401, 8664.263537599374, 9045.321600880092, 9058.094190580889, 9214.308926830381, 9270.44377337362, 10095.949520252352, 10119.949400252952, 10135.087842094246, 10251.794964100825, 10261.17910567138, 8685.609147588248, 8787.560621968749, 8965.058668839678, 9007.594658240527, 9111.681091161778, 8610.268127209258, 8618.913810861812, 8639.438436501607, 8717.869231961398, 8753.47479151253, 7636.770896873103, 7711.6915323388685, 7782.0, 7788.493747906368, 7806.219378062148, 5297.178937264576, 5934.851628709317, 5969.582129250911, 6012.759489620541, 6024.518038556993, 5815.534757219328, 5873.295204575478, 6028.608140470856, 6146.139540464428, 6162.445379915829], "value": 5153.228667109262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.54866885403106, 380.0517708317748, 388.6579809767404, 394.964453199212, 399.53254692010336, 5925.666899790191, 5986.01230796918, 6006.7897623583185, 6016.8796624067545, 6030.457258846703, 4493.472219445388, 5606.635568688032, 5785.363610002896, 5861.589688721791, 5879.382664820186, 4545.659075569329, 4548.295014272793, 4563.201439748047, 4569.428821397325, 4595.4255718035165, 4565.520620334867, 4581.3128030795315, 4594.0, 4597.793099310522, 4618.1687296286655, 3292.967070329302, 3417.299453612016, 4442.422485076935, 4486.55134486552, 4564.334018083392, 3761.6990640748672, 4137.524184718765, 4504.031633198845, 4506.616937560311, 4564.84023059193, 3347.577279656161, 3980.6218409251073, 3981.7013723970526, 3999.9400008999887, 4014.3175660137554, 4722.551357621055, 5506.64206826559, 5618.595351162209, 5698.886022279573, 5766.682832444214, 5320.893582128336, 5326.174442961347, 5332.386775520826, 5337.439568845254, 5359.812406565751, 6025.578209525335, 6321.336259692758, 6329.715162817638, 6331.986882098842, 6399.584027038274, 3704.518412606371, 3742.9438558421643, 4328.199283132631, 4394.309369366678, 4472.195004899117, 6045.788397406113, 6389.5527313088105, 6533.705983230766, 6590.999999999952, 6639.435647969928, 3804.3912973924594, 4585.151746926829, 4754.833580824655, 4789.473157952622, 4796.904061918812, 4446.755428451466, 4791.292919500471, 4804.471508134103, 4810.2683034107695, 4812.446568644651, 5261.026710058652, 5399.298091248191, 5461.508464238238, 5497.587680923947, 5525.419830917695, 6215.81352559424, 6304.865124277674, 6506.869862602816, 6513.208867561364, 6548.70530826114, 1342.8724271194221, 1573.6144644562155, 1653.2477722636172, 1664.716998110324, 1671.5236157694999, 963.9277054220961, 1012.9465356029793, 1088.9509972051276, 1130.1241537808264, 1167.3346192670097, 4723.291506274087, 5711.286089238845, 5931.822045338647, 5954.600668842819, 5965.0, 6038.879222415615, 6459.709313080923, 6536.705848236841, 6583.539152259296, 6627.5029372797235, 4829.492903245179, 5883.8232353529465, 5892.086726557391, 5975.880482390327, 6000.879982400327, 2659.1889473710316, 2876.295307649639, 3290.555774970396, 3348.464245720673, 3421.4012547804036, 5843.5909486336395, 6285.5914365567005, 6418.84460797061, 6819.748793429032, 6838.692258848364, 5376.032314183406, 6789.456843452512, 6842.281560436082, 6850.725970961105, 6880.0, 5936.910946335808, 6190.461910616716, 6725.899111513331, 6793.16186354708, 6878.931210687929, 6597.0764093026055, 6685.0, 6699.387238976706, 6699.631520266432, 6742.393184613408, 5546.362168350692, 6571.3757193066585, 6633.867322653617, 6640.0, 6661.300563440963, 6802.523823332319, 6814.182298124257, 6814.829629259309, 6848.554843935328, 6859.725610975505, 3673.8163091844767, 3690.1512652090705, 4339.457567804025, 4360.847370342023, 4365.410669559569, 5557.3331200256225, 6387.594729159578, 6571.934280657323, 6634.734610615521, 6651.7006734698025, 5940.049592065248, 6078.908816367846, 6319.873602527831, 6332.810015699536, 6342.7145778441, 5150.716710580954, 6083.908741368971, 6336.017917222838, 6396.744130234924, 6399.64801935898, 5960.910586341121, 6176.474999624902, 6193.566450348432, 6218.471429928507, 6236.563440559117, 5589.469000444951, 6366.172397588284, 6378.585391949508, 6400.743970241138, 6427.389398007369, 5627.0, 5917.467427931507, 6184.721687524162, 6201.627902325875, 6234.470070043915], "value": 4678.02706151144, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.8072779985506, 712.6151877985887, 736.0799001248439, 738.5273425007996, 742.881139017757, 12671.61985140445, 12728.299943503103, 13260.138091024077, 14490.492832750855, 15396.153211573359, 14064.789028164585, 14086.366113524891, 14243.287835608204, 14259.429622815102, 14457.915656325766, 783.2715574515684, 921.3044151665483, 929.5445231836385, 964.4406244378243, 966.1401352796017, 954.6324665003954, 966.9903300967006, 969.2730452160879, 969.8981606931277, 971.7910649210419, 992.8411454167334, 999.035930327234, 1001.7345403468087, 1001.8797744270697, 1001.9348742331725, 985.0543478260878, 995.6863587969839, 996.9451680157553, 998.8601595776524, 999.3753903810118, 735.0995031086929, 737.6164394514876, 740.9481336306461, 741.7515132430642, 741.8775901976168, 16050.197490125447, 16351.182440878027, 16380.590485237844, 16497.91751041249, 16602.916985415115, 14299.928500357535, 14467.30363568681, 14542.418303267963, 14543.200123993176, 14697.632559185999, 11681.182317237797, 11685.357305348203, 11872.014043346859, 11919.821202681967, 12224.572139975147, 3021.531662592302, 3051.969480305213, 3052.1759125036338, 3140.2149462634343, 3145.3237553926015, 96900.0, 98607.50696246544, 98963.50518247434, 99539.0, 99958.00083998352, 9162.679306224383, 10219.774226972751, 11448.656540303804, 11604.94197529007, 11885.346305953255, 22889.198878039188, 22959.540809184058, 23176.65235021476, 23285.417864553183, 23354.949027293813, 6983.790486285419, 7593.0, 7961.283484486424, 7981.640826162837, 8177.71378001779, 4090.263752524574, 4172.5410204877435, 4173.123644034726, 4179.352200408942, 4188.874333769992, 4025.295573274665, 4057.736247143985, 4078.1028173801715, 4106.3840423936645, 4124.7731374774075, 817.166490180013, 838.1660248053226, 858.0947100808582, 865.7143142762873, 867.1458613265928, 553.1619596311591, 557.3005877623638, 561.4694114062172, 569.8148101866827, 588.6026931821003, 9717.61129554824, 9781.951090244504, 10244.743881403025, 10347.793044139074, 10473.423961682181, 541.3720084701727, 578.259827420902, 579.8695293558916, 592.3809618948123, 592.4963780786297, 4597.287420449836, 4812.398450193726, 4822.9871726937035, 4833.589144922651, 4855.757212139379, 96218.0, 98797.0, 99705.00294996957, 100907.49546252369, 101246.49376753069, 87053.0, 87316.0, 87632.0, 88054.55972720095, 89070.55464722765, 48794.756026220355, 50782.74608626933, 52110.95778084417, 61316.69341653263, 61986.38013619806, 10361.533730981973, 10758.73103172427, 10844.349339039367, 10952.34285942846, 11079.390633515233, 36223.551057958444, 36265.19756838999, 36532.452013220354, 36665.26669466542, 36963.07592310214, 38486.80756596199, 48595.75702121467, 49059.52821415363, 49275.50724492709, 49792.75103624459, 11239.438028098399, 12838.486460541473, 13148.703212861277, 13182.274974876475, 13285.66785830362, 14035.789463158266, 14140.717185656435, 14153.858461415666, 14247.643808904446, 14330.57008289753, 12583.307918064229, 13212.537561185312, 13499.932500337436, 13549.187048777105, 13939.651508712363, 12120.515179393078, 12380.195287306296, 12969.0, 13209.867901321248, 13917.58247252584, 11448.484818183035, 12733.29966851832, 12925.870741292843, 13041.478340866639, 13485.86514134846, 10787.946060269649, 10851.348919064565, 11005.669829905115, 11025.0, 11785.882141178821, 7967.760967170994, 9357.298202634693, 9619.326647134634, 11226.158038147334, 11241.437928103398], "value": 7326.324209611196, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 117.0, 116.0, 118.0, 117.0, 116.0, 119.0, 130.0, 162.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 138.0, 114.0, 152.0, 149.0, 149.0, 148.0, 117.0, 135.0, 122.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 207.0, 212.0, 217.0, 259.0, 277.0, 201.0, 197.0, 199.0, 197.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 59.0, 63.0, 85.0, 61.0, 60.0, 63.0, 67.0, 103.0, 60.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 84.0, 71.0, 70.0, 74.0, 87.0, 71.0, 74.0, 84.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 95.0, 94.0, 92.0, 78.0, 136.0, 94.0, 133.0], "value": 100.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 112.0, 152.0, 151.0, 100.0, 99.0, 100.0, 146.0, 150.0], "value": 127.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 130.0, 100.0, 141.0, 89.0, 80.0, 146.0, 146.0, 93.0], "value": 110.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 70.0, 68.0, 67.0, 68.0, 69.0, 70.0, 69.0], "value": 68.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 60.0, 64.0, 77.0, 83.0, 79.0, 58.0, 59.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 107.0, 107.0, 103.0, 94.0, 83.0, 85.0, 84.0, 83.0, 83.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 143.0, 144.0, 161.0, 154.0, 137.0, 142.0, 232.0, 143.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 189.0, 190.0, 200.0, 231.0, 200.0, 231.0, 202.0, 191.0, 188.0], "value": 201.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 55.0, 84.0, 87.0, 90.0, 81.0, 70.0, 71.0, 73.0], "value": 75.2, "unit": "score"}]}]} 23:18:22 INFO - Return code: 0 23:18:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:18:22 ERROR - # TBPL SUCCESS # 23:18:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:18:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:18:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:18:22 INFO - 2017-03-21 23:18:22.559 screenresolution[1655:10795] starting screenresolution argv=screenresolution get 23:18:22 INFO - 2017-03-21 23:18:22.561 screenresolution[1655:10795] Display 0: 1600x1200x32@60 23:18:22 INFO - 2017-03-21 23:18:22.568 screenresolution[1656:10798] starting screenresolution argv=screenresolution list 23:18:22 INFO - Available Modes on Display 0 23:18:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:18:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:18:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:18:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:18:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:18:22 INFO - Intel Iris: 23:18:22 INFO - Chipset Model: Intel Iris 23:18:22 INFO - Type: GPU 23:18:22 INFO - Bus: Built-In 23:18:22 INFO - VRAM (Dynamic, Max): 1536 MB 23:18:22 INFO - Vendor: Intel (0x8086) 23:18:22 INFO - Device ID: 0x0a2e 23:18:22 INFO - Revision ID: 0x0009 23:18:22 INFO - Displays: 23:18:22 INFO - Display: 23:18:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:18:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:18:22 INFO - Display Serial Number: 1600x1200 60 23:18:22 INFO - Main Display: Yes 23:18:22 INFO - Mirror: Off 23:18:22 INFO - Online: Yes 23:18:22 INFO - Rotation: Supported 23:18:22 INFO - Return code: 0 23:18:22 INFO - Running post-action listener: _resource_record_post_action 23:18:22 INFO - [mozharness: 2017-03-22 06:18:22.809792Z] Finished run-tests step (success) 23:18:22 INFO - Running post-run listener: _resource_record_post_run 23:18:22 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39938560; Write bytes: 517370368; Read time: 386; Write time: 1772 23:18:22 INFO - TinderboxPrint: CPU usage
54.1% 23:18:22 INFO - TinderboxPrint: I/O read bytes / time
39,938,560 / 386 23:18:22 INFO - TinderboxPrint: I/O write bytes / time
517,370,368 / 1,772 23:18:22 INFO - TinderboxPrint: CPU idle
2,206.1 (45.9%) 23:18:22 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 23:18:22 INFO - TinderboxPrint: CPU user
1,637.4 (34.0%) 23:18:22 INFO - TinderboxPrint: Swap in / out
250,126,336 / 0 23:18:22 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199434752; Write bytes: 194643968; Read time: 15658; Write time: 443 23:18:22 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 38971904; Write bytes: 302922240; Read time: 371; Write time: 1273 23:18:23 INFO - Running post-run listener: _upload_blobber_files 23:18:23 INFO - Blob upload gear active. 23:18:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:18:23 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 23:18:23 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'] 23:18:23 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 23:18:23 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:18:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:18:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:24 INFO - (blobuploader) - INFO - Done attempting. 23:18:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:18:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:18:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:25 INFO - (blobuploader) - INFO - Done attempting. 23:18:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:18:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:18:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:26 INFO - (blobuploader) - INFO - Done attempting. 23:18:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:18:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:18:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:28 INFO - (blobuploader) - INFO - Done attempting. 23:18:28 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:28 INFO - Return code: 0 23:18:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:18:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ac01a9a75c21ca27fdb7b2fd2194f86a3d797e8d4e9cfdd23313260a3d1507cda14fe9e24cce759efcce7a32905acb9ae0da304ad7964d4ce8dd7fdbb6739d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35b68085d91e64ee8e9621a63380eda4a47499e9ef4dc1fdee78a3c5eb9b7fe930da4a4ae935f95f55da6a5483165c29d57556752277ab6fdb1333e4aa6a5ac5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/765ac84e243e3d02e08730a34e1a494bcba3da35448e7d636986c640f3a0b176ff30d7e3ca4414dda822a1bbb5af12e8d23da2e3c81654a5ea0388716b919dd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd63fe05467fca920b54a6cd9c3bb73658c741798a8ce0da943f1b7e3bb01213040f5851a13254660f727ebdde097de3120e874b1d2b1cf48de72e6c3624cc65"} 23:18:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:18:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:18:28 INFO - Contents: 23:18:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ac01a9a75c21ca27fdb7b2fd2194f86a3d797e8d4e9cfdd23313260a3d1507cda14fe9e24cce759efcce7a32905acb9ae0da304ad7964d4ce8dd7fdbb6739d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35b68085d91e64ee8e9621a63380eda4a47499e9ef4dc1fdee78a3c5eb9b7fe930da4a4ae935f95f55da6a5483165c29d57556752277ab6fdb1333e4aa6a5ac5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/765ac84e243e3d02e08730a34e1a494bcba3da35448e7d636986c640f3a0b176ff30d7e3ca4414dda822a1bbb5af12e8d23da2e3c81654a5ea0388716b919dd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd63fe05467fca920b54a6cd9c3bb73658c741798a8ce0da943f1b7e3bb01213040f5851a13254660f727ebdde097de3120e874b1d2b1cf48de72e6c3624cc65"} 23:18:28 INFO - Running post-run listener: copy_logs_to_upload_dir 23:18:28 INFO - Copying logs to upload dir... 23:18:28 INFO - mkdir: /builds/slave/test/build/upload/logs 23:18:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.765315 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-21 23:18:28.746516) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 23:18:28.755253) ========= 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.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/16ac01a9a75c21ca27fdb7b2fd2194f86a3d797e8d4e9cfdd23313260a3d1507cda14fe9e24cce759efcce7a32905acb9ae0da304ad7964d4ce8dd7fdbb6739d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35b68085d91e64ee8e9621a63380eda4a47499e9ef4dc1fdee78a3c5eb9b7fe930da4a4ae935f95f55da6a5483165c29d57556752277ab6fdb1333e4aa6a5ac5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/765ac84e243e3d02e08730a34e1a494bcba3da35448e7d636986c640f3a0b176ff30d7e3ca4414dda822a1bbb5af12e8d23da2e3c81654a5ea0388716b919dd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd63fe05467fca920b54a6cd9c3bb73658c741798a8ce0da943f1b7e3bb01213040f5851a13254660f727ebdde097de3120e874b1d2b1cf48de72e6c3624cc65"} build_url:https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010953 build_url: 'https://queue.taskcluster.net/v1/task/NMneLvmfQJGWwc2uKTj57A/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/16ac01a9a75c21ca27fdb7b2fd2194f86a3d797e8d4e9cfdd23313260a3d1507cda14fe9e24cce759efcce7a32905acb9ae0da304ad7964d4ce8dd7fdbb6739d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35b68085d91e64ee8e9621a63380eda4a47499e9ef4dc1fdee78a3c5eb9b7fe930da4a4ae935f95f55da6a5483165c29d57556752277ab6fdb1333e4aa6a5ac5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/765ac84e243e3d02e08730a34e1a494bcba3da35448e7d636986c640f3a0b176ff30d7e3ca4414dda822a1bbb5af12e8d23da2e3c81654a5ea0388716b919dd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd63fe05467fca920b54a6cd9c3bb73658c741798a8ce0da943f1b7e3bb01213040f5851a13254660f727ebdde097de3120e874b1d2b1cf48de72e6c3624cc65"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 23:18:28.788853) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 23:18:28.789172) ========= 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.ADiCvhMtPh/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.tRg9qssf2P/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.004900 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 23:18:28.842496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 23:18:28.844840) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 23:18:28.845190) ========= ========= Total master_lag: 0.23 =========