builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0228 starttime: 1474977873.58 results: success (0) buildid: 20160927022250 builduid: f92cbe29358b499ab47884c3242bd418 revision: 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:33.582397) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:33.582826) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:33.583517) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:33.614073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:33.614371) ========= 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.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 --2016-09-27 05:04:33-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-09-27 05:04:34 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.464729 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.119117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.119424) ========= 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.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.021114 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.198163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.198516) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd --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 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 2016-09-27 05:04:34,262 truncating revision to first 12 chars 2016-09-27 05:04:34,262 Setting DEBUG logging. 2016-09-27 05:04:34,262 attempt 1/10 2016-09-27 05:04:34,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66a77b9bfe5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 05:04:34,650 unpacking tar archive at: mozilla-central-66a77b9bfe5d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682431 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.916194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.916501) ========= script_repo_revision: 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.919926) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.920218) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:04:34.938870) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-09-27 05:04:34.939193) ========= /tools/buildbot/bin/python 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', '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:04:35 INFO - MultiFileLogger online at 20160927 05:04:35 in /builds/slave/test 05:04:35 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 05:04:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:35 INFO - {'append_to_log': False, 05:04:35 INFO - 'base_work_dir': '/builds/slave/test', 05:04:35 INFO - 'blob_upload_branch': 'Firefox', 05:04:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:35 INFO - 'branch': 'Firefox', 05:04:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:35 INFO - 'config_files': ('talos/mac_config.py',), 05:04:35 INFO - 'default_actions': ('clobber', 05:04:35 INFO - 'read-buildbot-config', 05:04:35 INFO - 'download-and-extract', 05:04:35 INFO - 'populate-webroot', 05:04:35 INFO - 'create-virtualenv', 05:04:35 INFO - 'install', 05:04:35 INFO - 'run-tests'), 05:04:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:35 INFO - 'download_minidump_stackwalk': True, 05:04:35 INFO - 'download_symbols': 'ondemand', 05:04:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:35 INFO - 'installer_path': 'installer.exe', 05:04:35 INFO - 'log_level': 'info', 05:04:35 INFO - 'log_name': 'talos', 05:04:35 INFO - 'log_to_console': True, 05:04:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:04:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:04:35 INFO - 'opt_config_files': (), 05:04:35 INFO - 'pip_index': False, 05:04:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:35 INFO - 'cmd': ('bash', 05:04:35 INFO - '-c', 05:04:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:35 INFO - 'enabled': True, 05:04:35 INFO - 'halt_on_failure': False, 05:04:35 INFO - 'name': 'check_screen_resolution'},), 05:04:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:35 INFO - 'cmd': ('bash', 05:04:35 INFO - '-c', 05:04:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:35 INFO - 'enabled': True, 05:04:35 INFO - 'halt_on_failure': False, 05:04:35 INFO - 'name': 'check_screen_resolution'},), 05:04:35 INFO - 'run_cmd_checks_enabled': True, 05:04:35 INFO - 'sps_profile': False, 05:04:35 INFO - 'sps_profile_interval': 0, 05:04:35 INFO - 'suite': 'dromaeojs-e10s', 05:04:35 INFO - 'system_bits': '32', 05:04:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:35 INFO - 'title': 't-yosemite-r7-0228', 05:04:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:35 INFO - 'use_talos_json': True, 05:04:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:35 INFO - 'work_dir': 'build'} 05:04:35 INFO - [mozharness: 2016-09-27 12:04:35.108510Z] Running clobber step. 05:04:35 INFO - Running pre-action listener: _resource_record_pre_action 05:04:35 INFO - Running main action method: clobber 05:04:35 INFO - rmtree: /builds/slave/test/build 05:04:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:35 INFO - Running post-action listener: _resource_record_post_action 05:04:35 INFO - [mozharness: 2016-09-27 12:04:35.344419Z] Finished clobber step (success) 05:04:35 INFO - [mozharness: 2016-09-27 12:04:35.344547Z] Running read-buildbot-config step. 05:04:35 INFO - Running pre-action listener: _resource_record_pre_action 05:04:35 INFO - Running main action method: read_buildbot_config 05:04:35 INFO - Using buildbot properties: 05:04:35 INFO - { 05:04:35 INFO - "project": "", 05:04:35 INFO - "product": "firefox", 05:04:35 INFO - "script_repo_revision": "production", 05:04:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:04:35 INFO - "repository": "", 05:04:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:04:35 INFO - "buildid": "20160927022250", 05:04:35 INFO - "pgo_build": "False", 05:04:35 INFO - "basedir": "/builds/slave/test", 05:04:35 INFO - "buildnumber": 126, 05:04:35 INFO - "slavename": "t-yosemite-r7-0228", 05:04:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:04:35 INFO - "platform": "yosemite_r7", 05:04:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:04:35 INFO - "branch": "mozilla-central", 05:04:35 INFO - "repo_path": "mozilla-central", 05:04:35 INFO - "revision": "66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd", 05:04:35 INFO - "stage_platform": "macosx64", 05:04:35 INFO - "builduid": "f92cbe29358b499ab47884c3242bd418", 05:04:35 INFO - "slavebuilddir": "test" 05:04:35 INFO - } 05:04:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:04:35 INFO - Running post-action listener: _resource_record_post_action 05:04:35 INFO - [mozharness: 2016-09-27 12:04:35.347837Z] Finished read-buildbot-config step (success) 05:04:35 INFO - [mozharness: 2016-09-27 12:04:35.347967Z] Running download-and-extract step. 05:04:35 INFO - Running pre-action listener: _resource_record_pre_action 05:04:35 INFO - Running main action method: download_and_extract 05:04:35 INFO - mkdir: /builds/slave/test/build/tests 05:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:35 INFO - trying https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:04:35 INFO - Downloading https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:04:36 INFO - Downloaded 1199 bytes. 05:04:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:04:36 INFO - Using the following test package requirements: 05:04:36 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:04:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:04:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:04:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'jsshell-mac.zip'], 05:04:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:04:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:04:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:04:36 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:04:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:04:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:36 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:04:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:04:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:04:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:36 INFO - Fetch https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:04:37 INFO - Expected file size: 16470485 05:04:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:04:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:04:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:43 INFO - Fetch https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:04:44 INFO - Expected file size: 16470485 05:04:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:04:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:04:50 INFO - Fetch https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:04:51 INFO - Expected file size: 14414057 05:04:52 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')]} 05:04:52 INFO - trying https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:52 INFO - Downloading https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:04:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:04:54 INFO - Downloaded 111551339 bytes. 05:04:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:54 INFO - mkdir: /builds/slave/test/properties 05:04:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:54 INFO - Contents: 05:04:54 INFO - build_url:https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:55 INFO - Running post-action listener: _resource_record_post_action 05:04:55 INFO - Running post-action listener: set_extra_try_arguments 05:04:55 INFO - [mozharness: 2016-09-27 12:04:55.597155Z] Finished download-and-extract step (success) 05:04:55 INFO - [mozharness: 2016-09-27 12:04:55.597304Z] Running populate-webroot step. 05:04:55 INFO - Running pre-action listener: _resource_record_pre_action 05:04:55 INFO - Running main action method: populate_webroot 05:04:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:55 INFO - '--tppagecycles', 05:04:55 INFO - '7'], 05:04:55 INFO - 'tests': ['tsvgm']}}, 05:04:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['damp', 'tps']}, 05:04:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'tests': ['damp', 'tps']}, 05:04:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['dromaeo_dom']}, 05:04:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['basic_compositor_video']}, 05:04:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:04:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['a11yr', 05:04:55 INFO - 'ts_paint', 05:04:55 INFO - 'tpaint', 05:04:55 INFO - 'sessionrestore', 05:04:55 INFO - 'sessionrestore_no_auto_restore', 05:04:55 INFO - 'tabpaint']}, 05:04:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['tsvgx', 05:04:55 INFO - 'tsvgr_opacity', 05:04:55 INFO - 'tart', 05:04:55 INFO - 'tscrollx', 05:04:55 INFO - 'cart']}, 05:04:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:55 INFO - 'tsvgr_opacity', 05:04:55 INFO - 'tart', 05:04:55 INFO - 'tscrollx', 05:04:55 INFO - 'cart']}, 05:04:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'talos_options': ['--disable-e10s'], 05:04:55 INFO - 'tests': ['tp5o']}, 05:04:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'tests': ['tp5o']}, 05:04:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'talos_options': ['--disable-e10s', 05:04:55 INFO - '--xperf_path', 05:04:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:55 INFO - 'tests': ['tp5n']}, 05:04:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:55 INFO - 'talos_options': ['--xperf_path', 05:04:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:55 INFO - 'tests': ['tp5n']}}, 05:04:55 INFO - 'talos.zip': {'path': '', 05:04:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:55 INFO - Running post-action listener: _resource_record_post_action 05:04:55 INFO - [mozharness: 2016-09-27 12:04:55.616521Z] Finished populate-webroot step (success) 05:04:55 INFO - [mozharness: 2016-09-27 12:04:55.616634Z] Running create-virtualenv step. 05:04:55 INFO - Running pre-action listener: _resource_record_pre_action 05:04:55 INFO - Running main action method: create_virtualenv 05:04:55 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:55 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 05:04:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:55 INFO - Using real prefix '/tools/python27' 05:04:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:56 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:59 INFO - Installing pip.................done. 05:04:59 INFO - Return code: 0 05:04:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:59 INFO - Reading from file tmpfile_stdout 05:04:59 INFO - Output received: 05:04:59 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) 05:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:59 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:04:59 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 05:04:59 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 05:04:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:04:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:59 INFO - 'HOME': '/Users/cltbld', 05:04:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:59 INFO - 'LOGNAME': 'cltbld', 05:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:59 INFO - 'MOZ_NO_REMOTE': '1', 05:04:59 INFO - 'NO_EM_RESTART': '1', 05:04:59 INFO - 'PAGER': '/bin/cat', 05:04:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:59 INFO - 'PWD': '/builds/slave/test', 05:04:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:59 INFO - 'SHELL': '/bin/bash', 05:04:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:04:59 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:04:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:59 INFO - 'USER': 'cltbld', 05:04:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:59 INFO - 'XPC_FLAGS': '0x0', 05:04:59 INFO - 'XPC_SERVICE_NAME': '0', 05:04:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:59 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 05:04:59 INFO - Cleaning up... 05:04:59 INFO - Return code: 0 05:04:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:59 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:04:59 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 05:04:59 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 05:04:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:04:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:59 INFO - 'HOME': '/Users/cltbld', 05:04:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:59 INFO - 'LOGNAME': 'cltbld', 05:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:59 INFO - 'MOZ_NO_REMOTE': '1', 05:04:59 INFO - 'NO_EM_RESTART': '1', 05:04:59 INFO - 'PAGER': '/bin/cat', 05:04:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:59 INFO - 'PWD': '/builds/slave/test', 05:04:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:59 INFO - 'SHELL': '/bin/bash', 05:04:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:04:59 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:04:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:59 INFO - 'USER': 'cltbld', 05:04:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:59 INFO - 'XPC_FLAGS': '0x0', 05:04:59 INFO - 'XPC_SERVICE_NAME': '0', 05:04:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:00 INFO - Downloading/unpacking psutil>=3.1.1 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:03 INFO - Installing collected packages: psutil 05:05:03 INFO - Running setup.py install for psutil 05:05:04 INFO - building 'psutil._psutil_osx' extension 05:05:04 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 05:05:04 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 05:05:04 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 05:05:04 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 05:05:04 INFO - building 'psutil._psutil_posix' extension 05:05:04 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 05:05:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:04 INFO - ^ 05:05:04 INFO - 1 warning generated. 05:05:04 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 05:05:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:04 INFO - Successfully installed psutil 05:05:04 INFO - Cleaning up... 05:05:04 INFO - Return code: 0 05:05:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:05:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:04 INFO - 'HOME': '/Users/cltbld', 05:05:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:04 INFO - 'LOGNAME': 'cltbld', 05:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:04 INFO - 'MOZ_NO_REMOTE': '1', 05:05:04 INFO - 'NO_EM_RESTART': '1', 05:05:04 INFO - 'PAGER': '/bin/cat', 05:05:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:04 INFO - 'PWD': '/builds/slave/test', 05:05:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:04 INFO - 'SHELL': '/bin/bash', 05:05:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:04 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:04 INFO - 'USER': 'cltbld', 05:05:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:04 INFO - 'XPC_FLAGS': '0x0', 05:05:04 INFO - 'XPC_SERVICE_NAME': '0', 05:05:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:05 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 05:05:05 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 05:05:05 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 05:05:05 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 05:05:05 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 05:05:05 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 05:05:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:07 INFO - Installing collected packages: mozsystemmonitor 05:05:07 INFO - Running setup.py install for mozsystemmonitor 05:05:07 INFO - Successfully installed mozsystemmonitor 05:05:07 INFO - Cleaning up... 05:05:07 INFO - Return code: 0 05:05:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:07 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')]} 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:07 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')]} 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:07 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:07 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 05:05:07 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 05:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:07 INFO - 'HOME': '/Users/cltbld', 05:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:07 INFO - 'LOGNAME': 'cltbld', 05:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:07 INFO - 'MOZ_NO_REMOTE': '1', 05:05:07 INFO - 'NO_EM_RESTART': '1', 05:05:07 INFO - 'PAGER': '/bin/cat', 05:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:07 INFO - 'PWD': '/builds/slave/test', 05:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:07 INFO - 'SHELL': '/bin/bash', 05:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:07 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:07 INFO - 'USER': 'cltbld', 05:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:07 INFO - 'XPC_FLAGS': '0x0', 05:05:07 INFO - 'XPC_SERVICE_NAME': '0', 05:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:10 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 05:05:10 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 05:05:10 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 05:05:10 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 05:05:10 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 05:05:10 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 05:05:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:10 INFO - warning: no files found matching '*.txt' 05:05:10 INFO - no previously-included directories found matching 'build' 05:05:10 INFO - no previously-included directories found matching 'dist' 05:05:10 INFO - no previously-included directories found matching '.git*' 05:05:10 INFO - Installing collected packages: jsonschema, functools32 05:05:11 INFO - Running setup.py install for functools32 05:05:11 INFO - warning: no files found matching '*.txt' 05:05:11 INFO - no previously-included directories found matching 'build' 05:05:11 INFO - no previously-included directories found matching 'dist' 05:05:11 INFO - no previously-included directories found matching '.git*' 05:05:11 INFO - Successfully installed jsonschema functools32 05:05:11 INFO - Cleaning up... 05:05:11 INFO - Return code: 0 05:05:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:11 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:11 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 05:05:11 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 05:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:11 INFO - 'HOME': '/Users/cltbld', 05:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:11 INFO - 'LOGNAME': 'cltbld', 05:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:11 INFO - 'MOZ_NO_REMOTE': '1', 05:05:11 INFO - 'NO_EM_RESTART': '1', 05:05:11 INFO - 'PAGER': '/bin/cat', 05:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:11 INFO - 'PWD': '/builds/slave/test', 05:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:11 INFO - 'SHELL': '/bin/bash', 05:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:11 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:11 INFO - 'USER': 'cltbld', 05:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:11 INFO - 'XPC_FLAGS': '0x0', 05:05:11 INFO - 'XPC_SERVICE_NAME': '0', 05:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:11 INFO - Cleaning up... 05:05:11 INFO - Return code: 0 05:05:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:11 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:11 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 05:05:11 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 05:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:11 INFO - 'HOME': '/Users/cltbld', 05:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:11 INFO - 'LOGNAME': 'cltbld', 05:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:11 INFO - 'MOZ_NO_REMOTE': '1', 05:05:11 INFO - 'NO_EM_RESTART': '1', 05:05:11 INFO - 'PAGER': '/bin/cat', 05:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:11 INFO - 'PWD': '/builds/slave/test', 05:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:11 INFO - 'SHELL': '/bin/bash', 05:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:11 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:11 INFO - 'USER': 'cltbld', 05:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:11 INFO - 'XPC_FLAGS': '0x0', 05:05:11 INFO - 'XPC_SERVICE_NAME': '0', 05:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:11 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 05:05:11 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 05:05:11 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 05:05:11 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 05:05:11 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 05:05:11 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 05:05:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:14 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 05:05:15 INFO - Downloading docopt-0.6.1.tar.gz 05:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:15 INFO - Running setup.py install for blobuploader 05:05:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:15 INFO - Running setup.py install for requests 05:05:16 INFO - Running setup.py install for docopt 05:05:16 INFO - Successfully installed blobuploader requests docopt 05:05:16 INFO - Cleaning up... 05:05:16 INFO - Return code: 0 05:05:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:16 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 05:05:16 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 05:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:16 INFO - 'HOME': '/Users/cltbld', 05:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:16 INFO - 'LOGNAME': 'cltbld', 05:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:16 INFO - 'MOZ_NO_REMOTE': '1', 05:05:16 INFO - 'NO_EM_RESTART': '1', 05:05:16 INFO - 'PAGER': '/bin/cat', 05:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:16 INFO - 'PWD': '/builds/slave/test', 05:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:16 INFO - 'SHELL': '/bin/bash', 05:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:16 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:16 INFO - 'USER': 'cltbld', 05:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:16 INFO - 'XPC_FLAGS': '0x0', 05:05:16 INFO - 'XPC_SERVICE_NAME': '0', 05:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:16 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tyx3K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:16 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ZIKyVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:16 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uHmqZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:16 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-U_oRfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:16 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uZ8f2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xdNwR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-cm5Jpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tCrJ87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SGeyIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-fRyjqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-oRjwgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uk7hVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7rUWli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-n_kDK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:17 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kPgeW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:18 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-I2RtJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:18 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ycXGkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:18 INFO - Running setup.py install for manifestparser 05:05:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:18 INFO - Running setup.py install for mozcrash 05:05:18 INFO - Running setup.py install for mozdebug 05:05:18 INFO - Running setup.py install for mozdevice 05:05:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:18 INFO - Running setup.py install for mozfile 05:05:19 INFO - Running setup.py install for mozhttpd 05:05:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Running setup.py install for mozinfo 05:05:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Running setup.py install for mozInstall 05:05:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Running setup.py install for mozleak 05:05:19 INFO - Running setup.py install for mozlog 05:05:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Running setup.py install for moznetwork 05:05:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:19 INFO - Running setup.py install for mozprocess 05:05:20 INFO - Running setup.py install for mozprofile 05:05:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:20 INFO - Running setup.py install for mozrunner 05:05:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:20 INFO - Running setup.py install for mozscreenshot 05:05:20 INFO - Running setup.py install for moztest 05:05:20 INFO - Running setup.py install for mozversion 05:05:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:20 INFO - Cleaning up... 05:05:20 INFO - Return code: 0 05:05:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:20 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:20 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 05:05:20 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 05:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:20 INFO - 'HOME': '/Users/cltbld', 05:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:20 INFO - 'LOGNAME': 'cltbld', 05:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:20 INFO - 'MOZ_NO_REMOTE': '1', 05:05:20 INFO - 'NO_EM_RESTART': '1', 05:05:20 INFO - 'PAGER': '/bin/cat', 05:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:20 INFO - 'PWD': '/builds/slave/test', 05:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:20 INFO - 'SHELL': '/bin/bash', 05:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:20 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:20 INFO - 'USER': 'cltbld', 05:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:20 INFO - 'XPC_FLAGS': '0x0', 05:05:20 INFO - 'XPC_SERVICE_NAME': '0', 05:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tUK3HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SNocR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-FJGSYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-J7gvlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RLVY8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-E7pBxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Zux1Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-80wtuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:21 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)) 05:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QVB0C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:22 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-dWzvGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:22 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-P_mPEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:22 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-w2GUVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RMiRe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:22 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-hzGgSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-HIeGTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:22 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-pctlMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 05:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-UTal24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:22 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)) 05:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:22 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)) 05:05:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:22 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 05:05:22 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 05:05:22 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 05:05:22 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 05:05:22 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 05:05:22 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 05:05:25 INFO - Downloading blessings-1.6.tar.gz 05:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:25 INFO - Installing collected packages: blessings 05:05:25 INFO - Running setup.py install for blessings 05:05:25 INFO - Successfully installed blessings 05:05:25 INFO - Cleaning up... 05:05:25 INFO - Return code: 0 05:05:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:26 INFO - Reading from file tmpfile_stdout 05:05:26 INFO - Current package versions: 05:05:26 INFO - blessings == 1.6 05:05:26 INFO - blobuploader == 1.2.4 05:05:26 INFO - docopt == 0.6.1 05:05:26 INFO - functools32 == 3.2.3-2 05:05:26 INFO - jsonschema == 2.5.1 05:05:26 INFO - manifestparser == 1.1 05:05:26 INFO - mozInstall == 1.12 05:05:26 INFO - mozcrash == 0.17 05:05:26 INFO - mozdebug == 0.1 05:05:26 INFO - mozdevice == 0.48 05:05:26 INFO - mozfile == 1.2 05:05:26 INFO - mozhttpd == 0.7 05:05:26 INFO - mozinfo == 0.9 05:05:26 INFO - mozleak == 0.1 05:05:26 INFO - mozlog == 3.4 05:05:26 INFO - moznetwork == 0.27 05:05:26 INFO - mozprocess == 0.23 05:05:26 INFO - mozprofile == 0.28 05:05:26 INFO - mozrunner == 6.12 05:05:26 INFO - mozscreenshot == 0.1 05:05:26 INFO - mozsystemmonitor == 0.3 05:05:26 INFO - moztest == 0.7 05:05:26 INFO - mozversion == 1.4 05:05:26 INFO - psutil == 3.1.1 05:05:26 INFO - requests == 1.2.3 05:05:26 INFO - wsgiref == 0.1.2 05:05:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:26 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 05:05:26 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 05:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:26 INFO - 'HOME': '/Users/cltbld', 05:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:26 INFO - 'LOGNAME': 'cltbld', 05:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:26 INFO - 'MOZ_NO_REMOTE': '1', 05:05:26 INFO - 'NO_EM_RESTART': '1', 05:05:26 INFO - 'PAGER': '/bin/cat', 05:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:26 INFO - 'PWD': '/builds/slave/test', 05:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:26 INFO - 'SHELL': '/bin/bash', 05:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:26 INFO - 'USER': 'cltbld', 05:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:26 INFO - 'XPC_FLAGS': '0x0', 05:05:26 INFO - 'XPC_SERVICE_NAME': '0', 05:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 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)) 05:05:26 INFO - Cleaning up... 05:05:26 INFO - Return code: 0 05:05:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 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 0x106b3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0c34850d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, '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.kngfkE9lCe/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.6gMZxsGpAR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 05:05:26 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 05:05:26 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 05:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:26 INFO - 'HOME': '/Users/cltbld', 05:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:26 INFO - 'LOGNAME': 'cltbld', 05:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:26 INFO - 'MOZ_NO_REMOTE': '1', 05:05:26 INFO - 'NO_EM_RESTART': '1', 05:05:26 INFO - 'PAGER': '/bin/cat', 05:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:26 INFO - 'PWD': '/builds/slave/test', 05:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:26 INFO - 'SHELL': '/bin/bash', 05:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:05:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:26 INFO - 'USER': 'cltbld', 05:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:26 INFO - 'XPC_FLAGS': '0x0', 05:05:26 INFO - 'XPC_SERVICE_NAME': '0', 05:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:26 INFO - Cleaning up... 05:05:26 INFO - Return code: 0 05:05:26 INFO - Running post-action listener: _resource_record_post_action 05:05:26 INFO - Running post-action listener: _start_resource_monitoring 05:05:26 INFO - Starting resource monitoring. 05:05:26 INFO - [mozharness: 2016-09-27 12:05:26.656265Z] Finished create-virtualenv step (success) 05:05:26 INFO - [mozharness: 2016-09-27 12:05:26.657347Z] Running install step. 05:05:26 INFO - Running pre-action listener: _resource_record_pre_action 05:05:26 INFO - Running main action method: install 05:05:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:26 INFO - Reading from file tmpfile_stdout 05:05:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:27 INFO - Reading from file tmpfile_stdout 05:05:27 INFO - Output received: 05:05:27 INFO - Usage: mozinstall [options] installer 05:05:27 INFO - Options: 05:05:27 INFO - -h, --help show this help message and exit 05:05:27 INFO - -d DEST, --destination=DEST 05:05:27 INFO - Directory to install application into. [default: 05:05:27 INFO - "/builds/slave/test"] 05:05:27 INFO - --app=APP Application being installed. [default: firefox] 05:05:27 INFO - mkdir: /builds/slave/test/build/application 05:05:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:06:01 INFO - Reading from file tmpfile_stdout 05:06:01 INFO - Output received: 05:06:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:06:01 INFO - Running post-action listener: _resource_record_post_action 05:06:01 INFO - [mozharness: 2016-09-27 12:06:01.367739Z] Finished install step (success) 05:06:01 INFO - [mozharness: 2016-09-27 12:06:01.367899Z] Running run-tests step. 05:06:01 INFO - Running pre-action listener: _resource_record_pre_action 05:06:01 INFO - Running main action method: run_tests 05:06:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:01 INFO - 2016-09-27 05:06:01.382 screenresolution[2012:280928] starting screenresolution argv=screenresolution get 05:06:01 INFO - 2016-09-27 05:06:01.385 screenresolution[2012:280928] Display 0: 1600x1200x32@60 05:06:01 INFO - 2016-09-27 05:06:01.391 screenresolution[2013:280930] starting screenresolution argv=screenresolution list 05:06:01 INFO - Available Modes on Display 0 05:06:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:01 INFO - Intel Iris: 05:06:01 INFO - Chipset Model: Intel Iris 05:06:01 INFO - Type: GPU 05:06:01 INFO - Bus: Built-In 05:06:01 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:01 INFO - Vendor: Intel (0x8086) 05:06:01 INFO - Device ID: 0x0a2e 05:06:01 INFO - Revision ID: 0x0009 05:06:01 INFO - Displays: 05:06:01 INFO - Display: 05:06:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:01 INFO - Display Serial Number: 1600x1200 60 05:06:01 INFO - Main Display: Yes 05:06:01 INFO - Mirror: Off 05:06:01 INFO - Online: Yes 05:06:01 INFO - Rotation: Supported 05:06:01 INFO - Return code: 0 05:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:01 INFO - Python 2.7.3 05:06:01 INFO - Return code: 0 05:06:01 INFO - grabbing minidump binary from tooltool 05:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:01 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694ed30>, '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 0x106774db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106946b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:06:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:06:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:06:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:01 INFO - Return code: 0 05:06:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 05:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --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 05:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kngfkE9lCe/Render', 05:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:01 INFO - 'HOME': '/Users/cltbld', 05:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:01 INFO - 'LOGNAME': 'cltbld', 05:06:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:01 INFO - 'MOZ_NO_REMOTE': '1', 05:06:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:01 INFO - 'NO_EM_RESTART': '1', 05:06:01 INFO - 'PAGER': '/bin/cat', 05:06:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:01 INFO - 'PWD': '/builds/slave/test', 05:06:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:01 INFO - 'SHELL': '/bin/bash', 05:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gMZxsGpAR/Listeners', 05:06:01 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 05:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:01 INFO - 'USER': 'cltbld', 05:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:01 INFO - 'XPC_FLAGS': '0x0', 05:06:01 INFO - 'XPC_SERVICE_NAME': '0', 05:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 05:06:01 INFO - mozversion application_buildid: 20160927022250 05:06:01 INFO - mozversion application_changeset: 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd 05:06:01 INFO - mozversion application_display_name: Nightly 05:06:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:01 INFO - mozversion application_name: Firefox 05:06:01 INFO - mozversion application_remotingname: firefox 05:06:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:06:01 INFO - mozversion application_vendor: Mozilla 05:06:01 INFO - mozversion application_version: 52.0a1 05:06:01 INFO - mozversion platform_buildid: 20160927022250 05:06:01 INFO - mozversion platform_changeset: 66a77b9bfe5dcacd50eccf85de7c0e7e15ce0ffd 05:06:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:06:01 INFO - mozversion platform_version: 52.0a1 05:06:01 INFO - using testdate: 1474977961 05:06:01 INFO - actual date: 1474977961 05:06:01 INFO - starting webserver on 'localhost:49377' 05:06:01 INFO - SUITE-START | Running 2 tests 05:06:01 INFO - TEST-START | dromaeo_css 05:06:01 INFO - operating with platform_type : mac_ 05:06:01 INFO - Initialising browser for dromaeo_css test... 05:06:01 INFO - TEST-INFO | started process 2023 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpVGVzuE/profile http://localhost:49377/getInfo.html) 05:06:05 INFO - PROCESS | 2023 | 2016-09-27 05:06:05.036 plugin-container[2027:281100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 05:06:05 INFO - PROCESS | 2023 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:05 INFO - PROCESS | 2023 | 2016-09-27 05:06:05.040 plugin-container[2027:281100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 05:06:05 INFO - PROCESS | 2023 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:05 INFO - PROCESS | 2023 | 2016-09-27 05:06:05.040 plugin-container[2027:281100] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:05 INFO - PROCESS | 2023 | __metrics Screen width/height:1600/1200 05:06:05 INFO - PROCESS | 2023 | colorDepth:24 05:06:05 INFO - PROCESS | 2023 | Browser inner width/height: 1024/689 05:06:05 INFO - PROCESS | 2023 | __metrics 05:06:05 ERROR - PROCESS | 2023 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:06:05 INFO - TEST-INFO | 2023: exit 0 05:06:05 INFO - Browser initialized. 05:06:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:05 INFO - TEST-INFO | started process 2028 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpVGVzuE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:08 INFO - PROCESS | 2028 | 2016-09-27 05:06:08.193 plugin-container[2029:281216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8927, name = 'com.apple.tsm.portname' 05:06:08 INFO - PROCESS | 2028 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:08 INFO - PROCESS | 2028 | 2016-09-27 05:06:08.196 plugin-container[2029:281216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 05:06:08 INFO - PROCESS | 2028 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:08 INFO - PROCESS | 2028 | 2016-09-27 05:06:08.196 plugin-container[2029:281216] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:17 INFO - PROCESS | 2028 | RSS: Main: 239755264 05:06:17 INFO - PROCESS | 2028 | 05:07:07 INFO - PROCESS | 2028 | 1474978027558 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:09:19 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49377/tests/dromaeo/cssquery-ext.html) 05:09:20 INFO - PROCESS | 2028 | RSS: Main: 255242240 05:09:20 INFO - PROCESS | 2028 | 05:12:23 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-ext.html (next: http://localhost:49377/tests/dromaeo/cssquery-jquery.html) 05:12:23 INFO - PROCESS | 2028 | RSS: Main: 264761344 05:12:23 INFO - PROCESS | 2028 | 05:15:25 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49377/tests/dromaeo/cssquery-mootools.html) 05:15:26 INFO - PROCESS | 2028 | RSS: Main: 269389824 05:15:26 INFO - PROCESS | 2028 | 05:18:28 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49377/tests/dromaeo/cssquery-prototype.html) 05:18:29 INFO - PROCESS | 2028 | RSS: Main: 270901248 05:18:29 INFO - PROCESS | 2028 | 05:21:32 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49377/tests/dromaeo/cssquery-yui.html) 05:21:32 INFO - PROCESS | 2028 | RSS: Main: 276230144 05:21:32 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-yui.html (next: http://localhost:49377/tests/dromaeo/cssquery-dojo.html) 05:24:35 INFO - PROCESS | 2028 | RSS: Main: 279003136 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | __start_tp_report 05:24:35 INFO - PROCESS | 2028 | _x_x_mozilla_page_load 05:24:35 INFO - PROCESS | 2028 | _x_x_mozilla_page_load_details 05:24:35 INFO - PROCESS | 2028 | |i|pagename|runs| 05:24:35 INFO - PROCESS | 2028 | |0;dojo.html;3413.965860341399;3417.982910085449;3438.1576513754135;3458.41206994811;3489.494023366612;5715.057015592428;5738.42615738426;5779.566532510057;5794.884102317951;5843.4156584341545;5770.9422905770925;5779.306483222019;5784.68184249866;5814.09881468372;5887.411258874111;5232.529072383485;5272.789088436477;5319.361676598814;5339.119045357511;5356.54469370104;5119.744012799345;5239.476052394768;5285.339332583427;5337.226102215176;5344.331958505187;5183.326167598207;5201.765920533585;5260.184671375904;5304.602154838402;5336.51971322579;5251.317328747238;5268.5521730652945;5270.657407268515;5272.815451459181;5303.681779093228;5141.922871156936;5214.661047031931;5234.18870075139;5246.685198888079;5283.181106928432;5663.235463212455;5668.57485688575;5764.106563482625;5836.9124463133085;5840.620359676607;6185.876282474325;6249.812505624838;6258.906116408258;6268.529860260499;6281.560290779647;6223.1287619733275;6253;6304.085907543426;6307.905381419282;6320.74717011319;5735.397783232784;5760.654360738369;5806.44838740319;5814.76740930358;5839.182514447981;6349.777757778455;6350.618962862243;6355.269144048401;6357.8728425432155;6358.968205158944;6199;6201.534884883562;6219.533534984804;6219.813405597839;6224.502039836802;6110.741024289823;6127.097902969301;6163.660998645028;6227.595206311576;6284.622922624657;6147.9548476759055;6164.4143806338325;6187.628742275388;6194.287656919512;6210.658413787195;6188.938110618927;6189.04069869171;6200.937990620127;6212.254529456403;6212.285587157535;4431.534688857688;4914.311996320518;4924.852254432372;4964.35463389757;4966.379202599675;4440.7335559866515;4451.621612163002;4456.68803183774;4476.485204201494;4477.238869392211;5054.898902022012;5896.675682837399;5969.403059693996;5996.940030599725;6009.36901625323;6171.56799024064;6205.565610407228;6229.252489701174;6240.906386404207;6271.310155882849;4869.63477739171;5963.761449541969;6027.879442411215;6065.878682426327;6072.332043475214;5462.153366228241;5470.699111548824;5473.972630136904;5481;5487.42382049887;6250.624962502174;6264;6294.842628934313;6417.721708793404;6417.74329026843;6214.316425193316;6230.376962303733;6288.5598008138995;6316.147320111726;6323.936760632335;6326.177596912372;6366.076918846834;6374.553781235269;6378.521610879296;6476.12572302752;6099.24925446448;6170.784022559189;6242.563020588515;6246.3753624637175;6248.0940263660605;6225.159603453657;6231.158793562811;6288.402601752826;6296.087067375113;6298.779542715983;6188.690565471618;6211.068339748893;6226.937730622636;6243.469305109116;6248.375162483897;6077.604955677867;6187.195664563578;6230.937690623217;6238.126662267377;6240.906386404298;4552.8861778454475;5902.085176797603;5942.524598032061;6045.6070355426755;6161.507079433724;6138.5396095292135;6139.938600614116;6176.752929882754;6200.720967556561;6251.874962500634;5945.554083443672;5991.3409524953095;6112.388761123852;6113.388661134029;6133.723982420713 05:24:35 INFO - PROCESS | 2028 | |1;ext.html;471.66511776638583;494.8070252601485;530.2179285553808;533.2853975672598;541.8780774325775;39189;41182.58817411825;41236;41296.79351603241;41345.793271033675;42616.78691606538;43268;43320;43339.3499097513;43362.132757344836;9749.366291191085;9814.754631134207;9829.115379615832;9864.112229899307;9895.50522473877;9873.851892221623;9895;9896.703098907044;9906.752331191741;9930.950345248264;9889.307748457612;9912.504374781269;9915.504224788769;9919;9931.453770042644;9061.821963144748;9865.852012219822;9884.011598840094;9902.009799020112;9929.900700993043;8522.573871306471;8768.210861022475;9253.583588738456;9289.767755806091;9600.66397676078;28198.859005705046;40594.797026014974;40691.38962915559;40744.38883416751;40898.79550602258;3320.8173550454717;3350.64818194091;3361.6974472297366;3368.764186506921;3386.8983930482127;29327.70672293271;30402;30701.078967631005;31452.37095258105;31748.841255793574;3182.6817318268218;3188.3463889902514;3217.308278720063;3228.531862879869;3311.3211791582667;395568;400507;402642.9867850671;403657;406361;63631;64992.35007649958;65086.349136508026;65101.67449162724;65170;35223.82388088043;36150.63849361473;68147;68297.31702683009;68716;16124.596885077732;16227.675446491241;16646.334146634243;16669.41657042022;16809.6638067238;12681.175723578122;12751.936240318924;12752.553660621834;12768;12781.936090319676;16665;20373.796262037486;20658.690119648218;20675.483112921997;20709.964501775154;8140.389470789714;8918.55407229636;8999.37004409685;9000.909990900138;9089.681861134828;831.8377916306263;875.369733791669;978.6966040527459;1047.371577053777;1051.9474026298808;28288.00991965271;29914.70085299119;30224;30630.846845766075;30982.690173098432;917.513717729591;1020.8213562626511;1039.147898723055;1070.4059247117768;1081.7566047639218;12033.63899083029;13289.671032896826;15381;15642.368747250981;15863.286152123183;495577;530696;530815;531442;531661;56564.71717641468;56668.63307770046;56808;57309.71345143331;57559;1373.6291201375573;1374.8762611365025;1401.810755547988;1403.2632867744312;1403.6841710615233;27892;29328.56007159937;29436.852815735783;29702;29966;47608.76195619;47835;47850.5214947846;48020.27969580389;48031.519684802704;444293.5570644252;450904;451774;452707;455575;26952.460950781267;27256.863715681295;27287.863560682068;27294.590581141696;27383.86308068447;46047.76976115098;46552.254963303545;46670.76664616655;47019;47702;38302.42546361751;38616.66898014968;38621.80689096537;39520.802395987834;39929.2014159721;37986.62013379831;38068.42897356486;38711;38756.031099222746;39338.80330598443;37113.88658340254;37281.08548602466;37701.24597508089;37962;38107.047323817125;32229.838850805594;33771.81798637036;33890;34012.149696257795;34066.318673625894;23842.284731458083;24157.516849662556;24461.143859965527;24513.754862451147;24556.631650524898 05:24:35 INFO - PROCESS | 2028 | |2;jquery.html;321.2018134934688;340.71550255536624;372.82849889051045;383.72755343705967;398.3288159451325;19278.90360548197;19388.41834744958;20625.174993000262;20778.480537986557;20888.791112088875;19500.9274489903;20327;20331.898340508276;20390.082446289915;20831.375058748225;5395.865103372408;5541.27963364764;5589.664620122806;5612.242347283126;5978.730957106919;4978.684911516144;5225.947740522603;5454.754536045887;6121.969390153043;6139.478144357735;5796.768129274824;6083.8174854754425;6119.204503414572;6122.969385153068;6132.570720049598;6130.3141636049995;6134.570580059398;6155.230596175478;6165.366177962821;6167.537434692416;5865.794697185578;5886.70566471679;5997.190379298782;6040.365761595013;6058.4244496772735;12787.168834025759;12944.611661650166;13021;24178.39554011146;24850.378740531633;2142.967855482169;2189.5183059726833;2412.8418359187513;2629.329520972159;2893.363460038788;9466.668666596704;9861.309708320421;10132.189424845994;10345.844812327821;10379.962003799636;1644.9917750411291;1933;1942.7765806932243;1966.9901650491656;1983.880967141976;112558.31162532569;119711;120001.99999999913;120760.39619801845;122631.99999999911;9169.77075573116;11343.489542970587;11552.942235288938;11591.130665200144;11765.411729413496;14317.498887538886;14347.139171649735;14366.497172598909;14375.856241437661;14387.856121438861;6639.302873198341;8479.745607631781;8545.401821872409;8589.914100858912;8646.308295336357;8500.574971251413;8514.659413623509;8525.360597955178;8667.73996780085;8703.782405439788;4394.318880573516;4532.546745325441;4539.660800063989;4576.176288268143;4624.468186158568;1639.524537884024;1940.951476213106;1941.4272789527124;1942.1066309497508;1946.1826033066145;814.8981377327834;886.9866951995596;911.4368857409575;922.3220932614523;942.8255772682076;20926.267580634605;21472.78527214739;21666.783332166477;21773.23793667214;23416.531669366857;750.7147284032142;840.9026220781816;936.0499093420048;942.7171848445439;948.6821914658667;11179.385133817555;11401.657950261506;11429.485673144729;11444.656660300205;11454.026407755267;454353;468958;472118;473455;490735;69138.65430672815;69921.30078699147;70199;70641.58716825738;79144.6042769794;890.4790697442069;968.2979839616081;999.8200323941614;1002.7192386131894;1002.8094662014194;18072.819271807115;18354;18629.068546572347;18878.05609719536;19120.713189301892;54146.45853541572;54258;54359.45640543544;54717.72641136769;55353;71225.64387178238;73093.34533273421;75120;78394.6080269595;81114;5874.412558744091;6436.809190299809;6974.860502790018;7316.890246646198;7962.084360298408;9987.500624968869;12670.683232919251;12765.234085954873;13024.934875325942;13276.137051091648;11007.174461915229;11214.439278035903;11395.373254471084;11417.714557136138;11452.713682157679;10104.49477526106;10574.788504229718;10889.292196007234;10983;11140.108791296514;10555.313904596176;11519.078473721915;11544.942275288906;11630.767384652429;11716.882831171579;7994.720184793504;8132.67469301245;8264.710735124241;8268.173182681683;8279;10460.843087353544;11254.774904502028;11386.487608057492;11400.429978501208;11427.257228280469 05:24:35 INFO - PROCESS | 2028 | |3;mootools.html;1505.277466815928;1559.6724687815558;1727.3695101288033;1779.3416435918705;1900.401373567327;7334.229905859888;8191.754247372573;8280.585970701472;9210.30922680799;9229.861552076716;8618.827623447527;8910.198082172603;8952.552372381388;9069;9083.137101975319;2009.974912794478;2379.8096152307817;2412.2522018174423;2416.9516609667794;2436.244764123119;2149.3014770199748;2377.5363804058215;2400.7359190489033;2403.507281007398;2409.6867407237037;2379.6192609182535;2396.101461951768;2407.879606019701;2409.795167410772;2417.492326611414;2361.2325994052003;2367.5738367094004;2367.7868991790733;2383.833131680783;2399.9280021599207;2375.714914210306;2385.0459816073535;2389.832711710181;2391.6890804195345;2398;8892;8978.50618215998;9024.278057755364;9034.54827258641;9047.321450891144;5198.298229738974;5404.945950540523;5446;5451.809186678487;5452.945470545284;3637.8181090945345;3681.2821499807546;3682.2819550187724;3722.590515043343;3733.9253214935547;1089.5641743302663;1091.6233899304727;1091.7161538000178;1091.8307662312354;1108.141190577301;11929.642110736691;11970.281783093042;12069.939650301692;12097.455614497369;12104.81842772359;4711.293306004127;4724.478424005908;4741.454732705783;4871.050145221648;4912.901741965212;9648.855267170999;10140.797184056277;10243.692689219335;10264.897351026544;10716.249862509705;4086.3053280942313;4467.910641787211;4610.377599024089;4927.704337739747;5405.243265942692;4001.799910004488;4082.9591704082577;4184.660908509248;4747.454042785055;4931.802727890916;3394.405978953673;3982.8605998789903;3984.5019372578427;4046.251443482965;4112.773797441109;1165.9125565582615;1248.9000879929583;1296.7730647136714;1309.2668105860557;1312.4159748911643;797.401948538596;875.711015364928;899.3030401438937;911.4941207629829;913.830941275866;5839.854003649943;5919.6152250103605;5926.614770039933;6122;6241.812745617639;822.33390953328;868.1535502884637;899.964001439935;949.8765160529088;975.8145952269189;3894.1822217334675;3996.2606917720314;3999.600039995977;4090.2228576570355;4092.46797916269;10208.234382421348;10709.892901071045;10718.196135289885;10872.45637718111;10877.782444351069;10878;10885.564577416973;10896.61861834832;10896.891031089588;10929.234953553334;8086.9191308086165;8197.180281971756;8282.792930176673;8321.459105158145;8325.458845175044;5519.862003449946;5688.943110569007;5703.515201207943;6035.818925432244;6080.817575472743;8811.647534098563;9971.30200885931;10199.898001019896;10237.795244095225;10268.948655256676;9902.702918912444;10457.68626941193;10475.895241047492;10484.21368397358;10486.108680761916;7150.570965742072;7324.414046876131;7476;7712.730054448068;7863.882041769263;8401.579921004048;8672.349573781865;8675.175858293453;8681;8692;7572.583507907118;7700.768976930701;7713.691452342068;7793.9220607793195;7835.5690437026515;6370.4903607712195;7395.334419902235;7494.437917156126;7563.848723025399;7658.655360508679;7065.646717664199;7071.646417679199;7083.185433675193;7188.317109874553;7210.675519601736;4310.857622729916;4863.440704318907;4934.383202099738;5068.746562671778;5130.846074617767;4594.9310760339295;5022.4977502249485;5026.4973502649445;5062.772175252197;5128.487151284841 05:24:35 INFO - PROCESS | 2028 | |4;prototype.html;300.4561743244727;304.3942554316909;312.40175064751;316.1968599756618;317.4809186979288;4834.960483496047;5190.273361729356;5337.42545948945;5480.04099282626;5687.402822703619;5230.686158830473;5446.183072539121;5515.4760297771745;5516.696581688006;5554;3847.711421643374;4203.663706903456;4236.258654735417;4246;4257.7445353278745;3711.4247291669835;4058.736182148166;4156.2934301168725;4226.767527785971;4244.745315281093;4118.670506359484;4130.442390277319;4189.63836753055;4202.936955945664;4203.432536607549;3799.7270914243763;3817.3319669058083;4184.686148538872;4195.517515485728;4225.154969006211;3061.862216200276;3661.176235347026;3692.667659910594;3693.298273328086;3698.5746639136573;3989.860354887565;4054.16889537647;4380.18966491197;4943.431505376891;5300.337457817773;4788.401449818773;4808.3989501312335;4812.687175333627;4837.782299796518;4838.274258861164;4382.737035777864;4996.975015124901;5807.448292412257;5829.504492118175;5837.211976383219;3336.516205150264;3442.4853064651475;3985.8241818663564;3993.600639936012;4010.458588090629;4891.874868780165;5501.807436739695;5535.557155427597;6175.44421002112;6200.224971878515;4054.412110243998;4065.682560940295;4069.4709687740997;4079.551249362568;4091.202215567995;3952.6837852971694;4102.528209255914;4107.665008872129;4137.110521237888;4148.2947898857265;3784.262068896539;4189.994401343655;4613.884652883705;4754.716226618793;4807.326974223612;4631.490536041033;4929.433115191799;5832.912506312408;5836.7957121501395;5890.4993075589055;1105.40308233553;1322.4710115953696;1344.7243315120377;1371.0745246958263;1386.83357997041;888.8266787976412;937.4843835890233;974.9756256093655;977.7213494154132;979.2166266986383;4404.801783919731;4653.790579423934;5439.85125875277;5501.669899806025;5522.364928033247;5491.670499770026;5839.394166604217;5933.495652869555;6024.126501657235;6029.8191054268445;4530.682852200381;5196.506331898464;5493.395726470085;5497;5519.448055194448;2973.8550657996393;3167.8099314041233;3190.074878285271;3198.4082944655315;3221.0658908916153;5450.509454149145;6284.24589049317;6312.6212427253595;6322.747090116344;6393.296737358887;5307.708076055854;5720.685362305113;5781;6262.870623987775;6277.7175027123885;5765.452282033284;6319.6208227506495;6348.365163483615;6352.968235158794;6371.8088457347285;5693.572982026281;6060.39396060408;6118.724657390517;6119.663418512025;6184.752609895554;5786.42135786418;6025.909611355745;6052.727627256696;6079.300880398634;6100.328963813889;5832.445917637818;6104.8779024420155;6149.063045140766;6324.462420694293;6380.266269378895;3853.595372485961;3967.4842270505806;3994.3409337458993;4007.3788562772816;4016.678665706678;4758.500357462417;5652.236948011966;5810.186573879576;6108.541859360655;6241.344658810759;4400.955990440055;5260.502270558617;5545.445455454423;5686.829395118153;5886.558508111823;5391.818826892152;5936.732847021808;5938.910916336345;5939.851503712442;5953.761849526144;5370.3287089113865;5734.885302294014;5796.565257605781;5861;5873.353931067664;5156.871078223075;5780.508656764222;5861.593217627824;5917.230760001345;5927.229460170151;4270.636995855263;5542.307211598551;5831.501099340531;5835.183074369506;5871.884341975011 05:24:35 INFO - PROCESS | 2028 | |5;yui.html;650.9804705858822;732.77284041947;733.6038539188838;766.9194734552872;827.780638130895;14705.117692938431;15272.923635381807;15553.53339399817;15644.296006679699;15679.45121920733;14501.274936253172;14505.564833055025;14543.927280363583;14561.271936403164;14658.63353416165;855.2217482091282;871.6164887449534;875.2166810704416;875.4878396138266;875.6978842299396;673.124937581145;692.5671455340412;738.2617382617383;865.2299453486371;874.64576846377;896.5203616065406;916.9679061232858;917.7980844214262;919.9310051746112;922.4280945813572;886.7251152142857;900.5542256582968;909.1454033208763;911.3620465674055;913.7989642278689;556.6576555418388;643.6234802640461;675.4562577125412;677.2076670295717;677.793273051719;14890.106593604418;14963.70072598542;15044;15053.698926021527;15072.321745521369;13680.52118175869;14315.427382904776;14483.986120971536;14711.190884501348;14743.926280368636;11674.591389301333;11771.941140294244;11778.352190629512;11883.227590206609;12054.397280135958;3660.5790334111653;3770.5286839145106;3825.158465137665;3845.038740314921;3877.495925529663;68104.6594767023;110581.44709276402;111036;111077.88922110676;111094;9076.727698169065;10656.840147397796;10696.358218506915;10830.350178989129;10922.890771092187;17603.55991100207;17977.910110449626;20206;20315;20480.38558843237;7944.404169687296;8034.317083048007;8110.918890811016;8233.135520770233;8251.793705157419;4415.735055896656;4434.623057040188;4479.529649386767;4491.955080449154;4643.233866412004;3560.6795388415167;3693.778373297611;3698.63013698628;3701.241245544657;3811.1805961718364;558.3104865491115;709.7835160276062;712.0814149746872;715.4812760748416;730.7369347035063;587.8030859662061;672.9461643068543;673.4040374268808;674.5851301449671;684.7295318349225;8468.618912148968;8750.899064972358;9594.66418675343;9739.513024348897;9825.950870245602;606.3875485759414;650.1320736816309;650.6616559389091;673.0543586261213;707.9327463890922;4403.647708183337;4634.86095417138;4639.907201856012;4651.209294419957;4724.858254252378;89209.55395222982;89311.55344223237;126255.3687231558;144404;145918;99265;99853.50073249587;102247;124651.3767431157;126850;72671.63664181645;89784.55107724419;92589.5370523143;96747.51626241824;100741.49629251953;9407.059294070537;9587.808243834945;9922.56897060225;10009.647732782936;10035.648752293926;58235.417645823;59506;60920.08619870794;61281.69359153176;61770.38229617646;86623.56688216771;87069;89926.55036724775;92126;92220;12742.362881856056;12904.806427903777;13199.07606467538;13376.331183440594;13697.931510342385;14329.5793341848;14405.711885762435;14471.927640361731;14566.635834104232;15090.849091508944;10840.566377344818;13613.977603068855;13694.45222191141;13889.166650001032;14053.367598458299;13495;13626.386812593655;13717.245551494365;13807.240601767;14144.292785360896;13204;13308.467661293827;13460.394282257517;13853.030287879752;13937.930310348384;11114.610988615359;11144.721381965192;11163.330200188015;11296.887031129583;11347.716307092389;7006;10502.10732087781;11218.495167717636;11439.771204575696;11484.94257528707 05:24:35 INFO - PROCESS | 2028 | __end_tp_report 05:24:35 INFO - PROCESS | 2028 | __start_cc_report 05:24:35 INFO - PROCESS | 2028 | _x_x_mozilla_cycle_collect,362 05:24:35 INFO - PROCESS | 2028 | __end_cc_report 05:24:35 INFO - PROCESS | 2028 | __startTimestamp1474979075404__endTimestamp 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | ------- Summary: start ------- 05:24:35 INFO - PROCESS | 2028 | Number of tests: 6 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#0] dojo.html Cycles:165 Average:5787.29 Median:6099.25 stddev:651.85 (10.7%) stddev-sans-first:626.70 05:24:35 INFO - PROCESS | 2028 | Values: 3414.0 3418.0 3438.2 3458.4 3489.5 5715.1 5738.4 5779.6 5794.9 5843.4 5770.9 5779.3 5784.7 5814.1 5887.4 5232.5 5272.8 5319.4 5339.1 5356.5 5119.7 5239.5 5285.3 5337.2 5344.3 5183.3 5201.8 5260.2 5304.6 5336.5 5251.3 5268.6 5270.7 5272.8 5303.7 5141.9 5214.7 5234.2 5246.7 5283.2 5663.2 5668.6 5764.1 5836.9 5840.6 6185.9 6249.8 6258.9 6268.5 6281.6 6223.1 6253.0 6304.1 6307.9 6320.7 5735.4 5760.7 5806.4 5814.8 5839.2 6349.8 6350.6 6355.3 6357.9 6359.0 6199.0 6201.5 6219.5 6219.8 6224.5 6110.7 6127.1 6163.7 6227.6 6284.6 6148.0 6164.4 6187.6 6194.3 6210.7 6188.9 6189.0 6200.9 6212.3 6212.3 4431.5 4914.3 4924.9 4964.4 4966.4 4440.7 4451.6 4456.7 4476.5 4477.2 5054.9 5896.7 5969.4 5996.9 6009.4 6171.6 6205.6 6229.3 6240.9 6271.3 4869.6 5963.8 6027.9 6065.9 6072.3 5462.2 5470.7 5474.0 5481.0 5487.4 6250.6 6264.0 6294.8 6417.7 6417.7 6214.3 6230.4 6288.6 6316.1 6323.9 6326.2 6366.1 6374.6 6378.5 6476.1 6099.2 6170.8 6242.6 6246.4 6248.1 6225.2 6231.2 6288.4 6296.1 6298.8 6188.7 6211.1 6226.9 6243.5 6248.4 6077.6 6187.2 6230.9 6238.1 6240.9 4552.9 5902.1 5942.5 6045.6 6161.5 6138.5 6139.9 6176.8 6200.7 6251.9 5945.6 5991.3 6112.4 6113.4 6133.7 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#1] ext.html Cycles:180 Average:60901.33 Median:220218.10 stddev:122547.42 (55.6%) stddev-sans-first:122807.21 05:24:35 INFO - PROCESS | 2028 | Values: 471.7 494.8 530.2 533.3 541.9 39189.0 41182.6 41236.0 41296.8 41345.8 42616.8 43268.0 43320.0 43339.3 43362.1 9749.4 9814.8 9829.1 9864.1 9895.5 9873.9 9895.0 9896.7 9906.8 9931.0 9889.3 9912.5 9915.5 9919.0 9931.5 9061.8 9865.9 9884.0 9902.0 9929.9 8522.6 8768.2 9253.6 9289.8 9600.7 28198.9 40594.8 40691.4 40744.4 40898.8 3320.8 3350.6 3361.7 3368.8 3386.9 29327.7 30402.0 30701.1 31452.4 31748.8 3182.7 3188.3 3217.3 3228.5 3311.3 395568.0 400507.0 402643.0 403657.0 406361.0 63631.0 64992.4 65086.3 65101.7 65170.0 35223.8 36150.6 68147.0 68297.3 68716.0 16124.6 16227.7 16646.3 16669.4 16809.7 12681.2 12751.9 12752.6 12768.0 12781.9 16665.0 20373.8 20658.7 20675.5 20710.0 8140.4 8918.6 8999.4 9000.9 9089.7 831.8 875.4 978.7 1047.4 1051.9 28288.0 29914.7 30224.0 30630.8 30982.7 917.5 1020.8 1039.1 1070.4 1081.8 12033.6 13289.7 15381.0 15642.4 15863.3 495577.0 530696.0 530815.0 531442.0 531661.0 56564.7 56668.6 56808.0 57309.7 57559.0 1373.6 1374.9 1401.8 1403.3 1403.7 27892.0 29328.6 29436.9 29702.0 29966.0 47608.8 47835.0 47850.5 48020.3 48031.5 444293.6 450904.0 451774.0 452707.0 455575.0 26952.5 27256.9 27287.9 27294.6 27383.9 46047.8 46552.3 46670.8 47019.0 47702.0 38302.4 38616.7 38621.8 39520.8 39929.2 37986.6 38068.4 38711.0 38756.0 39338.8 37113.9 37281.1 37701.2 37962.0 38107.0 32229.8 33771.8 33890.0 34012.1 34066.3 23842.3 24157.5 24461.1 24513.8 24556.6 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#2] jquery.html Cycles:180 Average:29740.36 Median:378.28 stddev:78885.03 (20853.7%) stddev-sans-first:79075.40 05:24:35 INFO - PROCESS | 2028 | Values: 321.2 340.7 372.8 383.7 398.3 19278.9 19388.4 20625.2 20778.5 20888.8 19500.9 20327.0 20331.9 20390.1 20831.4 5395.9 5541.3 5589.7 5612.2 5978.7 4978.7 5225.9 5454.8 6122.0 6139.5 5796.8 6083.8 6119.2 6123.0 6132.6 6130.3 6134.6 6155.2 6165.4 6167.5 5865.8 5886.7 5997.2 6040.4 6058.4 12787.2 12944.6 13021.0 24178.4 24850.4 2143.0 2189.5 2412.8 2629.3 2893.4 9466.7 9861.3 10132.2 10345.8 10380.0 1645.0 1933.0 1942.8 1967.0 1983.9 112558.3 119711.0 120002.0 120760.4 122632.0 9169.8 11343.5 11552.9 11591.1 11765.4 14317.5 14347.1 14366.5 14375.9 14387.9 6639.3 8479.7 8545.4 8589.9 8646.3 8500.6 8514.7 8525.4 8667.7 8703.8 4394.3 4532.5 4539.7 4576.2 4624.5 1639.5 1941.0 1941.4 1942.1 1946.2 814.9 887.0 911.4 922.3 942.8 20926.3 21472.8 21666.8 21773.2 23416.5 750.7 840.9 936.0 942.7 948.7 11179.4 11401.7 11429.5 11444.7 11454.0 454353.0 468958.0 472118.0 473455.0 490735.0 69138.7 69921.3 70199.0 70641.6 79144.6 890.5 968.3 999.8 1002.7 1002.8 18072.8 18354.0 18629.1 18878.1 19120.7 54146.5 54258.0 54359.5 54717.7 55353.0 71225.6 73093.3 75120.0 78394.6 81114.0 5874.4 6436.8 6974.9 7316.9 7962.1 9987.5 12670.7 12765.2 13024.9 13276.1 11007.2 11214.4 11395.4 11417.7 11452.7 10104.5 10574.8 10889.3 10983.0 11140.1 10555.3 11519.1 11544.9 11630.8 11716.9 7994.7 8132.7 8264.7 8268.2 8279.0 10460.8 11254.8 11386.5 11400.4 11427.3 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#3] mootools.html Cycles:180 Average:5754.72 Median:4732.97 stddev:3270.86 (69.1%) stddev-sans-first:3264.44 05:24:35 INFO - PROCESS | 2028 | Values: 1505.3 1559.7 1727.4 1779.3 1900.4 7334.2 8191.8 8280.6 9210.3 9229.9 8618.8 8910.2 8952.6 9069.0 9083.1 2010.0 2379.8 2412.3 2417.0 2436.2 2149.3 2377.5 2400.7 2403.5 2409.7 2379.6 2396.1 2407.9 2409.8 2417.5 2361.2 2367.6 2367.8 2383.8 2399.9 2375.7 2385.0 2389.8 2391.7 2398.0 8892.0 8978.5 9024.3 9034.5 9047.3 5198.3 5404.9 5446.0 5451.8 5452.9 3637.8 3681.3 3682.3 3722.6 3733.9 1089.6 1091.6 1091.7 1091.8 1108.1 11929.6 11970.3 12069.9 12097.5 12104.8 4711.3 4724.5 4741.5 4871.1 4912.9 9648.9 10140.8 10243.7 10264.9 10716.2 4086.3 4467.9 4610.4 4927.7 5405.2 4001.8 4083.0 4184.7 4747.5 4931.8 3394.4 3982.9 3984.5 4046.3 4112.8 1165.9 1248.9 1296.8 1309.3 1312.4 797.4 875.7 899.3 911.5 913.8 5839.9 5919.6 5926.6 6122.0 6241.8 822.3 868.2 900.0 949.9 975.8 3894.2 3996.3 3999.6 4090.2 4092.5 10208.2 10709.9 10718.2 10872.5 10877.8 10878.0 10885.6 10896.6 10896.9 10929.2 8086.9 8197.2 8282.8 8321.5 8325.5 5519.9 5688.9 5703.5 6035.8 6080.8 8811.6 9971.3 10199.9 10237.8 10268.9 9902.7 10457.7 10475.9 10484.2 10486.1 7150.6 7324.4 7476.0 7712.7 7863.9 8401.6 8672.3 8675.2 8681.0 8692.0 7572.6 7700.8 7713.7 7793.9 7835.6 6370.5 7395.3 7494.4 7563.8 7658.7 7065.6 7071.6 7083.2 7188.3 7210.7 4310.9 4863.4 4934.4 5068.7 5130.8 4594.9 5022.5 5026.5 5062.8 5128.5 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#4] prototype.html Cycles:180 Average:4697.53 Median:5280.42 stddev:1469.72 (27.8%) stddev-sans-first:1436.31 05:24:35 INFO - PROCESS | 2028 | Values: 300.5 304.4 312.4 316.2 317.5 4835.0 5190.3 5337.4 5480.0 5687.4 5230.7 5446.2 5515.5 5516.7 5554.0 3847.7 4203.7 4236.3 4246.0 4257.7 3711.4 4058.7 4156.3 4226.8 4244.7 4118.7 4130.4 4189.6 4202.9 4203.4 3799.7 3817.3 4184.7 4195.5 4225.2 3061.9 3661.2 3692.7 3693.3 3698.6 3989.9 4054.2 4380.2 4943.4 5300.3 4788.4 4808.4 4812.7 4837.8 4838.3 4382.7 4997.0 5807.4 5829.5 5837.2 3336.5 3442.5 3985.8 3993.6 4010.5 4891.9 5501.8 5535.6 6175.4 6200.2 4054.4 4065.7 4069.5 4079.6 4091.2 3952.7 4102.5 4107.7 4137.1 4148.3 3784.3 4190.0 4613.9 4754.7 4807.3 4631.5 4929.4 5832.9 5836.8 5890.5 1105.4 1322.5 1344.7 1371.1 1386.8 888.8 937.5 975.0 977.7 979.2 4404.8 4653.8 5439.9 5501.7 5522.4 5491.7 5839.4 5933.5 6024.1 6029.8 4530.7 5196.5 5493.4 5497.0 5519.4 2973.9 3167.8 3190.1 3198.4 3221.1 5450.5 6284.2 6312.6 6322.7 6393.3 5307.7 5720.7 5781.0 6262.9 6277.7 5765.5 6319.6 6348.4 6353.0 6371.8 5693.6 6060.4 6118.7 6119.7 6184.8 5786.4 6025.9 6052.7 6079.3 6100.3 5832.4 6104.9 6149.1 6324.5 6380.3 3853.6 3967.5 3994.3 4007.4 4016.7 4758.5 5652.2 5810.2 6108.5 6241.3 4401.0 5260.5 5545.4 5686.8 5886.6 5391.8 5936.7 5938.9 5939.9 5953.8 5370.3 5734.9 5796.6 5861.0 5873.4 5156.9 5780.5 5861.6 5917.2 5927.2 4270.6 5542.3 5831.5 5835.2 5871.9 05:24:35 INFO - PROCESS | 2028 | 05:24:35 INFO - PROCESS | 2028 | [#5] yui.html Cycles:180 Average:22604.77 Median:4140.57 stddev:34465.00 (832.4%) stddev-sans-first:34522.26 05:24:35 INFO - PROCESS | 2028 | Values: 651.0 732.8 733.6 766.9 827.8 14705.1 15272.9 15553.5 15644.3 15679.5 14501.3 14505.6 14543.9 14561.3 14658.6 855.2 871.6 875.2 875.5 875.7 673.1 692.6 738.3 865.2 874.6 896.5 917.0 917.8 919.9 922.4 886.7 900.6 909.1 911.4 913.8 556.7 643.6 675.5 677.2 677.8 14890.1 14963.7 15044.0 15053.7 15072.3 13680.5 14315.4 14484.0 14711.2 14743.9 11674.6 11771.9 11778.4 11883.2 12054.4 3660.6 3770.5 3825.2 3845.0 3877.5 68104.7 110581.4 111036.0 111077.9 111094.0 9076.7 10656.8 10696.4 10830.4 10922.9 17603.6 17977.9 20206.0 20315.0 20480.4 7944.4 8034.3 8110.9 8233.1 8251.8 4415.7 4434.6 4479.5 4492.0 4643.2 3560.7 3693.8 3698.6 3701.2 3811.2 558.3 709.8 712.1 715.5 730.7 587.8 672.9 673.4 674.6 684.7 8468.6 8750.9 9594.7 9739.5 9826.0 606.4 650.1 650.7 673.1 707.9 4403.6 4634.9 4639.9 4651.2 4724.9 89209.6 89311.6 126255.4 144404.0 145918.0 99265.0 99853.5 102247.0 124651.4 126850.0 72671.6 89784.6 92589.5 96747.5 100741.5 9407.1 9587.8 9922.6 10009.6 10035.6 58235.4 59506.0 60920.1 61281.7 61770.4 86623.6 87069.0 89926.6 92126.0 92220.0 12742.4 12904.8 13199.1 13376.3 13697.9 14329.6 14405.7 14471.9 14566.6 15090.8 10840.6 13614.0 13694.5 13889.2 14053.4 13495.0 13626.4 13717.2 13807.2 14144.3 13204.0 13308.5 13460.4 13853.0 13937.9 11114.6 11144.7 11163.3 11296.9 11347.7 7006.0 10502.1 11218.5 11439.8 11484.9 05:24:35 INFO - PROCESS | 2028 | -------- Summary: end -------- 05:24:35 INFO - PROCESS | 2028 | 05:24:36 INFO - TEST-INFO | 2028: exit 0 05:24:36 INFO - TEST-OK | dromaeo_css | took 1114141ms 05:24:36 INFO - TEST-START | kraken 05:24:36 INFO - operating with platform_type : mac_ 05:24:36 INFO - Initialising browser for kraken test... 05:24:36 INFO - TEST-INFO | started process 2033 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpPbQpSA/profile http://localhost:49377/getInfo.html) 05:24:38 INFO - PROCESS | 2033 | 2016-09-27 05:24:38.799 plugin-container[2034:283103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 05:24:38 INFO - PROCESS | 2033 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:38 INFO - PROCESS | 2033 | 2016-09-27 05:24:38.802 plugin-container[2034:283103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 05:24:38 INFO - PROCESS | 2033 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:38 INFO - PROCESS | 2033 | 2016-09-27 05:24:38.803 plugin-container[2034:283103] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:38 INFO - PROCESS | 2033 | __metrics Screen width/height:1600/1200 05:24:38 INFO - PROCESS | 2033 | colorDepth:24 05:24:38 INFO - PROCESS | 2033 | Browser inner width/height: 1024/689 05:24:38 INFO - PROCESS | 2033 | __metrics 05:24:38 ERROR - PROCESS | 2033 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:24:39 INFO - TEST-INFO | 2033: exit 0 05:24:39 INFO - Browser initialized. 05:24:39 INFO - Running cycle 1/1 for kraken test... 05:24:39 INFO - TEST-INFO | started process 2035 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpPbQpSA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:42 INFO - PROCESS | 2035 | 2016-09-27 05:24:42.066 plugin-container[2036:283218] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8827, name = 'com.apple.tsm.portname' 05:24:42 INFO - PROCESS | 2035 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:42 INFO - PROCESS | 2035 | 2016-09-27 05:24:42.069 plugin-container[2036:283218] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 05:24:42 INFO - PROCESS | 2035 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:42 INFO - PROCESS | 2035 | 2016-09-27 05:24:42.069 plugin-container[2036:283218] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:51 INFO - PROCESS | 2035 | RSS: Main: 240070656 05:24:51 INFO - PROCESS | 2035 | 05:24:54 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:55 INFO - PROCESS | 2035 | RSS: Main: 253693952 05:24:55 INFO - PROCESS | 2035 | 05:24:58 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-dft) 05:24:58 INFO - PROCESS | 2035 | RSS: Main: 262230016 05:24:58 INFO - PROCESS | 2035 | 05:25:02 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-fft) 05:25:02 INFO - PROCESS | 2035 | RSS: Main: 262340608 05:25:02 INFO - PROCESS | 2035 | 05:25:04 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator) 05:25:05 INFO - PROCESS | 2035 | RSS: Main: 263168000 05:25:05 INFO - PROCESS | 2035 | 05:25:07 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:08 INFO - PROCESS | 2035 | RSS: Main: 264904704 05:25:08 INFO - PROCESS | 2035 | 05:25:10 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:11 INFO - PROCESS | 2035 | RSS: Main: 265310208 05:25:11 INFO - PROCESS | 2035 | 05:25:14 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:14 INFO - PROCESS | 2035 | RSS: Main: 265191424 05:25:14 INFO - PROCESS | 2035 | 05:25:17 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial) 05:25:18 INFO - PROCESS | 2035 | RSS: Main: 265822208 05:25:18 INFO - PROCESS | 2035 | 05:25:19 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:20 INFO - PROCESS | 2035 | RSS: Main: 265355264 05:25:20 INFO - PROCESS | 2035 | 05:25:22 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:22 INFO - PROCESS | 2035 | RSS: Main: 265437184 05:25:22 INFO - PROCESS | 2035 | 05:25:25 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:25 INFO - PROCESS | 2035 | RSS: Main: 265412608 05:25:25 INFO - PROCESS | 2035 | 05:25:28 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:29 INFO - PROCESS | 2035 | RSS: Main: 265732096 05:25:29 INFO - PROCESS | 2035 | 05:25:32 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:33 INFO - PROCESS | 2035 | RSS: Main: 265871360 05:25:33 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49377/tests/kraken/driver.html?testName=ai-astar) 05:25:35 INFO - PROCESS | 2035 | RSS: Main: 267636736 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | __start_tp_report 05:25:35 INFO - PROCESS | 2035 | _x_x_mozilla_page_load 05:25:35 INFO - PROCESS | 2035 | _x_x_mozilla_page_load_details 05:25:35 INFO - PROCESS | 2035 | |i|pagename|runs| 05:25:35 INFO - PROCESS | 2035 | |0;ai-astar;104;116;117;115;116;117;126;110;140;104 05:25:35 INFO - PROCESS | 2035 | |1;audio-beat-detection;141;110;112;148;111;113;112;120;148;131 05:25:35 INFO - PROCESS | 2035 | |2;audio-dft;200;205;233;244;243;203;200;199;192;196 05:25:35 INFO - PROCESS | 2035 | |3;audio-fft;86;60;60;84;60;71;59;56;75;51 05:25:35 INFO - PROCESS | 2035 | |4;audio-oscillator;86;73;85;72;69;72;87;70;72;87 05:25:35 INFO - PROCESS | 2035 | |5;imaging-gaussian-blur;89;87;89;87;90;90;90;87;136;85 05:25:35 INFO - PROCESS | 2035 | |6;imaging-darkroom;99;96;95;95;96;99;95;95;95;122 05:25:35 INFO - PROCESS | 2035 | |7;imaging-desaturate;91;93;92;88;94;90;91;94;89;89 05:25:35 INFO - PROCESS | 2035 | |8;json-parse-financial;58;57;55;58;58;59;58;56;57;58 05:25:35 INFO - PROCESS | 2035 | |9;json-stringify-tinderbox;69;77;68;83;75;71;72;70;71;74 05:25:35 INFO - PROCESS | 2035 | |10;stanford-crypto-aes;86;84;90;101;113;112;112;88;89;90 05:25:35 INFO - PROCESS | 2035 | |11;stanford-crypto-ccm;140;151;138;138;171;138;141;154;178;147 05:25:35 INFO - PROCESS | 2035 | |12;stanford-crypto-pbkdf2;177;171;244;248;185;173;177;174;184;244 05:25:35 INFO - PROCESS | 2035 | |13;stanford-crypto-sha256-iterative;71;72;81;92;85;59;77;93;90;90 05:25:35 INFO - PROCESS | 2035 | __end_tp_report 05:25:35 INFO - PROCESS | 2035 | __start_cc_report 05:25:35 INFO - PROCESS | 2035 | _x_x_mozilla_cycle_collect,916 05:25:35 INFO - PROCESS | 2035 | __end_cc_report 05:25:35 INFO - PROCESS | 2035 | __startTimestamp1474979135402__endTimestamp 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | ------- Summary: start ------- 05:25:35 INFO - PROCESS | 2035 | Number of tests: 14 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.50 stddev:10.54 (9.1%) stddev-sans-first:10.17 05:25:35 INFO - PROCESS | 2035 | Values: 104.0 116.0 117.0 115.0 116.0 117.0 126.0 110.0 140.0 104.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:125.50 stddev:15.90 (12.7%) stddev-sans-first:15.72 05:25:35 INFO - PROCESS | 2035 | Values: 141.0 110.0 112.0 148.0 111.0 113.0 112.0 120.0 148.0 131.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#2] audio-dft Cycles:10 Average:211.50 Median:204.00 stddev:20.18 (9.9%) stddev-sans-first:20.97 05:25:35 INFO - PROCESS | 2035 | Values: 200.0 205.0 233.0 244.0 243.0 203.0 200.0 199.0 192.0 196.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#3] audio-fft Cycles:10 Average:66.20 Median:65.50 stddev:12.07 (18.4%) stddev-sans-first:10.46 05:25:35 INFO - PROCESS | 2035 | Values: 86.0 60.0 60.0 84.0 60.0 71.0 59.0 56.0 75.0 51.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.00 stddev:7.80 (9.9%) stddev-sans-first:7.62 05:25:35 INFO - PROCESS | 2035 | Values: 86.0 73.0 85.0 72.0 69.0 72.0 87.0 70.0 72.0 87.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:89.00 stddev:15.20 (17.1%) stddev-sans-first:16.06 05:25:35 INFO - PROCESS | 2035 | Values: 89.0 87.0 89.0 87.0 90.0 90.0 90.0 87.0 136.0 85.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:95.50 stddev:8.34 (8.7%) stddev-sans-first:8.85 05:25:35 INFO - PROCESS | 2035 | Values: 99.0 96.0 95.0 95.0 96.0 99.0 95.0 95.0 95.0 122.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.50 stddev:2.13 (2.3%) stddev-sans-first:2.26 05:25:35 INFO - PROCESS | 2035 | Values: 91.0 93.0 92.0 88.0 94.0 90.0 91.0 94.0 89.0 89.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.22 05:25:35 INFO - PROCESS | 2035 | Values: 58.0 57.0 55.0 58.0 58.0 59.0 58.0 56.0 57.0 58.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:4.47 (6.1%) stddev-sans-first:4.50 05:25:35 INFO - PROCESS | 2035 | Values: 69.0 77.0 68.0 83.0 75.0 71.0 72.0 70.0 71.0 74.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:87.00 stddev:11.80 (13.6%) stddev-sans-first:11.88 05:25:35 INFO - PROCESS | 2035 | Values: 86.0 84.0 90.0 101.0 113.0 112.0 112.0 88.0 89.0 90.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:149.00 stddev:14.39 (9.7%) stddev-sans-first:14.83 05:25:35 INFO - PROCESS | 2035 | Values: 140.0 151.0 138.0 138.0 171.0 138.0 141.0 154.0 178.0 147.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.70 Median:184.50 stddev:33.18 (18.0%) stddev-sans-first:34.34 05:25:35 INFO - PROCESS | 2035 | Values: 177.0 171.0 244.0 248.0 185.0 173.0 177.0 174.0 184.0 244.0 05:25:35 INFO - PROCESS | 2035 | 05:25:35 INFO - PROCESS | 2035 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.00 Median:87.50 stddev:11.18 (12.8%) stddev-sans-first:11.25 05:25:35 INFO - PROCESS | 2035 | Values: 71.0 72.0 81.0 92.0 85.0 59.0 77.0 93.0 90.0 90.0 05:25:35 INFO - PROCESS | 2035 | -------- Summary: end -------- 05:25:35 INFO - PROCESS | 2035 | 05:25:36 INFO - TEST-INFO | 2035: exit 0 05:25:36 INFO - TEST-OK | kraken | took 60250ms 05:25:36 INFO - SUITE-END | took 1174s 05:25:36 INFO - Completed test suite (00:19:34) 05:25:36 INFO - javascript benchmark 05:25:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7332.3363385412495, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.965860341399, 3417.982910085449, 3438.1576513754135, 3458.41206994811, 3489.494023366612, 5715.057015592428, 5738.42615738426, 5779.566532510057, 5794.884102317951, 5843.4156584341545, 5770.9422905770925, 5779.306483222019, 5784.68184249866, 5814.09881468372, 5887.411258874111, 5232.529072383485, 5272.789088436477, 5319.361676598814, 5339.119045357511, 5356.54469370104, 5119.744012799345, 5239.476052394768, 5285.339332583427, 5337.226102215176, 5344.331958505187, 5183.326167598207, 5201.765920533585, 5260.184671375904, 5304.602154838402, 5336.51971322579, 5251.317328747238, 5268.5521730652945, 5270.657407268515, 5272.815451459181, 5303.681779093228, 5141.922871156936, 5214.661047031931, 5234.18870075139, 5246.685198888079, 5283.181106928432, 5663.235463212455, 5668.57485688575, 5764.106563482625, 5836.9124463133085, 5840.620359676607, 6185.876282474325, 6249.812505624838, 6258.906116408258, 6268.529860260499, 6281.560290779647, 6223.1287619733275, 6253.0, 6304.085907543426, 6307.905381419282, 6320.74717011319, 5735.397783232784, 5760.654360738369, 5806.44838740319, 5814.76740930358, 5839.182514447981, 6349.777757778455, 6350.618962862243, 6355.269144048401, 6357.8728425432155, 6358.968205158944, 6199.0, 6201.534884883562, 6219.533534984804, 6219.813405597839, 6224.502039836802, 6110.741024289823, 6127.097902969301, 6163.660998645028, 6227.595206311576, 6284.622922624657, 6147.9548476759055, 6164.4143806338325, 6187.628742275388, 6194.287656919512, 6210.658413787195, 6188.938110618927, 6189.04069869171, 6200.937990620127, 6212.254529456403, 6212.285587157535, 4431.534688857688, 4914.311996320518, 4924.852254432372, 4964.35463389757, 4966.379202599675, 4440.7335559866515, 4451.621612163002, 4456.68803183774, 4476.485204201494, 4477.238869392211, 5054.898902022012, 5896.675682837399, 5969.403059693996, 5996.940030599725, 6009.36901625323, 6171.56799024064, 6205.565610407228, 6229.252489701174, 6240.906386404207, 6271.310155882849, 4869.63477739171, 5963.761449541969, 6027.879442411215, 6065.878682426327, 6072.332043475214, 5462.153366228241, 5470.699111548824, 5473.972630136904, 5481.0, 5487.42382049887, 6250.624962502174, 6264.0, 6294.842628934313, 6417.721708793404, 6417.74329026843, 6214.316425193316, 6230.376962303733, 6288.5598008138995, 6316.147320111726, 6323.936760632335, 6326.177596912372, 6366.076918846834, 6374.553781235269, 6378.521610879296, 6476.12572302752, 6099.24925446448, 6170.784022559189, 6242.563020588515, 6246.3753624637175, 6248.0940263660605, 6225.159603453657, 6231.158793562811, 6288.402601752826, 6296.087067375113, 6298.779542715983, 6188.690565471618, 6211.068339748893, 6226.937730622636, 6243.469305109116, 6248.375162483897, 6077.604955677867, 6187.195664563578, 6230.937690623217, 6238.126662267377, 6240.906386404298, 4552.8861778454475, 5902.085176797603, 5942.524598032061, 6045.6070355426755, 6161.507079433724, 6138.5396095292135, 6139.938600614116, 6176.752929882754, 6200.720967556561, 6251.874962500634, 5945.554083443672, 5991.3409524953095, 6112.388761123852, 6113.388661134029, 6133.723982420713], "value": 5746.501532454541, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.66511776638583, 494.8070252601485, 530.2179285553808, 533.2853975672598, 541.8780774325775, 39189.0, 41182.58817411825, 41236.0, 41296.79351603241, 41345.793271033675, 42616.78691606538, 43268.0, 43320.0, 43339.3499097513, 43362.132757344836, 9749.366291191085, 9814.754631134207, 9829.115379615832, 9864.112229899307, 9895.50522473877, 9873.851892221623, 9895.0, 9896.703098907044, 9906.752331191741, 9930.950345248264, 9889.307748457612, 9912.504374781269, 9915.504224788769, 9919.0, 9931.453770042644, 9061.821963144748, 9865.852012219822, 9884.011598840094, 9902.009799020112, 9929.900700993043, 8522.573871306471, 8768.210861022475, 9253.583588738456, 9289.767755806091, 9600.66397676078, 28198.859005705046, 40594.797026014974, 40691.38962915559, 40744.38883416751, 40898.79550602258, 3320.8173550454717, 3350.64818194091, 3361.6974472297366, 3368.764186506921, 3386.8983930482127, 29327.70672293271, 30402.0, 30701.078967631005, 31452.37095258105, 31748.841255793574, 3182.6817318268218, 3188.3463889902514, 3217.308278720063, 3228.531862879869, 3311.3211791582667, 395568.0, 400507.0, 402642.9867850671, 403657.0, 406361.0, 63631.0, 64992.35007649958, 65086.349136508026, 65101.67449162724, 65170.0, 35223.82388088043, 36150.63849361473, 68147.0, 68297.31702683009, 68716.0, 16124.596885077732, 16227.675446491241, 16646.334146634243, 16669.41657042022, 16809.6638067238, 12681.175723578122, 12751.936240318924, 12752.553660621834, 12768.0, 12781.936090319676, 16665.0, 20373.796262037486, 20658.690119648218, 20675.483112921997, 20709.964501775154, 8140.389470789714, 8918.55407229636, 8999.37004409685, 9000.909990900138, 9089.681861134828, 831.8377916306263, 875.369733791669, 978.6966040527459, 1047.371577053777, 1051.9474026298808, 28288.00991965271, 29914.70085299119, 30224.0, 30630.846845766075, 30982.690173098432, 917.513717729591, 1020.8213562626511, 1039.147898723055, 1070.4059247117768, 1081.7566047639218, 12033.63899083029, 13289.671032896826, 15381.0, 15642.368747250981, 15863.286152123183, 495577.0, 530696.0, 530815.0, 531442.0, 531661.0, 56564.71717641468, 56668.63307770046, 56808.0, 57309.71345143331, 57559.0, 1373.6291201375573, 1374.8762611365025, 1401.810755547988, 1403.2632867744312, 1403.6841710615233, 27892.0, 29328.56007159937, 29436.852815735783, 29702.0, 29966.0, 47608.76195619, 47835.0, 47850.5214947846, 48020.27969580389, 48031.519684802704, 444293.5570644252, 450904.0, 451774.0, 452707.0, 455575.0, 26952.460950781267, 27256.863715681295, 27287.863560682068, 27294.590581141696, 27383.86308068447, 46047.76976115098, 46552.254963303545, 46670.76664616655, 47019.0, 47702.0, 38302.42546361751, 38616.66898014968, 38621.80689096537, 39520.802395987834, 39929.2014159721, 37986.62013379831, 38068.42897356486, 38711.0, 38756.031099222746, 39338.80330598443, 37113.88658340254, 37281.08548602466, 37701.24597508089, 37962.0, 38107.047323817125, 32229.838850805594, 33771.81798637036, 33890.0, 34012.149696257795, 34066.318673625894, 23842.284731458083, 24157.516849662556, 24461.143859965527, 24513.754862451147, 24556.631650524898], "value": 19620.68575866305, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.2018134934688, 340.71550255536624, 372.82849889051045, 383.72755343705967, 398.3288159451325, 19278.90360548197, 19388.41834744958, 20625.174993000262, 20778.480537986557, 20888.791112088875, 19500.9274489903, 20327.0, 20331.898340508276, 20390.082446289915, 20831.375058748225, 5395.865103372408, 5541.27963364764, 5589.664620122806, 5612.242347283126, 5978.730957106919, 4978.684911516144, 5225.947740522603, 5454.754536045887, 6121.969390153043, 6139.478144357735, 5796.768129274824, 6083.8174854754425, 6119.204503414572, 6122.969385153068, 6132.570720049598, 6130.3141636049995, 6134.570580059398, 6155.230596175478, 6165.366177962821, 6167.537434692416, 5865.794697185578, 5886.70566471679, 5997.190379298782, 6040.365761595013, 6058.4244496772735, 12787.168834025759, 12944.611661650166, 13021.0, 24178.39554011146, 24850.378740531633, 2142.967855482169, 2189.5183059726833, 2412.8418359187513, 2629.329520972159, 2893.363460038788, 9466.668666596704, 9861.309708320421, 10132.189424845994, 10345.844812327821, 10379.962003799636, 1644.9917750411291, 1933.0, 1942.7765806932243, 1966.9901650491656, 1983.880967141976, 112558.31162532569, 119711.0, 120001.99999999913, 120760.39619801845, 122631.99999999911, 9169.77075573116, 11343.489542970587, 11552.942235288938, 11591.130665200144, 11765.411729413496, 14317.498887538886, 14347.139171649735, 14366.497172598909, 14375.856241437661, 14387.856121438861, 6639.302873198341, 8479.745607631781, 8545.401821872409, 8589.914100858912, 8646.308295336357, 8500.574971251413, 8514.659413623509, 8525.360597955178, 8667.73996780085, 8703.782405439788, 4394.318880573516, 4532.546745325441, 4539.660800063989, 4576.176288268143, 4624.468186158568, 1639.524537884024, 1940.951476213106, 1941.4272789527124, 1942.1066309497508, 1946.1826033066145, 814.8981377327834, 886.9866951995596, 911.4368857409575, 922.3220932614523, 942.8255772682076, 20926.267580634605, 21472.78527214739, 21666.783332166477, 21773.23793667214, 23416.531669366857, 750.7147284032142, 840.9026220781816, 936.0499093420048, 942.7171848445439, 948.6821914658667, 11179.385133817555, 11401.657950261506, 11429.485673144729, 11444.656660300205, 11454.026407755267, 454353.0, 468958.0, 472118.0, 473455.0, 490735.0, 69138.65430672815, 69921.30078699147, 70199.0, 70641.58716825738, 79144.6042769794, 890.4790697442069, 968.2979839616081, 999.8200323941614, 1002.7192386131894, 1002.8094662014194, 18072.819271807115, 18354.0, 18629.068546572347, 18878.05609719536, 19120.713189301892, 54146.45853541572, 54258.0, 54359.45640543544, 54717.72641136769, 55353.0, 71225.64387178238, 73093.34533273421, 75120.0, 78394.6080269595, 81114.0, 5874.412558744091, 6436.809190299809, 6974.860502790018, 7316.890246646198, 7962.084360298408, 9987.500624968869, 12670.683232919251, 12765.234085954873, 13024.934875325942, 13276.137051091648, 11007.174461915229, 11214.439278035903, 11395.373254471084, 11417.714557136138, 11452.713682157679, 10104.49477526106, 10574.788504229718, 10889.292196007234, 10983.0, 11140.108791296514, 10555.313904596176, 11519.078473721915, 11544.942275288906, 11630.767384652429, 11716.882831171579, 7994.720184793504, 8132.67469301245, 8264.710735124241, 8268.173182681683, 8279.0, 10460.843087353544, 11254.774904502028, 11386.487608057492, 11400.429978501208, 11427.257228280469], "value": 9213.723369342571, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.277466815928, 1559.6724687815558, 1727.3695101288033, 1779.3416435918705, 1900.401373567327, 7334.229905859888, 8191.754247372573, 8280.585970701472, 9210.30922680799, 9229.861552076716, 8618.827623447527, 8910.198082172603, 8952.552372381388, 9069.0, 9083.137101975319, 2009.974912794478, 2379.8096152307817, 2412.2522018174423, 2416.9516609667794, 2436.244764123119, 2149.3014770199748, 2377.5363804058215, 2400.7359190489033, 2403.507281007398, 2409.6867407237037, 2379.6192609182535, 2396.101461951768, 2407.879606019701, 2409.795167410772, 2417.492326611414, 2361.2325994052003, 2367.5738367094004, 2367.7868991790733, 2383.833131680783, 2399.9280021599207, 2375.714914210306, 2385.0459816073535, 2389.832711710181, 2391.6890804195345, 2398.0, 8892.0, 8978.50618215998, 9024.278057755364, 9034.54827258641, 9047.321450891144, 5198.298229738974, 5404.945950540523, 5446.0, 5451.809186678487, 5452.945470545284, 3637.8181090945345, 3681.2821499807546, 3682.2819550187724, 3722.590515043343, 3733.9253214935547, 1089.5641743302663, 1091.6233899304727, 1091.7161538000178, 1091.8307662312354, 1108.141190577301, 11929.642110736691, 11970.281783093042, 12069.939650301692, 12097.455614497369, 12104.81842772359, 4711.293306004127, 4724.478424005908, 4741.454732705783, 4871.050145221648, 4912.901741965212, 9648.855267170999, 10140.797184056277, 10243.692689219335, 10264.897351026544, 10716.249862509705, 4086.3053280942313, 4467.910641787211, 4610.377599024089, 4927.704337739747, 5405.243265942692, 4001.799910004488, 4082.9591704082577, 4184.660908509248, 4747.454042785055, 4931.802727890916, 3394.405978953673, 3982.8605998789903, 3984.5019372578427, 4046.251443482965, 4112.773797441109, 1165.9125565582615, 1248.9000879929583, 1296.7730647136714, 1309.2668105860557, 1312.4159748911643, 797.401948538596, 875.711015364928, 899.3030401438937, 911.4941207629829, 913.830941275866, 5839.854003649943, 5919.6152250103605, 5926.614770039933, 6122.0, 6241.812745617639, 822.33390953328, 868.1535502884637, 899.964001439935, 949.8765160529088, 975.8145952269189, 3894.1822217334675, 3996.2606917720314, 3999.600039995977, 4090.2228576570355, 4092.46797916269, 10208.234382421348, 10709.892901071045, 10718.196135289885, 10872.45637718111, 10877.782444351069, 10878.0, 10885.564577416973, 10896.61861834832, 10896.891031089588, 10929.234953553334, 8086.9191308086165, 8197.180281971756, 8282.792930176673, 8321.459105158145, 8325.458845175044, 5519.862003449946, 5688.943110569007, 5703.515201207943, 6035.818925432244, 6080.817575472743, 8811.647534098563, 9971.30200885931, 10199.898001019896, 10237.795244095225, 10268.948655256676, 9902.702918912444, 10457.68626941193, 10475.895241047492, 10484.21368397358, 10486.108680761916, 7150.570965742072, 7324.414046876131, 7476.0, 7712.730054448068, 7863.882041769263, 8401.579921004048, 8672.349573781865, 8675.175858293453, 8681.0, 8692.0, 7572.583507907118, 7700.768976930701, 7713.691452342068, 7793.9220607793195, 7835.5690437026515, 6370.4903607712195, 7395.334419902235, 7494.437917156126, 7563.848723025399, 7658.655360508679, 7065.646717664199, 7071.646417679199, 7083.185433675193, 7188.317109874553, 7210.675519601736, 4310.857622729916, 4863.440704318907, 4934.383202099738, 5068.746562671778, 5130.846074617767, 4594.9310760339295, 5022.4977502249485, 5026.4973502649445, 5062.772175252197, 5128.487151284841], "value": 4608.436989225138, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.4561743244727, 304.3942554316909, 312.40175064751, 316.1968599756618, 317.4809186979288, 4834.960483496047, 5190.273361729356, 5337.42545948945, 5480.04099282626, 5687.402822703619, 5230.686158830473, 5446.183072539121, 5515.4760297771745, 5516.696581688006, 5554.0, 3847.711421643374, 4203.663706903456, 4236.258654735417, 4246.0, 4257.7445353278745, 3711.4247291669835, 4058.736182148166, 4156.2934301168725, 4226.767527785971, 4244.745315281093, 4118.670506359484, 4130.442390277319, 4189.63836753055, 4202.936955945664, 4203.432536607549, 3799.7270914243763, 3817.3319669058083, 4184.686148538872, 4195.517515485728, 4225.154969006211, 3061.862216200276, 3661.176235347026, 3692.667659910594, 3693.298273328086, 3698.5746639136573, 3989.860354887565, 4054.16889537647, 4380.18966491197, 4943.431505376891, 5300.337457817773, 4788.401449818773, 4808.3989501312335, 4812.687175333627, 4837.782299796518, 4838.274258861164, 4382.737035777864, 4996.975015124901, 5807.448292412257, 5829.504492118175, 5837.211976383219, 3336.516205150264, 3442.4853064651475, 3985.8241818663564, 3993.600639936012, 4010.458588090629, 4891.874868780165, 5501.807436739695, 5535.557155427597, 6175.44421002112, 6200.224971878515, 4054.412110243998, 4065.682560940295, 4069.4709687740997, 4079.551249362568, 4091.202215567995, 3952.6837852971694, 4102.528209255914, 4107.665008872129, 4137.110521237888, 4148.2947898857265, 3784.262068896539, 4189.994401343655, 4613.884652883705, 4754.716226618793, 4807.326974223612, 4631.490536041033, 4929.433115191799, 5832.912506312408, 5836.7957121501395, 5890.4993075589055, 1105.40308233553, 1322.4710115953696, 1344.7243315120377, 1371.0745246958263, 1386.83357997041, 888.8266787976412, 937.4843835890233, 974.9756256093655, 977.7213494154132, 979.2166266986383, 4404.801783919731, 4653.790579423934, 5439.85125875277, 5501.669899806025, 5522.364928033247, 5491.670499770026, 5839.394166604217, 5933.495652869555, 6024.126501657235, 6029.8191054268445, 4530.682852200381, 5196.506331898464, 5493.395726470085, 5497.0, 5519.448055194448, 2973.8550657996393, 3167.8099314041233, 3190.074878285271, 3198.4082944655315, 3221.0658908916153, 5450.509454149145, 6284.24589049317, 6312.6212427253595, 6322.747090116344, 6393.296737358887, 5307.708076055854, 5720.685362305113, 5781.0, 6262.870623987775, 6277.7175027123885, 5765.452282033284, 6319.6208227506495, 6348.365163483615, 6352.968235158794, 6371.8088457347285, 5693.572982026281, 6060.39396060408, 6118.724657390517, 6119.663418512025, 6184.752609895554, 5786.42135786418, 6025.909611355745, 6052.727627256696, 6079.300880398634, 6100.328963813889, 5832.445917637818, 6104.8779024420155, 6149.063045140766, 6324.462420694293, 6380.266269378895, 3853.595372485961, 3967.4842270505806, 3994.3409337458993, 4007.3788562772816, 4016.678665706678, 4758.500357462417, 5652.236948011966, 5810.186573879576, 6108.541859360655, 6241.344658810759, 4400.955990440055, 5260.502270558617, 5545.445455454423, 5686.829395118153, 5886.558508111823, 5391.818826892152, 5936.732847021808, 5938.910916336345, 5939.851503712442, 5953.761849526144, 5370.3287089113865, 5734.885302294014, 5796.565257605781, 5861.0, 5873.353931067664, 5156.871078223075, 5780.508656764222, 5861.593217627824, 5917.230760001345, 5927.229460170151, 4270.636995855263, 5542.307211598551, 5831.501099340531, 5835.183074369506, 5871.884341975011], "value": 4232.013472521464, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.9804705858822, 732.77284041947, 733.6038539188838, 766.9194734552872, 827.780638130895, 14705.117692938431, 15272.923635381807, 15553.53339399817, 15644.296006679699, 15679.45121920733, 14501.274936253172, 14505.564833055025, 14543.927280363583, 14561.271936403164, 14658.63353416165, 855.2217482091282, 871.6164887449534, 875.2166810704416, 875.4878396138266, 875.6978842299396, 673.124937581145, 692.5671455340412, 738.2617382617383, 865.2299453486371, 874.64576846377, 896.5203616065406, 916.9679061232858, 917.7980844214262, 919.9310051746112, 922.4280945813572, 886.7251152142857, 900.5542256582968, 909.1454033208763, 911.3620465674055, 913.7989642278689, 556.6576555418388, 643.6234802640461, 675.4562577125412, 677.2076670295717, 677.793273051719, 14890.106593604418, 14963.70072598542, 15044.0, 15053.698926021527, 15072.321745521369, 13680.52118175869, 14315.427382904776, 14483.986120971536, 14711.190884501348, 14743.926280368636, 11674.591389301333, 11771.941140294244, 11778.352190629512, 11883.227590206609, 12054.397280135958, 3660.5790334111653, 3770.5286839145106, 3825.158465137665, 3845.038740314921, 3877.495925529663, 68104.6594767023, 110581.44709276402, 111036.0, 111077.88922110676, 111094.0, 9076.727698169065, 10656.840147397796, 10696.358218506915, 10830.350178989129, 10922.890771092187, 17603.55991100207, 17977.910110449626, 20206.0, 20315.0, 20480.38558843237, 7944.404169687296, 8034.317083048007, 8110.918890811016, 8233.135520770233, 8251.793705157419, 4415.735055896656, 4434.623057040188, 4479.529649386767, 4491.955080449154, 4643.233866412004, 3560.6795388415167, 3693.778373297611, 3698.63013698628, 3701.241245544657, 3811.1805961718364, 558.3104865491115, 709.7835160276062, 712.0814149746872, 715.4812760748416, 730.7369347035063, 587.8030859662061, 672.9461643068543, 673.4040374268808, 674.5851301449671, 684.7295318349225, 8468.618912148968, 8750.899064972358, 9594.66418675343, 9739.513024348897, 9825.950870245602, 606.3875485759414, 650.1320736816309, 650.6616559389091, 673.0543586261213, 707.9327463890922, 4403.647708183337, 4634.86095417138, 4639.907201856012, 4651.209294419957, 4724.858254252378, 89209.55395222982, 89311.55344223237, 126255.3687231558, 144404.0, 145918.0, 99265.0, 99853.50073249587, 102247.0, 124651.3767431157, 126850.0, 72671.63664181645, 89784.55107724419, 92589.5370523143, 96747.51626241824, 100741.49629251953, 9407.059294070537, 9587.808243834945, 9922.56897060225, 10009.647732782936, 10035.648752293926, 58235.417645823, 59506.0, 60920.08619870794, 61281.69359153176, 61770.38229617646, 86623.56688216771, 87069.0, 89926.55036724775, 92126.0, 92220.0, 12742.362881856056, 12904.806427903777, 13199.07606467538, 13376.331183440594, 13697.931510342385, 14329.5793341848, 14405.711885762435, 14471.927640361731, 14566.635834104232, 15090.849091508944, 10840.566377344818, 13613.977603068855, 13694.45222191141, 13889.166650001032, 14053.367598458299, 13495.0, 13626.386812593655, 13717.245551494365, 13807.240601767, 14144.292785360896, 13204.0, 13308.467661293827, 13460.394282257517, 13853.030287879752, 13937.930310348384, 11114.610988615359, 11144.721381965192, 11163.330200188015, 11296.887031129583, 11347.716307092389, 7006.0, 10502.10732087781, 11218.495167717636, 11439.771204575696, 11484.94257528707], "value": 7669.335797495572, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 117.0, 115.0, 116.0, 117.0, 126.0, 110.0, 140.0, 104.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 112.0, 148.0, 111.0, 113.0, 112.0, 120.0, 148.0, 131.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 205.0, 233.0, 244.0, 243.0, 203.0, 200.0, 199.0, 192.0, 196.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 60.0, 84.0, 60.0, 71.0, 59.0, 56.0, 75.0, 51.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 73.0, 85.0, 72.0, 69.0, 72.0, 87.0, 70.0, 72.0, 87.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 90.0, 90.0, 90.0, 87.0, 136.0, 85.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 95.0, 95.0, 96.0, 99.0, 95.0, 95.0, 95.0, 122.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 92.0, 88.0, 94.0, 90.0, 91.0, 94.0, 89.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 55.0, 58.0, 58.0, 59.0, 58.0, 56.0, 57.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 68.0, 83.0, 75.0, 71.0, 72.0, 70.0, 71.0, 74.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 90.0, 101.0, 113.0, 112.0, 112.0, 88.0, 89.0, 90.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 151.0, 138.0, 138.0, 171.0, 138.0, 141.0, 154.0, 178.0, 147.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 171.0, 244.0, 248.0, 185.0, 173.0, 177.0, 174.0, 184.0, 244.0], "value": 197.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 81.0, 92.0, 85.0, 59.0, 77.0, 93.0, 90.0, 90.0], "value": 81.0, "unit": "score"}]}]} 05:25:36 INFO - Return code: 0 05:25:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:36 ERROR - # TBPL SUCCESS # 05:25:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:36 INFO - 2016-09-27 05:25:36.715 screenresolution[2040:283594] starting screenresolution argv=screenresolution get 05:25:36 INFO - 2016-09-27 05:25:36.718 screenresolution[2040:283594] Display 0: 1600x1200x32@60 05:25:36 INFO - 2016-09-27 05:25:36.725 screenresolution[2041:283597] starting screenresolution argv=screenresolution list 05:25:36 INFO - Available Modes on Display 0 05:25:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:36 INFO - Intel Iris: 05:25:36 INFO - Chipset Model: Intel Iris 05:25:36 INFO - Type: GPU 05:25:36 INFO - Bus: Built-In 05:25:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:36 INFO - Vendor: Intel (0x8086) 05:25:36 INFO - Device ID: 0x0a2e 05:25:36 INFO - Revision ID: 0x0009 05:25:36 INFO - Displays: 05:25:36 INFO - Display: 05:25:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:36 INFO - Display Serial Number: 1600x1200 60 05:25:36 INFO - Main Display: Yes 05:25:36 INFO - Mirror: Off 05:25:36 INFO - Online: Yes 05:25:36 INFO - Rotation: Supported 05:25:36 INFO - Return code: 0 05:25:36 INFO - Running post-action listener: _resource_record_post_action 05:25:36 INFO - [mozharness: 2016-09-27 12:25:36.966258Z] Finished run-tests step (success) 05:25:36 INFO - Running post-run listener: _resource_record_post_run 05:25:37 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 2437632; Write bytes: 708367360; Read time: 21; Write time: 2017 05:25:37 INFO - TinderboxPrint: CPU usage
54.3% 05:25:37 INFO - TinderboxPrint: I/O read bytes / time
2,437,632 / 21 05:25:37 INFO - TinderboxPrint: I/O write bytes / time
708,367,360 / 2,017 05:25:37 INFO - TinderboxPrint: CPU idle
2,209.2 (45.7%) 05:25:37 INFO - TinderboxPrint: CPU system
974.3 (20.1%) 05:25:37 INFO - TinderboxPrint: CPU user
1,654.2 (34.2%) 05:25:37 INFO - TinderboxPrint: Swap in / out
424,345,600 / 0 05:25:37 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392948224; Write bytes: 386697216; Read time: 29537; Write time: 803 05:25:37 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 2437632; Write bytes: 299555840; Read time: 21; Write time: 1161 05:25:37 INFO - Running post-run listener: _upload_blobber_files 05:25:37 INFO - Blob upload gear active. 05:25:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:37 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 05:25:37 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'] 05:25:37 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 05:25:37 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:39 INFO - (blobuploader) - INFO - Done attempting. 05:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:40 INFO - (blobuploader) - INFO - Done attempting. 05:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:42 INFO - (blobuploader) - INFO - Done attempting. 05:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:42 INFO - Return code: 0 05:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260132db39bbf5f08e11173a69210f45323a0180b6f59b00a1c6475c4c501ce9aa3d57390c01d7855f51441302b2a6bb522887e1197fa764b1ff6d14d599a996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67a623177791b98c866d62eca66df8b2f892aa0c6eb68d7562e1a58d71cbaaa0ff5029262e57e5131082a0ba81ce09d3aa7f1a1f0ca87817a4d59d84be6d19da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94cd46d6d07f062ad2ef0227c5ec576c8d5629a2065849e6d4722e5581bf24144736056986a2d3a34239279299ff4429b6436a77599e415f89b09b224d81f8"} 05:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:42 INFO - Contents: 05:25:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260132db39bbf5f08e11173a69210f45323a0180b6f59b00a1c6475c4c501ce9aa3d57390c01d7855f51441302b2a6bb522887e1197fa764b1ff6d14d599a996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67a623177791b98c866d62eca66df8b2f892aa0c6eb68d7562e1a58d71cbaaa0ff5029262e57e5131082a0ba81ce09d3aa7f1a1f0ca87817a4d59d84be6d19da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94cd46d6d07f062ad2ef0227c5ec576c8d5629a2065849e6d4722e5581bf24144736056986a2d3a34239279299ff4429b6436a77599e415f89b09b224d81f8"} 05:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:42 INFO - Copying logs to upload dir... 05:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.534776 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-09-27 05:25:42.524844) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 05:25:42.532407) ========= 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.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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/260132db39bbf5f08e11173a69210f45323a0180b6f59b00a1c6475c4c501ce9aa3d57390c01d7855f51441302b2a6bb522887e1197fa764b1ff6d14d599a996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67a623177791b98c866d62eca66df8b2f892aa0c6eb68d7562e1a58d71cbaaa0ff5029262e57e5131082a0ba81ce09d3aa7f1a1f0ca87817a4d59d84be6d19da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94cd46d6d07f062ad2ef0227c5ec576c8d5629a2065849e6d4722e5581bf24144736056986a2d3a34239279299ff4429b6436a77599e415f89b09b224d81f8"} build_url:https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/AvYnIWyhQmm5OZaISPfbkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260132db39bbf5f08e11173a69210f45323a0180b6f59b00a1c6475c4c501ce9aa3d57390c01d7855f51441302b2a6bb522887e1197fa764b1ff6d14d599a996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67a623177791b98c866d62eca66df8b2f892aa0c6eb68d7562e1a58d71cbaaa0ff5029262e57e5131082a0ba81ce09d3aa7f1a1f0ca87817a4d59d84be6d19da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e94cd46d6d07f062ad2ef0227c5ec576c8d5629a2065849e6d4722e5581bf24144736056986a2d3a34239279299ff4429b6436a77599e415f89b09b224d81f8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 05:25:42.568748) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:25:42.569097) ========= 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.kngfkE9lCe/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.6gMZxsGpAR/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005014 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:25:42.622331) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 05:25:42.622867) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 05:25:42.623409) ========= ========= Total master_lag: 0.26 =========