builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0147 starttime: 1489942235.71 results: success (0) buildid: 20170319081320 builduid: 46c85b4b111b4a5ab3a89a5ad7335448 revision: 928b6052cbcf3c1c29436313b61760dc35248d4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:35.723862) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:35.724422) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:35.724746) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:35.785495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-19 09:50:35.785880) ========= 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.WrJGKiCKzo/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.47lDue5lN6/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-19 09:50:35-- 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% 126M=0s 2017-03-19 09:50:35 (126 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128802 ========= master_lag: 1.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-19 09:50:36.942439) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:36.942772) ========= 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.WrJGKiCKzo/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.47lDue5lN6/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.028566 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:37.000686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-19 09:50:37.001121) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 928b6052cbcf3c1c29436313b61760dc35248d4a --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 928b6052cbcf3c1c29436313b61760dc35248d4a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WrJGKiCKzo/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.47lDue5lN6/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-19 09:50:37,112 truncating revision to first 12 chars 2017-03-19 09:50:37,112 Setting DEBUG logging. 2017-03-19 09:50:37,112 attempt 1/10 2017-03-19 09:50:37,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/928b6052cbcf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-19 09:50:38,847 unpacking tar archive at: mozilla-central-928b6052cbcf/testing/mozharness/ program finished with exit code 0 elapsedTime=2.025098 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-19 09:50:39.053711) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:39.054059) ========= script_repo_revision: 928b6052cbcf3c1c29436313b61760dc35248d4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:39.057655) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:39.057964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 09:50:39.077968) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-19 09:50:39.078366) ========= /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.WrJGKiCKzo/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.47lDue5lN6/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 09:50:39 INFO - MultiFileLogger online at 20170319 09:50:39 in /builds/slave/test 09:50:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:50:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:50:39 INFO - {'append_to_log': False, 09:50:39 INFO - 'base_work_dir': '/builds/slave/test', 09:50:39 INFO - 'blob_upload_branch': 'Firefox', 09:50:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:50:39 INFO - 'branch': 'Firefox', 09:50:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:50:39 INFO - 'config_files': ('talos/mac_config.py',), 09:50:39 INFO - 'default_actions': ('clobber', 09:50:39 INFO - 'read-buildbot-config', 09:50:39 INFO - 'download-and-extract', 09:50:39 INFO - 'populate-webroot', 09:50:39 INFO - 'create-virtualenv', 09:50:39 INFO - 'install', 09:50:39 INFO - 'run-tests'), 09:50:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:50:39 INFO - 'download_minidump_stackwalk': True, 09:50:39 INFO - 'download_symbols': 'ondemand', 09:50:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:50:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:50:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:50:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:50:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:50:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:50:39 INFO - 'gecko_profile': False, 09:50:39 INFO - 'gecko_profile_interval': 0, 09:50:39 INFO - 'installer_path': 'installer.exe', 09:50:39 INFO - 'log_level': 'info', 09:50:39 INFO - 'log_name': 'talos', 09:50:39 INFO - 'log_to_console': True, 09:50:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:50:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:50:39 INFO - 'opt_config_files': (), 09:50:39 INFO - 'pip_index': False, 09:50:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:50:39 INFO - 'cmd': ('bash', 09:50:39 INFO - '-c', 09:50:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:50:39 INFO - 'enabled': True, 09:50:39 INFO - 'halt_on_failure': False, 09:50:39 INFO - 'name': 'check_screen_resolution'},), 09:50:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:50:39 INFO - 'cmd': ('bash', 09:50:39 INFO - '-c', 09:50:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:50:39 INFO - 'enabled': True, 09:50:39 INFO - 'halt_on_failure': False, 09:50:39 INFO - 'name': 'check_screen_resolution'},), 09:50:39 INFO - 'run_cmd_checks_enabled': True, 09:50:39 INFO - 'suite': 'dromaeojs-e10s', 09:50:39 INFO - 'system_bits': '32', 09:50:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:50:39 INFO - 'title': 't-yosemite-r7-0147', 09:50:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:50:39 INFO - 'use_talos_json': True, 09:50:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:50:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:50:39 INFO - 'work_dir': 'build'} 09:50:39 INFO - [mozharness: 2017-03-19 16:50:39.288698Z] Running clobber step. 09:50:39 INFO - Running pre-action listener: _resource_record_pre_action 09:50:39 INFO - Running main action method: clobber 09:50:39 INFO - rmtree: /builds/slave/test/build 09:50:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:50:41 INFO - Running post-action listener: _resource_record_post_action 09:50:41 INFO - [mozharness: 2017-03-19 16:50:41.746500Z] Finished clobber step (success) 09:50:41 INFO - [mozharness: 2017-03-19 16:50:41.746627Z] Running read-buildbot-config step. 09:50:41 INFO - Running pre-action listener: _resource_record_pre_action 09:50:41 INFO - Running main action method: read_buildbot_config 09:50:41 INFO - Using buildbot properties: 09:50:41 INFO - { 09:50:41 INFO - "project": "", 09:50:41 INFO - "product": "firefox", 09:50:41 INFO - "script_repo_revision": "production", 09:50:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:50:41 INFO - "repository": "", 09:50:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:50:41 INFO - "buildid": "20170319081320", 09:50:41 INFO - "pgo_build": "False", 09:50:41 INFO - "basedir": "/builds/slave/test", 09:50:41 INFO - "buildnumber": 13, 09:50:41 INFO - "slavename": "t-yosemite-r7-0147", 09:50:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:50:41 INFO - "platform": "yosemite_r7", 09:50:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:50:41 INFO - "branch": "mozilla-central", 09:50:41 INFO - "repo_path": "mozilla-central", 09:50:41 INFO - "revision": "928b6052cbcf3c1c29436313b61760dc35248d4a", 09:50:41 INFO - "stage_platform": "macosx64", 09:50:41 INFO - "builduid": "46c85b4b111b4a5ab3a89a5ad7335448", 09:50:41 INFO - "slavebuilddir": "test" 09:50:41 INFO - } 09:50:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:50:41 INFO - Running post-action listener: _resource_record_post_action 09:50:41 INFO - [mozharness: 2017-03-19 16:50:41.750072Z] Finished read-buildbot-config step (success) 09:50:41 INFO - [mozharness: 2017-03-19 16:50:41.750187Z] Running download-and-extract step. 09:50:41 INFO - Running pre-action listener: _resource_record_pre_action 09:50:41 INFO - Running main action method: download_and_extract 09:50:41 INFO - mkdir: /builds/slave/test/build/tests 09:50: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')]} 09:50:41 INFO - trying https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:50:41 INFO - Downloading https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:50:42 INFO - Downloaded 1325 bytes. 09:50:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:50:42 INFO - Using the following test package requirements: 09:50:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:50:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:50:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:50:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:50:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'jsshell-mac.zip'], 09:50:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:50:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:50:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:50:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:50:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:50:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:50:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:50:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:50:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:50:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:50:42 INFO - Fetch https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:50:44 INFO - Expected file size: 52473590 09:50:44 INFO - Obtained file size: 52473590 09:51:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:51:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:51:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:51:00 INFO - Fetch https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:51:02 INFO - Expected file size: 52473590 09:51:02 INFO - Obtained file size: 52473590 09:51:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:51:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:51:18 INFO - Fetch https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:51:19 INFO - Expected file size: 14404171 09:51:19 INFO - Obtained file size: 14404171 09:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:19 INFO - trying https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:51:22 INFO - Downloaded 75163169 bytes. 09:51:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:22 INFO - mkdir: /builds/slave/test/properties 09:51:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:51:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:51:22 INFO - Contents: 09:51:22 INFO - build_url:https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:22 INFO - Running post-action listener: _resource_record_post_action 09:51:22 INFO - Running post-action listener: set_extra_try_arguments 09:51:22 INFO - [mozharness: 2017-03-19 16:51:22.778016Z] Finished download-and-extract step (success) 09:51:22 INFO - [mozharness: 2017-03-19 16:51:22.778157Z] Running populate-webroot step. 09:51:22 INFO - Running pre-action listener: _resource_record_pre_action 09:51:22 INFO - Running main action method: populate_webroot 09:51:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:51:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:51:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:51:22 INFO - '--tppagecycles', 09:51:22 INFO - '7'], 09:51:22 INFO - 'tests': ['tsvgm']}}, 09:51:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:51:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:51:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:51:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:51:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['damp', 'tps']}, 09:51:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'tests': ['damp', 'tps']}, 09:51:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['dromaeo_dom']}, 09:51:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:51:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['basic_compositor_video']}, 09:51:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:51:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'other-e10s': {'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['a11yr', 09:51:22 INFO - 'ts_paint', 09:51:22 INFO - 'tpaint', 09:51:22 INFO - 'sessionrestore', 09:51:22 INFO - 'sessionrestore_no_auto_restore', 09:51:22 INFO - 'tabpaint']}, 09:51:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['tsvgx', 09:51:22 INFO - 'tsvgr_opacity', 09:51:22 INFO - 'tart', 09:51:22 INFO - 'tscrollx', 09:51:22 INFO - 'cart', 09:51:22 INFO - 'tsvg_static']}, 09:51:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:51:22 INFO - 'tsvgr_opacity', 09:51:22 INFO - 'tart', 09:51:22 INFO - 'tscrollx', 09:51:22 INFO - 'cart', 09:51:22 INFO - 'tsvg_static']}, 09:51:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'talos_options': ['--disable-e10s'], 09:51:22 INFO - 'tests': ['tp5o']}, 09:51:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:51:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'talos_options': ['--disable-e10s', 09:51:22 INFO - '--xperf_path', 09:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:22 INFO - 'tests': ['tp5n']}, 09:51:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:51:22 INFO - 'talos_options': ['--xperf_path', 09:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:22 INFO - 'tests': ['tp5n']}}, 09:51:22 INFO - 'talos.zip': {'path': '', 09:51:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:51:22 INFO - Running post-action listener: _resource_record_post_action 09:51:22 INFO - [mozharness: 2017-03-19 16:51:22.796669Z] Finished populate-webroot step (success) 09:51:22 INFO - [mozharness: 2017-03-19 16:51:22.796802Z] Running create-virtualenv step. 09:51:22 INFO - Running pre-action listener: _resource_record_pre_action 09:51:22 INFO - Running main action method: create_virtualenv 09:51:22 INFO - Creating virtualenv /builds/slave/test/build/venv 09:51:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:51:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:51:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:51:22 INFO - Using real prefix '/tools/python27' 09:51:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:51:23 INFO - Installing distribute.............................................................................................................................................................................................done. 09:51:26 INFO - Installing pip.................done. 09:51:26 INFO - Return code: 0 09:51:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:51:26 INFO - Reading from file tmpfile_stdout 09:51:26 INFO - Output received: 09:51:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:51:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:27 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:51:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:27 INFO - 'HOME': '/Users/cltbld', 09:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:27 INFO - 'LOGNAME': 'cltbld', 09:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:27 INFO - 'MOZ_NO_REMOTE': '1', 09:51:27 INFO - 'NO_EM_RESTART': '1', 09:51:27 INFO - 'PAGER': '/bin/cat', 09:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:27 INFO - 'PWD': '/builds/slave/test', 09:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:27 INFO - 'SHELL': '/bin/bash', 09:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:27 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:27 INFO - 'USER': 'cltbld', 09:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:27 INFO - 'XPC_FLAGS': '0x0', 09:51:27 INFO - 'XPC_SERVICE_NAME': '0', 09:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:51:27 INFO - Cleaning up... 09:51:27 INFO - Return code: 0 09:51:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:51:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:28 INFO - 'HOME': '/Users/cltbld', 09:51:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:28 INFO - 'LOGNAME': 'cltbld', 09:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:28 INFO - 'MOZ_NO_REMOTE': '1', 09:51:28 INFO - 'NO_EM_RESTART': '1', 09:51:28 INFO - 'PAGER': '/bin/cat', 09:51:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:28 INFO - 'PWD': '/builds/slave/test', 09:51:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:28 INFO - 'SHELL': '/bin/bash', 09:51:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:28 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:28 INFO - 'USER': 'cltbld', 09:51:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:28 INFO - 'XPC_FLAGS': '0x0', 09:51:28 INFO - 'XPC_SERVICE_NAME': '0', 09:51:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:28 INFO - Downloading/unpacking psutil>=3.1.1 09:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:51:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:32 INFO - Installing collected packages: psutil 09:51:32 INFO - Running setup.py install for psutil 09:51:32 INFO - building 'psutil._psutil_osx' extension 09:51:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:51:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:51:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:51:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:51:33 INFO - building 'psutil._psutil_posix' extension 09:51:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:51:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:51:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:51:33 INFO - ^ 09:51:33 INFO - 1 warning generated. 09:51:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:51:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:33 INFO - Successfully installed psutil 09:51:33 INFO - Cleaning up... 09:51:33 INFO - Return code: 0 09:51:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:33 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:51:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:33 INFO - 'HOME': '/Users/cltbld', 09:51:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:33 INFO - 'LOGNAME': 'cltbld', 09:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:33 INFO - 'MOZ_NO_REMOTE': '1', 09:51:33 INFO - 'NO_EM_RESTART': '1', 09:51:33 INFO - 'PAGER': '/bin/cat', 09:51:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:33 INFO - 'PWD': '/builds/slave/test', 09:51:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:33 INFO - 'SHELL': '/bin/bash', 09:51:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:33 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:33 INFO - 'USER': 'cltbld', 09:51:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:33 INFO - 'XPC_FLAGS': '0x0', 09:51:33 INFO - 'XPC_SERVICE_NAME': '0', 09:51:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:51:36 INFO - Installing collected packages: mozsystemmonitor 09:51:36 INFO - Running setup.py install for mozsystemmonitor 09:51:36 INFO - Successfully installed mozsystemmonitor 09:51:36 INFO - Cleaning up... 09:51:36 INFO - Return code: 0 09:51:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:36 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:51:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:36 INFO - 'HOME': '/Users/cltbld', 09:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:36 INFO - 'LOGNAME': 'cltbld', 09:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:36 INFO - 'MOZ_NO_REMOTE': '1', 09:51:36 INFO - 'NO_EM_RESTART': '1', 09:51:36 INFO - 'PAGER': '/bin/cat', 09:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:36 INFO - 'PWD': '/builds/slave/test', 09:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:36 INFO - 'SHELL': '/bin/bash', 09:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:36 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:36 INFO - 'USER': 'cltbld', 09:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:36 INFO - 'XPC_FLAGS': '0x0', 09:51:36 INFO - 'XPC_SERVICE_NAME': '0', 09:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:51:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:51:40 INFO - warning: no files found matching '*.txt' 09:51:40 INFO - no previously-included directories found matching 'build' 09:51:40 INFO - no previously-included directories found matching 'dist' 09:51:40 INFO - no previously-included directories found matching '.git*' 09:51:40 INFO - Installing collected packages: jsonschema, functools32 09:51:40 INFO - Running setup.py install for functools32 09:51:40 INFO - warning: no files found matching '*.txt' 09:51:40 INFO - no previously-included directories found matching 'build' 09:51:40 INFO - no previously-included directories found matching 'dist' 09:51:40 INFO - no previously-included directories found matching '.git*' 09:51:40 INFO - Successfully installed jsonschema functools32 09:51:40 INFO - Cleaning up... 09:51:40 INFO - Return code: 0 09:51:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:40 INFO - 'HOME': '/Users/cltbld', 09:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:40 INFO - 'LOGNAME': 'cltbld', 09:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:40 INFO - 'MOZ_NO_REMOTE': '1', 09:51:40 INFO - 'NO_EM_RESTART': '1', 09:51:40 INFO - 'PAGER': '/bin/cat', 09:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:40 INFO - 'PWD': '/builds/slave/test', 09:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:40 INFO - 'SHELL': '/bin/bash', 09:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:40 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:40 INFO - 'USER': 'cltbld', 09:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:40 INFO - 'XPC_FLAGS': '0x0', 09:51:40 INFO - 'XPC_SERVICE_NAME': '0', 09:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:51:40 INFO - Cleaning up... 09:51:40 INFO - Return code: 0 09:51:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:40 INFO - 'HOME': '/Users/cltbld', 09:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:40 INFO - 'LOGNAME': 'cltbld', 09:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:40 INFO - 'MOZ_NO_REMOTE': '1', 09:51:40 INFO - 'NO_EM_RESTART': '1', 09:51:40 INFO - 'PAGER': '/bin/cat', 09:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:40 INFO - 'PWD': '/builds/slave/test', 09:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:40 INFO - 'SHELL': '/bin/bash', 09:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:40 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:40 INFO - 'USER': 'cltbld', 09:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:40 INFO - 'XPC_FLAGS': '0x0', 09:51:40 INFO - 'XPC_SERVICE_NAME': '0', 09:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:51:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:51:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:51:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:44 INFO - Downloading docopt-0.6.1.tar.gz 09:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:51:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:51:44 INFO - Running setup.py install for blobuploader 09:51:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:51:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:51:45 INFO - Running setup.py install for requests 09:51:45 INFO - Running setup.py install for docopt 09:51:45 INFO - Successfully installed blobuploader requests docopt 09:51:45 INFO - Cleaning up... 09:51:45 INFO - Return code: 0 09:51:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:45 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:51:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:45 INFO - 'HOME': '/Users/cltbld', 09:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:45 INFO - 'LOGNAME': 'cltbld', 09:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:45 INFO - 'MOZ_NO_REMOTE': '1', 09:51:45 INFO - 'NO_EM_RESTART': '1', 09:51:45 INFO - 'PAGER': '/bin/cat', 09:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:45 INFO - 'PWD': '/builds/slave/test', 09:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:45 INFO - 'SHELL': '/bin/bash', 09:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:45 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:45 INFO - 'USER': 'cltbld', 09:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:45 INFO - 'XPC_FLAGS': '0x0', 09:51:45 INFO - 'XPC_SERVICE_NAME': '0', 09:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4RbGgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XISu22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-gp05Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-c_sqWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-44reyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-EgX8Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-z4_Cxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-frnwrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NkSPgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-L8J6Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-FJvhxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-v6L91C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UqsoLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-L9bbav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Jc7LUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ydXAeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:47 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fCrcbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:51:47 INFO - Running setup.py install for manifestparser 09:51:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:51:48 INFO - Running setup.py install for mozcrash 09:51:48 INFO - Running setup.py install for mozdebug 09:51:48 INFO - Running setup.py install for mozdevice 09:51:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:51:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:51:48 INFO - Running setup.py install for mozfile 09:51:48 INFO - Running setup.py install for mozhttpd 09:51:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:51:48 INFO - Running setup.py install for mozinfo 09:51:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:51:48 INFO - Running setup.py install for mozInstall 09:51:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Running setup.py install for mozleak 09:51:49 INFO - Running setup.py install for mozlog 09:51:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Running setup.py install for moznetwork 09:51:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Running setup.py install for mozprocess 09:51:49 INFO - Running setup.py install for mozprofile 09:51:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Running setup.py install for mozrunner 09:51:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:51:49 INFO - Running setup.py install for mozscreenshot 09:51:50 INFO - Running setup.py install for moztest 09:51:50 INFO - Running setup.py install for mozversion 09:51:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:51:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:51:50 INFO - Cleaning up... 09:51:50 INFO - Return code: 0 09:51:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51: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')]} 09:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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')]} 09:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:50 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:51:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:50 INFO - 'HOME': '/Users/cltbld', 09:51:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:50 INFO - 'LOGNAME': 'cltbld', 09:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:50 INFO - 'MOZ_NO_REMOTE': '1', 09:51:50 INFO - 'NO_EM_RESTART': '1', 09:51:50 INFO - 'PAGER': '/bin/cat', 09:51:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:50 INFO - 'PWD': '/builds/slave/test', 09:51:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:50 INFO - 'SHELL': '/bin/bash', 09:51:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:50 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:50 INFO - 'USER': 'cltbld', 09:51:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:50 INFO - 'XPC_FLAGS': '0x0', 09:51:50 INFO - 'XPC_SERVICE_NAME': '0', 09:51:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-N9SJzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zDwmhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-RTMCN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4idBIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:51 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)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-k5PjQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HdGzkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-F87zJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-F10lrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2JTe5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-M0l8O4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-aMF7zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:51 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-un85X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:52 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-CcmRgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:52 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-dl1IzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:52 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WNsREN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:52 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-r0kEFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:52 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Vm9UgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:51:52 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)) 09:51:52 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)) 09:51:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:55 INFO - Downloading blessings-1.6.tar.gz 09:51:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:51:55 INFO - Installing collected packages: blessings 09:51:55 INFO - Running setup.py install for blessings 09:51:55 INFO - Successfully installed blessings 09:51:55 INFO - Cleaning up... 09:51:55 INFO - Return code: 0 09:51:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:51:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:55 INFO - Reading from file tmpfile_stdout 09:51:55 INFO - Current package versions: 09:51:55 INFO - blessings == 1.6 09:51:55 INFO - blobuploader == 1.2.4 09:51:55 INFO - docopt == 0.6.1 09:51:55 INFO - functools32 == 3.2.3-2 09:51:55 INFO - jsonschema == 2.5.1 09:51:55 INFO - manifestparser == 1.1 09:51:55 INFO - mozInstall == 1.12 09:51:55 INFO - mozcrash == 1.0 09:51:55 INFO - mozdebug == 0.1 09:51:55 INFO - mozdevice == 0.49 09:51:55 INFO - mozfile == 1.2 09:51:55 INFO - mozhttpd == 0.7 09:51:55 INFO - mozinfo == 0.9 09:51:55 INFO - mozleak == 0.1 09:51:55 INFO - mozlog == 3.4 09:51:55 INFO - moznetwork == 0.27 09:51:55 INFO - mozprocess == 0.25 09:51:55 INFO - mozprofile == 0.28 09:51:55 INFO - mozrunner == 6.13 09:51:55 INFO - mozscreenshot == 0.1 09:51:55 INFO - mozsystemmonitor == 0.3 09:51:55 INFO - moztest == 0.8 09:51:55 INFO - mozversion == 1.4 09:51:55 INFO - psutil == 3.1.1 09:51:55 INFO - requests == 1.2.3 09:51:55 INFO - wsgiref == 0.1.2 09:51:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:55 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 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:55 INFO - 'HOME': '/Users/cltbld', 09:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:55 INFO - 'LOGNAME': 'cltbld', 09:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:55 INFO - 'MOZ_NO_REMOTE': '1', 09:51:55 INFO - 'NO_EM_RESTART': '1', 09:51:55 INFO - 'PAGER': '/bin/cat', 09:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:55 INFO - 'PWD': '/builds/slave/test', 09:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:55 INFO - 'SHELL': '/bin/bash', 09:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:55 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:55 INFO - 'USER': 'cltbld', 09:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:55 INFO - 'XPC_FLAGS': '0x0', 09:51:55 INFO - 'XPC_SERVICE_NAME': '0', 09:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:51:56 INFO - Cleaning up... 09:51:56 INFO - Return code: 0 09:51:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:51: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')]} 09:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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')]} 09:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabe2ce0e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, '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.WrJGKiCKzo/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.47lDue5lN6/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 09:51: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', 'jsonschema'] in /builds/slave/test/build 09:51: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 jsonschema 09:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:56 INFO - 'HOME': '/Users/cltbld', 09:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:56 INFO - 'LOGNAME': 'cltbld', 09:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:56 INFO - 'MOZ_NO_REMOTE': '1', 09:51:56 INFO - 'NO_EM_RESTART': '1', 09:51:56 INFO - 'PAGER': '/bin/cat', 09:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:56 INFO - 'PWD': '/builds/slave/test', 09:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:56 INFO - 'SHELL': '/bin/bash', 09:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:51:56 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:56 INFO - 'USER': 'cltbld', 09:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:56 INFO - 'XPC_FLAGS': '0x0', 09:51:56 INFO - 'XPC_SERVICE_NAME': '0', 09:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:51:56 INFO - Cleaning up... 09:51:56 INFO - Return code: 0 09:51:56 INFO - Running post-action listener: _resource_record_post_action 09:51:56 INFO - Running post-action listener: _start_resource_monitoring 09:51:56 INFO - Starting resource monitoring. 09:51:56 INFO - [mozharness: 2017-03-19 16:51:56.314252Z] Finished create-virtualenv step (success) 09:51:56 INFO - [mozharness: 2017-03-19 16:51:56.315215Z] Running install step. 09:51:56 INFO - Running pre-action listener: _resource_record_pre_action 09:51:56 INFO - Running main action method: install 09:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:56 INFO - Reading from file tmpfile_stdout 09:51:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:51:56 INFO - Reading from file tmpfile_stdout 09:51:56 INFO - Output received: 09:51:56 INFO - Usage: mozinstall [options] installer 09:51:56 INFO - Options: 09:51:56 INFO - -h, --help show this help message and exit 09:51:56 INFO - -d DEST, --destination=DEST 09:51:56 INFO - Directory to install application into. [default: 09:51:56 INFO - "/builds/slave/test"] 09:51:56 INFO - --app=APP Application being installed. [default: firefox] 09:51:56 INFO - mkdir: /builds/slave/test/build/application 09:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:52:16 INFO - Reading from file tmpfile_stdout 09:52:16 INFO - Output received: 09:52:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:52:16 INFO - Running post-action listener: _resource_record_post_action 09:52:16 INFO - [mozharness: 2017-03-19 16:52:16.722558Z] Finished install step (success) 09:52:16 INFO - [mozharness: 2017-03-19 16:52:16.722699Z] Running run-tests step. 09:52:16 INFO - Running pre-action listener: _resource_record_pre_action 09:52:16 INFO - Running main action method: run_tests 09:52:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:16 INFO - 2017-03-19 09:52:16.736 screenresolution[1630:7909] starting screenresolution argv=screenresolution get 09:52:16 INFO - 2017-03-19 09:52:16.739 screenresolution[1630:7909] Display 0: 1600x1200x32@60 09:52:16 INFO - 2017-03-19 09:52:16.745 screenresolution[1631:7912] starting screenresolution argv=screenresolution list 09:52:16 INFO - Available Modes on Display 0 09:52:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:16 INFO - Intel Iris: 09:52:16 INFO - Chipset Model: Intel Iris 09:52:16 INFO - Type: GPU 09:52:16 INFO - Bus: Built-In 09:52:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:16 INFO - Vendor: Intel (0x8086) 09:52:16 INFO - Device ID: 0x0a2e 09:52:16 INFO - Revision ID: 0x0009 09:52:16 INFO - Displays: 09:52:16 INFO - Display: 09:52:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:16 INFO - Display Serial Number: 1600x1200 60 09:52:16 INFO - Main Display: Yes 09:52:16 INFO - Mirror: Off 09:52:16 INFO - Online: Yes 09:52:16 INFO - Rotation: Supported 09:52:16 INFO - Return code: 0 09:52:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:52:16 INFO - Python 2.7.3 09:52:16 INFO - Return code: 0 09:52:16 INFO - grabbing minidump binary from tooltool 09:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:16 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 0x1048e5d30>, '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 0x10480cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:52:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:52:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:52:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:52:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:52:17 INFO - Return code: 0 09:52:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:52:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:52:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:52:17 INFO - ENV: RUST_BACKTRACE is now 1 09:52:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:52:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:52:17 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/SPUJAxLOQzWBMjL_-ds79w/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 09:52:17 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/SPUJAxLOQzWBMjL_-ds79w/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 09:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WrJGKiCKzo/Render', 09:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:17 INFO - 'HOME': '/Users/cltbld', 09:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:17 INFO - 'LOGNAME': 'cltbld', 09:52:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:52:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:17 INFO - 'MOZ_NO_REMOTE': '1', 09:52:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:52:17 INFO - 'NO_EM_RESTART': '1', 09:52:17 INFO - 'PAGER': '/bin/cat', 09:52:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:17 INFO - 'PWD': '/builds/slave/test', 09:52:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:17 INFO - 'RUST_BACKTRACE': '1', 09:52:17 INFO - 'SHELL': '/bin/bash', 09:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47lDue5lN6/Listeners', 09:52:17 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 09:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:17 INFO - 'USER': 'cltbld', 09:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:17 INFO - 'XPC_FLAGS': '0x0', 09:52:17 INFO - 'XPC_SERVICE_NAME': '0', 09:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:17 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/SPUJAxLOQzWBMjL_-ds79w/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 09:52:17 INFO - mozversion application_buildid: 20170319081320 09:52:17 INFO - mozversion application_changeset: 928b6052cbcf3c1c29436313b61760dc35248d4a 09:52:17 INFO - mozversion application_display_name: Nightly 09:52:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:52:17 INFO - mozversion application_name: Firefox 09:52:17 INFO - mozversion application_remotingname: firefox 09:52:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:52:17 INFO - mozversion application_vendor: Mozilla 09:52:17 INFO - mozversion application_version: 55.0a1 09:52:17 INFO - mozversion platform_buildid: 20170319081320 09:52:17 INFO - mozversion platform_changeset: 928b6052cbcf3c1c29436313b61760dc35248d4a 09:52:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:52:17 INFO - mozversion platform_version: 55.0a1 09:52:17 INFO - using testdate: 1489942337 09:52:17 INFO - actual date: 1489942337 09:52:17 INFO - starting webserver on 'localhost:49240' 09:52:17 INFO - SUITE-START | Running 2 tests 09:52:17 INFO - TEST-START | dromaeo_css 09:52:17 INFO - operating with platform_type : mac_ 09:52:17 INFO - Initialising browser for dromaeo_css test... 09:52:17 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp0pgMQ3/profile http://localhost:49240/getInfo.html) 09:52:20 INFO - PID 1641 | 2017-03-19 09:52:20.455 plugin-container[1643:8097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 09:52:20 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:20 INFO - PID 1641 | 2017-03-19 09:52:20.458 plugin-container[1643:8097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 09:52:20 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:20 INFO - PID 1641 | 2017-03-19 09:52:20.459 plugin-container[1643:8097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:20 INFO - PID 1641 | __metrics Screen width/height:1600/1200 09:52:20 INFO - PID 1641 | colorDepth:24 09:52:20 INFO - PID 1641 | Browser inner width/height: 1024/689 09:52:20 INFO - PID 1641 | __metrics 09:52:20 ERROR - PID 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:52:21 INFO - TEST-INFO | 1641: exit 0 09:52:21 INFO - Browser initialized. 09:52:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:52:21 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp0pgMQ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:24 INFO - PID 1644 | 2017-03-19 09:52:24.277 plugin-container[1645:8222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 09:52:24 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:24 INFO - PID 1644 | 2017-03-19 09:52:24.278 plugin-container[1645:8222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.CFPasteboardClient' 09:52:24 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:24 INFO - PID 1644 | 2017-03-19 09:52:24.278 plugin-container[1645:8222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:33 INFO - PID 1644 | RSS: Main: 227479552 09:52:33 INFO - PID 1644 | 09:55:36 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:55:38 INFO - PID 1644 | RSS: Main: 252592128 09:55:38 INFO - PID 1644 | 09:57:21 INFO - PID 1644 | ************************* 09:57:21 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 09:57:21 INFO - PID 1644 | 09:57:21 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:57:21 INFO - PID 1644 | Full stack: 09:57:21 INFO - PID 1644 | ************************* 09:58:40 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:58:41 INFO - PID 1644 | RSS: Main: 259874816 09:58:41 INFO - PID 1644 | 10:01:43 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 10:01:44 INFO - PID 1644 | RSS: Main: 272457728 10:01:44 INFO - PID 1644 | 10:04:45 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 10:04:46 INFO - PID 1644 | RSS: Main: 264769536 10:04:46 INFO - PID 1644 | 10:07:49 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:07:50 INFO - PID 1644 | RSS: Main: 274305024 10:07:50 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:10:53 INFO - PID 1644 | RSS: Main: 268169216 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | __start_tp_report 10:10:53 INFO - PID 1644 | _x_x_mozilla_page_load 10:10:53 INFO - PID 1644 | _x_x_mozilla_page_load_details 10:10:53 INFO - PID 1644 | |i|pagename|runs| 10:10:53 INFO - PID 1644 | |0;dojo.html;3333.4666453367468;3434.8626054957804;3462.5498685170924;3483.7038851697603;3499.2476617527227;5836.15375770513;5928.52571794256;5949;5960.254968128984;5998.980173370526;5853.707314634262;5897.882042359151;5902.498287645555;5991.580589358747;6125.356837532062;5298.602604804636;5377.65045272058;5443.972780136094;5459.672419654814;5479.3424789025175;5295.126304159809;5374.596905232103;5460.017196904556;5464.972675136639;5468.28912241408;4500.909981800346;5426.050441172819;5437.265969094181;5445.319335083114;5450.482204190595;4190.28765109932;4492.438445194351;4568.748718820463;5261.421243663194;5313.3092697949405;5341.759620817073;5363.168708850135;5371.543418809406;5382.9730851345885;5405;4713.528647135293;5222.616006758212;5855.531557475391;5978.163057172;6052.939470605282;5285.524302812765;6266.561340706153;6319.336469670664;6328.303886572473;6357.268914074894;6350.714217860207;6408.295087540367;6415.486761059104;6417.326180751001;6457.2574153971955;6125.173101631267;6155.784547540815;6158.384161583851;6166.599171053867;6173.598716083485;6279.560430769802;6435.099086127946;6494.350564943562;6540.771073012517;6563.835904102436;5293.285406470154;6193.566450348522;6232.0963460298;6253.593516421418;6297.4017468340435;6321.683915804191;6349.269833969152;6384.3934826191435;6393.872122557523;6410.358964103552;5163.845084647466;5288.735563221823;6125.050617154348;6318.368163183737;6322.209723784527;6260.029695397221;6322.557420980579;6345.016522439029;6346.365363463617;6353.5234857385885;4472.8210871565425;5051.090803655377;5072.771725272371;5138.12651849179;5180.274761533388;4566.45202575693;4592.2422800238255;4602.953970460319;4603.194440972883;4620.075984803053;6057.57596968208;6072.635641861502;6096.817095487142;6108.725107370179;6123.908141377883;5459.918101228484;6273.466755325757;6289.276733175652;6329.303776584572;6377.394147555975;6042.637441753509;6080.543959202989;6109.389061093855;6110.847228819315;6133.754649813957;5653.265075540236;5657.717114144359;5669.43305669438;5673.149027645886;5675.80134695292;6351.491880649444;6414.294427612866;6473.449756770728;6597.406233438917;6641;6362.172917520693;6373.490120790419;6388.93611063902;6395.232572091193;6405.487560995016;6572.4084832365315;6583.506237032146;6594.208694956636;6599.274079851117;6603.339666033358;6115.149072927005;6299.401556852092;6314.684265786784;6315.873682526416;6344.397282258178;5953.166556682155;6330.715117819617;6377.553571249968;6378.521610879296;6387.584806987531;6267.561270711189;6310.5898116622275;6314.463270622048;6314.8737025258315;6315.052742088724;6145.3407579954055;6146.446819786241;6252.656103914328;6288.088227207121;6307.779227727007;5241.711705856214;5704.914426283526;6192;6207.906881396873;6263.311035786151;5232.3982741985155;6217.409346112112;6318.652474113968;6320.715567799368;6383.329750376143;6112.138751317827;6130.969345153246;6147.477464415396;6201.410865967726;6234.906476402948 10:10:53 INFO - PID 1644 | |1;ext.html;706.4066184405099;718.6155406857331;720.5784615999643;800.4716886854677;836.891204143462;36193.457098143546;37369.439458408146;37403;37432;37673.81163094184;38467.61532384675;38792.61207387925;38876.41685374715;38881;38881.83354499363;9072.455652660845;9290.58192381341;9301.906980930205;10653.627123050695;11028.393438360887;8420.368472364567;10190.541425635827;10956.397398143099;11127.833082503768;11132;11070.225084244106;11116.777664446707;11152;11159.776804463905;11235.438228088562;11086.279391839586;11095.667129986114;11153.275037122561;11155.944220278929;11201.943990280079;9361.391509551857;10904.836427453594;10978;11003.889961100367;11011.449427528672;37314;37636.43545346822;37702.245955081016;38842.417363739565;38900.61099388998;3410;3431.656834316573;3919.6004869686262;3936.3701807710627;4084.7957602119777;27910.302242443897;27966.063875679993;27968.860155699294;28102.297442563897;28915;3119.797213181136;3136.106209730239;3160.352127813793;3163.825989570573;3279.7868138571157;234003;267956.22372098395;298818;299064;301321;50542.494575054516;50651;50744.492555074714;50783.49216507861;50806.491935080914;53139.734301329016;53182.20226696603;53330;53373.73313133487;53428;13714.24571648549;14066.944979126401;14102.71794564103;14113.223772692396;14142.85857141436;11152.442377881074;11155.888441115485;11193.10455163585;11201.935816097457;11253.887461125447;13362;14010.43958241659;14033.78949315761;14044.157350558999;14132;5109.489051094935;6065.059915713072;6142.385761423911;6143.170671959318;6153.323134455206;1127.7388835385163;1131.5247596009697;1137.6132115080911;1161.8838116188483;1192.3203773848995;19779.703304450446;25732.09937652173;26475;26594.6010809838;28570.571441428394;1053.9051485366201;1058.5501162006117;1080.978380432387;1092.3991804507489;1094.4199574225513;11807.763844723057;14245.002849800414;15025.248737563079;15130.470433534774;15241.63754618036;384789;457641;460658;461442;463379;41002.38496422556;56416.15375769367;56790.716046419504;56901.71549142228;56986.43013569894;1237.8824011718873;1397.7553928062548;1413.152108734751;1417.978730319046;1520.0575643101347;28021.579676304464;28574;28939;29250.561241580966;29742.553861692526;33868;41323.380149298384;41348.58651413448;41415.55045573391;41600.79199603983;420105;420798;421146.89426552673;421413;423786.76213237474;33594;34224;34254.31491370109;34302.313953721285;34544.136396590286;43506;43806.780966094964;44499.77750111229;45142.09715805731;45167.77416112898;34824.12939676528;35726;35743.46384804178;36890.44664329984;37046.62953370432;35571.822140890166;35717;36722;36893.07767305839;36937.63062369342;35689.64310356863;35834.74578389744;36692.581341434125;37093.88718338454;37284.6271537292;23260.069597215923;24870.50258994846;30086.69913300839;31749.5237571432;31767.841160794047;21284.574308514053;23018.309450716504;23818.047278108683;24193.911273992362;24256.878715606308 10:10:53 INFO - PID 1644 | |2;jquery.html;389.86354775828454;401.54224184429773;403.97374170678904;421.6057985783292;432.7879339123828;16153.676926461463;20407.897960510196;21164.35445569304;21212.57574848504;22116;18672.81327186728;18751.3437029704;18764.90617546914;18842.528936776587;20281.69577456339;6599.999999999988;6982.965085174567;7047.788566343018;7055.188653304858;7104.609246491441;4270.97864510677;6525.249596296416;6888.250717942315;6931.445484361264;6949.444044476455;4513.390692256553;7675.232476752336;7977.720779772709;8019.1980801919635;8054.758357249262;7679.6928122875015;7860.331871790904;7956.880646790303;7969.960150199212;8002.519848809061;6665.566738162004;6736.292689267605;7697.961510192413;7745.457817952745;7776;26245.73754262452;26954.730452695614;27201.591976120373;27334.863325683444;27380.863095684592;2378.3459548624096;2649.3906401527756;2765.4192619549917;2872.956905646417;2893.132060381877;14439.855601444062;15223.467178648694;15482.845171548366;16363.754543681855;16644.916775416168;2077.480629842539;2123.7982391672767;2134.125008746422;2145.9463513412134;2227.098025299756;94760.10479790435;135449.3227533856;140356.29821850825;140514;140687;22326.646303970894;23821.285361439186;23880.522389552458;23891.641625375632;24185.879070604533;36195.819020904724;36266.8186659065;36447.81776091103;36501.26997460036;36549.634503654626;9794.853077203848;9964.651237206663;10065.647702330383;10217.33587316822;10323.793524129474;7970.442069055111;8001.8799718004275;8009.038915330081;8148.918510814935;8152.510849349058;10085.747856303651;10513.738351397882;11074.557017719362;11190.04884584803;11351.262167959056;4188.28799104153;4461.196984542748;4515.345274935116;4654.394928659252;4662.673612847136;853.8036251662153;862.6376921692904;866.3112825303898;881.709006240466;896.7982204004047;17463.56341091483;18636.129083475545;20378.286759963332;21092.894535527223;23924.282271531883;869.1482347299556;884.8849649545518;891.5586784541564;903.3856977255398;937.7843096087938;9925.553350099262;12000.339981301113;12092.27446353204;12118.515259389702;12317.938410307892;624747;687391;708958;712777;728194;115286;126252;127405;149745;152358;1104.3318792130806;1299.4477347127433;1300.8959283257316;1330.6806366471976;1333.8532761396239;16696.582585434975;19882.801171988096;20190.09144588468;20270.986450677112;20850.791492085493;58082.4191758077;58268;59468;61285.693571531854;62686;84680;85033.149668505;85489.57255213684;85820.57089714511;87190.69213961669;10944.671659850217;11539.88460115388;11899.345535995604;11970;12290.20113692607;9756.951215244162;12823.294718790183;12916.870831291566;13162.934185329012;13241.867581324064;10639.946800265949;11017.889821101686;11263.66209013698;11406;11526.654200374001;10770.030697237285;11171.776564468828;11173.497192626513;11348.092152627605;11495.425228738697;11493.597724079616;11542.59600913964;11706.70733231676;11781.469833857669;11897;10789.40658263803;11738.237014594024;11844.703882402664;11931.582394616147;12094;9778.55996480146;11177.273477223955;11232.887671123512;11254.549818007516;11265.549378024787 10:10:53 INFO - PID 1644 | |3;mootools.html;1358.4905660377358;1369.3974651153492;1505.849415058495;1516.7042426726794;1527.1905889878362;8992.640294388233;10429;10860.294080884747;10861.07680847128;10967.725806854833;10696.51865666045;10877.728056798584;10914.126869850414;10951.945240273788;11018.39398833064;2034.901153377182;2211.358705975273;2359.976400235997;2401.8318717689767;2403.2790162951133;1952.5509132899442;2010.8491863110253;2164.2425151197035;2347.2371479269223;2384.6303822907475;2188.5841690078832;2376.025829409942;2395.281415575329;2398.8680622565753;2399.1243196233336;1958.9706154407695;2353;2353.2351985604746;2361.3388251289666;2374.204641445118;2322.547103314854;2363.491849252419;2363.7399886012527;2366.751491093428;2372.5966585680476;11037.724056898562;11077.169212309029;11119.72200694989;11131.72170695731;11175.608853690163;5636.520895723868;5836.270466191726;5842.4741773240185;5846.298444186682;5865.472107510344;3159.873605055795;3386.9661303386897;3398.9633161885613;3667.9083022924374;3672.908177295589;1103.6137351926761;1237.9690507737378;1791.4625612316254;1810.0859066171631;1898.228586264056;14000.579982600539;14194.580541945827;14326;14622.26888655563;14832;4359.80380882861;4737.573618374363;4761.2382018876815;4810.206315957827;5001.974990125026;10070.949645251874;12262.754744905053;12592.4333404997;12626.179298345578;12634.2419454833;5857.853553661193;5923.378045305267;5928.614640048383;5938.61399009063;5941.58408911372;4979.004199160183;5047.141985862412;5081.441041485434;5111.667741596784;5211.530962213419;3268.7385009199206;3387.2209391840015;3942.6254505821903;4037.555868854424;4085.55058943516;1109.1237922041496;1141.5947338694643;1153.9423028848523;1165.0854079547514;1166.877477864812;899.7628261140932;913.881195444588;918.4764684130115;947.7677968897664;952.5427794658602;5702.800401985994;5972.790952316648;6340.175777148941;6536.182977127859;6601.73593056282;939.33307351781;969.2875736333789;993.8719553306904;1031.7979553819866;1045.0176833776227;3595;3894.9094253609037;4051.9870032491876;4173.373993900878;4192.769397683096;12913.548025819051;13289.867101328862;13395.26326052057;13403.798943015863;13418.194908305533;13261.138026028277;13292.268925209206;13329.133606315558;13332;13336.933045356349;7785.221477852283;8177.119262569523;8856.291496680251;9943.552540135855;9943.900560994442;5568.331800184003;5838.824835254949;6517.185351831021;6584;6731.899021514583;11774.3524106175;11838.348890810743;12028.699282518008;12047.156699030984;12137.575184868487;8489.660413583388;11858.762824743284;12422.192557483735;12434.875651243734;12440.751184976069;8778.91221087781;8948.821023579361;8969.865452018355;9053.864192037256;9075.13786190311;9987.300888937705;10040.297179197387;10069.345492542961;10174.745631359276;10238.18094552449;8977.640894364153;8989.41568798026;8998.505082220541;9197.724068277963;9198.586063627286;8729.388942773945;8765.737027889174;8848.557572121497;8895.866562001445;8905.910940890768;6275.3724627536885;7597.6581053851605;7688.807779805549;7772.844543108993;7777.261160189773;5748.223989761329;5777.826665200051;5821.738021789114;5906.557008224315;5953.10703394477;5976.581639285208;5994.400559944145;6010.849728756816;6027.427394397525;6030.28637113292 10:10:53 INFO - PID 1644 | |4;prototype.html;390.6132928400884;390.8983664247295;391.9333713268746;392.26646171659;394.205675563739;5099.949000509994;5144.4598317176715;6028.879422411549;6115.847103822407;6184.783532576361;5325.680459172443;5346.465353464661;5676.886462270752;5958.672273024982;6000.159977603139;4927.778249978742;4979.50204979501;4995.250712393152;4997.075541024904;5016.122178618745;3804.154984832354;4158.209940111385;4327.848525301615;4989.727619457033;5056.696598204101;3991.186213719321;4989.925151122736;5004.923941352608;5005.699658020512;5025.597952163818;4971.179755340365;4971.37857767779;4974.651774375795;4976.2286845539;4978.90042199154;4565.954340456587;4568.5431456854385;4574.4510658721165;4576.336431217488;4577.519360467137;4847.5394837490385;5700.686462244575;5976.731047102848;5977.252843394575;5983.700814959278;4645.4437763349315;4886.75566221691;5208.244804503363;5760.9054279686725;5867.794627188071;6170.784022559234;6545.9018114728315;6556.573822701509;6567.047778072153;6584.209894812605;4032.6169013943627;4478.992226748988;4799.856004319876;5045.545900868903;5123.615728820353;5597.684544132167;6026.397360263983;6550.312217217219;6847;6971.860562788766;4195.328747400401;4776.9761151194025;5157.432682404932;5343.919841202385;5345.625806193529;5409.945060713202;5439.238506609078;5442.591805614595;5464.508194262536;5485.122380419114;4808.35087263215;5354.41101478837;5376.763344430803;5700.828975130753;5768.596198266165;5665.858353541194;6270.059491076375;6514.869702605922;6542.901856472156;6554.016897465418;1571.9842801572067;1638.2136574444096;1825.3246298869503;1852.3887117251275;1869.9158537865828;1338.9330533473287;1344.475654494748;1353.911995720275;1365.9931700341633;1379.3654918737293;4580.473245576734;5464.797744496204;5575.52608028314;5805.216295800097;6168.1056246843955;6628.436582890412;6791.660416979132;6794.3545363190415;6810.353016463527;6836.863262734817;4815.783289751969;5648.887022259614;6184.783532576338;6222.937770622327;6243.219597550306;3043.345680678665;3544.255706301654;3557.48416479609;3584.086058055231;3587.964120358763;6621.550026490229;6680.766173183915;6767.154105736783;6982.254436390902;7043.894341584881;5962.731677074542;6290.370962903765;6381.489480841522;6962.895556566656;6986.056882320821;5974.372690867396;6742.393184613408;6964.094667693167;7064.646767661597;7065.4700897432895;5851;6473.449756770728;6659.500537459807;6844.555103918229;6860.279670634512;6174.506039516918;6325.746970121144;6566.24488183865;6748.628825414453;6758.459323254227;5783.8554036149435;5844.707764611838;6561.868762624817;7005.684744186626;7008.652101546039;4403.779811009501;5257.710825904459;5325.227841962971;5329.600279979095;5331.3868905076415;5714.657120572779;6249.656433807891;6320.4311611955145;6760.3239676032;6765.42493888025;6499;6526.347365263436;6526.967365163144;6533.705983230671;6608.966955165193;5125.89793194465;6267.4045965633195;6534.901976470452;6558;6572.112764776694;6158.353372895782;6330.778422755181;6332.461740752087;6353.809385718436;6356.586821856565;5686.583202139157;6197.473214776614;6429.421352078335;6496.740130394731;6548.574342667902;5284.735763211747;5525.502704756591;6305.054241863757;6351.522176865391;6373.298937117006 10:10:53 INFO - PID 1644 | |5;yui.html;716.1084449859925;728.1226122522364;756.7578374920024;768.3814529303911;840.5839109640726;14959;15551.222438878067;15599.298031588578;15604.60988475286;15621.687566248682;15226.771598426005;15274.236288185573;15275.694486110271;15289.770653440179;15293.770593441108;896.0725648953319;942.6417961174744;949.3164921256684;950.9049095090505;951.0822056715268;914.1772404835668;945.9952700236523;948.7296120605629;959.4866746290736;961.956711947964;923.6813299411726;1007.531497853498;1015.0509273828957;1015.537930241742;1017.2218253036433;951.6621599332241;989.4063561862897;1000.6547741029333;1012.3824467075078;1013.3514550687565;749.7300971650202;751.8571471420413;756.6179079564785;756.7767508584981;758.3023618271204;16339.59151021234;16783.6643267134;16876.662466750597;16890.40883569069;17089.658206835793;14557.417703291856;14756.926215368854;14805.481808136663;14892.553223403314;14960.47638332664;11884.762304753856;12122;12173.20874143178;12205.328706921116;12348.320842353667;3694.907627309312;3745.9812700936322;3783.3946568549177;3815.4467602197524;3938.5864484229314;90349.54825225832;109926.45036774845;110013;112551.43724281408;115624;9540.856887146698;10002.549885255181;11369.147313951487;11935.940320298516;11956.701082472833;24656.137035203683;24906.626400604004;25099.121530746335;25140.24579262625;25201.747982520308;6973.12835895513;7877.133515313311;7966.123726390092;8019.03771547418;8166.918330816735;3966.9090999975238;4182.853600123981;4258.297380932174;4273.35899615054;4279.443672322578;3445.776024558396;3508.1755787389943;3516.5252690886914;3527.0476971217627;3533.311004354178;620.2990620598695;624.316373570939;627.7645882793952;638.651934695592;640.3052687833753;591.6716222496556;631.9115323854664;688.5558814564678;696;713.6931119618511;8880.911190888008;8950.283977281802;10043.648472303581;10292.639757608447;10392.480375981171;667.4560233409755;699.1121275979477;707.3845754193884;718.9496735228483;740.4224704730319;4676.602488788423;4789.880252993703;5031.371078615173;5092.694438333638;5096.1336572781975;99977;101070.49464752777;101558;101593;102115.48942255242;57988;87814.68277975835;89133.3260002201;89939;91725.54137229404;76803;77410;77490.45019099586;77765;78992.60503697445;7473.738419155304;8729.214370706666;9240.630374784932;9624.903750962401;9625.978544505731;49722;50025.24962125498;50122.74693132701;52139.21791173059;52245.216321754444;52721;54047;62017.069743952976;62825.68587157035;64044.67977660082;11764.764704705687;11778.705532361759;11904.226225295328;11979.401029948642;12019.399030048287;12687.492500299884;12901.483940642269;12951.805722913976;12990.350482476028;13576.524821631196;11732.354720490455;11893.06279406016;11897.107716921439;12052.397380131133;12181.939090304491;11849.881501184878;11872.406379681153;11881.881181188077;12104.213226140273;12269.69325766863;11700.239484433485;11815.232009919328;12016.33910134951;12220.388980551115;12239.75520489603;10152;10208.744781380228;10290.485475726333;10504.737381565523;10740.194485413782;8743.604546992934;8781.341399395198;9036.59335329925;9962.55168517404;10007.549660265451 10:10:53 INFO - PID 1644 | __end_tp_report 10:10:53 INFO - PID 1644 | __start_cc_report 10:10:53 INFO - PID 1644 | _x_x_mozilla_cycle_collect,424 10:10:53 INFO - PID 1644 | __end_cc_report 10:10:53 INFO - PID 1644 | __startTimestamp1489943453574__endTimestamp 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | ------- Summary: start ------- 10:10:53 INFO - PID 1644 | Number of tests: 6 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:5853.75 Median:6130.97 stddev:697.13 (11.4%) stddev-sans-first:670.65 10:10:53 INFO - PID 1644 | Values: 3333.5 3434.9 3462.5 3483.7 3499.2 5836.2 5928.5 5949.0 5960.3 5999.0 5853.7 5897.9 5902.5 5991.6 6125.4 5298.6 5377.7 5444.0 5459.7 5479.3 5295.1 5374.6 5460.0 5465.0 5468.3 4500.9 5426.1 5437.3 5445.3 5450.5 4190.3 4492.4 4568.7 5261.4 5313.3 5341.8 5363.2 5371.5 5383.0 5405.0 4713.5 5222.6 5855.5 5978.2 6052.9 5285.5 6266.6 6319.3 6328.3 6357.3 6350.7 6408.3 6415.5 6417.3 6457.3 6125.2 6155.8 6158.4 6166.6 6173.6 6279.6 6435.1 6494.4 6540.8 6563.8 5293.3 6193.6 6232.1 6253.6 6297.4 6321.7 6349.3 6384.4 6393.9 6410.4 5163.8 5288.7 6125.1 6318.4 6322.2 6260.0 6322.6 6345.0 6346.4 6353.5 4472.8 5051.1 5072.8 5138.1 5180.3 4566.5 4592.2 4603.0 4603.2 4620.1 6057.6 6072.6 6096.8 6108.7 6123.9 5459.9 6273.5 6289.3 6329.3 6377.4 6042.6 6080.5 6109.4 6110.8 6133.8 5653.3 5657.7 5669.4 5673.1 5675.8 6351.5 6414.3 6473.4 6597.4 6641.0 6362.2 6373.5 6388.9 6395.2 6405.5 6572.4 6583.5 6594.2 6599.3 6603.3 6115.1 6299.4 6314.7 6315.9 6344.4 5953.2 6330.7 6377.6 6378.5 6387.6 6267.6 6310.6 6314.5 6314.9 6315.1 6145.3 6146.4 6252.7 6288.1 6307.8 5241.7 5704.9 6192.0 6207.9 6263.3 5232.4 6217.4 6318.7 6320.7 6383.3 6112.1 6131.0 6147.5 6201.4 6234.9 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:52900.16 Median:33731.00 stddev:103288.67 (306.2%) stddev-sans-first:103504.08 10:10:53 INFO - PID 1644 | Values: 706.4 718.6 720.6 800.5 836.9 36193.5 37369.4 37403.0 37432.0 37673.8 38467.6 38792.6 38876.4 38881.0 38881.8 9072.5 9290.6 9301.9 10653.6 11028.4 8420.4 10190.5 10956.4 11127.8 11132.0 11070.2 11116.8 11152.0 11159.8 11235.4 11086.3 11095.7 11153.3 11155.9 11201.9 9361.4 10904.8 10978.0 11003.9 11011.4 37314.0 37636.4 37702.2 38842.4 38900.6 3410.0 3431.7 3919.6 3936.4 4084.8 27910.3 27966.1 27968.9 28102.3 28915.0 3119.8 3136.1 3160.4 3163.8 3279.8 234003.0 267956.2 298818.0 299064.0 301321.0 50542.5 50651.0 50744.5 50783.5 50806.5 53139.7 53182.2 53330.0 53373.7 53428.0 13714.2 14066.9 14102.7 14113.2 14142.9 11152.4 11155.9 11193.1 11201.9 11253.9 13362.0 14010.4 14033.8 14044.2 14132.0 5109.5 6065.1 6142.4 6143.2 6153.3 1127.7 1131.5 1137.6 1161.9 1192.3 19779.7 25732.1 26475.0 26594.6 28570.6 1053.9 1058.6 1081.0 1092.4 1094.4 11807.8 14245.0 15025.2 15130.5 15241.6 384789.0 457641.0 460658.0 461442.0 463379.0 41002.4 56416.2 56790.7 56901.7 56986.4 1237.9 1397.8 1413.2 1418.0 1520.1 28021.6 28574.0 28939.0 29250.6 29742.6 33868.0 41323.4 41348.6 41415.6 41600.8 420105.0 420798.0 421146.9 421413.0 423786.8 33594.0 34224.0 34254.3 34302.3 34544.1 43506.0 43806.8 44499.8 45142.1 45167.8 34824.1 35726.0 35743.5 36890.4 37046.6 35571.8 35717.0 36722.0 36893.1 36937.6 35689.6 35834.7 36692.6 37093.9 37284.6 23260.1 24870.5 30086.7 31749.5 31767.8 21284.6 23018.3 23818.0 24193.9 24256.9 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:40399.38 Median:23850.90 stddev:115133.11 (482.7%) stddev-sans-first:115416.90 10:10:53 INFO - PID 1644 | Values: 389.9 401.5 404.0 421.6 432.8 16153.7 20407.9 21164.4 21212.6 22116.0 18672.8 18751.3 18764.9 18842.5 20281.7 6600.0 6983.0 7047.8 7055.2 7104.6 4271.0 6525.2 6888.3 6931.4 6949.4 4513.4 7675.2 7977.7 8019.2 8054.8 7679.7 7860.3 7956.9 7970.0 8002.5 6665.6 6736.3 7698.0 7745.5 7776.0 26245.7 26954.7 27201.6 27334.9 27380.9 2378.3 2649.4 2765.4 2873.0 2893.1 14439.9 15223.5 15482.8 16363.8 16644.9 2077.5 2123.8 2134.1 2145.9 2227.1 94760.1 135449.3 140356.3 140514.0 140687.0 22326.6 23821.3 23880.5 23891.6 24185.9 36195.8 36266.8 36447.8 36501.3 36549.6 9794.9 9964.7 10065.6 10217.3 10323.8 7970.4 8001.9 8009.0 8148.9 8152.5 10085.7 10513.7 11074.6 11190.0 11351.3 4188.3 4461.2 4515.3 4654.4 4662.7 853.8 862.6 866.3 881.7 896.8 17463.6 18636.1 20378.3 21092.9 23924.3 869.1 884.9 891.6 903.4 937.8 9925.6 12000.3 12092.3 12118.5 12317.9 624747.0 687391.0 708958.0 712777.0 728194.0 115286.0 126252.0 127405.0 149745.0 152358.0 1104.3 1299.4 1300.9 1330.7 1333.9 16696.6 19882.8 20190.1 20271.0 20850.8 58082.4 58268.0 59468.0 61285.7 62686.0 84680.0 85033.1 85489.6 85820.6 87190.7 10944.7 11539.9 11899.3 11970.0 12290.2 9757.0 12823.3 12916.9 13162.9 13241.9 10639.9 11017.9 11263.7 11406.0 11526.7 10770.0 11171.8 11173.5 11348.1 11495.4 11493.6 11542.6 11706.7 11781.5 11897.0 10789.4 11738.2 11844.7 11931.6 12094.0 9778.6 11177.3 11232.9 11254.5 11265.5 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:6579.68 Median:3327.85 stddev:4088.97 (122.9%) stddev-sans-first:4081.62 10:10:53 INFO - PID 1644 | Values: 1358.5 1369.4 1505.8 1516.7 1527.2 8992.6 10429.0 10860.3 10861.1 10967.7 10696.5 10877.7 10914.1 10951.9 11018.4 2034.9 2211.4 2360.0 2401.8 2403.3 1952.6 2010.8 2164.2 2347.2 2384.6 2188.6 2376.0 2395.3 2398.9 2399.1 1959.0 2353.0 2353.2 2361.3 2374.2 2322.5 2363.5 2363.7 2366.8 2372.6 11037.7 11077.2 11119.7 11131.7 11175.6 5636.5 5836.3 5842.5 5846.3 5865.5 3159.9 3387.0 3399.0 3667.9 3672.9 1103.6 1238.0 1791.5 1810.1 1898.2 14000.6 14194.6 14326.0 14622.3 14832.0 4359.8 4737.6 4761.2 4810.2 5002.0 10070.9 12262.8 12592.4 12626.2 12634.2 5857.9 5923.4 5928.6 5938.6 5941.6 4979.0 5047.1 5081.4 5111.7 5211.5 3268.7 3387.2 3942.6 4037.6 4085.6 1109.1 1141.6 1153.9 1165.1 1166.9 899.8 913.9 918.5 947.8 952.5 5702.8 5972.8 6340.2 6536.2 6601.7 939.3 969.3 993.9 1031.8 1045.0 3595.0 3894.9 4052.0 4173.4 4192.8 12913.5 13289.9 13395.3 13403.8 13418.2 13261.1 13292.3 13329.1 13332.0 13336.9 7785.2 8177.1 8856.3 9943.6 9943.9 5568.3 5838.8 6517.2 6584.0 6731.9 11774.4 11838.3 12028.7 12047.2 12137.6 8489.7 11858.8 12422.2 12434.9 12440.8 8778.9 8948.8 8969.9 9053.9 9075.1 9987.3 10040.3 10069.3 10174.7 10238.2 8977.6 8989.4 8998.5 9197.7 9198.6 8729.4 8765.7 8848.6 8895.9 8905.9 6275.4 7597.7 7688.8 7772.8 7777.3 5748.2 5777.8 5821.7 5906.6 5953.1 5976.6 5994.4 6010.8 6027.4 6030.3 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5369.70 Median:5737.78 stddev:1538.77 (26.8%) stddev-sans-first:1497.02 10:10:53 INFO - PID 1644 | Values: 390.6 390.9 391.9 392.3 394.2 5099.9 5144.5 6028.9 6115.8 6184.8 5325.7 5346.5 5676.9 5958.7 6000.2 4927.8 4979.5 4995.3 4997.1 5016.1 3804.2 4158.2 4327.8 4989.7 5056.7 3991.2 4989.9 5004.9 5005.7 5025.6 4971.2 4971.4 4974.7 4976.2 4978.9 4566.0 4568.5 4574.5 4576.3 4577.5 4847.5 5700.7 5976.7 5977.3 5983.7 4645.4 4886.8 5208.2 5760.9 5867.8 6170.8 6545.9 6556.6 6567.0 6584.2 4032.6 4479.0 4799.9 5045.5 5123.6 5597.7 6026.4 6550.3 6847.0 6971.9 4195.3 4777.0 5157.4 5343.9 5345.6 5409.9 5439.2 5442.6 5464.5 5485.1 4808.4 5354.4 5376.8 5700.8 5768.6 5665.9 6270.1 6514.9 6542.9 6554.0 1572.0 1638.2 1825.3 1852.4 1869.9 1338.9 1344.5 1353.9 1366.0 1379.4 4580.5 5464.8 5575.5 5805.2 6168.1 6628.4 6791.7 6794.4 6810.4 6836.9 4815.8 5648.9 6184.8 6222.9 6243.2 3043.3 3544.3 3557.5 3584.1 3588.0 6621.6 6680.8 6767.2 6982.3 7043.9 5962.7 6290.4 6381.5 6962.9 6986.1 5974.4 6742.4 6964.1 7064.6 7065.5 5851.0 6473.4 6659.5 6844.6 6860.3 6174.5 6325.7 6566.2 6748.6 6758.5 5783.9 5844.7 6561.9 7005.7 7008.7 4403.8 5257.7 5325.2 5329.6 5331.4 5714.7 6249.7 6320.4 6760.3 6765.4 6499.0 6526.3 6527.0 6533.7 6609.0 5125.9 6267.4 6534.9 6558.0 6572.1 6158.4 6330.8 6332.5 6353.8 6356.6 5686.6 6197.5 6429.4 6496.7 6548.6 5284.7 5525.5 6305.1 6351.5 6373.3 10:10:53 INFO - PID 1644 | 10:10:53 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:20033.45 Median:3799.42 stddev:28873.62 (759.9%) stddev-sans-first:28918.19 10:10:53 INFO - PID 1644 | Values: 716.1 728.1 756.8 768.4 840.6 14959.0 15551.2 15599.3 15604.6 15621.7 15226.8 15274.2 15275.7 15289.8 15293.8 896.1 942.6 949.3 950.9 951.1 914.2 946.0 948.7 959.5 962.0 923.7 1007.5 1015.1 1015.5 1017.2 951.7 989.4 1000.7 1012.4 1013.4 749.7 751.9 756.6 756.8 758.3 16339.6 16783.7 16876.7 16890.4 17089.7 14557.4 14756.9 14805.5 14892.6 14960.5 11884.8 12122.0 12173.2 12205.3 12348.3 3694.9 3746.0 3783.4 3815.4 3938.6 90349.5 109926.5 110013.0 112551.4 115624.0 9540.9 10002.5 11369.1 11935.9 11956.7 24656.1 24906.6 25099.1 25140.2 25201.7 6973.1 7877.1 7966.1 8019.0 8166.9 3966.9 4182.9 4258.3 4273.4 4279.4 3445.8 3508.2 3516.5 3527.0 3533.3 620.3 624.3 627.8 638.7 640.3 591.7 631.9 688.6 696.0 713.7 8880.9 8950.3 10043.6 10292.6 10392.5 667.5 699.1 707.4 718.9 740.4 4676.6 4789.9 5031.4 5092.7 5096.1 99977.0 101070.5 101558.0 101593.0 102115.5 57988.0 87814.7 89133.3 89939.0 91725.5 76803.0 77410.0 77490.5 77765.0 78992.6 7473.7 8729.2 9240.6 9624.9 9626.0 49722.0 50025.2 50122.7 52139.2 52245.2 52721.0 54047.0 62017.1 62825.7 64044.7 11764.8 11778.7 11904.2 11979.4 12019.4 12687.5 12901.5 12951.8 12990.4 13576.5 11732.4 11893.1 11897.1 12052.4 12181.9 11849.9 11872.4 11881.9 12104.2 12269.7 11700.2 11815.2 12016.3 12220.4 12239.8 10152.0 10208.7 10290.5 10504.7 10740.2 8743.6 8781.3 9036.6 9962.6 10007.5 10:10:53 INFO - PID 1644 | -------- Summary: end -------- 10:10:53 INFO - PID 1644 | 10:10:54 INFO - TEST-INFO | 1644: exit 0 10:10:54 INFO - TEST-OK | dromaeo_css | took 1116880ms 10:10:54 INFO - TEST-START | kraken 10:10:54 INFO - operating with platform_type : mac_ 10:10:54 INFO - Initialising browser for kraken test... 10:10:54 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpeKfPAF/profile http://localhost:49240/getInfo.html) 10:10:56 INFO - PID 1656 | 2017-03-19 10:10:56.978 plugin-container[1657:10842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 10:10:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:56 INFO - PID 1656 | 2017-03-19 10:10:56.981 plugin-container[1657:10842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.CFPasteboardClient' 10:10:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:56 INFO - PID 1656 | 2017-03-19 10:10:56.981 plugin-container[1657:10842] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:10:57 INFO - PID 1656 | __metrics Screen width/height:1600/1200 10:10:57 INFO - PID 1656 | colorDepth:24 10:10:57 INFO - PID 1656 | Browser inner width/height: 1024/689 10:10:57 INFO - PID 1656 | __metrics 10:10:57 ERROR - PID 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 10:10:57 INFO - TEST-INFO | 1656: exit 0 10:10:57 INFO - Browser initialized. 10:10:57 INFO - Running cycle 1/1 for kraken test... 10:10:57 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpeKfPAF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:11:00 INFO - PID 1658 | 2017-03-19 10:11:00.273 plugin-container[1659:10970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 10:11:00 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:11:00 INFO - PID 1658 | 2017-03-19 10:11:00.273 plugin-container[1659:10970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 10:11:00 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:11:00 INFO - PID 1658 | 2017-03-19 10:11:00.273 plugin-container[1659:10970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:11:10 INFO - PID 1658 | RSS: Main: 227393536 10:11:10 INFO - PID 1658 | 10:11:12 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 10:11:13 INFO - PID 1658 | RSS: Main: 241950720 10:11:13 INFO - PID 1658 | 10:11:16 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 10:11:17 INFO - PID 1658 | RSS: Main: 250478592 10:11:17 INFO - PID 1658 | 10:11:20 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 10:11:21 INFO - PID 1658 | RSS: Main: 253075456 10:11:21 INFO - PID 1658 | 10:11:23 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 10:11:23 INFO - PID 1658 | RSS: Main: 255336448 10:11:23 INFO - PID 1658 | 10:11:25 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:11:26 INFO - PID 1658 | RSS: Main: 255684608 10:11:26 INFO - PID 1658 | 10:11:29 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 10:11:30 INFO - PID 1658 | RSS: Main: 261365760 10:11:30 INFO - PID 1658 | 10:11:33 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 10:11:34 INFO - PID 1658 | RSS: Main: 261881856 10:11:34 INFO - PID 1658 | 10:11:37 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 10:11:38 INFO - PID 1658 | RSS: Main: 261840896 10:11:38 INFO - PID 1658 | 10:11:39 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:11:40 INFO - PID 1658 | RSS: Main: 261664768 10:11:40 INFO - PID 1658 | 10:11:42 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:11:43 INFO - PID 1658 | RSS: Main: 261652480 10:11:43 INFO - PID 1658 | 10:11:45 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:11:45 INFO - PID 1658 | RSS: Main: 261922816 10:11:45 INFO - PID 1658 | 10:11:48 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:11:49 INFO - PID 1658 | RSS: Main: 262529024 10:11:49 INFO - PID 1658 | 10:11:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:11:53 INFO - PID 1658 | RSS: Main: 262295552 10:11:53 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 10:11:55 INFO - PID 1658 | RSS: Main: 264622080 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | __start_tp_report 10:11:55 INFO - PID 1658 | _x_x_mozilla_page_load 10:11:55 INFO - PID 1658 | _x_x_mozilla_page_load_details 10:11:55 INFO - PID 1658 | |i|pagename|runs| 10:11:55 INFO - PID 1658 | |0;ai-astar;109;127;101;162;181;118;119;121;121;119 10:11:55 INFO - PID 1658 | |1;audio-beat-detection;146;117;142;179;115;116;116;118;140;114 10:11:55 INFO - PID 1658 | |2;audio-dft;198;202;203;220;219;203;197;197;228;246 10:11:55 INFO - PID 1658 | |3;audio-fft;86;60;59;90;57;58;58;56;71;60 10:11:55 INFO - PID 1658 | |4;audio-oscillator;86;106;123;104;75;71;86;73;71;85 10:11:55 INFO - PID 1658 | |5;imaging-gaussian-blur;90;90;82;139;136;129;85;137;138;137 10:11:55 INFO - PID 1658 | |6;imaging-darkroom;96;117;92;93;91;104;133;91;93;149 10:11:55 INFO - PID 1658 | |7;imaging-desaturate;91;97;144;93;145;88;140;86;112;132 10:11:55 INFO - PID 1658 | |8;json-parse-financial;70;70;70;100;68;68;67;68;68;77 10:11:55 INFO - PID 1658 | |9;json-stringify-tinderbox;58;77;90;65;62;55;58;57;57;57 10:11:55 INFO - PID 1658 | |10;stanford-crypto-aes;83;84;81;85;85;83;82;87;83;98 10:11:55 INFO - PID 1658 | |11;stanford-crypto-ccm;139;142;140;139;152;159;155;152;188;158 10:11:55 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;230;200;185;188;183;197;240;239;187;187 10:11:55 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;72;79;94;86;69;70;68;69;71;77 10:11:55 INFO - PID 1658 | __end_tp_report 10:11:55 INFO - PID 1658 | __start_cc_report 10:11:55 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1003 10:11:55 INFO - PID 1658 | __end_cc_report 10:11:55 INFO - PID 1658 | __startTimestamp1489943515412__endTimestamp 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | ------- Summary: start ------- 10:11:55 INFO - PID 1658 | Number of tests: 14 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:127.80 Median:121.00 stddev:24.53 (20.3%) stddev-sans-first:25.06 10:11:55 INFO - PID 1658 | Values: 109.0 127.0 101.0 162.0 181.0 118.0 119.0 121.0 121.0 119.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:129.00 stddev:21.31 (16.5%) stddev-sans-first:21.83 10:11:55 INFO - PID 1658 | Values: 146.0 117.0 142.0 179.0 115.0 116.0 116.0 118.0 140.0 114.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:211.30 Median:211.00 stddev:16.43 (7.8%) stddev-sans-first:16.70 10:11:55 INFO - PID 1658 | Values: 198.0 202.0 203.0 220.0 219.0 203.0 197.0 197.0 228.0 246.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:65.50 Median:60.00 stddev:12.60 (21.0%) stddev-sans-first:10.96 10:11:55 INFO - PID 1658 | Values: 86.0 60.0 59.0 90.0 57.0 58.0 58.0 56.0 71.0 60.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:74.00 stddev:17.62 (23.8%) stddev-sans-first:18.67 10:11:55 INFO - PID 1658 | Values: 86.0 106.0 123.0 104.0 75.0 71.0 86.0 73.0 71.0 85.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:110.50 stddev:25.67 (23.2%) stddev-sans-first:25.41 10:11:55 INFO - PID 1658 | Values: 90.0 90.0 82.0 139.0 136.0 129.0 85.0 137.0 138.0 137.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:91.50 stddev:20.51 (22.4%) stddev-sans-first:21.44 10:11:55 INFO - PID 1658 | Values: 96.0 117.0 92.0 93.0 91.0 104.0 133.0 91.0 93.0 149.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:87.00 stddev:24.88 (28.6%) stddev-sans-first:25.10 10:11:55 INFO - PID 1658 | Values: 91.0 97.0 144.0 93.0 145.0 88.0 140.0 86.0 112.0 132.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:72.60 Median:69.00 stddev:10.04 (14.5%) stddev-sans-first:10.60 10:11:55 INFO - PID 1658 | Values: 70.0 70.0 70.0 100.0 68.0 68.0 67.0 68.0 68.0 77.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:60.00 stddev:11.30 (18.8%) stddev-sans-first:11.80 10:11:55 INFO - PID 1658 | Values: 58.0 77.0 90.0 65.0 62.0 55.0 58.0 57.0 57.0 57.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.50 stddev:4.84 (5.7%) stddev-sans-first:5.07 10:11:55 INFO - PID 1658 | Values: 83.0 84.0 81.0 85.0 85.0 83.0 82.0 87.0 83.0 98.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:153.50 stddev:14.80 (9.6%) stddev-sans-first:14.88 10:11:55 INFO - PID 1658 | Values: 139.0 142.0 140.0 139.0 152.0 159.0 155.0 152.0 188.0 158.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.60 Median:198.50 stddev:23.32 (11.8%) stddev-sans-first:22.70 10:11:55 INFO - PID 1658 | Values: 230.0 200.0 185.0 188.0 183.0 197.0 240.0 239.0 187.0 187.0 10:11:55 INFO - PID 1658 | 10:11:55 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.50 Median:74.50 stddev:8.63 (11.6%) stddev-sans-first:9.06 10:11:55 INFO - PID 1658 | Values: 72.0 79.0 94.0 86.0 69.0 70.0 68.0 69.0 71.0 77.0 10:11:55 INFO - PID 1658 | -------- Summary: end -------- 10:11:55 INFO - PID 1658 | 10:11:56 INFO - TEST-INFO | 1658: exit 0 10:11:56 INFO - TEST-OK | kraken | took 62210ms 10:11:56 INFO - SUITE-END | took 1179s 10:11:56 INFO - Completed test suite (00:19:39) 10:11:56 INFO - javascript benchmark 10:11:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7852.910472480665, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.4666453367468, 3434.8626054957804, 3462.5498685170924, 3483.7038851697603, 3499.2476617527227, 5836.15375770513, 5928.52571794256, 5949.0, 5960.254968128984, 5998.980173370526, 5853.707314634262, 5897.882042359151, 5902.498287645555, 5991.580589358747, 6125.356837532062, 5298.602604804636, 5377.65045272058, 5443.972780136094, 5459.672419654814, 5479.3424789025175, 5295.126304159809, 5374.596905232103, 5460.017196904556, 5464.972675136639, 5468.28912241408, 4500.909981800346, 5426.050441172819, 5437.265969094181, 5445.319335083114, 5450.482204190595, 4190.28765109932, 4492.438445194351, 4568.748718820463, 5261.421243663194, 5313.3092697949405, 5341.759620817073, 5363.168708850135, 5371.543418809406, 5382.9730851345885, 5405.0, 4713.528647135293, 5222.616006758212, 5855.531557475391, 5978.163057172, 6052.939470605282, 5285.524302812765, 6266.561340706153, 6319.336469670664, 6328.303886572473, 6357.268914074894, 6350.714217860207, 6408.295087540367, 6415.486761059104, 6417.326180751001, 6457.2574153971955, 6125.173101631267, 6155.784547540815, 6158.384161583851, 6166.599171053867, 6173.598716083485, 6279.560430769802, 6435.099086127946, 6494.350564943562, 6540.771073012517, 6563.835904102436, 5293.285406470154, 6193.566450348522, 6232.0963460298, 6253.593516421418, 6297.4017468340435, 6321.683915804191, 6349.269833969152, 6384.3934826191435, 6393.872122557523, 6410.358964103552, 5163.845084647466, 5288.735563221823, 6125.050617154348, 6318.368163183737, 6322.209723784527, 6260.029695397221, 6322.557420980579, 6345.016522439029, 6346.365363463617, 6353.5234857385885, 4472.8210871565425, 5051.090803655377, 5072.771725272371, 5138.12651849179, 5180.274761533388, 4566.45202575693, 4592.2422800238255, 4602.953970460319, 4603.194440972883, 4620.075984803053, 6057.57596968208, 6072.635641861502, 6096.817095487142, 6108.725107370179, 6123.908141377883, 5459.918101228484, 6273.466755325757, 6289.276733175652, 6329.303776584572, 6377.394147555975, 6042.637441753509, 6080.543959202989, 6109.389061093855, 6110.847228819315, 6133.754649813957, 5653.265075540236, 5657.717114144359, 5669.43305669438, 5673.149027645886, 5675.80134695292, 6351.491880649444, 6414.294427612866, 6473.449756770728, 6597.406233438917, 6641.0, 6362.172917520693, 6373.490120790419, 6388.93611063902, 6395.232572091193, 6405.487560995016, 6572.4084832365315, 6583.506237032146, 6594.208694956636, 6599.274079851117, 6603.339666033358, 6115.149072927005, 6299.401556852092, 6314.684265786784, 6315.873682526416, 6344.397282258178, 5953.166556682155, 6330.715117819617, 6377.553571249968, 6378.521610879296, 6387.584806987531, 6267.561270711189, 6310.5898116622275, 6314.463270622048, 6314.8737025258315, 6315.052742088724, 6145.3407579954055, 6146.446819786241, 6252.656103914328, 6288.088227207121, 6307.779227727007, 5241.711705856214, 5704.914426283526, 6192.0, 6207.906881396873, 6263.311035786151, 5232.3982741985155, 6217.409346112112, 6318.652474113968, 6320.715567799368, 6383.329750376143, 6112.138751317827, 6130.969345153246, 6147.477464415396, 6201.410865967726, 6234.906476402948], "value": 5810.2899845318025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.4066184405099, 718.6155406857331, 720.5784615999643, 800.4716886854677, 836.891204143462, 36193.457098143546, 37369.439458408146, 37403.0, 37432.0, 37673.81163094184, 38467.61532384675, 38792.61207387925, 38876.41685374715, 38881.0, 38881.83354499363, 9072.455652660845, 9290.58192381341, 9301.906980930205, 10653.627123050695, 11028.393438360887, 8420.368472364567, 10190.541425635827, 10956.397398143099, 11127.833082503768, 11132.0, 11070.225084244106, 11116.777664446707, 11152.0, 11159.776804463905, 11235.438228088562, 11086.279391839586, 11095.667129986114, 11153.275037122561, 11155.944220278929, 11201.943990280079, 9361.391509551857, 10904.836427453594, 10978.0, 11003.889961100367, 11011.449427528672, 37314.0, 37636.43545346822, 37702.245955081016, 38842.417363739565, 38900.61099388998, 3410.0, 3431.656834316573, 3919.6004869686262, 3936.3701807710627, 4084.7957602119777, 27910.302242443897, 27966.063875679993, 27968.860155699294, 28102.297442563897, 28915.0, 3119.797213181136, 3136.106209730239, 3160.352127813793, 3163.825989570573, 3279.7868138571157, 234003.0, 267956.22372098395, 298818.0, 299064.0, 301321.0, 50542.494575054516, 50651.0, 50744.492555074714, 50783.49216507861, 50806.491935080914, 53139.734301329016, 53182.20226696603, 53330.0, 53373.73313133487, 53428.0, 13714.24571648549, 14066.944979126401, 14102.71794564103, 14113.223772692396, 14142.85857141436, 11152.442377881074, 11155.888441115485, 11193.10455163585, 11201.935816097457, 11253.887461125447, 13362.0, 14010.43958241659, 14033.78949315761, 14044.157350558999, 14132.0, 5109.489051094935, 6065.059915713072, 6142.385761423911, 6143.170671959318, 6153.323134455206, 1127.7388835385163, 1131.5247596009697, 1137.6132115080911, 1161.8838116188483, 1192.3203773848995, 19779.703304450446, 25732.09937652173, 26475.0, 26594.6010809838, 28570.571441428394, 1053.9051485366201, 1058.5501162006117, 1080.978380432387, 1092.3991804507489, 1094.4199574225513, 11807.763844723057, 14245.002849800414, 15025.248737563079, 15130.470433534774, 15241.63754618036, 384789.0, 457641.0, 460658.0, 461442.0, 463379.0, 41002.38496422556, 56416.15375769367, 56790.716046419504, 56901.71549142228, 56986.43013569894, 1237.8824011718873, 1397.7553928062548, 1413.152108734751, 1417.978730319046, 1520.0575643101347, 28021.579676304464, 28574.0, 28939.0, 29250.561241580966, 29742.553861692526, 33868.0, 41323.380149298384, 41348.58651413448, 41415.55045573391, 41600.79199603983, 420105.0, 420798.0, 421146.89426552673, 421413.0, 423786.76213237474, 33594.0, 34224.0, 34254.31491370109, 34302.313953721285, 34544.136396590286, 43506.0, 43806.780966094964, 44499.77750111229, 45142.09715805731, 45167.77416112898, 34824.12939676528, 35726.0, 35743.46384804178, 36890.44664329984, 37046.62953370432, 35571.822140890166, 35717.0, 36722.0, 36893.07767305839, 36937.63062369342, 35689.64310356863, 35834.74578389744, 36692.581341434125, 37093.88718338454, 37284.6271537292, 23260.069597215923, 24870.50258994846, 30086.69913300839, 31749.5237571432, 31767.841160794047, 21284.574308514053, 23018.309450716504, 23818.047278108683, 24193.911273992362, 24256.878715606308], "value": 18733.17174511826, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.86354775828454, 401.54224184429773, 403.97374170678904, 421.6057985783292, 432.7879339123828, 16153.676926461463, 20407.897960510196, 21164.35445569304, 21212.57574848504, 22116.0, 18672.81327186728, 18751.3437029704, 18764.90617546914, 18842.528936776587, 20281.69577456339, 6599.999999999988, 6982.965085174567, 7047.788566343018, 7055.188653304858, 7104.609246491441, 4270.97864510677, 6525.249596296416, 6888.250717942315, 6931.445484361264, 6949.444044476455, 4513.390692256553, 7675.232476752336, 7977.720779772709, 8019.1980801919635, 8054.758357249262, 7679.6928122875015, 7860.331871790904, 7956.880646790303, 7969.960150199212, 8002.519848809061, 6665.566738162004, 6736.292689267605, 7697.961510192413, 7745.457817952745, 7776.0, 26245.73754262452, 26954.730452695614, 27201.591976120373, 27334.863325683444, 27380.863095684592, 2378.3459548624096, 2649.3906401527756, 2765.4192619549917, 2872.956905646417, 2893.132060381877, 14439.855601444062, 15223.467178648694, 15482.845171548366, 16363.754543681855, 16644.916775416168, 2077.480629842539, 2123.7982391672767, 2134.125008746422, 2145.9463513412134, 2227.098025299756, 94760.10479790435, 135449.3227533856, 140356.29821850825, 140514.0, 140687.0, 22326.646303970894, 23821.285361439186, 23880.522389552458, 23891.641625375632, 24185.879070604533, 36195.819020904724, 36266.8186659065, 36447.81776091103, 36501.26997460036, 36549.634503654626, 9794.853077203848, 9964.651237206663, 10065.647702330383, 10217.33587316822, 10323.793524129474, 7970.442069055111, 8001.8799718004275, 8009.038915330081, 8148.918510814935, 8152.510849349058, 10085.747856303651, 10513.738351397882, 11074.557017719362, 11190.04884584803, 11351.262167959056, 4188.28799104153, 4461.196984542748, 4515.345274935116, 4654.394928659252, 4662.673612847136, 853.8036251662153, 862.6376921692904, 866.3112825303898, 881.709006240466, 896.7982204004047, 17463.56341091483, 18636.129083475545, 20378.286759963332, 21092.894535527223, 23924.282271531883, 869.1482347299556, 884.8849649545518, 891.5586784541564, 903.3856977255398, 937.7843096087938, 9925.553350099262, 12000.339981301113, 12092.27446353204, 12118.515259389702, 12317.938410307892, 624747.0, 687391.0, 708958.0, 712777.0, 728194.0, 115286.0, 126252.0, 127405.0, 149745.0, 152358.0, 1104.3318792130806, 1299.4477347127433, 1300.8959283257316, 1330.6806366471976, 1333.8532761396239, 16696.582585434975, 19882.801171988096, 20190.09144588468, 20270.986450677112, 20850.791492085493, 58082.4191758077, 58268.0, 59468.0, 61285.693571531854, 62686.0, 84680.0, 85033.149668505, 85489.57255213684, 85820.57089714511, 87190.69213961669, 10944.671659850217, 11539.88460115388, 11899.345535995604, 11970.0, 12290.20113692607, 9756.951215244162, 12823.294718790183, 12916.870831291566, 13162.934185329012, 13241.867581324064, 10639.946800265949, 11017.889821101686, 11263.66209013698, 11406.0, 11526.654200374001, 10770.030697237285, 11171.776564468828, 11173.497192626513, 11348.092152627605, 11495.425228738697, 11493.597724079616, 11542.59600913964, 11706.70733231676, 11781.469833857669, 11897.0, 10789.40658263803, 11738.237014594024, 11844.703882402664, 11931.582394616147, 12094.0, 9778.55996480146, 11177.273477223955, 11232.887671123512, 11254.549818007516, 11265.549378024787], "value": 11570.02736733005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.4905660377358, 1369.3974651153492, 1505.849415058495, 1516.7042426726794, 1527.1905889878362, 8992.640294388233, 10429.0, 10860.294080884747, 10861.07680847128, 10967.725806854833, 10696.51865666045, 10877.728056798584, 10914.126869850414, 10951.945240273788, 11018.39398833064, 2034.901153377182, 2211.358705975273, 2359.976400235997, 2401.8318717689767, 2403.2790162951133, 1952.5509132899442, 2010.8491863110253, 2164.2425151197035, 2347.2371479269223, 2384.6303822907475, 2188.5841690078832, 2376.025829409942, 2395.281415575329, 2398.8680622565753, 2399.1243196233336, 1958.9706154407695, 2353.0, 2353.2351985604746, 2361.3388251289666, 2374.204641445118, 2322.547103314854, 2363.491849252419, 2363.7399886012527, 2366.751491093428, 2372.5966585680476, 11037.724056898562, 11077.169212309029, 11119.72200694989, 11131.72170695731, 11175.608853690163, 5636.520895723868, 5836.270466191726, 5842.4741773240185, 5846.298444186682, 5865.472107510344, 3159.873605055795, 3386.9661303386897, 3398.9633161885613, 3667.9083022924374, 3672.908177295589, 1103.6137351926761, 1237.9690507737378, 1791.4625612316254, 1810.0859066171631, 1898.228586264056, 14000.579982600539, 14194.580541945827, 14326.0, 14622.26888655563, 14832.0, 4359.80380882861, 4737.573618374363, 4761.2382018876815, 4810.206315957827, 5001.974990125026, 10070.949645251874, 12262.754744905053, 12592.4333404997, 12626.179298345578, 12634.2419454833, 5857.853553661193, 5923.378045305267, 5928.614640048383, 5938.61399009063, 5941.58408911372, 4979.004199160183, 5047.141985862412, 5081.441041485434, 5111.667741596784, 5211.530962213419, 3268.7385009199206, 3387.2209391840015, 3942.6254505821903, 4037.555868854424, 4085.55058943516, 1109.1237922041496, 1141.5947338694643, 1153.9423028848523, 1165.0854079547514, 1166.877477864812, 899.7628261140932, 913.881195444588, 918.4764684130115, 947.7677968897664, 952.5427794658602, 5702.800401985994, 5972.790952316648, 6340.175777148941, 6536.182977127859, 6601.73593056282, 939.33307351781, 969.2875736333789, 993.8719553306904, 1031.7979553819866, 1045.0176833776227, 3595.0, 3894.9094253609037, 4051.9870032491876, 4173.373993900878, 4192.769397683096, 12913.548025819051, 13289.867101328862, 13395.26326052057, 13403.798943015863, 13418.194908305533, 13261.138026028277, 13292.268925209206, 13329.133606315558, 13332.0, 13336.933045356349, 7785.221477852283, 8177.119262569523, 8856.291496680251, 9943.552540135855, 9943.900560994442, 5568.331800184003, 5838.824835254949, 6517.185351831021, 6584.0, 6731.899021514583, 11774.3524106175, 11838.348890810743, 12028.699282518008, 12047.156699030984, 12137.575184868487, 8489.660413583388, 11858.762824743284, 12422.192557483735, 12434.875651243734, 12440.751184976069, 8778.91221087781, 8948.821023579361, 8969.865452018355, 9053.864192037256, 9075.13786190311, 9987.300888937705, 10040.297179197387, 10069.345492542961, 10174.745631359276, 10238.18094552449, 8977.640894364153, 8989.41568798026, 8998.505082220541, 9197.724068277963, 9198.586063627286, 8729.388942773945, 8765.737027889174, 8848.557572121497, 8895.866562001445, 8905.910940890768, 6275.3724627536885, 7597.6581053851605, 7688.807779805549, 7772.844543108993, 7777.261160189773, 5748.223989761329, 5777.826665200051, 5821.738021789114, 5906.557008224315, 5953.10703394477, 5976.581639285208, 5994.400559944145, 6010.849728756816, 6027.427394397525, 6030.28637113292], "value": 5062.1617892779095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6132928400884, 390.8983664247295, 391.9333713268746, 392.26646171659, 394.205675563739, 5099.949000509994, 5144.4598317176715, 6028.879422411549, 6115.847103822407, 6184.783532576361, 5325.680459172443, 5346.465353464661, 5676.886462270752, 5958.672273024982, 6000.159977603139, 4927.778249978742, 4979.50204979501, 4995.250712393152, 4997.075541024904, 5016.122178618745, 3804.154984832354, 4158.209940111385, 4327.848525301615, 4989.727619457033, 5056.696598204101, 3991.186213719321, 4989.925151122736, 5004.923941352608, 5005.699658020512, 5025.597952163818, 4971.179755340365, 4971.37857767779, 4974.651774375795, 4976.2286845539, 4978.90042199154, 4565.954340456587, 4568.5431456854385, 4574.4510658721165, 4576.336431217488, 4577.519360467137, 4847.5394837490385, 5700.686462244575, 5976.731047102848, 5977.252843394575, 5983.700814959278, 4645.4437763349315, 4886.75566221691, 5208.244804503363, 5760.9054279686725, 5867.794627188071, 6170.784022559234, 6545.9018114728315, 6556.573822701509, 6567.047778072153, 6584.209894812605, 4032.6169013943627, 4478.992226748988, 4799.856004319876, 5045.545900868903, 5123.615728820353, 5597.684544132167, 6026.397360263983, 6550.312217217219, 6847.0, 6971.860562788766, 4195.328747400401, 4776.9761151194025, 5157.432682404932, 5343.919841202385, 5345.625806193529, 5409.945060713202, 5439.238506609078, 5442.591805614595, 5464.508194262536, 5485.122380419114, 4808.35087263215, 5354.41101478837, 5376.763344430803, 5700.828975130753, 5768.596198266165, 5665.858353541194, 6270.059491076375, 6514.869702605922, 6542.901856472156, 6554.016897465418, 1571.9842801572067, 1638.2136574444096, 1825.3246298869503, 1852.3887117251275, 1869.9158537865828, 1338.9330533473287, 1344.475654494748, 1353.911995720275, 1365.9931700341633, 1379.3654918737293, 4580.473245576734, 5464.797744496204, 5575.52608028314, 5805.216295800097, 6168.1056246843955, 6628.436582890412, 6791.660416979132, 6794.3545363190415, 6810.353016463527, 6836.863262734817, 4815.783289751969, 5648.887022259614, 6184.783532576338, 6222.937770622327, 6243.219597550306, 3043.345680678665, 3544.255706301654, 3557.48416479609, 3584.086058055231, 3587.964120358763, 6621.550026490229, 6680.766173183915, 6767.154105736783, 6982.254436390902, 7043.894341584881, 5962.731677074542, 6290.370962903765, 6381.489480841522, 6962.895556566656, 6986.056882320821, 5974.372690867396, 6742.393184613408, 6964.094667693167, 7064.646767661597, 7065.4700897432895, 5851.0, 6473.449756770728, 6659.500537459807, 6844.555103918229, 6860.279670634512, 6174.506039516918, 6325.746970121144, 6566.24488183865, 6748.628825414453, 6758.459323254227, 5783.8554036149435, 5844.707764611838, 6561.868762624817, 7005.684744186626, 7008.652101546039, 4403.779811009501, 5257.710825904459, 5325.227841962971, 5329.600279979095, 5331.3868905076415, 5714.657120572779, 6249.656433807891, 6320.4311611955145, 6760.3239676032, 6765.42493888025, 6499.0, 6526.347365263436, 6526.967365163144, 6533.705983230671, 6608.966955165193, 5125.89793194465, 6267.4045965633195, 6534.901976470452, 6558.0, 6572.112764776694, 6158.353372895782, 6330.778422755181, 6332.461740752087, 6353.809385718436, 6356.586821856565, 5686.583202139157, 6197.473214776614, 6429.421352078335, 6496.740130394731, 6548.574342667902, 5284.735763211747, 5525.502704756591, 6305.054241863757, 6351.522176865391, 6373.298937117006], "value": 4912.538163089399, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1084449859925, 728.1226122522364, 756.7578374920024, 768.3814529303911, 840.5839109640726, 14959.0, 15551.222438878067, 15599.298031588578, 15604.60988475286, 15621.687566248682, 15226.771598426005, 15274.236288185573, 15275.694486110271, 15289.770653440179, 15293.770593441108, 896.0725648953319, 942.6417961174744, 949.3164921256684, 950.9049095090505, 951.0822056715268, 914.1772404835668, 945.9952700236523, 948.7296120605629, 959.4866746290736, 961.956711947964, 923.6813299411726, 1007.531497853498, 1015.0509273828957, 1015.537930241742, 1017.2218253036433, 951.6621599332241, 989.4063561862897, 1000.6547741029333, 1012.3824467075078, 1013.3514550687565, 749.7300971650202, 751.8571471420413, 756.6179079564785, 756.7767508584981, 758.3023618271204, 16339.59151021234, 16783.6643267134, 16876.662466750597, 16890.40883569069, 17089.658206835793, 14557.417703291856, 14756.926215368854, 14805.481808136663, 14892.553223403314, 14960.47638332664, 11884.762304753856, 12122.0, 12173.20874143178, 12205.328706921116, 12348.320842353667, 3694.907627309312, 3745.9812700936322, 3783.3946568549177, 3815.4467602197524, 3938.5864484229314, 90349.54825225832, 109926.45036774845, 110013.0, 112551.43724281408, 115624.0, 9540.856887146698, 10002.549885255181, 11369.147313951487, 11935.940320298516, 11956.701082472833, 24656.137035203683, 24906.626400604004, 25099.121530746335, 25140.24579262625, 25201.747982520308, 6973.12835895513, 7877.133515313311, 7966.123726390092, 8019.03771547418, 8166.918330816735, 3966.9090999975238, 4182.853600123981, 4258.297380932174, 4273.35899615054, 4279.443672322578, 3445.776024558396, 3508.1755787389943, 3516.5252690886914, 3527.0476971217627, 3533.311004354178, 620.2990620598695, 624.316373570939, 627.7645882793952, 638.651934695592, 640.3052687833753, 591.6716222496556, 631.9115323854664, 688.5558814564678, 696.0, 713.6931119618511, 8880.911190888008, 8950.283977281802, 10043.648472303581, 10292.639757608447, 10392.480375981171, 667.4560233409755, 699.1121275979477, 707.3845754193884, 718.9496735228483, 740.4224704730319, 4676.602488788423, 4789.880252993703, 5031.371078615173, 5092.694438333638, 5096.1336572781975, 99977.0, 101070.49464752777, 101558.0, 101593.0, 102115.48942255242, 57988.0, 87814.68277975835, 89133.3260002201, 89939.0, 91725.54137229404, 76803.0, 77410.0, 77490.45019099586, 77765.0, 78992.60503697445, 7473.738419155304, 8729.214370706666, 9240.630374784932, 9624.903750962401, 9625.978544505731, 49722.0, 50025.24962125498, 50122.74693132701, 52139.21791173059, 52245.216321754444, 52721.0, 54047.0, 62017.069743952976, 62825.68587157035, 64044.67977660082, 11764.764704705687, 11778.705532361759, 11904.226225295328, 11979.401029948642, 12019.399030048287, 12687.492500299884, 12901.483940642269, 12951.805722913976, 12990.350482476028, 13576.524821631196, 11732.354720490455, 11893.06279406016, 11897.107716921439, 12052.397380131133, 12181.939090304491, 11849.881501184878, 11872.406379681153, 11881.881181188077, 12104.213226140273, 12269.69325766863, 11700.239484433485, 11815.232009919328, 12016.33910134951, 12220.388980551115, 12239.75520489603, 10152.0, 10208.744781380228, 10290.485475726333, 10504.737381565523, 10740.194485413782, 8743.604546992934, 8781.341399395198, 9036.59335329925, 9962.55168517404, 10007.549660265451], "value": 7487.943848247949, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 127.0, 101.0, 162.0, 181.0, 118.0, 119.0, 121.0, 121.0, 119.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 142.0, 179.0, 115.0, 116.0, 116.0, 118.0, 140.0, 114.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 202.0, 203.0, 220.0, 219.0, 203.0, 197.0, 197.0, 228.0, 246.0], "value": 211.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 59.0, 90.0, 57.0, 58.0, 58.0, 56.0, 71.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 106.0, 123.0, 104.0, 75.0, 71.0, 86.0, 73.0, 71.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 82.0, 139.0, 136.0, 129.0, 85.0, 137.0, 138.0, 137.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 117.0, 92.0, 93.0, 91.0, 104.0, 133.0, 91.0, 93.0, 149.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 144.0, 93.0, 145.0, 88.0, 140.0, 86.0, 112.0, 132.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 100.0, 68.0, 68.0, 67.0, 68.0, 68.0, 77.0], "value": 72.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 77.0, 90.0, 65.0, 62.0, 55.0, 58.0, 57.0, 57.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 85.0, 85.0, 83.0, 82.0, 87.0, 83.0, 98.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 140.0, 139.0, 152.0, 159.0, 155.0, 152.0, 188.0, 158.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 200.0, 185.0, 188.0, 183.0, 197.0, 240.0, 239.0, 187.0, 187.0], "value": 203.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 94.0, 86.0, 69.0, 70.0, 68.0, 69.0, 71.0, 77.0], "value": 75.5, "unit": "score"}]}]} 10:11:56 INFO - Return code: 0 10:11:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:11:56 ERROR - # TBPL SUCCESS # 10:11:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:11:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:11:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:11:56 INFO - 2017-03-19 10:11:56.745 screenresolution[1663:11353] starting screenresolution argv=screenresolution get 10:11:56 INFO - 2017-03-19 10:11:56.748 screenresolution[1663:11353] Display 0: 1600x1200x32@60 10:11:56 INFO - 2017-03-19 10:11:56.755 screenresolution[1664:11356] starting screenresolution argv=screenresolution list 10:11:56 INFO - Available Modes on Display 0 10:11:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:11:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:11:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:11:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:11:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:11:56 INFO - Intel Iris: 10:11:56 INFO - Chipset Model: Intel Iris 10:11:56 INFO - Type: GPU 10:11:56 INFO - Bus: Built-In 10:11:56 INFO - VRAM (Dynamic, Max): 1536 MB 10:11:56 INFO - Vendor: Intel (0x8086) 10:11:56 INFO - Device ID: 0x0a2e 10:11:56 INFO - Revision ID: 0x0009 10:11:56 INFO - Displays: 10:11:56 INFO - Display: 10:11:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:11:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:11:56 INFO - Display Serial Number: 1600x1200 60 10:11:56 INFO - Main Display: Yes 10:11:56 INFO - Mirror: Off 10:11:56 INFO - Online: Yes 10:11:56 INFO - Rotation: Supported 10:11:56 INFO - Return code: 0 10:11:56 INFO - Running post-action listener: _resource_record_post_action 10:11:56 INFO - [mozharness: 2017-03-19 17:11:56.992204Z] Finished run-tests step (success) 10:11:56 INFO - Running post-run listener: _resource_record_post_run 10:11:57 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 40331776; Write bytes: 517949952; Read time: 392; Write time: 1677 10:11:57 INFO - TinderboxPrint: CPU usage
54.4% 10:11:57 INFO - TinderboxPrint: I/O read bytes / time
40,331,776 / 392 10:11:57 INFO - TinderboxPrint: I/O write bytes / time
517,949,952 / 1,677 10:11:57 INFO - TinderboxPrint: CPU idle
2,187.5 (45.6%) 10:11:57 INFO - TinderboxPrint: CPU system
974.8 (20.3%) 10:11:57 INFO - TinderboxPrint: CPU user
1,639.2 (34.1%) 10:11:57 INFO - TinderboxPrint: Swap in / out
250,466,304 / 0 10:11:57 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205920768; Write bytes: 205182976; Read time: 15566; Write time: 456 10:11:57 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 38226432; Write bytes: 301765120; Read time: 368; Write time: 1190 10:11:57 INFO - Running post-run listener: _upload_blobber_files 10:11:57 INFO - Blob upload gear active. 10:11:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:11:57 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 10:11:57 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'] 10:11:57 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 10:11:57 INFO - (blobuploader) - INFO - Open directory for files ... 10:11:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:11:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:11:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:59 INFO - (blobuploader) - INFO - Done attempting. 10:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:11:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:59 INFO - (blobuploader) - INFO - Done attempting. 10:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:12:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:00 INFO - (blobuploader) - INFO - Done attempting. 10:12:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:12:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:12:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:03 INFO - (blobuploader) - INFO - Done attempting. 10:12:03 INFO - (blobuploader) - INFO - Iteration through files over. 10:12:03 INFO - Return code: 0 10:12:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:12:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:12:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce44f6e5e048ec29a1ca9450cc6817151891d8867ea57ab851f223c0aa9dca1cecd667144150641b61f0c544c77e115955eb0cfbbbe292735e62d7a060a25f92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52dc4dcfe47f08a72827d70c8fe9b5ec1d9f3518ca5f88c0d2a29e3ba7a0762da29d6d80ae07f738eb0969cd691c8fc2622612b3eb644e37506397722fe1666c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13754920af4bf129144bd0cee6adb42cf6428d1913576dd7ac7854fae0fbce591ab18926f096a8a44dd77c20201870084751b1d0a53a6f37d099d7e573f4badc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef5863609fe401b1950429f6c24aa4da16f453a919ae87d4d4be546018287e409c86628cfe30da26d48b39d5a6aacc11610a735b4752c98fb5d349bd8c992e45"} 10:12:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:12:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:12:03 INFO - Contents: 10:12:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce44f6e5e048ec29a1ca9450cc6817151891d8867ea57ab851f223c0aa9dca1cecd667144150641b61f0c544c77e115955eb0cfbbbe292735e62d7a060a25f92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52dc4dcfe47f08a72827d70c8fe9b5ec1d9f3518ca5f88c0d2a29e3ba7a0762da29d6d80ae07f738eb0969cd691c8fc2622612b3eb644e37506397722fe1666c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13754920af4bf129144bd0cee6adb42cf6428d1913576dd7ac7854fae0fbce591ab18926f096a8a44dd77c20201870084751b1d0a53a6f37d099d7e573f4badc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef5863609fe401b1950429f6c24aa4da16f453a919ae87d4d4be546018287e409c86628cfe30da26d48b39d5a6aacc11610a735b4752c98fb5d349bd8c992e45"} 10:12:03 INFO - Running post-run listener: copy_logs_to_upload_dir 10:12:03 INFO - Copying logs to upload dir... 10:12:03 INFO - mkdir: /builds/slave/test/build/upload/logs 10:12:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.134087 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-19 10:12:03.255106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 10:12:03.258570) ========= 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.WrJGKiCKzo/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.47lDue5lN6/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/ce44f6e5e048ec29a1ca9450cc6817151891d8867ea57ab851f223c0aa9dca1cecd667144150641b61f0c544c77e115955eb0cfbbbe292735e62d7a060a25f92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52dc4dcfe47f08a72827d70c8fe9b5ec1d9f3518ca5f88c0d2a29e3ba7a0762da29d6d80ae07f738eb0969cd691c8fc2622612b3eb644e37506397722fe1666c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13754920af4bf129144bd0cee6adb42cf6428d1913576dd7ac7854fae0fbce591ab18926f096a8a44dd77c20201870084751b1d0a53a6f37d099d7e573f4badc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef5863609fe401b1950429f6c24aa4da16f453a919ae87d4d4be546018287e409c86628cfe30da26d48b39d5a6aacc11610a735b4752c98fb5d349bd8c992e45"} build_url:https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/SPUJAxLOQzWBMjL_-ds79w/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/ce44f6e5e048ec29a1ca9450cc6817151891d8867ea57ab851f223c0aa9dca1cecd667144150641b61f0c544c77e115955eb0cfbbbe292735e62d7a060a25f92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52dc4dcfe47f08a72827d70c8fe9b5ec1d9f3518ca5f88c0d2a29e3ba7a0762da29d6d80ae07f738eb0969cd691c8fc2622612b3eb644e37506397722fe1666c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13754920af4bf129144bd0cee6adb42cf6428d1913576dd7ac7854fae0fbce591ab18926f096a8a44dd77c20201870084751b1d0a53a6f37d099d7e573f4badc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef5863609fe401b1950429f6c24aa4da16f453a919ae87d4d4be546018287e409c86628cfe30da26d48b39d5a6aacc11610a735b4752c98fb5d349bd8c992e45"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 10:12:03.293616) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 10:12:03.293945) ========= 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.WrJGKiCKzo/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.47lDue5lN6/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.005438 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 10:12:03.344287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 10:12:03.344831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 10:12:03.345462) ========= ========= Total master_lag: 1.20 =========