builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1484972690.29 results: success (0) buildid: 20170120190126 builduid: b407ae89d4a1468a97ffbae90274d769 revision: 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.292201) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.293239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.294000) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.322200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.322540) ========= 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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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-01-20 20:24:50-- 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% 18.9M=0.001s 2017-01-20 20:24:50 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145416 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.493954) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.494318) ========= 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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.025650 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:50.542630) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-20 20:24:50.543014) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f --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 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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-01-20 20:24:50,649 truncating revision to first 12 chars 2017-01-20 20:24:50,649 Setting DEBUG logging. 2017-01-20 20:24:50,649 attempt 1/10 2017-01-20 20:24:50,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/487a4e43eb9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 20:24:52,814 unpacking tar archive at: mozilla-central-487a4e43eb9d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.434601 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-20 20:24:53.001390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:53.001745) ========= script_repo_revision: 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:53.002150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:53.002420) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 20:24:53.022777) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-20 20:24:53.023574) ========= /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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:24:53 INFO - MultiFileLogger online at 20170120 20:24:53 in /builds/slave/test 20:24:53 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 20:24:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:24:53 INFO - {'append_to_log': False, 20:24:53 INFO - 'base_work_dir': '/builds/slave/test', 20:24:53 INFO - 'blob_upload_branch': 'Firefox', 20:24:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:24:53 INFO - 'branch': 'Firefox', 20:24:53 INFO - 'buildbot_json_path': 'buildprops.json', 20:24:53 INFO - 'config_files': ('talos/mac_config.py',), 20:24:53 INFO - 'default_actions': ('clobber', 20:24:53 INFO - 'read-buildbot-config', 20:24:53 INFO - 'download-and-extract', 20:24:53 INFO - 'populate-webroot', 20:24:53 INFO - 'create-virtualenv', 20:24:53 INFO - 'install', 20:24:53 INFO - 'run-tests'), 20:24:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:24:53 INFO - 'download_minidump_stackwalk': True, 20:24:53 INFO - 'download_symbols': 'ondemand', 20:24:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:24:53 INFO - 'tooltool.py': '/tools/tooltool.py', 20:24:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:24:53 INFO - '/tools/misc-python/virtualenv.py')}, 20:24:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:24:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:24:53 INFO - 'installer_path': 'installer.exe', 20:24:53 INFO - 'log_level': 'info', 20:24:53 INFO - 'log_name': 'talos', 20:24:53 INFO - 'log_to_console': True, 20:24:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:24:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:24:53 INFO - 'opt_config_files': (), 20:24:53 INFO - 'pip_index': False, 20:24:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:24:53 INFO - 'cmd': ('bash', 20:24:53 INFO - '-c', 20:24:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:24:53 INFO - 'enabled': True, 20:24:53 INFO - 'halt_on_failure': False, 20:24:53 INFO - 'name': 'check_screen_resolution'},), 20:24:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:24:53 INFO - 'cmd': ('bash', 20:24:53 INFO - '-c', 20:24:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:24:53 INFO - 'enabled': True, 20:24:53 INFO - 'halt_on_failure': False, 20:24:53 INFO - 'name': 'check_screen_resolution'},), 20:24:53 INFO - 'run_cmd_checks_enabled': True, 20:24:53 INFO - 'sps_profile': False, 20:24:53 INFO - 'sps_profile_interval': 0, 20:24:53 INFO - 'suite': 'dromaeojs-e10s', 20:24:53 INFO - 'system_bits': '32', 20:24:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:24:53 INFO - 'title': 't-yosemite-r7-0383', 20:24:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:24:53 INFO - 'use_talos_json': True, 20:24:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:24:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:24:53 INFO - 'work_dir': 'build'} 20:24:53 INFO - [mozharness: 2017-01-21 04:24:53.239762Z] Running clobber step. 20:24:53 INFO - Running pre-action listener: _resource_record_pre_action 20:24:53 INFO - Running main action method: clobber 20:24:53 INFO - rmtree: /builds/slave/test/build 20:24:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:24:55 INFO - Running post-action listener: _resource_record_post_action 20:24:55 INFO - [mozharness: 2017-01-21 04:24:55.701030Z] Finished clobber step (success) 20:24:55 INFO - [mozharness: 2017-01-21 04:24:55.701179Z] Running read-buildbot-config step. 20:24:55 INFO - Running pre-action listener: _resource_record_pre_action 20:24:55 INFO - Running main action method: read_buildbot_config 20:24:55 INFO - Using buildbot properties: 20:24:55 INFO - { 20:24:55 INFO - "project": "", 20:24:55 INFO - "product": "firefox", 20:24:55 INFO - "script_repo_revision": "production", 20:24:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:24:55 INFO - "repository": "", 20:24:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:24:55 INFO - "buildid": "20170120190126", 20:24:55 INFO - "pgo_build": "False", 20:24:55 INFO - "basedir": "/builds/slave/test", 20:24:55 INFO - "buildnumber": 63, 20:24:55 INFO - "slavename": "t-yosemite-r7-0383", 20:24:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:24:55 INFO - "platform": "yosemite_r7", 20:24:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:24:55 INFO - "branch": "mozilla-central", 20:24:55 INFO - "repo_path": "mozilla-central", 20:24:55 INFO - "revision": "487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f", 20:24:55 INFO - "stage_platform": "macosx64", 20:24:55 INFO - "builduid": "b407ae89d4a1468a97ffbae90274d769", 20:24:55 INFO - "slavebuilddir": "test" 20:24:55 INFO - } 20:24:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:24:55 INFO - Running post-action listener: _resource_record_post_action 20:24:55 INFO - [mozharness: 2017-01-21 04:24:55.704547Z] Finished read-buildbot-config step (success) 20:24:55 INFO - [mozharness: 2017-01-21 04:24:55.704627Z] Running download-and-extract step. 20:24:55 INFO - Running pre-action listener: _resource_record_pre_action 20:24:55 INFO - Running main action method: download_and_extract 20:24:55 INFO - mkdir: /builds/slave/test/build/tests 20:24: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')]} 20:24:55 INFO - trying https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:24:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:24:57 INFO - Downloaded 1199 bytes. 20:24:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:24:57 INFO - Using the following test package requirements: 20:24:57 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:24:57 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:24:57 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:24:57 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'jsshell-mac.zip'], 20:24:57 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:24:57 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:24:57 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:24:57 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:24:57 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:24:57 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:24:57 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:24:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:24:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:24:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:24:57 INFO - Fetch https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:24:57 INFO - Expected file size: 16348432 20:24:57 INFO - Obtained file size: 16348432 20:25:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:25:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:25:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:25:04 INFO - Fetch https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:25:05 INFO - Expected file size: 16348432 20:25:05 INFO - Obtained file size: 16348432 20:25:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:25:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:25:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:25:12 INFO - Expected file size: 14403476 20:25:12 INFO - Obtained file size: 14403476 20:25:13 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')]} 20:25:13 INFO - trying https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:25:15 INFO - Downloaded 69928450 bytes. 20:25:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:25:15 INFO - mkdir: /builds/slave/test/properties 20:25:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:25:15 INFO - Writing to file /builds/slave/test/properties/build_url 20:25:15 INFO - Contents: 20:25:15 INFO - build_url:https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:25:15 INFO - Running post-action listener: _resource_record_post_action 20:25:15 INFO - Running post-action listener: set_extra_try_arguments 20:25:15 INFO - [mozharness: 2017-01-21 04:25:15.879132Z] Finished download-and-extract step (success) 20:25:15 INFO - [mozharness: 2017-01-21 04:25:15.879250Z] Running populate-webroot step. 20:25:15 INFO - Running pre-action listener: _resource_record_pre_action 20:25:15 INFO - Running main action method: populate_webroot 20:25:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:25:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:25:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:25:15 INFO - '--tppagecycles', 20:25:15 INFO - '7'], 20:25:15 INFO - 'tests': ['tsvgm']}}, 20:25:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:25:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:25:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:25:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:25:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:25:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:25:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['damp', 'tps']}, 20:25:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'tests': ['damp', 'tps']}, 20:25:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['dromaeo_dom']}, 20:25:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:25:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['basic_compositor_video']}, 20:25:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:25:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'other-e10s': {'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['a11yr', 20:25:15 INFO - 'ts_paint', 20:25:15 INFO - 'tpaint', 20:25:15 INFO - 'sessionrestore', 20:25:15 INFO - 'sessionrestore_no_auto_restore', 20:25:15 INFO - 'tabpaint']}, 20:25:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['tsvgx', 20:25:15 INFO - 'tsvgr_opacity', 20:25:15 INFO - 'tart', 20:25:15 INFO - 'tscrollx', 20:25:15 INFO - 'cart', 20:25:15 INFO - 'tsvg_static']}, 20:25:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:25:15 INFO - 'tsvgr_opacity', 20:25:15 INFO - 'tart', 20:25:15 INFO - 'tscrollx', 20:25:15 INFO - 'cart', 20:25:15 INFO - 'tsvg_static']}, 20:25:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'talos_options': ['--disable-e10s'], 20:25:15 INFO - 'tests': ['tp5o']}, 20:25:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:25:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'talos_options': ['--disable-e10s', 20:25:15 INFO - '--xperf_path', 20:25:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:25:15 INFO - 'tests': ['tp5n']}, 20:25:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:25:15 INFO - 'talos_options': ['--xperf_path', 20:25:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:25:15 INFO - 'tests': ['tp5n']}}, 20:25:15 INFO - 'talos.zip': {'path': '', 20:25:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:25:15 INFO - Running post-action listener: _resource_record_post_action 20:25:15 INFO - [mozharness: 2017-01-21 04:25:15.897691Z] Finished populate-webroot step (success) 20:25:15 INFO - [mozharness: 2017-01-21 04:25:15.897829Z] Running create-virtualenv step. 20:25:15 INFO - Running pre-action listener: _resource_record_pre_action 20:25:15 INFO - Running main action method: create_virtualenv 20:25:15 INFO - Creating virtualenv /builds/slave/test/build/venv 20:25:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:25:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:25:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:25:16 INFO - Using real prefix '/tools/python27' 20:25:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:25:16 INFO - Installing distribute.............................................................................................................................................................................................done. 20:25:19 INFO - Installing pip.................done. 20:25:19 INFO - Return code: 0 20:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:25:19 INFO - Reading from file tmpfile_stdout 20:25:19 INFO - Output received: 20:25:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:25:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:25: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')]} 20:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25: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')]} 20:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:25:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:19 INFO - 'HOME': '/Users/cltbld', 20:25:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:19 INFO - 'LOGNAME': 'cltbld', 20:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:19 INFO - 'MOZ_NO_REMOTE': '1', 20:25:19 INFO - 'NO_EM_RESTART': '1', 20:25:19 INFO - 'PAGER': '/bin/cat', 20:25:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:19 INFO - 'PWD': '/builds/slave/test', 20:25:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:19 INFO - 'SHELL': '/bin/bash', 20:25:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:19 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:19 INFO - 'USER': 'cltbld', 20:25:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:19 INFO - 'XPC_FLAGS': '0x0', 20:25:19 INFO - 'XPC_SERVICE_NAME': '0', 20:25:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:20 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 20:25:20 INFO - Cleaning up... 20:25:20 INFO - Return code: 0 20:25:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:25:20 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')]} 20:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:20 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')]} 20:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:20 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 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:20 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 20:25:20 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 20:25:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:20 INFO - 'HOME': '/Users/cltbld', 20:25:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:20 INFO - 'LOGNAME': 'cltbld', 20:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:20 INFO - 'MOZ_NO_REMOTE': '1', 20:25:20 INFO - 'NO_EM_RESTART': '1', 20:25:20 INFO - 'PAGER': '/bin/cat', 20:25:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:20 INFO - 'PWD': '/builds/slave/test', 20:25:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:20 INFO - 'SHELL': '/bin/bash', 20:25:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:20 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:20 INFO - 'USER': 'cltbld', 20:25:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:20 INFO - 'XPC_FLAGS': '0x0', 20:25:20 INFO - 'XPC_SERVICE_NAME': '0', 20:25:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:20 INFO - Downloading/unpacking psutil>=3.1.1 20:25:20 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 20:25:20 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 20:25:20 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 20:25:20 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 20:25:20 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 20:25:20 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 20:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:25:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:25:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:25:23 INFO - Installing collected packages: psutil 20:25:23 INFO - Running setup.py install for psutil 20:25:24 INFO - building 'psutil._psutil_osx' extension 20:25:24 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 20:25:24 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 20:25:24 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 20:25:24 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 20:25:24 INFO - building 'psutil._psutil_posix' extension 20:25:24 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 20:25:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:25:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:25:24 INFO - ^ 20:25:24 INFO - 1 warning generated. 20:25:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:25:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:25:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:25:25 INFO - Successfully installed psutil 20:25:25 INFO - Cleaning up... 20:25:25 INFO - Return code: 0 20:25:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:25 INFO - 'HOME': '/Users/cltbld', 20:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:25 INFO - 'LOGNAME': 'cltbld', 20:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:25 INFO - 'MOZ_NO_REMOTE': '1', 20:25:25 INFO - 'NO_EM_RESTART': '1', 20:25:25 INFO - 'PAGER': '/bin/cat', 20:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:25 INFO - 'PWD': '/builds/slave/test', 20:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:25 INFO - 'SHELL': '/bin/bash', 20:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:25 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:25 INFO - 'USER': 'cltbld', 20:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:25 INFO - 'XPC_FLAGS': '0x0', 20:25:25 INFO - 'XPC_SERVICE_NAME': '0', 20:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:25:25 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 20:25:25 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 20:25:25 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 20:25:25 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 20:25:25 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 20:25:25 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 20:25:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:25:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:25:28 INFO - Installing collected packages: mozsystemmonitor 20:25:28 INFO - Running setup.py install for mozsystemmonitor 20:25:28 INFO - Successfully installed mozsystemmonitor 20:25:28 INFO - Cleaning up... 20:25:28 INFO - Return code: 0 20:25:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:25:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:25:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:28 INFO - 'HOME': '/Users/cltbld', 20:25:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:28 INFO - 'LOGNAME': 'cltbld', 20:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:28 INFO - 'MOZ_NO_REMOTE': '1', 20:25:28 INFO - 'NO_EM_RESTART': '1', 20:25:28 INFO - 'PAGER': '/bin/cat', 20:25:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:28 INFO - 'PWD': '/builds/slave/test', 20:25:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:28 INFO - 'SHELL': '/bin/bash', 20:25:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:28 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:28 INFO - 'USER': 'cltbld', 20:25:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:28 INFO - 'XPC_FLAGS': '0x0', 20:25:28 INFO - 'XPC_SERVICE_NAME': '0', 20:25:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:28 INFO - Downloading/unpacking jsonschema==2.5.1 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:25:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:31 INFO - Downloading functools32-3.2.3-2.tar.gz 20:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:25:31 INFO - warning: no files found matching '*.txt' 20:25:31 INFO - no previously-included directories found matching 'build' 20:25:31 INFO - no previously-included directories found matching 'dist' 20:25:31 INFO - no previously-included directories found matching '.git*' 20:25:31 INFO - Installing collected packages: jsonschema, functools32 20:25:31 INFO - Running setup.py install for functools32 20:25:32 INFO - warning: no files found matching '*.txt' 20:25:32 INFO - no previously-included directories found matching 'build' 20:25:32 INFO - no previously-included directories found matching 'dist' 20:25:32 INFO - no previously-included directories found matching '.git*' 20:25:32 INFO - Successfully installed jsonschema functools32 20:25:32 INFO - Cleaning up... 20:25:32 INFO - Return code: 0 20:25:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:25:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:32 INFO - 'HOME': '/Users/cltbld', 20:25:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:32 INFO - 'LOGNAME': 'cltbld', 20:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:32 INFO - 'MOZ_NO_REMOTE': '1', 20:25:32 INFO - 'NO_EM_RESTART': '1', 20:25:32 INFO - 'PAGER': '/bin/cat', 20:25:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:32 INFO - 'PWD': '/builds/slave/test', 20:25:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:32 INFO - 'SHELL': '/bin/bash', 20:25:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:32 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:32 INFO - 'USER': 'cltbld', 20:25:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:32 INFO - 'XPC_FLAGS': '0x0', 20:25:32 INFO - 'XPC_SERVICE_NAME': '0', 20:25:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:25:32 INFO - Cleaning up... 20:25:32 INFO - Return code: 0 20:25:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:25:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:32 INFO - 'HOME': '/Users/cltbld', 20:25:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:32 INFO - 'LOGNAME': 'cltbld', 20:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:32 INFO - 'MOZ_NO_REMOTE': '1', 20:25:32 INFO - 'NO_EM_RESTART': '1', 20:25:32 INFO - 'PAGER': '/bin/cat', 20:25:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:32 INFO - 'PWD': '/builds/slave/test', 20:25:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:32 INFO - 'SHELL': '/bin/bash', 20:25:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:32 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:32 INFO - 'USER': 'cltbld', 20:25:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:32 INFO - 'XPC_FLAGS': '0x0', 20:25:32 INFO - 'XPC_SERVICE_NAME': '0', 20:25:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:32 INFO - Downloading/unpacking blobuploader==1.2.4 20:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:35 INFO - Downloading blobuploader-1.2.4.tar.gz 20:25:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:25:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:25:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:36 INFO - Downloading docopt-0.6.1.tar.gz 20:25:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:25:36 INFO - Installing collected packages: blobuploader, requests, docopt 20:25:36 INFO - Running setup.py install for blobuploader 20:25:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:25:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:25:36 INFO - Running setup.py install for requests 20:25:37 INFO - Running setup.py install for docopt 20:25:37 INFO - Successfully installed blobuploader requests docopt 20:25:37 INFO - Cleaning up... 20:25:37 INFO - Return code: 0 20:25:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:37 INFO - 'HOME': '/Users/cltbld', 20:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:37 INFO - 'LOGNAME': 'cltbld', 20:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:37 INFO - 'MOZ_NO_REMOTE': '1', 20:25:37 INFO - 'NO_EM_RESTART': '1', 20:25:37 INFO - 'PAGER': '/bin/cat', 20:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:37 INFO - 'PWD': '/builds/slave/test', 20:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:37 INFO - 'SHELL': '/bin/bash', 20:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:37 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:37 INFO - 'USER': 'cltbld', 20:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:37 INFO - 'XPC_FLAGS': '0x0', 20:25:37 INFO - 'XPC_SERVICE_NAME': '0', 20:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-nZAMy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-MTWfdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hEY3Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:37 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Azp44x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xi_a1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-GOTHI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-rJtPQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-q7lLF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-x48cy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-13S6_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-m_0kej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-203mH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:25:38 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-phxYQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:25:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-7JgBSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-GmqeJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:25:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-5pjsSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:25:39 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-siZKwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:25:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:25:39 INFO - Running setup.py install for manifestparser 20:25:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:25:39 INFO - Running setup.py install for mozcrash 20:25:39 INFO - Running setup.py install for mozdebug 20:25:39 INFO - Running setup.py install for mozdevice 20:25:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Running setup.py install for mozfile 20:25:40 INFO - Running setup.py install for mozhttpd 20:25:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Running setup.py install for mozinfo 20:25:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Running setup.py install for mozInstall 20:25:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Running setup.py install for mozleak 20:25:40 INFO - Running setup.py install for mozlog 20:25:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:25:40 INFO - Running setup.py install for moznetwork 20:25:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Running setup.py install for mozprocess 20:25:41 INFO - Running setup.py install for mozprofile 20:25:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Running setup.py install for mozrunner 20:25:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Running setup.py install for mozscreenshot 20:25:41 INFO - Running setup.py install for moztest 20:25:41 INFO - Running setup.py install for mozversion 20:25:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:25:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:25:41 INFO - Cleaning up... 20:25:42 INFO - Return code: 0 20:25:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:42 INFO - 'HOME': '/Users/cltbld', 20:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:42 INFO - 'LOGNAME': 'cltbld', 20:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:42 INFO - 'MOZ_NO_REMOTE': '1', 20:25:42 INFO - 'NO_EM_RESTART': '1', 20:25:42 INFO - 'PAGER': '/bin/cat', 20:25:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:42 INFO - 'PWD': '/builds/slave/test', 20:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:42 INFO - 'SHELL': '/bin/bash', 20:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:42 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:42 INFO - 'USER': 'cltbld', 20:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:42 INFO - 'XPC_FLAGS': '0x0', 20:25:42 INFO - 'XPC_SERVICE_NAME': '0', 20:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-I7A_Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-EEcQET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zJgvuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-RnMb_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-VOxczG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-t3c2Ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:42 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)) 20:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-dmRuyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-1sGZxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Rbu6lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-y6E61e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-FcoqGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-yO_ceX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-QjaPLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-RP6swi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-kuWPSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:43 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)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-IjHLbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:25:43 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)) 20:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:25:43 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-rxrPwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:25:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25:46 INFO - Downloading blessings-1.6.tar.gz 20:25:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:25:46 INFO - Installing collected packages: blessings 20:25:46 INFO - Running setup.py install for blessings 20:25:46 INFO - Successfully installed blessings 20:25:46 INFO - Cleaning up... 20:25:47 INFO - Return code: 0 20:25:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:25:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:25:47 INFO - Reading from file tmpfile_stdout 20:25:47 INFO - Current package versions: 20:25:47 INFO - blessings == 1.6 20:25:47 INFO - blobuploader == 1.2.4 20:25:47 INFO - docopt == 0.6.1 20:25:47 INFO - functools32 == 3.2.3-2 20:25:47 INFO - jsonschema == 2.5.1 20:25:47 INFO - manifestparser == 1.1 20:25:47 INFO - mozInstall == 1.12 20:25:47 INFO - mozcrash == 1.0 20:25:47 INFO - mozdebug == 0.1 20:25:47 INFO - mozdevice == 0.49 20:25:47 INFO - mozfile == 1.2 20:25:47 INFO - mozhttpd == 0.7 20:25:47 INFO - mozinfo == 0.9 20:25:47 INFO - mozleak == 0.1 20:25:47 INFO - mozlog == 3.4 20:25:47 INFO - moznetwork == 0.27 20:25:47 INFO - mozprocess == 0.24 20:25:47 INFO - mozprofile == 0.28 20:25:47 INFO - mozrunner == 6.13 20:25:47 INFO - mozscreenshot == 0.1 20:25:47 INFO - mozsystemmonitor == 0.3 20:25:47 INFO - moztest == 0.8 20:25:47 INFO - mozversion == 1.4 20:25:47 INFO - psutil == 3.1.1 20:25:47 INFO - requests == 1.2.3 20:25:47 INFO - wsgiref == 0.1.2 20:25:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:48 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 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:48 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 20:25:48 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 20:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:48 INFO - 'HOME': '/Users/cltbld', 20:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:48 INFO - 'LOGNAME': 'cltbld', 20:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:48 INFO - 'MOZ_NO_REMOTE': '1', 20:25:48 INFO - 'NO_EM_RESTART': '1', 20:25:48 INFO - 'PAGER': '/bin/cat', 20:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:48 INFO - 'PWD': '/builds/slave/test', 20:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:48 INFO - 'SHELL': '/bin/bash', 20:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:48 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:48 INFO - 'USER': 'cltbld', 20:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:48 INFO - 'XPC_FLAGS': '0x0', 20:25:48 INFO - 'XPC_SERVICE_NAME': '0', 20:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 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)) 20:25:48 INFO - Cleaning up... 20:25:48 INFO - Return code: 0 20:25:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:25:48 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')]} 20:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:48 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')]} 20:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:48 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 0x105d54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec0bd749e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, '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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 20:25:48 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 20:25:48 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 20:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:48 INFO - 'HOME': '/Users/cltbld', 20:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:48 INFO - 'LOGNAME': 'cltbld', 20:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:48 INFO - 'MOZ_NO_REMOTE': '1', 20:25:48 INFO - 'NO_EM_RESTART': '1', 20:25:48 INFO - 'PAGER': '/bin/cat', 20:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:48 INFO - 'PWD': '/builds/slave/test', 20:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:48 INFO - 'SHELL': '/bin/bash', 20:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:25:48 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:48 INFO - 'USER': 'cltbld', 20:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:48 INFO - 'XPC_FLAGS': '0x0', 20:25:48 INFO - 'XPC_SERVICE_NAME': '0', 20:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:25:49 INFO - Cleaning up... 20:25:49 INFO - Return code: 0 20:25:49 INFO - Running post-action listener: _resource_record_post_action 20:25:49 INFO - Running post-action listener: _start_resource_monitoring 20:25:49 INFO - Starting resource monitoring. 20:25:49 INFO - [mozharness: 2017-01-21 04:25:49.119084Z] Finished create-virtualenv step (success) 20:25:49 INFO - [mozharness: 2017-01-21 04:25:49.120016Z] Running install step. 20:25:49 INFO - Running pre-action listener: _resource_record_pre_action 20:25:49 INFO - Running main action method: install 20:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:25:49 INFO - Reading from file tmpfile_stdout 20:25:49 INFO - Detecting whether we're running mozinstall >=1.0... 20:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:25:49 INFO - Reading from file tmpfile_stdout 20:25:49 INFO - Output received: 20:25:49 INFO - Usage: mozinstall [options] installer 20:25:49 INFO - Options: 20:25:49 INFO - -h, --help show this help message and exit 20:25:49 INFO - -d DEST, --destination=DEST 20:25:49 INFO - Directory to install application into. [default: 20:25:49 INFO - "/builds/slave/test"] 20:25:49 INFO - --app=APP Application being installed. [default: firefox] 20:25:49 INFO - mkdir: /builds/slave/test/build/application 20:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:26:11 INFO - Reading from file tmpfile_stdout 20:26:11 INFO - Output received: 20:26:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:26:11 INFO - Running post-action listener: _resource_record_post_action 20:26:11 INFO - [mozharness: 2017-01-21 04:26:11.542663Z] Finished install step (success) 20:26:11 INFO - [mozharness: 2017-01-21 04:26:11.542818Z] Running run-tests step. 20:26:11 INFO - Running pre-action listener: _resource_record_pre_action 20:26:11 INFO - Running main action method: run_tests 20:26:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:26:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:26:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:26:11 INFO - 2017-01-20 20:26:11.557 screenresolution[1623:7343] starting screenresolution argv=screenresolution get 20:26:11 INFO - 2017-01-20 20:26:11.559 screenresolution[1623:7343] Display 0: 1600x1200x32@60 20:26:11 INFO - 2017-01-20 20:26:11.565 screenresolution[1624:7346] starting screenresolution argv=screenresolution list 20:26:11 INFO - Available Modes on Display 0 20:26:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:26:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:26:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:26:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:26:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:26:11 INFO - Intel Iris: 20:26:11 INFO - Chipset Model: Intel Iris 20:26:11 INFO - Type: GPU 20:26:11 INFO - Bus: Built-In 20:26:11 INFO - VRAM (Dynamic, Max): 1536 MB 20:26:11 INFO - Vendor: Intel (0x8086) 20:26:11 INFO - Device ID: 0x0a2e 20:26:11 INFO - Revision ID: 0x0009 20:26:11 INFO - Displays: 20:26:11 INFO - Display: 20:26:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:26:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:26:11 INFO - Display Serial Number: 1600x1200 60 20:26:11 INFO - Main Display: Yes 20:26:11 INFO - Mirror: Off 20:26:11 INFO - Online: Yes 20:26:11 INFO - Rotation: Supported 20:26:11 INFO - Return code: 0 20:26:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:26:11 INFO - Python 2.7.3 20:26:11 INFO - Return code: 0 20:26:11 INFO - grabbing minidump binary from tooltool 20:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:26:11 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 0x105b32d30>, '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 0x105a59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b27be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:26:11 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 20:26:11 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 20:26:11 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 20:26:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:26:11 INFO - Return code: 0 20:26:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:26:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:26:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:26:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:26:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:26:11 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/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 20:26:11 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/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 20:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g9CtjTBzB8/Render', 20:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:26:11 INFO - 'HOME': '/Users/cltbld', 20:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:26:11 INFO - 'LOGNAME': 'cltbld', 20:26:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:26:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:26:11 INFO - 'MOZ_NO_REMOTE': '1', 20:26:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:26:11 INFO - 'NO_EM_RESTART': '1', 20:26:11 INFO - 'PAGER': '/bin/cat', 20:26:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:26:11 INFO - 'PWD': '/builds/slave/test', 20:26:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:26:11 INFO - 'SHELL': '/bin/bash', 20:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ly9SRWhrFn/Listeners', 20:26:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 20:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:26:11 INFO - 'USER': 'cltbld', 20:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:26:11 INFO - 'XPC_FLAGS': '0x0', 20:26:11 INFO - 'XPC_SERVICE_NAME': '0', 20:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:26:11 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/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 20:26:12 INFO - mozversion application_buildid: 20170120190126 20:26:12 INFO - mozversion application_changeset: 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f 20:26:12 INFO - mozversion application_display_name: Nightly 20:26:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:26:12 INFO - mozversion application_name: Firefox 20:26:12 INFO - mozversion application_remotingname: firefox 20:26:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:26:12 INFO - mozversion application_vendor: Mozilla 20:26:12 INFO - mozversion application_version: 53.0a1 20:26:12 INFO - mozversion platform_buildid: 20170120190126 20:26:12 INFO - mozversion platform_changeset: 487a4e43eb9d1f04a5d8e3dd183fe38dbe105e1f 20:26:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:26:12 INFO - mozversion platform_version: 53.0a1 20:26:12 INFO - using testdate: 1484972772 20:26:12 INFO - actual date: 1484972772 20:26:12 INFO - starting webserver on 'localhost:49241' 20:26:12 INFO - SUITE-START | Running 2 tests 20:26:12 INFO - TEST-START | dromaeo_css 20:26:12 INFO - operating with platform_type : mac_ 20:26:12 INFO - Initialising browser for dromaeo_css test... 20:26:12 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpmXaALn/profile http://localhost:49241/getInfo.html) 20:26:14 INFO - PROCESS | 1634 | 2017-01-20 20:26:14.914 plugin-container[1636:7516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 20:26:14 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:14 INFO - PROCESS | 1634 | 2017-01-20 20:26:14.916 plugin-container[1636:7516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 20:26:14 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:14 INFO - PROCESS | 1634 | 2017-01-20 20:26:14.916 plugin-container[1636:7516] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:26:15 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 20:26:15 INFO - PROCESS | 1634 | colorDepth:24 20:26:15 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 20:26:15 INFO - PROCESS | 1634 | __metrics 20:26:15 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3273: TypeError: this.tabs is undefined 20:26:15 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 20:26:15 INFO - TEST-INFO | 1634: exit 0 20:26:15 INFO - Browser initialized. 20:26:15 INFO - Running cycle 1/1 for dromaeo_css test... 20:26:15 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpmXaALn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:26:18 INFO - PROCESS | 1637 | 2017-01-20 20:26:18.440 plugin-container[1638:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 20:26:18 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:18 INFO - PROCESS | 1637 | 2017-01-20 20:26:18.440 plugin-container[1638:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c2b, name = 'com.apple.CFPasteboardClient' 20:26:18 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:18 INFO - PROCESS | 1637 | 2017-01-20 20:26:18.440 plugin-container[1638:7645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:26:28 INFO - PROCESS | 1637 | RSS: Main: 232824832 20:26:28 INFO - PROCESS | 1637 | 20:29:31 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 20:29:32 INFO - PROCESS | 1637 | RSS: Main: 261787648 20:29:32 INFO - PROCESS | 1637 | 20:31:16 INFO - PROCESS | 1637 | ************************* 20:31:16 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 20:31:16 INFO - PROCESS | 1637 | 20:31:16 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:31:16 INFO - PROCESS | 1637 | Full stack: 20:31:16 INFO - PROCESS | 1637 | ************************* 20:32:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 20:32:36 INFO - PROCESS | 1637 | RSS: Main: 261578752 20:32:36 INFO - PROCESS | 1637 | 20:35:37 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 20:35:38 INFO - PROCESS | 1637 | RSS: Main: 270987264 20:35:38 INFO - PROCESS | 1637 | 20:38:40 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 20:38:40 INFO - PROCESS | 1637 | RSS: Main: 267333632 20:38:40 INFO - PROCESS | 1637 | 20:41:43 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 20:41:44 INFO - PROCESS | 1637 | RSS: Main: 272470016 20:41:44 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 20:44:47 INFO - PROCESS | 1637 | RSS: Main: 275644416 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | __start_tp_report 20:44:47 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 20:44:47 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 20:44:47 INFO - PROCESS | 1637 | |i|pagename|runs| 20:44:47 INFO - PROCESS | 1637 | |0;dojo.html;3378.1048022274085;3388.864445422183;3426.22909845285;3429.7084747796434;3435.673611006954;4742.810287588501;5481.534069604083;5621.4940655341015;5683.460071293221;5767.250257466534;4327.4374331336885;5312.485941506671;5644.350899646542;5689.4879460848515;5730.05454100073;4376.868693939171;4772.475027746962;4980.501949805009;5195.766190521416;5321.893562128756;5166.612504062192;5294.1529355303155;5317.6809391436445;5322.600804939626;5325.840224793263;5118.07874582575;5132.666376685504;5233.581313494911;5275.551578115865;5296.152615581488;5170.482951704799;5175.430702622746;5176.327077479904;5182.896342073175;5183.118869792144;4624.306354046887;4959.6528243023;5125.1287281162295;5140.074786538422;5145.202493613496;4520.299353600164;4994.35073440454;5481.506664400184;5777.971110144465;5795.130730390433;6257.593256438363;6283.748650053993;6294.464970477515;6296;6308.179936608258;6204;6217.098520714516;6228.314885362606;6229.87540249197;6304.968475157641;5820.708964551755;5822.679752613647;5830.883382332372;5897.941020589825;5924.348321684612;6152.230971128609;6340.1440805490665;6341.112244285804;6343.841403964845;6345.302016778151;6156.507479401637;6165.938340616626;6174.166487524217;6180.443760061616;6216.378362163748;6140.565599444524;6145.108959200891;6208.2239720034995;6229.377062293825;6236.126942228092;6107.006761825686;6152.723127459275;6174.475169610633;6205.782797602063;6211.875762484816;5192.844214673566;6158.291796443376;6168.599041062316;6180.783672571439;6186.381361863868;4890.119778439844;4936.654434189644;4970.90058198834;4987.526185012418;5008.424031236382;4406.9559304407185;4409.985703288262;4460;4466.173757854743;4553.795079221443;5023.2214006829;5809.738561764731;5815.9709201453725;5838.153467747153;5885.735141918624;6140.048292514667;6280.842978925472;6288.748450061947;6305.4009869062365;6316.526260530479;5602.719864006784;5642.435756424325;5687.402822703557;5694.829155125353;5723.713814309352;5390.353157621033;5434.375046869582;5462.508374246337;5466.234727138204;5520.1443776214755;6299.6850157491945;6334.5249106317215;6340.651264180515;6348.111264422984;6351.809445716636;6117.796956032597;6197.535184861244;6226.719797609209;6267.373262673696;6278.937210627835;5245.6852588844795;6221.813345599639;6252.718627661857;6283.5601507895835;6316.684165791784;6090.208272924491;6125.84685382869;6146.508279337731;6243.37566243372;6272.247330320391;6188.071789231652;6229.065640154013;6301.842453938688;6308.14839996613;6314.842128946813;6014.398560143951;6072.605280656743;6166.784162554289;6204.844878878064;6206.875862482635;5956.910646340395;6092.299385570716;6099.84750381244;6114.205153330038;6122.032243765608;5344.706041167773;5792.623479473821;5824.330202026652;5844.006518891713;5997.700114994145;6116.26604807426;6160.3223645398075;6174.38256174379;6180.103884936749;6204.255489341308;5854.677992710442;6029.909551356821;6030.5778595497895;6032.276126864805;6054.152418661303 20:44:47 INFO - PROCESS | 1637 | |1;ext.html;465.94231095413414;472.9314249433832;491.82048552278417;498.3372115086935;505.95699365553924;40075.79772606823;42100.57899421009;42237.15525689488;44128.11743765123;44185;44010.67967960966;44335;44487.55512444874;44491;44676.77661611696;8164.795880102985;9326.673566425177;9574.664886728966;10954.507047182897;11328.150388720878;11086.2239643225;11168.218224724273;11169;11192.384418857;11226.438678066104;11274.267172633794;11302.039326657245;11306.095512358992;11328.376939268337;11330.943345283262;11233.66299011031;11242.99999999996;11245.943770281177;11270.830937535944;11298.265612735227;10931.726706832313;11026.228164028522;11026.614068507564;11036.613718519893;11071.50178241973;37531.24937501262;37797.433038504445;37810.81094594537;37843.24313513714;37868.43197352042;3447.517347571342;3978.5026871641044;3992.1449700464896;4077.4903137107863;4171.249175148472;29069.854650726822;29540.40919181626;29553;29596.70403295961;29597;3149.1497295730023;3302.554155189067;3337.732981361485;3341.4820702791108;3358.932821343584;475577;476615;478155;479983;480051;57922.7103864478;58214;58537.70731146317;58597.12104318439;58823.52941176419;59806.10290845641;59845.70077149587;60166.69916650389;60292;60484.395156047874;15607;15683.294251758698;15850.44523441674;15871;15915.363385464452;10938.671839844817;11222.943885280521;12089.697757556132;12347.073969452327;12349.567765128177;16492.505224843273;18029.368972085915;18271.908640456713;18312;18383.632327353378;6926.445884329241;8167.550784706898;8317.41778075541;8356.164383561596;8436.831263374697;885.6014793342893;888.9199972002551;908.1281969309537;909.8316811389914;926.3746970794687;30464.69535304663;30658.84670576633;31105.68894311028;31369.37261254808;31522;1049.3913530152493;1055.2613170780485;1092.8688557373166;1094.8248280275118;1213.648042067808;15655.217239137997;16055;16075.678486430206;16263.18684065792;16352.75470867938;604675;610572;611204.9439752772;611631;613848;49125.75437122863;61443.162899524476;62130.68934655298;62334.68832655808;62377;1402.4670625162578;1440.107133577189;1455.992720036393;1457.3951809998732;1463.531669865654;23919.713946121243;29252.70747292585;29483.705162948954;30459.89559556196;30734.53898191484;43214.271429142485;50326.74836625793;52796.73601631967;52947;53063.73468132634;491684;505177.4741126271;552532;556473.4352656583;556740;22610.547789044456;23551.057957682187;26700.06549770749;26928.78820453045;27009.459810804066;38216;48525.757371212916;50203;50338.99322013466;50674;39442.40836387399;39965.80017100012;40380.394294085025;40891;41767;39326.01684957899;40290.798546007085;40810.04921416112;41012.38481422721;41322.173556529306;37285.6271437293;38033.80983095067;38515.6148438512;38780.80609597047;38797.8060109709;31086.844565777028;32949.67050329466;33237.83667571624;34015.14962125967;34057.318853622295;24110.638340424557;24186;24203.974503187103;24367.781610919737;24481.52964886032 20:44:47 INFO - PROCESS | 1637 | |2;jquery.html;387.99418008729856;390.324738202909;405.19770853709656;405.94316795648604;421.1850070114329;18059.638807223848;20328.288509902155;20512.38462846113;21666.566668666615;21731.67402488962;21292.787072129275;21464;21510.924453777327;21880.12479500818;22201.777982220174;5746.9712651436785;6046.39536046394;6048.45563899249;6053.303870054956;6075.605085669417;5683.204351390808;5917.112433135021;5963.1055341698875;6027.065804800263;6039.184710064151;6069.30203026651;6078.513718902477;6082.239720034996;6082.726277317532;6110.358412366704;5891.733277345348;5986.311574168983;6080.2703675559;6329.810105696836;6561.245456772484;5851.561132915049;5924.377940316248;5928.940710592882;5935.762569497215;5952.58331916766;13166.539171128965;13315.733685326239;13435.529756458476;13445.126066805724;13471.191728496322;2524.1544082732307;2527.747225277476;2608.7303646633695;2621.6722909636296;2624.1471521755507;9884.901150988542;9916.504174791231;10122.89877101227;10294.330868493524;10542.578296868116;1779.2303909637783;1792.507060558344;1796.6316905034437;1831.8900865948085;1831.9633607327912;131270;138322;138356;140791.88812167826;142793;11980.585533123018;11982.400879955967;12157.452914618863;12213.603102883133;12270.754584908253;15674.843251567338;15774.60563485899;15778.368865245262;15789;15853.365865365256;8438.282745966662;8644.13558644143;8746.475211487204;8884.333674974403;8889.555522223864;8397.370197235232;8434.746957591282;8450.577471126418;8586.09845966177;8601.096884827128;3994.78028708418;4723.834665786681;4762.904741905212;4849.612031037508;4878.07316609843;1983.5834474760172;2006.3980805758215;2027.7363942687357;2034.3795142481686;2038.327351973845;300.69629674029386;417.3156024120445;1034.8551202831609;1035.4822588705647;1057.027534668092;11130.721731956766;11672.299662020307;12007.759844803055;12144.696382590506;12403.317817520123;439.2313451459945;449.9437570303712;450.58095970746865;455.0920912779027;464.51226212476763;5277.551408130275;5955.40445955401;6017.638941663514;6091.451769340781;6177.567570270128;697888;723245;729153;749880.7589430815;749942;156637.2168139152;158622;158860;162135.18932405263;162493.18753406394;864.3560547392233;1002.478711070254;1067.9038886500252;1071.3893080943787;1075.0056198016803;19749.506262343555;19959.323379136214;20041.098150582966;20064.98659838591;20237.494062647962;56582;57022;57842.55393615193;60979.78040439255;61663.38336616756;87839.1216087831;89088;89294;89350.10649893418;89389.55305223432;7880.921190788248;8104.554249516334;8395.286400655768;8612.569371531523;8769.298456123368;11522.596709115141;13257;13496.32518374097;13664.385102670205;13718.17690938547;10985.79156292823;11287.171047725127;11378.374189419661;11688.824667630162;11743.354115523729;11428.771424571629;11495.597654082067;11738.823917641072;11971.820422693841;11975;9365.438073715599;11463.713407164554;11638.185327027028;11774.940255377058;12019.038476922;7189.388901943394;8375.916240837514;8625.223729864343;8657.783555411164;8753.299736020976;9710.320277580502;11840.881591183977;11977.580784672495;11984.520619175484;12012.879871201525 20:44:47 INFO - PROCESS | 1637 | |3;mootools.html;1321.2468892731142;1343.6439343573952;1449.384011794987;1451.404923981168;1460;7015.403690686291;7588.241175882409;7845.450818442711;8188.590570471475;8608.354373422002;8080.474769140016;8403.663853445854;8418.957905210465;8501.064882862896;8517.23344898959;1904.6190761847618;2283.5889539882855;2324.000679707725;2333.4982978659586;2344.15610380263;1836.4215272189285;2077.0757013129164;2148.6132496150685;2279.6010698127843;2310.4223944013997;2027.614753196888;2230.565039817236;2317.9569193862744;2339.040993192791;2343.7070366204225;1972.2505447929766;2276.7723227677266;2298.482841360689;2322.674825524428;2327.7904988551113;2313.5141620259765;2337.649352597107;2351.541449417364;2377.4531857672832;2385.403649087728;8513;8597.785055373666;8601.698940537051;8617.784555386166;8620.181082797124;5166.147585648364;5308.52223299901;5317.218731724877;5319.042572336978;5320.17537281722;3401.370746411897;3523.3481805865995;3539.0444579963264;3618.294432585647;3637.854485820564;1222.7798996180684;1224.2165014390707;1264.6142926407442;1271.313490715022;1272.5291642092393;10536.525856336484;10654.467276636136;10805.405702686428;10845.457727113691;10852.403117828517;4425.336199570026;4726.71639701619;4736.7631618418945;4768.976155119202;4793.856184314476;9669.98465161162;9673;9692.127708506127;9700.514974251259;9722.2708296878;5319.202119682079;5321.6540924839765;5333.6533125347505;5336.893262134736;5337.359516857924;4258.063226090255;4275.358696195595;4724.149653062482;4828.130936431476;4936.407631084221;3900.8687480630865;3972.364421692573;3990.7605543667473;4006.699497537638;4038.2932986727205;1207.3118322556059;1249.4002878618132;1303.8565757766637;1336.7460182565476;1341.5170538606094;765.4718244411339;766.3447752171925;773.4353921637236;799.6161842315605;820.4625969989667;5706.572007099484;5716.942830571724;5768.077107662753;5782.074868021181;5844.853878653068;574.7496618391028;581.4156772443667;588.9558283128782;589.9056151015902;703.9929600703928;3727.1613886875234;3830.272248272875;3932.4494570759543;3935.2916475034767;4036.616521430459;6988.545744526589;9779.706608801747;9823.508824558743;9838.75403114928;9901.554430050664;9744.561494732754;9825.803483930424;9884.55519501624;9901.158401535806;9910;6557.639329836809;7634.6946122155605;7837.608119593998;7838.5296882188395;7840.764777056697;4822.493638167942;5467.781288748564;5553.916691249554;5650.502984140908;5670.489655930986;8934.642614295355;8988.775280618036;9087.954560227421;9159.771005724644;9182.311326650395;9148.496832674267;9304.395214311047;9339.066093390607;9441.858372124561;9460.716178514656;7058.188308344606;7104.573725576482;7148.106486689164;7151.141862976476;7185.856282874418;7752.612369381395;7773.533587984739;7794.6882124714375;7852.097008844056;7864.52812831209;6574.506911981716;7152.356287934111;7220.530665506935;7290.453216008927;7291.817704557428;7097.25478824716;7128.928710712827;7137.214906360193;7183.245759195209;7264.455165862476;6086.178365920544;6250.781222657185;6408.871822563429;6454.48364130859;6461.224653041665;4746.691465054761;4984.700917944935;5019.498050194951;5117.411497677815;5190.948090519047;4137.275976704125;4688.203005489143;4745.620350372031;4830.589399901048;4914.557689807934 20:44:47 INFO - PROCESS | 1637 | |4;prototype.html;310.492345015899;353.30222809950334;360.02433405471174;361.7807987083529;376.23811781143166;4968.453470118284;5183.818566350178;5467.781288748451;5538.556915446765;5567.276254086968;4960.553550180483;5364.517193452589;5399.919001214975;5439.646422982503;5476.139321778147;3361.193313604742;4182.309918863384;4277.914441711164;4287.935680964788;4289.206496798087;3431.8627254909898;3952.8931899068307;3959.5050618672667;4223.162924127999;4331.7617531035785;3987.342088555385;4008.4388185654034;4135.545090040108;4178.916421671564;4250.638695710868;3883.8446462141483;4055.452513910614;4216.536181020086;4257.76582287974;4262.829486820523;3250.5774249347432;3282.885099021523;3473.3053389321985;3664.725145614063;3770.0763312988493;4655;4990.525900039491;5247.317848679647;5285.894282114336;5298.397089978813;4303.397524346594;4552.385427967249;4622.838200662961;4891.584215341701;4904.264360345941;4642.907141857144;4783.617310615177;5657.698729292286;5754.769809207627;5756.30924289088;3217.9034628961167;3538.8230588470733;3835.6931445484292;3953.011747063234;4198.265303571863;5749.367569567345;6048.395160483917;6053.545984051214;6059.515238780887;6064.332923378425;4244.981204510895;4253.107886637116;4253.6600970694135;4255.510616279167;4259.254630439723;3865.4201869719204;4070.898227544276;4296.742195468282;4309.353596960418;4314.439122914001;4123.402106694512;4859.757012149378;4920.400869717356;4922.483139270325;4931.58081563064;5169.379674439091;5445.992491389105;5598.188262701967;5787.135367992786;5817.360090390053;1144.7824913266452;1169.2049406403557;1196.5333519927235;1397.6925076483158;1438.892083093772;855.5978690015683;956.8803899512561;982.916452101565;984.389678399397;986.4623780039896;4240.448741663626;4266.930538688734;4742.956549559091;5155.226715992556;5189.55888749453;4905.9264111038365;5342.958123165945;5590.847523703322;5620.550355971594;5627.831165065055;4326.913461730748;4717.551832575899;4731.073209236326;4771.6898401603785;5016.2224855147515;2698.9069426882143;2793.7764978801647;2835.036087730182;2964.644242690848;2982.2395289201113;5039.573090298632;5215.608829337739;5327.440618735054;5344.652597581145;5846.427571868364;5270.998510283111;5404.243405923174;5801.390853960358;5806.796762113306;5864.296284445809;5477.534409575231;5852.912206316909;5889.970550147222;5906.911396329058;5918.319393269829;5077.644564880423;5607.467295603744;5662.745176466987;5698.601097923106;5699.059655156852;4844.0554091951735;5212.765425555935;5324.414314425333;5631.746571404215;5680.602357834912;5548.778048878;5752.7698892043845;6032.607880487754;6135.601185922901;6149.661768602769;3413.4367829307885;3524.933308022037;3964.544077431017;3988.8005599720477;4001.739886907458;4718.764061796828;5361.731913404236;5836.328822185334;5880.558958078076;5901.996116582523;4654.976725116353;5215.530602245816;5666.291713535808;5742.712864356849;5764.711764411679;5058.873528161826;5708.745045707005;5718.828435146952;5731.512821410227;5762.913556296743;4711.528847115261;4834.444038935568;5303.2840566524565;5594.86012849682;5630.662160270397;5342.931413717194;5701.515371193329;5718.971405142947;5722.7710891563875;5769;4910.558049775537;5107.821226257063;5183.663061900965;5565.916511252253;5604.8038318658655 20:44:47 INFO - PROCESS | 1637 | |5;yui.html;711.1040089487244;761.1475147834425;783.6669415498412;814.7189219719195;835.335907953177;12934.805977910339;15055.849441505581;15580.688386232268;15637.21813909303;15646.84353156468;11874.228175168608;14520.27398630067;14554.126752394837;14572.781408278885;14589.197594132318;674.4132604633975;697.905782719334;836.1429534726893;848.8005318750089;865.9523726195089;862;880.4981160738382;880.5465185429509;881.607684580362;884.2263019857625;936.929730270229;937.8827646544182;940.0364626258106;940.3323640215456;941.1388579449796;768.8885111658778;926.0600490502112;926.8702381666573;927.9443233406017;930.6370515498961;679.6737565968363;689.5896941319957;692.0829900382014;692.2454524568194;693.1647364925253;14914.477993270184;15079.321430535652;15141.772873406908;15278.923605382013;15897.28462219203;12353;13049.303590533265;14225.715485690333;14611.853881461262;14840.332185051699;11667.88332116685;11782.646520604396;11910.821337679941;11926.463309151022;12233.266004039786;3721.4045752679713;3734.514513113278;3735.701143908481;3755.8732380285805;3866.4007078902814;90207;113577;115467.42266288615;116196;116247.67504649944;9253.444793312423;11357.716057098638;11412.087033037336;11423.485943132579;11815.409229538489;22980.965856536495;23444.59332440059;23765.881170594035;23788.88105559461;23813.880930595584;6577.769778057745;7719.807004824925;7738.264864837832;7873.409494287951;7956.562389068657;4193.329067349209;4313.331433627793;4314;4348.43470348859;4391.494978077498;3380.661933806649;3441.9655803442147;3456.1359660084977;3471.288385880888;3498.177928186874;631.766246488802;641.1280658304702;648.7437462202404;658.4666420199653;658.805652332563;640.4716109209827;644.3331152257391;646.4549726154495;649.3603800256743;662.5262936999972;9820.214382849355;9845.113939745313;9852;9973.301868869255;10013.799724005625;645.9870802583922;647.9416852483299;675.7297081167474;681.8125015620644;687.1445050911647;4684.812607495662;4785.234362501983;4799.928001079987;4821.132196204647;4844.588210002189;90380;94927.57608635876;103405.48297258465;103855.92288154346;104728;88518.55740721384;88982;89764;90464;92576;76863;77458.61270693611;77491.83762243458;77904.44191116259;78601.60699196582;9214.907850921672;9243.352965292634;9359.719208423758;9382.765430864283;9403.764905877408;44867.775661121486;45359.77320113379;45794.542054579026;47008.29487557621;51765;54427.455725443826;59043.52391190254;63133;64219.357806423206;68366.9744953836;12686.555970540987;13262.80105798433;13410.32948352598;13438.46246150143;14043.016988811094;11843;14989.700205995601;15264.782052172568;15403.998740081859;15776.605584860012;13711.520096796963;14053.156810591398;14092.033055877617;14189;14330.49843255481;13382.397792099573;13860.445582177003;13963.441462341392;14047.157170569799;14353.497627582985;11662.94168529152;13848.991896174797;14026.579202623938;14366;14459.349329280418;8714.956425217833;11025.283356581796;11324.177376426771;11467;11579.942100289782;8697.652093916426;10167.288289819642;11167;11357.37534435614;11389.658310250707 20:44:47 INFO - PROCESS | 1637 | __end_tp_report 20:44:47 INFO - PROCESS | 1637 | __start_cc_report 20:44:47 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,387 20:44:47 INFO - PROCESS | 1637 | __end_cc_report 20:44:47 INFO - PROCESS | 1637 | __startTimestamp1484973887502__endTimestamp 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | ------- Summary: start ------- 20:44:47 INFO - PROCESS | 1637 | Number of tests: 6 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5697.52 Median:5956.91 stddev:675.09 (11.3%) stddev-sans-first:652.18 20:44:47 INFO - PROCESS | 1637 | Values: 3378.1 3388.9 3426.2 3429.7 3435.7 4742.8 5481.5 5621.5 5683.5 5767.3 4327.4 5312.5 5644.4 5689.5 5730.1 4376.9 4772.5 4980.5 5195.8 5321.9 5166.6 5294.2 5317.7 5322.6 5325.8 5118.1 5132.7 5233.6 5275.6 5296.2 5170.5 5175.4 5176.3 5182.9 5183.1 4624.3 4959.7 5125.1 5140.1 5145.2 4520.3 4994.4 5481.5 5778.0 5795.1 6257.6 6283.7 6294.5 6296.0 6308.2 6204.0 6217.1 6228.3 6229.9 6305.0 5820.7 5822.7 5830.9 5897.9 5924.3 6152.2 6340.1 6341.1 6343.8 6345.3 6156.5 6165.9 6174.2 6180.4 6216.4 6140.6 6145.1 6208.2 6229.4 6236.1 6107.0 6152.7 6174.5 6205.8 6211.9 5192.8 6158.3 6168.6 6180.8 6186.4 4890.1 4936.7 4970.9 4987.5 5008.4 4407.0 4410.0 4460.0 4466.2 4553.8 5023.2 5809.7 5816.0 5838.2 5885.7 6140.0 6280.8 6288.7 6305.4 6316.5 5602.7 5642.4 5687.4 5694.8 5723.7 5390.4 5434.4 5462.5 5466.2 5520.1 6299.7 6334.5 6340.7 6348.1 6351.8 6117.8 6197.5 6226.7 6267.4 6278.9 5245.7 6221.8 6252.7 6283.6 6316.7 6090.2 6125.8 6146.5 6243.4 6272.2 6188.1 6229.1 6301.8 6308.1 6314.8 6014.4 6072.6 6166.8 6204.8 6206.9 5956.9 6092.3 6099.8 6114.2 6122.0 5344.7 5792.6 5824.3 5844.0 5997.7 6116.3 6160.3 6174.4 6180.1 6204.3 5854.7 6029.9 6030.6 6032.3 6054.2 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:68012.64 Median:37804.12 stddev:144841.51 (383.1%) stddev-sans-first:145159.05 20:44:47 INFO - PROCESS | 1637 | Values: 465.9 472.9 491.8 498.3 506.0 40075.8 42100.6 42237.2 44128.1 44185.0 44010.7 44335.0 44487.6 44491.0 44676.8 8164.8 9326.7 9574.7 10954.5 11328.2 11086.2 11168.2 11169.0 11192.4 11226.4 11274.3 11302.0 11306.1 11328.4 11330.9 11233.7 11243.0 11245.9 11270.8 11298.3 10931.7 11026.2 11026.6 11036.6 11071.5 37531.2 37797.4 37810.8 37843.2 37868.4 3447.5 3978.5 3992.1 4077.5 4171.2 29069.9 29540.4 29553.0 29596.7 29597.0 3149.1 3302.6 3337.7 3341.5 3358.9 475577.0 476615.0 478155.0 479983.0 480051.0 57922.7 58214.0 58537.7 58597.1 58823.5 59806.1 59845.7 60166.7 60292.0 60484.4 15607.0 15683.3 15850.4 15871.0 15915.4 10938.7 11222.9 12089.7 12347.1 12349.6 16492.5 18029.4 18271.9 18312.0 18383.6 6926.4 8167.6 8317.4 8356.2 8436.8 885.6 888.9 908.1 909.8 926.4 30464.7 30658.8 31105.7 31369.4 31522.0 1049.4 1055.3 1092.9 1094.8 1213.6 15655.2 16055.0 16075.7 16263.2 16352.8 604675.0 610572.0 611204.9 611631.0 613848.0 49125.8 61443.2 62130.7 62334.7 62377.0 1402.5 1440.1 1456.0 1457.4 1463.5 23919.7 29252.7 29483.7 30459.9 30734.5 43214.3 50326.7 52796.7 52947.0 53063.7 491684.0 505177.5 552532.0 556473.4 556740.0 22610.5 23551.1 26700.1 26928.8 27009.5 38216.0 48525.8 50203.0 50339.0 50674.0 39442.4 39965.8 40380.4 40891.0 41767.0 39326.0 40290.8 40810.0 41012.4 41322.2 37285.6 38033.8 38515.6 38780.8 38797.8 31086.8 32949.7 33237.8 34015.1 34057.3 24110.6 24186.0 24204.0 24367.8 24481.5 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:40108.70 Median:2615.20 stddev:122223.08 (4673.6%) stddev-sans-first:122529.55 20:44:47 INFO - PROCESS | 1637 | Values: 388.0 390.3 405.2 405.9 421.2 18059.6 20328.3 20512.4 21666.6 21731.7 21292.8 21464.0 21510.9 21880.1 22201.8 5747.0 6046.4 6048.5 6053.3 6075.6 5683.2 5917.1 5963.1 6027.1 6039.2 6069.3 6078.5 6082.2 6082.7 6110.4 5891.7 5986.3 6080.3 6329.8 6561.2 5851.6 5924.4 5928.9 5935.8 5952.6 13166.5 13315.7 13435.5 13445.1 13471.2 2524.2 2527.7 2608.7 2621.7 2624.1 9884.9 9916.5 10122.9 10294.3 10542.6 1779.2 1792.5 1796.6 1831.9 1832.0 131270.0 138322.0 138356.0 140791.9 142793.0 11980.6 11982.4 12157.5 12213.6 12270.8 15674.8 15774.6 15778.4 15789.0 15853.4 8438.3 8644.1 8746.5 8884.3 8889.6 8397.4 8434.7 8450.6 8586.1 8601.1 3994.8 4723.8 4762.9 4849.6 4878.1 1983.6 2006.4 2027.7 2034.4 2038.3 300.7 417.3 1034.9 1035.5 1057.0 11130.7 11672.3 12007.8 12144.7 12403.3 439.2 449.9 450.6 455.1 464.5 5277.6 5955.4 6017.6 6091.5 6177.6 697888.0 723245.0 729153.0 749880.8 749942.0 156637.2 158622.0 158860.0 162135.2 162493.2 864.4 1002.5 1067.9 1071.4 1075.0 19749.5 19959.3 20041.1 20065.0 20237.5 56582.0 57022.0 57842.6 60979.8 61663.4 87839.1 89088.0 89294.0 89350.1 89389.6 7880.9 8104.6 8395.3 8612.6 8769.3 11522.6 13257.0 13496.3 13664.4 13718.2 10985.8 11287.2 11378.4 11688.8 11743.4 11428.8 11495.6 11738.8 11971.8 11975.0 9365.4 11463.7 11638.2 11774.9 12019.0 7189.4 8375.9 8625.2 8657.8 8753.3 9710.3 11840.9 11977.6 11984.5 12012.9 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5405.92 Median:5402.57 stddev:2953.96 (54.7%) stddev-sans-first:2946.29 20:44:47 INFO - PROCESS | 1637 | Values: 1321.2 1343.6 1449.4 1451.4 1460.0 7015.4 7588.2 7845.5 8188.6 8608.4 8080.5 8403.7 8419.0 8501.1 8517.2 1904.6 2283.6 2324.0 2333.5 2344.2 1836.4 2077.1 2148.6 2279.6 2310.4 2027.6 2230.6 2318.0 2339.0 2343.7 1972.3 2276.8 2298.5 2322.7 2327.8 2313.5 2337.6 2351.5 2377.5 2385.4 8513.0 8597.8 8601.7 8617.8 8620.2 5166.1 5308.5 5317.2 5319.0 5320.2 3401.4 3523.3 3539.0 3618.3 3637.9 1222.8 1224.2 1264.6 1271.3 1272.5 10536.5 10654.5 10805.4 10845.5 10852.4 4425.3 4726.7 4736.8 4769.0 4793.9 9670.0 9673.0 9692.1 9700.5 9722.3 5319.2 5321.7 5333.7 5336.9 5337.4 4258.1 4275.4 4724.1 4828.1 4936.4 3900.9 3972.4 3990.8 4006.7 4038.3 1207.3 1249.4 1303.9 1336.7 1341.5 765.5 766.3 773.4 799.6 820.5 5706.6 5716.9 5768.1 5782.1 5844.9 574.7 581.4 589.0 589.9 704.0 3727.2 3830.3 3932.4 3935.3 4036.6 6988.5 9779.7 9823.5 9838.8 9901.6 9744.6 9825.8 9884.6 9901.2 9910.0 6557.6 7634.7 7837.6 7838.5 7840.8 4822.5 5467.8 5553.9 5650.5 5670.5 8934.6 8988.8 9088.0 9159.8 9182.3 9148.5 9304.4 9339.1 9441.9 9460.7 7058.2 7104.6 7148.1 7151.1 7185.9 7752.6 7773.5 7794.7 7852.1 7864.5 6574.5 7152.4 7220.5 7290.5 7291.8 7097.3 7128.9 7137.2 7183.2 7264.5 6086.2 6250.8 6408.9 6454.5 6461.2 4746.7 4984.7 5019.5 5117.4 5190.9 4137.3 4688.2 4745.6 4830.6 4914.6 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4565.84 Median:5027.90 stddev:1380.37 (27.5%) stddev-sans-first:1346.79 20:44:47 INFO - PROCESS | 1637 | Values: 310.5 353.3 360.0 361.8 376.2 4968.5 5183.8 5467.8 5538.6 5567.3 4960.6 5364.5 5399.9 5439.6 5476.1 3361.2 4182.3 4277.9 4287.9 4289.2 3431.9 3952.9 3959.5 4223.2 4331.8 3987.3 4008.4 4135.5 4178.9 4250.6 3883.8 4055.5 4216.5 4257.8 4262.8 3250.6 3282.9 3473.3 3664.7 3770.1 4655.0 4990.5 5247.3 5285.9 5298.4 4303.4 4552.4 4622.8 4891.6 4904.3 4642.9 4783.6 5657.7 5754.8 5756.3 3217.9 3538.8 3835.7 3953.0 4198.3 5749.4 6048.4 6053.5 6059.5 6064.3 4245.0 4253.1 4253.7 4255.5 4259.3 3865.4 4070.9 4296.7 4309.4 4314.4 4123.4 4859.8 4920.4 4922.5 4931.6 5169.4 5446.0 5598.2 5787.1 5817.4 1144.8 1169.2 1196.5 1397.7 1438.9 855.6 956.9 982.9 984.4 986.5 4240.4 4266.9 4743.0 5155.2 5189.6 4905.9 5343.0 5590.8 5620.6 5627.8 4326.9 4717.6 4731.1 4771.7 5016.2 2698.9 2793.8 2835.0 2964.6 2982.2 5039.6 5215.6 5327.4 5344.7 5846.4 5271.0 5404.2 5801.4 5806.8 5864.3 5477.5 5852.9 5890.0 5906.9 5918.3 5077.6 5607.5 5662.7 5698.6 5699.1 4844.1 5212.8 5324.4 5631.7 5680.6 5548.8 5752.8 6032.6 6135.6 6149.7 3413.4 3524.9 3964.5 3988.8 4001.7 4718.8 5361.7 5836.3 5880.6 5902.0 4655.0 5215.5 5666.3 5742.7 5764.7 5058.9 5708.7 5718.8 5731.5 5762.9 4711.5 4834.4 5303.3 5594.9 5630.7 5342.9 5701.5 5719.0 5722.8 5769.0 4910.6 5107.8 5183.7 5565.9 5604.8 20:44:47 INFO - PROCESS | 1637 | 20:44:47 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20333.83 Median:45113.77 stddev:29288.71 (64.9%) stddev-sans-first:29333.81 20:44:47 INFO - PROCESS | 1637 | Values: 711.1 761.1 783.7 814.7 835.3 12934.8 15055.8 15580.7 15637.2 15646.8 11874.2 14520.3 14554.1 14572.8 14589.2 674.4 697.9 836.1 848.8 866.0 862.0 880.5 880.5 881.6 884.2 936.9 937.9 940.0 940.3 941.1 768.9 926.1 926.9 927.9 930.6 679.7 689.6 692.1 692.2 693.2 14914.5 15079.3 15141.8 15278.9 15897.3 12353.0 13049.3 14225.7 14611.9 14840.3 11667.9 11782.6 11910.8 11926.5 12233.3 3721.4 3734.5 3735.7 3755.9 3866.4 90207.0 113577.0 115467.4 116196.0 116247.7 9253.4 11357.7 11412.1 11423.5 11815.4 22981.0 23444.6 23765.9 23788.9 23813.9 6577.8 7719.8 7738.3 7873.4 7956.6 4193.3 4313.3 4314.0 4348.4 4391.5 3380.7 3442.0 3456.1 3471.3 3498.2 631.8 641.1 648.7 658.5 658.8 640.5 644.3 646.5 649.4 662.5 9820.2 9845.1 9852.0 9973.3 10013.8 646.0 647.9 675.7 681.8 687.1 4684.8 4785.2 4799.9 4821.1 4844.6 90380.0 94927.6 103405.5 103855.9 104728.0 88518.6 88982.0 89764.0 90464.0 92576.0 76863.0 77458.6 77491.8 77904.4 78601.6 9214.9 9243.4 9359.7 9382.8 9403.8 44867.8 45359.8 45794.5 47008.3 51765.0 54427.5 59043.5 63133.0 64219.4 68367.0 12686.6 13262.8 13410.3 13438.5 14043.0 11843.0 14989.7 15264.8 15404.0 15776.6 13711.5 14053.2 14092.0 14189.0 14330.5 13382.4 13860.4 13963.4 14047.2 14353.5 11662.9 13849.0 14026.6 14366.0 14459.3 8715.0 11025.3 11324.2 11467.0 11579.9 8697.7 10167.3 11167.0 11357.4 11389.7 20:44:47 INFO - PROCESS | 1637 | -------- Summary: end -------- 20:44:47 INFO - PROCESS | 1637 | 20:44:48 INFO - TEST-INFO | 1637: exit 0 20:44:48 INFO - TEST-OK | dromaeo_css | took 1115991ms 20:44:48 INFO - TEST-START | kraken 20:44:48 INFO - operating with platform_type : mac_ 20:44:48 INFO - Initialising browser for kraken test... 20:44:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpKZOADP/profile http://localhost:49241/getInfo.html) 20:44:50 INFO - PROCESS | 1650 | 2017-01-20 20:44:50.733 plugin-container[1651:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 20:44:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:44:50 INFO - PROCESS | 1650 | 2017-01-20 20:44:50.736 plugin-container[1651:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:44:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:44:50 INFO - PROCESS | 1650 | 2017-01-20 20:44:50.736 plugin-container[1651:10047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:44:50 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 20:44:50 INFO - PROCESS | 1650 | colorDepth:24 20:44:50 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 20:44:50 INFO - PROCESS | 1650 | __metrics 20:44:50 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3273: TypeError: this.tabs is undefined 20:44:50 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 20:44:51 INFO - TEST-INFO | 1650: exit 0 20:44:51 INFO - Browser initialized. 20:44:51 INFO - Running cycle 1/1 for kraken test... 20:44:51 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpKZOADP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:44:54 INFO - PROCESS | 1652 | 2017-01-20 20:44:54.186 plugin-container[1653:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:44:54 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:44:54 INFO - PROCESS | 1652 | 2017-01-20 20:44:54.187 plugin-container[1653:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 20:44:54 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:44:54 INFO - PROCESS | 1652 | 2017-01-20 20:44:54.187 plugin-container[1653:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:45:03 INFO - PROCESS | 1652 | RSS: Main: 232865792 20:45:03 INFO - PROCESS | 1652 | 20:45:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 20:45:07 INFO - PROCESS | 1652 | RSS: Main: 246870016 20:45:07 INFO - PROCESS | 1652 | 20:45:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 20:45:10 INFO - PROCESS | 1652 | RSS: Main: 254128128 20:45:10 INFO - PROCESS | 1652 | 20:45:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 20:45:14 INFO - PROCESS | 1652 | RSS: Main: 256782336 20:45:14 INFO - PROCESS | 1652 | 20:45:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 20:45:17 INFO - PROCESS | 1652 | RSS: Main: 259067904 20:45:17 INFO - PROCESS | 1652 | 20:45:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:45:20 INFO - PROCESS | 1652 | RSS: Main: 259788800 20:45:20 INFO - PROCESS | 1652 | 20:45:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 20:45:23 INFO - PROCESS | 1652 | RSS: Main: 264609792 20:45:23 INFO - PROCESS | 1652 | 20:45:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 20:45:27 INFO - PROCESS | 1652 | RSS: Main: 264204288 20:45:27 INFO - PROCESS | 1652 | 20:45:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 20:45:31 INFO - PROCESS | 1652 | RSS: Main: 264212480 20:45:31 INFO - PROCESS | 1652 | 20:45:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:45:33 INFO - PROCESS | 1652 | RSS: Main: 263536640 20:45:33 INFO - PROCESS | 1652 | 20:45:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:45:36 INFO - PROCESS | 1652 | RSS: Main: 263557120 20:45:36 INFO - PROCESS | 1652 | 20:45:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:45:38 INFO - PROCESS | 1652 | RSS: Main: 263655424 20:45:38 INFO - PROCESS | 1652 | 20:45:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:45:42 INFO - PROCESS | 1652 | RSS: Main: 264056832 20:45:42 INFO - PROCESS | 1652 | 20:45:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:45:46 INFO - PROCESS | 1652 | RSS: Main: 263999488 20:45:46 INFO - PROCESS | 1652 | 20:45:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 20:45:48 INFO - PROCESS | 1652 | RSS: Main: 265670656 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | __start_tp_report 20:45:48 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 20:45:48 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 20:45:48 INFO - PROCESS | 1652 | |i|pagename|runs| 20:45:48 INFO - PROCESS | 1652 | |0;ai-astar;114;127;103;159;174;171;117;118;116;118 20:45:48 INFO - PROCESS | 1652 | |1;audio-beat-detection;156;97;130;142;146;95;139;146;160;123 20:45:48 INFO - PROCESS | 1652 | |2;audio-dft;193;212;232;247;265;196;192;197;196;191 20:45:48 INFO - PROCESS | 1652 | |3;audio-fft;75;59;63;101;83;84;51;84;83;84 20:45:48 INFO - PROCESS | 1652 | |4;audio-oscillator;90;70;84;71;69;71;81;68;70;82 20:45:48 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;88;89;87;90;85;129;87;90;88 20:45:48 INFO - PROCESS | 1652 | |6;imaging-darkroom;122;108;154;93;136;155;107;152;95;96 20:45:48 INFO - PROCESS | 1652 | |7;imaging-desaturate;134;91;94;92;93;139;151;91;121;147 20:45:48 INFO - PROCESS | 1652 | |8;json-parse-financial;59;58;57;60;58;59;59;59;64;70 20:45:48 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;69;77;71;81;95;105;71;70;74;72 20:45:48 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;88;88;92;88;89;87;89;86;89;89 20:45:48 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;181;139;138;164;145;147;138;144;181;153 20:45:48 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;175;181;172;173;177;181;216;192;175;179 20:45:48 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;71;65;66;66;69;68;68;68;67 20:45:48 INFO - PROCESS | 1652 | __end_tp_report 20:45:48 INFO - PROCESS | 1652 | __start_cc_report 20:45:48 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,910 20:45:48 INFO - PROCESS | 1652 | __end_cc_report 20:45:48 INFO - PROCESS | 1652 | __startTimestamp1484973948108__endTimestamp 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | ------- Summary: start ------- 20:45:48 INFO - PROCESS | 1652 | Number of tests: 14 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:131.70 Median:122.50 stddev:25.98 (21.2%) stddev-sans-first:26.76 20:45:48 INFO - PROCESS | 1652 | Values: 114.0 127.0 103.0 159.0 174.0 171.0 117.0 118.0 116.0 118.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:151.00 stddev:22.51 (14.9%) stddev-sans-first:22.34 20:45:48 INFO - PROCESS | 1652 | Values: 156.0 97.0 130.0 142.0 146.0 95.0 139.0 146.0 160.0 123.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:212.10 Median:204.50 stddev:26.61 (13.0%) stddev-sans-first:27.31 20:45:48 INFO - PROCESS | 1652 | Values: 193.0 212.0 232.0 247.0 265.0 196.0 192.0 197.0 196.0 191.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:76.70 Median:83.00 stddev:14.89 (17.9%) stddev-sans-first:15.78 20:45:48 INFO - PROCESS | 1652 | Values: 75.0 59.0 63.0 101.0 83.0 84.0 51.0 84.0 83.0 84.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.00 stddev:7.85 (10.3%) stddev-sans-first:6.36 20:45:48 INFO - PROCESS | 1652 | Values: 90.0 70.0 84.0 71.0 69.0 71.0 81.0 68.0 70.0 82.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:88.50 stddev:13.00 (14.7%) stddev-sans-first:13.76 20:45:48 INFO - PROCESS | 1652 | Values: 90.0 88.0 89.0 87.0 90.0 85.0 129.0 87.0 90.0 88.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:121.80 Median:154.50 stddev:25.55 (16.5%) stddev-sans-first:27.10 20:45:48 INFO - PROCESS | 1652 | Values: 122.0 108.0 154.0 93.0 136.0 155.0 107.0 152.0 95.0 96.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:115.30 Median:91.00 stddev:25.60 (28.1%) stddev-sans-first:26.24 20:45:48 INFO - PROCESS | 1652 | Values: 134.0 91.0 94.0 92.0 93.0 139.0 151.0 91.0 121.0 147.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:59.00 stddev:3.89 (6.6%) stddev-sans-first:4.10 20:45:48 INFO - PROCESS | 1652 | Values: 59.0 58.0 57.0 60.0 58.0 59.0 59.0 59.0 64.0 70.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:73.00 stddev:12.11 (16.6%) stddev-sans-first:12.35 20:45:48 INFO - PROCESS | 1652 | Values: 69.0 77.0 71.0 81.0 95.0 105.0 71.0 70.0 74.0 72.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:89.00 stddev:1.58 (1.8%) stddev-sans-first:1.67 20:45:48 INFO - PROCESS | 1652 | Values: 88.0 88.0 92.0 88.0 89.0 87.0 89.0 86.0 89.0 89.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:150.00 stddev:16.72 (11.1%) stddev-sans-first:14.34 20:45:48 INFO - PROCESS | 1652 | Values: 181.0 139.0 138.0 164.0 145.0 147.0 138.0 144.0 181.0 153.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:180.00 stddev:13.21 (7.3%) stddev-sans-first:13.76 20:45:48 INFO - PROCESS | 1652 | Values: 175.0 181.0 172.0 173.0 177.0 181.0 216.0 192.0 175.0 179.0 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.72 (2.5%) stddev-sans-first:1.81 20:45:48 INFO - PROCESS | 1652 | Values: 67.0 71.0 65.0 66.0 66.0 69.0 68.0 68.0 68.0 67.0 20:45:48 INFO - PROCESS | 1652 | -------- Summary: end -------- 20:45:48 INFO - PROCESS | 1652 | 20:45:48 INFO - TEST-INFO | 1652: exit 0 20:45:49 INFO - TEST-OK | kraken | took 60866ms 20:45:49 INFO - SUITE-END | took 1176s 20:45:49 INFO - Completed test suite (00:19:37) 20:45:49 INFO - javascript benchmark 20:45:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7239.793164033655, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.1048022274085, 3388.864445422183, 3426.22909845285, 3429.7084747796434, 3435.673611006954, 4742.810287588501, 5481.534069604083, 5621.4940655341015, 5683.460071293221, 5767.250257466534, 4327.4374331336885, 5312.485941506671, 5644.350899646542, 5689.4879460848515, 5730.05454100073, 4376.868693939171, 4772.475027746962, 4980.501949805009, 5195.766190521416, 5321.893562128756, 5166.612504062192, 5294.1529355303155, 5317.6809391436445, 5322.600804939626, 5325.840224793263, 5118.07874582575, 5132.666376685504, 5233.581313494911, 5275.551578115865, 5296.152615581488, 5170.482951704799, 5175.430702622746, 5176.327077479904, 5182.896342073175, 5183.118869792144, 4624.306354046887, 4959.6528243023, 5125.1287281162295, 5140.074786538422, 5145.202493613496, 4520.299353600164, 4994.35073440454, 5481.506664400184, 5777.971110144465, 5795.130730390433, 6257.593256438363, 6283.748650053993, 6294.464970477515, 6296.0, 6308.179936608258, 6204.0, 6217.098520714516, 6228.314885362606, 6229.87540249197, 6304.968475157641, 5820.708964551755, 5822.679752613647, 5830.883382332372, 5897.941020589825, 5924.348321684612, 6152.230971128609, 6340.1440805490665, 6341.112244285804, 6343.841403964845, 6345.302016778151, 6156.507479401637, 6165.938340616626, 6174.166487524217, 6180.443760061616, 6216.378362163748, 6140.565599444524, 6145.108959200891, 6208.2239720034995, 6229.377062293825, 6236.126942228092, 6107.006761825686, 6152.723127459275, 6174.475169610633, 6205.782797602063, 6211.875762484816, 5192.844214673566, 6158.291796443376, 6168.599041062316, 6180.783672571439, 6186.381361863868, 4890.119778439844, 4936.654434189644, 4970.90058198834, 4987.526185012418, 5008.424031236382, 4406.9559304407185, 4409.985703288262, 4460.0, 4466.173757854743, 4553.795079221443, 5023.2214006829, 5809.738561764731, 5815.9709201453725, 5838.153467747153, 5885.735141918624, 6140.048292514667, 6280.842978925472, 6288.748450061947, 6305.4009869062365, 6316.526260530479, 5602.719864006784, 5642.435756424325, 5687.402822703557, 5694.829155125353, 5723.713814309352, 5390.353157621033, 5434.375046869582, 5462.508374246337, 5466.234727138204, 5520.1443776214755, 6299.6850157491945, 6334.5249106317215, 6340.651264180515, 6348.111264422984, 6351.809445716636, 6117.796956032597, 6197.535184861244, 6226.719797609209, 6267.373262673696, 6278.937210627835, 5245.6852588844795, 6221.813345599639, 6252.718627661857, 6283.5601507895835, 6316.684165791784, 6090.208272924491, 6125.84685382869, 6146.508279337731, 6243.37566243372, 6272.247330320391, 6188.071789231652, 6229.065640154013, 6301.842453938688, 6308.14839996613, 6314.842128946813, 6014.398560143951, 6072.605280656743, 6166.784162554289, 6204.844878878064, 6206.875862482635, 5956.910646340395, 6092.299385570716, 6099.84750381244, 6114.205153330038, 6122.032243765608, 5344.706041167773, 5792.623479473821, 5824.330202026652, 5844.006518891713, 5997.700114994145, 6116.26604807426, 6160.3223645398075, 6174.38256174379, 6180.103884936749, 6204.255489341308, 5854.677992710442, 6029.909551356821, 6030.5778595497895, 6032.276126864805, 6054.152418661303], "value": 5655.491974749895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.94231095413414, 472.9314249433832, 491.82048552278417, 498.3372115086935, 505.95699365553924, 40075.79772606823, 42100.57899421009, 42237.15525689488, 44128.11743765123, 44185.0, 44010.67967960966, 44335.0, 44487.55512444874, 44491.0, 44676.77661611696, 8164.795880102985, 9326.673566425177, 9574.664886728966, 10954.507047182897, 11328.150388720878, 11086.2239643225, 11168.218224724273, 11169.0, 11192.384418857, 11226.438678066104, 11274.267172633794, 11302.039326657245, 11306.095512358992, 11328.376939268337, 11330.943345283262, 11233.66299011031, 11242.99999999996, 11245.943770281177, 11270.830937535944, 11298.265612735227, 10931.726706832313, 11026.228164028522, 11026.614068507564, 11036.613718519893, 11071.50178241973, 37531.24937501262, 37797.433038504445, 37810.81094594537, 37843.24313513714, 37868.43197352042, 3447.517347571342, 3978.5026871641044, 3992.1449700464896, 4077.4903137107863, 4171.249175148472, 29069.854650726822, 29540.40919181626, 29553.0, 29596.70403295961, 29597.0, 3149.1497295730023, 3302.554155189067, 3337.732981361485, 3341.4820702791108, 3358.932821343584, 475577.0, 476615.0, 478155.0, 479983.0, 480051.0, 57922.7103864478, 58214.0, 58537.70731146317, 58597.12104318439, 58823.52941176419, 59806.10290845641, 59845.70077149587, 60166.69916650389, 60292.0, 60484.395156047874, 15607.0, 15683.294251758698, 15850.44523441674, 15871.0, 15915.363385464452, 10938.671839844817, 11222.943885280521, 12089.697757556132, 12347.073969452327, 12349.567765128177, 16492.505224843273, 18029.368972085915, 18271.908640456713, 18312.0, 18383.632327353378, 6926.445884329241, 8167.550784706898, 8317.41778075541, 8356.164383561596, 8436.831263374697, 885.6014793342893, 888.9199972002551, 908.1281969309537, 909.8316811389914, 926.3746970794687, 30464.69535304663, 30658.84670576633, 31105.68894311028, 31369.37261254808, 31522.0, 1049.3913530152493, 1055.2613170780485, 1092.8688557373166, 1094.8248280275118, 1213.648042067808, 15655.217239137997, 16055.0, 16075.678486430206, 16263.18684065792, 16352.75470867938, 604675.0, 610572.0, 611204.9439752772, 611631.0, 613848.0, 49125.75437122863, 61443.162899524476, 62130.68934655298, 62334.68832655808, 62377.0, 1402.4670625162578, 1440.107133577189, 1455.992720036393, 1457.3951809998732, 1463.531669865654, 23919.713946121243, 29252.70747292585, 29483.705162948954, 30459.89559556196, 30734.53898191484, 43214.271429142485, 50326.74836625793, 52796.73601631967, 52947.0, 53063.73468132634, 491684.0, 505177.4741126271, 552532.0, 556473.4352656583, 556740.0, 22610.547789044456, 23551.057957682187, 26700.06549770749, 26928.78820453045, 27009.459810804066, 38216.0, 48525.757371212916, 50203.0, 50338.99322013466, 50674.0, 39442.40836387399, 39965.80017100012, 40380.394294085025, 40891.0, 41767.0, 39326.01684957899, 40290.798546007085, 40810.04921416112, 41012.38481422721, 41322.173556529306, 37285.6271437293, 38033.80983095067, 38515.6148438512, 38780.80609597047, 38797.8060109709, 31086.844565777028, 32949.67050329466, 33237.83667571624, 34015.14962125967, 34057.318853622295, 24110.638340424557, 24186.0, 24203.974503187103, 24367.781610919737, 24481.52964886032], "value": 20281.94064534956, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.99418008729856, 390.324738202909, 405.19770853709656, 405.94316795648604, 421.1850070114329, 18059.638807223848, 20328.288509902155, 20512.38462846113, 21666.566668666615, 21731.67402488962, 21292.787072129275, 21464.0, 21510.924453777327, 21880.12479500818, 22201.777982220174, 5746.9712651436785, 6046.39536046394, 6048.45563899249, 6053.303870054956, 6075.605085669417, 5683.204351390808, 5917.112433135021, 5963.1055341698875, 6027.065804800263, 6039.184710064151, 6069.30203026651, 6078.513718902477, 6082.239720034996, 6082.726277317532, 6110.358412366704, 5891.733277345348, 5986.311574168983, 6080.2703675559, 6329.810105696836, 6561.245456772484, 5851.561132915049, 5924.377940316248, 5928.940710592882, 5935.762569497215, 5952.58331916766, 13166.539171128965, 13315.733685326239, 13435.529756458476, 13445.126066805724, 13471.191728496322, 2524.1544082732307, 2527.747225277476, 2608.7303646633695, 2621.6722909636296, 2624.1471521755507, 9884.901150988542, 9916.504174791231, 10122.89877101227, 10294.330868493524, 10542.578296868116, 1779.2303909637783, 1792.507060558344, 1796.6316905034437, 1831.8900865948085, 1831.9633607327912, 131270.0, 138322.0, 138356.0, 140791.88812167826, 142793.0, 11980.585533123018, 11982.400879955967, 12157.452914618863, 12213.603102883133, 12270.754584908253, 15674.843251567338, 15774.60563485899, 15778.368865245262, 15789.0, 15853.365865365256, 8438.282745966662, 8644.13558644143, 8746.475211487204, 8884.333674974403, 8889.555522223864, 8397.370197235232, 8434.746957591282, 8450.577471126418, 8586.09845966177, 8601.096884827128, 3994.78028708418, 4723.834665786681, 4762.904741905212, 4849.612031037508, 4878.07316609843, 1983.5834474760172, 2006.3980805758215, 2027.7363942687357, 2034.3795142481686, 2038.327351973845, 300.69629674029386, 417.3156024120445, 1034.8551202831609, 1035.4822588705647, 1057.027534668092, 11130.721731956766, 11672.299662020307, 12007.759844803055, 12144.696382590506, 12403.317817520123, 439.2313451459945, 449.9437570303712, 450.58095970746865, 455.0920912779027, 464.51226212476763, 5277.551408130275, 5955.40445955401, 6017.638941663514, 6091.451769340781, 6177.567570270128, 697888.0, 723245.0, 729153.0, 749880.7589430815, 749942.0, 156637.2168139152, 158622.0, 158860.0, 162135.18932405263, 162493.18753406394, 864.3560547392233, 1002.478711070254, 1067.9038886500252, 1071.3893080943787, 1075.0056198016803, 19749.506262343555, 19959.323379136214, 20041.098150582966, 20064.98659838591, 20237.494062647962, 56582.0, 57022.0, 57842.55393615193, 60979.78040439255, 61663.38336616756, 87839.1216087831, 89088.0, 89294.0, 89350.10649893418, 89389.55305223432, 7880.921190788248, 8104.554249516334, 8395.286400655768, 8612.569371531523, 8769.298456123368, 11522.596709115141, 13257.0, 13496.32518374097, 13664.385102670205, 13718.17690938547, 10985.79156292823, 11287.171047725127, 11378.374189419661, 11688.824667630162, 11743.354115523729, 11428.771424571629, 11495.597654082067, 11738.823917641072, 11971.820422693841, 11975.0, 9365.438073715599, 11463.713407164554, 11638.185327027028, 11774.940255377058, 12019.038476922, 7189.388901943394, 8375.916240837514, 8625.223729864343, 8657.783555411164, 8753.299736020976, 9710.320277580502, 11840.881591183977, 11977.580784672495, 11984.520619175484, 12012.879871201525], "value": 9318.127306729371, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.2468892731142, 1343.6439343573952, 1449.384011794987, 1451.404923981168, 1460.0, 7015.403690686291, 7588.241175882409, 7845.450818442711, 8188.590570471475, 8608.354373422002, 8080.474769140016, 8403.663853445854, 8418.957905210465, 8501.064882862896, 8517.23344898959, 1904.6190761847618, 2283.5889539882855, 2324.000679707725, 2333.4982978659586, 2344.15610380263, 1836.4215272189285, 2077.0757013129164, 2148.6132496150685, 2279.6010698127843, 2310.4223944013997, 2027.614753196888, 2230.565039817236, 2317.9569193862744, 2339.040993192791, 2343.7070366204225, 1972.2505447929766, 2276.7723227677266, 2298.482841360689, 2322.674825524428, 2327.7904988551113, 2313.5141620259765, 2337.649352597107, 2351.541449417364, 2377.4531857672832, 2385.403649087728, 8513.0, 8597.785055373666, 8601.698940537051, 8617.784555386166, 8620.181082797124, 5166.147585648364, 5308.52223299901, 5317.218731724877, 5319.042572336978, 5320.17537281722, 3401.370746411897, 3523.3481805865995, 3539.0444579963264, 3618.294432585647, 3637.854485820564, 1222.7798996180684, 1224.2165014390707, 1264.6142926407442, 1271.313490715022, 1272.5291642092393, 10536.525856336484, 10654.467276636136, 10805.405702686428, 10845.457727113691, 10852.403117828517, 4425.336199570026, 4726.71639701619, 4736.7631618418945, 4768.976155119202, 4793.856184314476, 9669.98465161162, 9673.0, 9692.127708506127, 9700.514974251259, 9722.2708296878, 5319.202119682079, 5321.6540924839765, 5333.6533125347505, 5336.893262134736, 5337.359516857924, 4258.063226090255, 4275.358696195595, 4724.149653062482, 4828.130936431476, 4936.407631084221, 3900.8687480630865, 3972.364421692573, 3990.7605543667473, 4006.699497537638, 4038.2932986727205, 1207.3118322556059, 1249.4002878618132, 1303.8565757766637, 1336.7460182565476, 1341.5170538606094, 765.4718244411339, 766.3447752171925, 773.4353921637236, 799.6161842315605, 820.4625969989667, 5706.572007099484, 5716.942830571724, 5768.077107662753, 5782.074868021181, 5844.853878653068, 574.7496618391028, 581.4156772443667, 588.9558283128782, 589.9056151015902, 703.9929600703928, 3727.1613886875234, 3830.272248272875, 3932.4494570759543, 3935.2916475034767, 4036.616521430459, 6988.545744526589, 9779.706608801747, 9823.508824558743, 9838.75403114928, 9901.554430050664, 9744.561494732754, 9825.803483930424, 9884.55519501624, 9901.158401535806, 9910.0, 6557.639329836809, 7634.6946122155605, 7837.608119593998, 7838.5296882188395, 7840.764777056697, 4822.493638167942, 5467.781288748564, 5553.916691249554, 5650.502984140908, 5670.489655930986, 8934.642614295355, 8988.775280618036, 9087.954560227421, 9159.771005724644, 9182.311326650395, 9148.496832674267, 9304.395214311047, 9339.066093390607, 9441.858372124561, 9460.716178514656, 7058.188308344606, 7104.573725576482, 7148.106486689164, 7151.141862976476, 7185.856282874418, 7752.612369381395, 7773.533587984739, 7794.6882124714375, 7852.097008844056, 7864.52812831209, 6574.506911981716, 7152.356287934111, 7220.530665506935, 7290.453216008927, 7291.817704557428, 7097.25478824716, 7128.928710712827, 7137.214906360193, 7183.245759195209, 7264.455165862476, 6086.178365920544, 6250.781222657185, 6408.871822563429, 6454.48364130859, 6461.224653041665, 4746.691465054761, 4984.700917944935, 5019.498050194951, 5117.411497677815, 5190.948090519047, 4137.275976704125, 4688.203005489143, 4745.620350372031, 4830.589399901048, 4914.557689807934], "value": 4344.3856715703505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.492345015899, 353.30222809950334, 360.02433405471174, 361.7807987083529, 376.23811781143166, 4968.453470118284, 5183.818566350178, 5467.781288748451, 5538.556915446765, 5567.276254086968, 4960.553550180483, 5364.517193452589, 5399.919001214975, 5439.646422982503, 5476.139321778147, 3361.193313604742, 4182.309918863384, 4277.914441711164, 4287.935680964788, 4289.206496798087, 3431.8627254909898, 3952.8931899068307, 3959.5050618672667, 4223.162924127999, 4331.7617531035785, 3987.342088555385, 4008.4388185654034, 4135.545090040108, 4178.916421671564, 4250.638695710868, 3883.8446462141483, 4055.452513910614, 4216.536181020086, 4257.76582287974, 4262.829486820523, 3250.5774249347432, 3282.885099021523, 3473.3053389321985, 3664.725145614063, 3770.0763312988493, 4655.0, 4990.525900039491, 5247.317848679647, 5285.894282114336, 5298.397089978813, 4303.397524346594, 4552.385427967249, 4622.838200662961, 4891.584215341701, 4904.264360345941, 4642.907141857144, 4783.617310615177, 5657.698729292286, 5754.769809207627, 5756.30924289088, 3217.9034628961167, 3538.8230588470733, 3835.6931445484292, 3953.011747063234, 4198.265303571863, 5749.367569567345, 6048.395160483917, 6053.545984051214, 6059.515238780887, 6064.332923378425, 4244.981204510895, 4253.107886637116, 4253.6600970694135, 4255.510616279167, 4259.254630439723, 3865.4201869719204, 4070.898227544276, 4296.742195468282, 4309.353596960418, 4314.439122914001, 4123.402106694512, 4859.757012149378, 4920.400869717356, 4922.483139270325, 4931.58081563064, 5169.379674439091, 5445.992491389105, 5598.188262701967, 5787.135367992786, 5817.360090390053, 1144.7824913266452, 1169.2049406403557, 1196.5333519927235, 1397.6925076483158, 1438.892083093772, 855.5978690015683, 956.8803899512561, 982.916452101565, 984.389678399397, 986.4623780039896, 4240.448741663626, 4266.930538688734, 4742.956549559091, 5155.226715992556, 5189.55888749453, 4905.9264111038365, 5342.958123165945, 5590.847523703322, 5620.550355971594, 5627.831165065055, 4326.913461730748, 4717.551832575899, 4731.073209236326, 4771.6898401603785, 5016.2224855147515, 2698.9069426882143, 2793.7764978801647, 2835.036087730182, 2964.644242690848, 2982.2395289201113, 5039.573090298632, 5215.608829337739, 5327.440618735054, 5344.652597581145, 5846.427571868364, 5270.998510283111, 5404.243405923174, 5801.390853960358, 5806.796762113306, 5864.296284445809, 5477.534409575231, 5852.912206316909, 5889.970550147222, 5906.911396329058, 5918.319393269829, 5077.644564880423, 5607.467295603744, 5662.745176466987, 5698.601097923106, 5699.059655156852, 4844.0554091951735, 5212.765425555935, 5324.414314425333, 5631.746571404215, 5680.602357834912, 5548.778048878, 5752.7698892043845, 6032.607880487754, 6135.601185922901, 6149.661768602769, 3413.4367829307885, 3524.933308022037, 3964.544077431017, 3988.8005599720477, 4001.739886907458, 4718.764061796828, 5361.731913404236, 5836.328822185334, 5880.558958078076, 5901.996116582523, 4654.976725116353, 5215.530602245816, 5666.291713535808, 5742.712864356849, 5764.711764411679, 5058.873528161826, 5708.745045707005, 5718.828435146952, 5731.512821410227, 5762.913556296743, 4711.528847115261, 4834.444038935568, 5303.2840566524565, 5594.86012849682, 5630.662160270397, 5342.931413717194, 5701.515371193329, 5718.971405142947, 5722.7710891563875, 5769.0, 4910.558049775537, 5107.821226257063, 5183.663061900965, 5565.916511252253, 5604.8038318658655], "value": 4139.629051184613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.1040089487244, 761.1475147834425, 783.6669415498412, 814.7189219719195, 835.335907953177, 12934.805977910339, 15055.849441505581, 15580.688386232268, 15637.21813909303, 15646.84353156468, 11874.228175168608, 14520.27398630067, 14554.126752394837, 14572.781408278885, 14589.197594132318, 674.4132604633975, 697.905782719334, 836.1429534726893, 848.8005318750089, 865.9523726195089, 862.0, 880.4981160738382, 880.5465185429509, 881.607684580362, 884.2263019857625, 936.929730270229, 937.8827646544182, 940.0364626258106, 940.3323640215456, 941.1388579449796, 768.8885111658778, 926.0600490502112, 926.8702381666573, 927.9443233406017, 930.6370515498961, 679.6737565968363, 689.5896941319957, 692.0829900382014, 692.2454524568194, 693.1647364925253, 14914.477993270184, 15079.321430535652, 15141.772873406908, 15278.923605382013, 15897.28462219203, 12353.0, 13049.303590533265, 14225.715485690333, 14611.853881461262, 14840.332185051699, 11667.88332116685, 11782.646520604396, 11910.821337679941, 11926.463309151022, 12233.266004039786, 3721.4045752679713, 3734.514513113278, 3735.701143908481, 3755.8732380285805, 3866.4007078902814, 90207.0, 113577.0, 115467.42266288615, 116196.0, 116247.67504649944, 9253.444793312423, 11357.716057098638, 11412.087033037336, 11423.485943132579, 11815.409229538489, 22980.965856536495, 23444.59332440059, 23765.881170594035, 23788.88105559461, 23813.880930595584, 6577.769778057745, 7719.807004824925, 7738.264864837832, 7873.409494287951, 7956.562389068657, 4193.329067349209, 4313.331433627793, 4314.0, 4348.43470348859, 4391.494978077498, 3380.661933806649, 3441.9655803442147, 3456.1359660084977, 3471.288385880888, 3498.177928186874, 631.766246488802, 641.1280658304702, 648.7437462202404, 658.4666420199653, 658.805652332563, 640.4716109209827, 644.3331152257391, 646.4549726154495, 649.3603800256743, 662.5262936999972, 9820.214382849355, 9845.113939745313, 9852.0, 9973.301868869255, 10013.799724005625, 645.9870802583922, 647.9416852483299, 675.7297081167474, 681.8125015620644, 687.1445050911647, 4684.812607495662, 4785.234362501983, 4799.928001079987, 4821.132196204647, 4844.588210002189, 90380.0, 94927.57608635876, 103405.48297258465, 103855.92288154346, 104728.0, 88518.55740721384, 88982.0, 89764.0, 90464.0, 92576.0, 76863.0, 77458.61270693611, 77491.83762243458, 77904.44191116259, 78601.60699196582, 9214.907850921672, 9243.352965292634, 9359.719208423758, 9382.765430864283, 9403.764905877408, 44867.775661121486, 45359.77320113379, 45794.542054579026, 47008.29487557621, 51765.0, 54427.455725443826, 59043.52391190254, 63133.0, 64219.357806423206, 68366.9744953836, 12686.555970540987, 13262.80105798433, 13410.32948352598, 13438.46246150143, 14043.016988811094, 11843.0, 14989.700205995601, 15264.782052172568, 15403.998740081859, 15776.605584860012, 13711.520096796963, 14053.156810591398, 14092.033055877617, 14189.0, 14330.49843255481, 13382.397792099573, 13860.445582177003, 13963.441462341392, 14047.157170569799, 14353.497627582985, 11662.94168529152, 13848.991896174797, 14026.579202623938, 14366.0, 14459.349329280418, 8714.956425217833, 11025.283356581796, 11324.177376426771, 11467.0, 11579.942100289782, 8697.652093916426, 10167.288289819642, 11167.0, 11357.37534435614, 11389.658310250707], "value": 7490.50129168815, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 127.0, 103.0, 159.0, 174.0, 171.0, 117.0, 118.0, 116.0, 118.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 97.0, 130.0, 142.0, 146.0, 95.0, 139.0, 146.0, 160.0, 123.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 212.0, 232.0, 247.0, 265.0, 196.0, 192.0, 197.0, 196.0, 191.0], "value": 212.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 59.0, 63.0, 101.0, 83.0, 84.0, 51.0, 84.0, 83.0, 84.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 84.0, 71.0, 69.0, 71.0, 81.0, 68.0, 70.0, 82.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 87.0, 90.0, 85.0, 129.0, 87.0, 90.0, 88.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 108.0, 154.0, 93.0, 136.0, 155.0, 107.0, 152.0, 95.0, 96.0], "value": 121.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 91.0, 94.0, 92.0, 93.0, 139.0, 151.0, 91.0, 121.0, 147.0], "value": 115.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 60.0, 58.0, 59.0, 59.0, 59.0, 64.0, 70.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 71.0, 81.0, 95.0, 105.0, 71.0, 70.0, 74.0, 72.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 88.0, 89.0, 87.0, 89.0, 86.0, 89.0, 89.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 139.0, 138.0, 164.0, 145.0, 147.0, 138.0, 144.0, 181.0, 153.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 181.0, 172.0, 173.0, 177.0, 181.0, 216.0, 192.0, 175.0, 179.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 65.0, 66.0, 66.0, 69.0, 68.0, 68.0, 68.0, 67.0], "value": 67.5, "unit": "score"}]}]} 20:45:49 INFO - Return code: 0 20:45:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:45:49 ERROR - # TBPL SUCCESS # 20:45:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:45:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:45:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:45:49 INFO - 2017-01-20 20:45:49.411 screenresolution[1657:10556] starting screenresolution argv=screenresolution get 20:45:49 INFO - 2017-01-20 20:45:49.414 screenresolution[1657:10556] Display 0: 1600x1200x32@60 20:45:49 INFO - 2017-01-20 20:45:49.420 screenresolution[1658:10559] starting screenresolution argv=screenresolution list 20:45:49 INFO - Available Modes on Display 0 20:45:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:45:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:45:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:45:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:45:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:45:49 INFO - Intel Iris: 20:45:49 INFO - Chipset Model: Intel Iris 20:45:49 INFO - Type: GPU 20:45:49 INFO - Bus: Built-In 20:45:49 INFO - VRAM (Dynamic, Max): 1536 MB 20:45:49 INFO - Vendor: Intel (0x8086) 20:45:49 INFO - Device ID: 0x0a2e 20:45:49 INFO - Revision ID: 0x0009 20:45:49 INFO - Displays: 20:45:49 INFO - Display: 20:45:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:45:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:45:49 INFO - Display Serial Number: 1600x1200 60 20:45:49 INFO - Main Display: Yes 20:45:49 INFO - Mirror: Off 20:45:49 INFO - Online: Yes 20:45:49 INFO - Rotation: Supported 20:45:49 INFO - Return code: 0 20:45:49 INFO - Running post-action listener: _resource_record_post_action 20:45:49 INFO - [mozharness: 2017-01-21 04:45:49.656186Z] Finished run-tests step (success) 20:45:49 INFO - Running post-run listener: _resource_record_post_run 20:45:49 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38705664; Write bytes: 554188800; Read time: 246; Write time: 1674 20:45:49 INFO - TinderboxPrint: CPU usage
54.5% 20:45:49 INFO - TinderboxPrint: I/O read bytes / time
38,705,664 / 246 20:45:49 INFO - TinderboxPrint: I/O write bytes / time
554,188,800 / 1,674 20:45:49 INFO - TinderboxPrint: CPU idle
2,184.5 (45.5%) 20:45:49 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 20:45:49 INFO - TinderboxPrint: CPU user
1,647.2 (34.3%) 20:45:49 INFO - TinderboxPrint: Swap in / out
290,177,024 / 0 20:45:49 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245273088; Write bytes: 237555712; Read time: 17536; Write time: 488 20:45:49 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 36682240; Write bytes: 301096960; Read time: 228; Write time: 1142 20:45:50 INFO - Running post-run listener: _upload_blobber_files 20:45:50 INFO - Blob upload gear active. 20:45:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:45:50 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 20:45:50 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'] 20:45:50 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 20:45:50 INFO - (blobuploader) - INFO - Open directory for files ... 20:45:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:45:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:45:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:51 INFO - (blobuploader) - INFO - Done attempting. 20:45:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:45:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:45:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:52 INFO - (blobuploader) - INFO - Done attempting. 20:45:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:45:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:45:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:54 INFO - (blobuploader) - INFO - Done attempting. 20:45:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:45:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:45:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:56 INFO - (blobuploader) - INFO - Done attempting. 20:45:56 INFO - (blobuploader) - INFO - Iteration through files over. 20:45:56 INFO - Return code: 0 20:45:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:45:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:45:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be874c6bb88d25ebfe4b6d3b2d8ac26bc0599e9ae970d45196dc1bc73e746f169849cdad4a7d384d1c57afba0293e160a216de019e4641c0da0983cc7dea7c15", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7410697721ca4b4f15074056b0e7ba549508d18719bd6dacdc51da200791b7bb1e84a4536831d6db1e2d91a7a60552807da36663cd07c048b7e6b70a337b97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1a3162ff9172c26bf0e44a30cbaa97007c49ad38555e7e5557cb119785c0f5ff540dd895fd58b330646f13f4f938e5da0dca0bc5b7419f4b2622dfd6bcaf034", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f371d4908e712a560cc7a6c515ef3e9b79cca2e083ebaaf47acbd840ebda40724ffdb05213617d0e68fc12ce96ce08c3e2b59a14b67d4af693dc5dcfc1953474"} 20:45:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:45:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:45:56 INFO - Contents: 20:45:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be874c6bb88d25ebfe4b6d3b2d8ac26bc0599e9ae970d45196dc1bc73e746f169849cdad4a7d384d1c57afba0293e160a216de019e4641c0da0983cc7dea7c15", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7410697721ca4b4f15074056b0e7ba549508d18719bd6dacdc51da200791b7bb1e84a4536831d6db1e2d91a7a60552807da36663cd07c048b7e6b70a337b97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1a3162ff9172c26bf0e44a30cbaa97007c49ad38555e7e5557cb119785c0f5ff540dd895fd58b330646f13f4f938e5da0dca0bc5b7419f4b2622dfd6bcaf034", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f371d4908e712a560cc7a6c515ef3e9b79cca2e083ebaaf47acbd840ebda40724ffdb05213617d0e68fc12ce96ce08c3e2b59a14b67d4af693dc5dcfc1953474"} 20:45:56 INFO - Running post-run listener: copy_logs_to_upload_dir 20:45:56 INFO - Copying logs to upload dir... 20:45:56 INFO - mkdir: /builds/slave/test/build/upload/logs 20:45:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.594393 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-20 20:45:56.761142) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 20:45:56.765126) ========= 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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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/be874c6bb88d25ebfe4b6d3b2d8ac26bc0599e9ae970d45196dc1bc73e746f169849cdad4a7d384d1c57afba0293e160a216de019e4641c0da0983cc7dea7c15", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7410697721ca4b4f15074056b0e7ba549508d18719bd6dacdc51da200791b7bb1e84a4536831d6db1e2d91a7a60552807da36663cd07c048b7e6b70a337b97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1a3162ff9172c26bf0e44a30cbaa97007c49ad38555e7e5557cb119785c0f5ff540dd895fd58b330646f13f4f938e5da0dca0bc5b7419f4b2622dfd6bcaf034", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f371d4908e712a560cc7a6c515ef3e9b79cca2e083ebaaf47acbd840ebda40724ffdb05213617d0e68fc12ce96ce08c3e2b59a14b67d4af693dc5dcfc1953474"} build_url:https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010498 build_url: 'https://queue.taskcluster.net/v1/task/OOlWIOIxTVy0Qmd1AX1NQw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be874c6bb88d25ebfe4b6d3b2d8ac26bc0599e9ae970d45196dc1bc73e746f169849cdad4a7d384d1c57afba0293e160a216de019e4641c0da0983cc7dea7c15", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7410697721ca4b4f15074056b0e7ba549508d18719bd6dacdc51da200791b7bb1e84a4536831d6db1e2d91a7a60552807da36663cd07c048b7e6b70a337b97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1a3162ff9172c26bf0e44a30cbaa97007c49ad38555e7e5557cb119785c0f5ff540dd895fd58b330646f13f4f938e5da0dca0bc5b7419f4b2622dfd6bcaf034", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f371d4908e712a560cc7a6c515ef3e9b79cca2e083ebaaf47acbd840ebda40724ffdb05213617d0e68fc12ce96ce08c3e2b59a14b67d4af693dc5dcfc1953474"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 20:45:56.804010) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:45:56.804333) ========= 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.g9CtjTBzB8/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.ly9SRWhrFn/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005127 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 20:45:56.852628) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 20:45:56.852952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 20:45:56.853269) ========= ========= Total master_lag: 0.29 =========