builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0228 starttime: 1490183217.48 results: success (0) buildid: 20170322031935 builduid: 0a7dc83674ad4ec883ce5c89341123ea revision: 888b1394a1972ee72c04ff45e9cba38f77e49bcb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.480211) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.480639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.480929) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.508678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.509023) ========= 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.4ibE7nsZSr/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.Qy73mbMhxc/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 --2017-03-22 04:46:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.79M=0.001s 2017-03-22 04:46:57 (7.79 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107918 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.642100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.642409) ========= 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.4ibE7nsZSr/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.Qy73mbMhxc/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.022241 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 04:46:57.690619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 04:46:57.690981) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 888b1394a1972ee72c04ff45e9cba38f77e49bcb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 888b1394a1972ee72c04ff45e9cba38f77e49bcb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4ibE7nsZSr/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.Qy73mbMhxc/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 2017-03-22 04:46:57,970 truncating revision to first 12 chars 2017-03-22 04:46:57,970 Setting DEBUG logging. 2017-03-22 04:46:57,970 attempt 1/10 2017-03-22 04:46:57,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/888b1394a197?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 04:46:59,844 unpacking tar archive at: mozilla-beta-888b1394a197/testing/mozharness/ program finished with exit code 0 elapsedTime=2.326360 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 04:47:00.040598) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 04:47:00.040927) ========= script_repo_revision: 888b1394a1972ee72c04ff45e9cba38f77e49bcb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 04:47:00.041302) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 04:47:00.041565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 04:47:00.061032) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-03-22 04:47:00.061302) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:00 INFO - MultiFileLogger online at 20170322 04:47:00 in /builds/slave/test 04:47:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:47:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:00 INFO - {'append_to_log': False, 04:47:00 INFO - 'base_work_dir': '/builds/slave/test', 04:47:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:47:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:00 INFO - 'branch': 'Mozilla-Beta', 04:47:00 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:00 INFO - 'config_files': ('talos/mac_config.py',), 04:47:00 INFO - 'default_actions': ('clobber', 04:47:00 INFO - 'read-buildbot-config', 04:47:00 INFO - 'download-and-extract', 04:47:00 INFO - 'populate-webroot', 04:47:00 INFO - 'create-virtualenv', 04:47:00 INFO - 'install', 04:47:00 INFO - 'run-tests'), 04:47:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:00 INFO - 'download_minidump_stackwalk': True, 04:47:00 INFO - 'download_symbols': 'ondemand', 04:47:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:00 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:00 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:00 INFO - 'installer_path': 'installer.exe', 04:47:00 INFO - 'log_level': 'info', 04:47:00 INFO - 'log_name': 'talos', 04:47:00 INFO - 'log_to_console': True, 04:47:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:00 INFO - 'opt_config_files': (), 04:47:00 INFO - 'pip_index': False, 04:47:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:00 INFO - 'cmd': ('bash', 04:47:00 INFO - '-c', 04:47:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:00 INFO - 'enabled': True, 04:47:00 INFO - 'halt_on_failure': False, 04:47:00 INFO - 'name': 'check_screen_resolution'},), 04:47:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:00 INFO - 'cmd': ('bash', 04:47:00 INFO - '-c', 04:47:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:00 INFO - 'enabled': True, 04:47:00 INFO - 'halt_on_failure': False, 04:47:00 INFO - 'name': 'check_screen_resolution'},), 04:47:00 INFO - 'run_cmd_checks_enabled': True, 04:47:00 INFO - 'sps_profile': False, 04:47:00 INFO - 'sps_profile_interval': 0, 04:47:00 INFO - 'suite': 'dromaeojs-e10s', 04:47:00 INFO - 'system_bits': '32', 04:47:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:00 INFO - 'title': 't-yosemite-r7-0228', 04:47:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:00 INFO - 'use_talos_json': True, 04:47:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:00 INFO - 'work_dir': 'build'} 04:47:00 INFO - [mozharness: 2017-03-22 11:47:00.461388Z] Running clobber step. 04:47:00 INFO - Running pre-action listener: _resource_record_pre_action 04:47:00 INFO - Running main action method: clobber 04:47:00 INFO - rmtree: /builds/slave/test/build 04:47:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:02 INFO - Running post-action listener: _resource_record_post_action 04:47:02 INFO - [mozharness: 2017-03-22 11:47:02.277975Z] Finished clobber step (success) 04:47:02 INFO - [mozharness: 2017-03-22 11:47:02.278258Z] Running read-buildbot-config step. 04:47:02 INFO - Running pre-action listener: _resource_record_pre_action 04:47:02 INFO - Running main action method: read_buildbot_config 04:47:02 INFO - Using buildbot properties: 04:47:02 INFO - { 04:47:02 INFO - "project": "", 04:47:02 INFO - "product": "firefox", 04:47:02 INFO - "script_repo_revision": "production", 04:47:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:47:02 INFO - "repository": "", 04:47:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:47:02 INFO - "buildid": "20170322031935", 04:47:02 INFO - "pgo_build": "False", 04:47:02 INFO - "basedir": "/builds/slave/test", 04:47:02 INFO - "buildnumber": 4, 04:47:02 INFO - "slavename": "t-yosemite-r7-0228", 04:47:02 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:47:02 INFO - "platform": "yosemite_r7", 04:47:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:47:02 INFO - "branch": "mozilla-beta", 04:47:02 INFO - "repo_path": "releases/mozilla-beta", 04:47:02 INFO - "revision": "888b1394a1972ee72c04ff45e9cba38f77e49bcb", 04:47:02 INFO - "stage_platform": "macosx64", 04:47:02 INFO - "builduid": "0a7dc83674ad4ec883ce5c89341123ea", 04:47:02 INFO - "slavebuilddir": "test" 04:47:02 INFO - } 04:47:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 04:47:02 INFO - Running post-action listener: _resource_record_post_action 04:47:02 INFO - [mozharness: 2017-03-22 11:47:02.282540Z] Finished read-buildbot-config step (success) 04:47:02 INFO - [mozharness: 2017-03-22 11:47:02.282674Z] Running download-and-extract step. 04:47:02 INFO - Running pre-action listener: _resource_record_pre_action 04:47:02 INFO - Running main action method: download_and_extract 04:47:02 INFO - mkdir: /builds/slave/test/build/tests 04:47:02 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')]} 04:47:02 INFO - trying https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 04:47:02 INFO - Downloading https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 04:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 04:47:03 INFO - Downloaded 1165 bytes. 04:47:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 04:47:03 INFO - Using the following test package requirements: 04:47:03 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 04:47:03 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 04:47:03 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 04:47:03 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:47:03 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 04:47:03 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 04:47:03 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 04:47:03 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 04:47:03 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 04:47:03 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:47:03 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 04:47:03 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 04:47:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 04:47:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 04:47:03 INFO - Fetch https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 04:47:04 INFO - Expected file size: 16108220 04:47:04 INFO - Obtained file size: 16108220 04:47:10 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 04:47:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 04:47:10 INFO - Fetch https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 04:47:11 INFO - Expected file size: 16108220 04:47:11 INFO - Obtained file size: 16108220 04:47:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 04:47:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 04:47:17 INFO - Fetch https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 04:47:18 INFO - Expected file size: 14403663 04:47:18 INFO - Obtained file size: 14403663 04:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:19 INFO - trying https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:47:19 INFO - Downloading https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 04:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 04:47:20 INFO - Downloaded 58737837 bytes. 04:47:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:47:20 INFO - mkdir: /builds/slave/test/properties 04:47:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:20 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:20 INFO - Contents: 04:47:20 INFO - build_url:https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:47:21 INFO - Running post-action listener: _resource_record_post_action 04:47:21 INFO - Running post-action listener: set_extra_try_arguments 04:47:21 INFO - [mozharness: 2017-03-22 11:47:21.571474Z] Finished download-and-extract step (success) 04:47:21 INFO - [mozharness: 2017-03-22 11:47:21.571597Z] Running populate-webroot step. 04:47:21 INFO - Running pre-action listener: _resource_record_pre_action 04:47:21 INFO - Running main action method: populate_webroot 04:47:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:21 INFO - '--tppagecycles', 04:47:21 INFO - '7'], 04:47:21 INFO - 'tests': ['tsvgm']}}, 04:47:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['damp', 'tps']}, 04:47:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'tests': ['damp', 'tps']}, 04:47:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['dromaeo_dom']}, 04:47:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['basic_compositor_video']}, 04:47:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:47:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['a11yr', 04:47:21 INFO - 'ts_paint', 04:47:21 INFO - 'tpaint', 04:47:21 INFO - 'sessionrestore', 04:47:21 INFO - 'sessionrestore_no_auto_restore', 04:47:21 INFO - 'tabpaint']}, 04:47:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['tsvgx', 04:47:21 INFO - 'tsvgr_opacity', 04:47:21 INFO - 'tart', 04:47:21 INFO - 'tscrollx', 04:47:21 INFO - 'cart', 04:47:21 INFO - 'tsvg_static']}, 04:47:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:21 INFO - 'tsvgr_opacity', 04:47:21 INFO - 'tart', 04:47:21 INFO - 'tscrollx', 04:47:21 INFO - 'cart', 04:47:21 INFO - 'tsvg_static']}, 04:47:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'talos_options': ['--disable-e10s'], 04:47:21 INFO - 'tests': ['tp5o']}, 04:47:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:47:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'talos_options': ['--disable-e10s', 04:47:21 INFO - '--xperf_path', 04:47:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:21 INFO - 'tests': ['tp5n']}, 04:47:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:47:21 INFO - 'talos_options': ['--xperf_path', 04:47:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:21 INFO - 'tests': ['tp5n']}}, 04:47:21 INFO - 'talos.zip': {'path': '', 04:47:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:21 INFO - Running post-action listener: _resource_record_post_action 04:47:21 INFO - [mozharness: 2017-03-22 11:47:21.589451Z] Finished populate-webroot step (success) 04:47:21 INFO - [mozharness: 2017-03-22 11:47:21.589592Z] Running create-virtualenv step. 04:47:21 INFO - Running pre-action listener: _resource_record_pre_action 04:47:21 INFO - Running main action method: create_virtualenv 04:47:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:21 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 04:47:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:21 INFO - Using real prefix '/tools/python27' 04:47:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:25 INFO - Installing pip.................done. 04:47:25 INFO - Return code: 0 04:47:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:25 INFO - Reading from file tmpfile_stdout 04:47:25 INFO - Output received: 04:47:25 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) 04:47:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:25 INFO - 'HOME': '/Users/cltbld', 04:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:25 INFO - 'LOGNAME': 'cltbld', 04:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:25 INFO - 'MOZ_NO_REMOTE': '1', 04:47:25 INFO - 'NO_EM_RESTART': '1', 04:47:25 INFO - 'PAGER': '/bin/cat', 04:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:25 INFO - 'PWD': '/builds/slave/test', 04:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:25 INFO - 'SHELL': '/bin/bash', 04:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:25 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:25 INFO - 'USER': 'cltbld', 04:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:25 INFO - 'XPC_FLAGS': '0x0', 04:47:25 INFO - 'XPC_SERVICE_NAME': '0', 04:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:25 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 04:47:25 INFO - Cleaning up... 04:47:25 INFO - Return code: 0 04:47:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:25 INFO - 'HOME': '/Users/cltbld', 04:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:25 INFO - 'LOGNAME': 'cltbld', 04:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:25 INFO - 'MOZ_NO_REMOTE': '1', 04:47:25 INFO - 'NO_EM_RESTART': '1', 04:47:25 INFO - 'PAGER': '/bin/cat', 04:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:25 INFO - 'PWD': '/builds/slave/test', 04:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:25 INFO - 'SHELL': '/bin/bash', 04:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:25 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:25 INFO - 'USER': 'cltbld', 04:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:25 INFO - 'XPC_FLAGS': '0x0', 04:47:25 INFO - 'XPC_SERVICE_NAME': '0', 04:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:26 INFO - Downloading/unpacking psutil>=3.1.1 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:29 INFO - Installing collected packages: psutil 04:47:29 INFO - Running setup.py install for psutil 04:47:29 INFO - building 'psutil._psutil_osx' extension 04:47:29 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 04:47:30 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 04:47:30 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 04:47:30 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 04:47:30 INFO - building 'psutil._psutil_posix' extension 04:47:30 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 04:47:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:30 INFO - ^ 04:47:30 INFO - 1 warning generated. 04:47:30 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 04:47:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:30 INFO - Successfully installed psutil 04:47:30 INFO - Cleaning up... 04:47:30 INFO - Return code: 0 04:47:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:30 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')]} 04:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:30 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')]} 04:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:30 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:30 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 04:47:30 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 04:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:30 INFO - 'HOME': '/Users/cltbld', 04:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:30 INFO - 'LOGNAME': 'cltbld', 04:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:30 INFO - 'MOZ_NO_REMOTE': '1', 04:47:30 INFO - 'NO_EM_RESTART': '1', 04:47:30 INFO - 'PAGER': '/bin/cat', 04:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:30 INFO - 'PWD': '/builds/slave/test', 04:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:30 INFO - 'SHELL': '/bin/bash', 04:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:30 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:30 INFO - 'USER': 'cltbld', 04:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:30 INFO - 'XPC_FLAGS': '0x0', 04:47:30 INFO - 'XPC_SERVICE_NAME': '0', 04:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:33 INFO - Installing collected packages: mozsystemmonitor 04:47:33 INFO - Running setup.py install for mozsystemmonitor 04:47:33 INFO - Successfully installed mozsystemmonitor 04:47:33 INFO - Cleaning up... 04:47:33 INFO - Return code: 0 04:47:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:33 INFO - 'HOME': '/Users/cltbld', 04:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:33 INFO - 'LOGNAME': 'cltbld', 04:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:33 INFO - 'MOZ_NO_REMOTE': '1', 04:47:33 INFO - 'NO_EM_RESTART': '1', 04:47:33 INFO - 'PAGER': '/bin/cat', 04:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:33 INFO - 'PWD': '/builds/slave/test', 04:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:33 INFO - 'SHELL': '/bin/bash', 04:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:33 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:33 INFO - 'USER': 'cltbld', 04:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:33 INFO - 'XPC_FLAGS': '0x0', 04:47:33 INFO - 'XPC_SERVICE_NAME': '0', 04:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:33 INFO - Downloading/unpacking jsonschema==2.5.1 04:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:36 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:37 INFO - warning: no files found matching '*.txt' 04:47:37 INFO - no previously-included directories found matching 'build' 04:47:37 INFO - no previously-included directories found matching 'dist' 04:47:37 INFO - no previously-included directories found matching '.git*' 04:47:37 INFO - Installing collected packages: jsonschema, functools32 04:47:37 INFO - Running setup.py install for functools32 04:47:37 INFO - warning: no files found matching '*.txt' 04:47:37 INFO - no previously-included directories found matching 'build' 04:47:37 INFO - no previously-included directories found matching 'dist' 04:47:37 INFO - no previously-included directories found matching '.git*' 04:47:37 INFO - Successfully installed jsonschema functools32 04:47:37 INFO - Cleaning up... 04:47:37 INFO - Return code: 0 04:47:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:37 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:37 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 04:47:37 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 04:47:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:37 INFO - 'HOME': '/Users/cltbld', 04:47:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:37 INFO - 'LOGNAME': 'cltbld', 04:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:37 INFO - 'MOZ_NO_REMOTE': '1', 04:47:37 INFO - 'NO_EM_RESTART': '1', 04:47:37 INFO - 'PAGER': '/bin/cat', 04:47:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:37 INFO - 'PWD': '/builds/slave/test', 04:47:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:37 INFO - 'SHELL': '/bin/bash', 04:47:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:37 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:37 INFO - 'USER': 'cltbld', 04:47:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:37 INFO - 'XPC_FLAGS': '0x0', 04:47:37 INFO - 'XPC_SERVICE_NAME': '0', 04:47:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:37 INFO - Cleaning up... 04:47:37 INFO - Return code: 0 04:47:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:37 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:37 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 04:47:37 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 04:47:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:37 INFO - 'HOME': '/Users/cltbld', 04:47:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:37 INFO - 'LOGNAME': 'cltbld', 04:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:37 INFO - 'MOZ_NO_REMOTE': '1', 04:47:37 INFO - 'NO_EM_RESTART': '1', 04:47:37 INFO - 'PAGER': '/bin/cat', 04:47:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:37 INFO - 'PWD': '/builds/slave/test', 04:47:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:37 INFO - 'SHELL': '/bin/bash', 04:47:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:37 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:37 INFO - 'USER': 'cltbld', 04:47:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:37 INFO - 'XPC_FLAGS': '0x0', 04:47:37 INFO - 'XPC_SERVICE_NAME': '0', 04:47:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:37 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:37 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 04:47:37 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 04:47:37 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 04:47:37 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 04:47:37 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 04:47:37 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 04:47:40 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 INFO - Downloading docopt-0.6.1.tar.gz 04:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:41 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:41 INFO - Running setup.py install for blobuploader 04:47:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:42 INFO - Running setup.py install for requests 04:47:42 INFO - Running setup.py install for docopt 04:47:42 INFO - Successfully installed blobuploader requests docopt 04:47:42 INFO - Cleaning up... 04:47:42 INFO - Return code: 0 04:47:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:42 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:42 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 04:47:42 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 04:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:42 INFO - 'HOME': '/Users/cltbld', 04:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:42 INFO - 'LOGNAME': 'cltbld', 04:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:42 INFO - 'MOZ_NO_REMOTE': '1', 04:47:42 INFO - 'NO_EM_RESTART': '1', 04:47:42 INFO - 'PAGER': '/bin/cat', 04:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:42 INFO - 'PWD': '/builds/slave/test', 04:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:42 INFO - 'SHELL': '/bin/bash', 04:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:42 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:42 INFO - 'USER': 'cltbld', 04:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:42 INFO - 'XPC_FLAGS': '0x0', 04:47:42 INFO - 'XPC_SERVICE_NAME': '0', 04:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QTYgbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-KGemVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-TSqhKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-cQXgxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-0eAV3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-GU36ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-8iP53F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Qk_WM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ZPkYXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-IVoO5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kUsGdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-D3Lq2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9rr4kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jFg2Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-nfe00F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7Lkxwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aImLBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:44 INFO - Running setup.py install for manifestparser 04:47:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:44 INFO - Running setup.py install for mozcrash 04:47:45 INFO - Running setup.py install for mozdebug 04:47:45 INFO - Running setup.py install for mozdevice 04:47:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:45 INFO - Running setup.py install for mozfile 04:47:45 INFO - Running setup.py install for mozhttpd 04:47:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:45 INFO - Running setup.py install for mozinfo 04:47:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:45 INFO - Running setup.py install for mozInstall 04:47:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Running setup.py install for mozleak 04:47:46 INFO - Running setup.py install for mozlog 04:47:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Running setup.py install for moznetwork 04:47:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Running setup.py install for mozprocess 04:47:46 INFO - Running setup.py install for mozprofile 04:47:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Running setup.py install for mozrunner 04:47:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:46 INFO - Running setup.py install for mozscreenshot 04:47:47 INFO - Running setup.py install for moztest 04:47:47 INFO - Running setup.py install for mozversion 04:47:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:47 INFO - Cleaning up... 04:47:47 INFO - Return code: 0 04:47:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:47 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:47 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 04:47:47 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 04:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:47 INFO - 'HOME': '/Users/cltbld', 04:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:47 INFO - 'LOGNAME': 'cltbld', 04:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:47 INFO - 'MOZ_NO_REMOTE': '1', 04:47:47 INFO - 'NO_EM_RESTART': '1', 04:47:47 INFO - 'PAGER': '/bin/cat', 04:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:47 INFO - 'PWD': '/builds/slave/test', 04:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:47 INFO - 'SHELL': '/bin/bash', 04:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:47 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:47 INFO - 'USER': 'cltbld', 04:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:47 INFO - 'XPC_FLAGS': '0x0', 04:47:47 INFO - 'XPC_SERVICE_NAME': '0', 04:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:47 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-06nJBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:47 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)) 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:47 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-4mGyHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:47 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-3jGR43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kWOktj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jgplnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WUEuBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zFTJbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9q_6J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-DJBJlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-A4cNDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WX3Y6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:48 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)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-12zIrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-OiMrt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:49 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)) 04:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SGN28o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-C1_HIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:49 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)) 04:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zD6hcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gv1U9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:49 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)) 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:49 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)) 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:47:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:49 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 04:47:49 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 04:47:49 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 04:47:49 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 04:47:49 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 04:47:49 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 04:47:52 INFO - Downloading blessings-1.6.tar.gz 04:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:52 INFO - Installing collected packages: blessings 04:47:52 INFO - Running setup.py install for blessings 04:47:52 INFO - Successfully installed blessings 04:47:52 INFO - Cleaning up... 04:47:52 INFO - Return code: 0 04:47:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:52 INFO - Reading from file tmpfile_stdout 04:47:52 INFO - Current package versions: 04:47:52 INFO - blessings == 1.6 04:47:52 INFO - blobuploader == 1.2.4 04:47:52 INFO - docopt == 0.6.1 04:47:52 INFO - functools32 == 3.2.3-2 04:47:52 INFO - jsonschema == 2.5.1 04:47:52 INFO - manifestparser == 1.1 04:47:52 INFO - mozInstall == 1.12 04:47:52 INFO - mozcrash == 1.0 04:47:52 INFO - mozdebug == 0.1 04:47:52 INFO - mozdevice == 0.49 04:47:52 INFO - mozfile == 1.2 04:47:52 INFO - mozhttpd == 0.7 04:47:52 INFO - mozinfo == 0.9 04:47:52 INFO - mozleak == 0.1 04:47:52 INFO - mozlog == 3.4 04:47:52 INFO - moznetwork == 0.27 04:47:52 INFO - mozprocess == 0.24 04:47:52 INFO - mozprofile == 0.28 04:47:52 INFO - mozrunner == 6.13 04:47:52 INFO - mozscreenshot == 0.1 04:47:52 INFO - mozsystemmonitor == 0.3 04:47:52 INFO - moztest == 0.8 04:47:52 INFO - mozversion == 1.4 04:47:52 INFO - psutil == 3.1.1 04:47:52 INFO - requests == 1.2.3 04:47:52 INFO - wsgiref == 0.1.2 04:47:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:52 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:52 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 04:47:52 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 04:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:52 INFO - 'HOME': '/Users/cltbld', 04:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:52 INFO - 'LOGNAME': 'cltbld', 04:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:52 INFO - 'MOZ_NO_REMOTE': '1', 04:47:52 INFO - 'NO_EM_RESTART': '1', 04:47:52 INFO - 'PAGER': '/bin/cat', 04:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:52 INFO - 'PWD': '/builds/slave/test', 04:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:52 INFO - 'SHELL': '/bin/bash', 04:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:52 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:52 INFO - 'USER': 'cltbld', 04:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:52 INFO - 'XPC_FLAGS': '0x0', 04:47:52 INFO - 'XPC_SERVICE_NAME': '0', 04:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 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)) 04:47:53 INFO - Cleaning up... 04:47:53 INFO - Return code: 0 04:47:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:53 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')]} 04:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:53 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')]} 04:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:53 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 0x105ae7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa968554ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, '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.4ibE7nsZSr/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.Qy73mbMhxc/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 04:47:53 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 04:47:53 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 04:47:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:47:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:53 INFO - 'HOME': '/Users/cltbld', 04:47:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:53 INFO - 'LOGNAME': 'cltbld', 04:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:53 INFO - 'MOZ_NO_REMOTE': '1', 04:47:53 INFO - 'NO_EM_RESTART': '1', 04:47:53 INFO - 'PAGER': '/bin/cat', 04:47:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:53 INFO - 'PWD': '/builds/slave/test', 04:47:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:53 INFO - 'SHELL': '/bin/bash', 04:47:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:47:53 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:47:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:53 INFO - 'USER': 'cltbld', 04:47:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:53 INFO - 'XPC_FLAGS': '0x0', 04:47:53 INFO - 'XPC_SERVICE_NAME': '0', 04:47:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:47:53 INFO - Cleaning up... 04:47:53 INFO - Return code: 0 04:47:53 INFO - Running post-action listener: _resource_record_post_action 04:47:53 INFO - Running post-action listener: _start_resource_monitoring 04:47:53 INFO - Starting resource monitoring. 04:47:53 INFO - [mozharness: 2017-03-22 11:47:53.323666Z] Finished create-virtualenv step (success) 04:47:53 INFO - [mozharness: 2017-03-22 11:47:53.324606Z] Running install step. 04:47:53 INFO - Running pre-action listener: _resource_record_pre_action 04:47:53 INFO - Running main action method: install 04:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:53 INFO - Reading from file tmpfile_stdout 04:47:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:53 INFO - Reading from file tmpfile_stdout 04:47:53 INFO - Output received: 04:47:53 INFO - Usage: mozinstall [options] installer 04:47:53 INFO - Options: 04:47:53 INFO - -h, --help show this help message and exit 04:47:53 INFO - -d DEST, --destination=DEST 04:47:53 INFO - Directory to install application into. [default: 04:47:53 INFO - "/builds/slave/test"] 04:47:53 INFO - --app=APP Application being installed. [default: firefox] 04:47:53 INFO - mkdir: /builds/slave/test/build/application 04:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:08 INFO - Reading from file tmpfile_stdout 04:48:08 INFO - Output received: 04:48:08 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:48:08 INFO - Running post-action listener: _resource_record_post_action 04:48:08 INFO - [mozharness: 2017-03-22 11:48:08.298015Z] Finished install step (success) 04:48:08 INFO - [mozharness: 2017-03-22 11:48:08.298137Z] Running run-tests step. 04:48:08 INFO - Running pre-action listener: _resource_record_pre_action 04:48:08 INFO - Running main action method: run_tests 04:48:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:08 INFO - 2017-03-22 04:48:08.313 screenresolution[2280:33761] starting screenresolution argv=screenresolution get 04:48:08 INFO - 2017-03-22 04:48:08.315 screenresolution[2280:33761] Display 0: 1600x1200x32@60 04:48:08 INFO - 2017-03-22 04:48:08.321 screenresolution[2281:33763] starting screenresolution argv=screenresolution list 04:48:08 INFO - Available Modes on Display 0 04:48:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:08 INFO - Intel Iris: 04:48:08 INFO - Chipset Model: Intel Iris 04:48:08 INFO - Type: GPU 04:48:08 INFO - Bus: Built-In 04:48:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:08 INFO - Vendor: Intel (0x8086) 04:48:08 INFO - Device ID: 0x0a2e 04:48:08 INFO - Revision ID: 0x0009 04:48:08 INFO - Displays: 04:48:08 INFO - Display: 04:48:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:08 INFO - Display Serial Number: 1600x1200 60 04:48:08 INFO - Main Display: Yes 04:48:08 INFO - Mirror: Off 04:48:08 INFO - Online: Yes 04:48:08 INFO - Rotation: Supported 04:48:08 INFO - Return code: 0 04:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:08 INFO - Python 2.7.3 04:48:08 INFO - Return code: 0 04:48:08 INFO - grabbing minidump binary from tooltool 04:48:08 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')]} 04:48:08 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 0x1058c5d30>, '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 0x1057ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058babe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:08 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 04:48:08 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 04:48:08 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 04:48:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:08 INFO - Return code: 0 04:48:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.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 04:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.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 04:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ibE7nsZSr/Render', 04:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:08 INFO - 'HOME': '/Users/cltbld', 04:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:08 INFO - 'LOGNAME': 'cltbld', 04:48:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:08 INFO - 'MOZ_NO_REMOTE': '1', 04:48:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:08 INFO - 'NO_EM_RESTART': '1', 04:48:08 INFO - 'PAGER': '/bin/cat', 04:48:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:08 INFO - 'PWD': '/builds/slave/test', 04:48:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:08 INFO - 'SHELL': '/bin/bash', 04:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qy73mbMhxc/Listeners', 04:48:08 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:08 INFO - 'USER': 'cltbld', 04:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:08 INFO - 'XPC_FLAGS': '0x0', 04:48:08 INFO - 'XPC_SERVICE_NAME': '0', 04:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.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 04:48:08 INFO - mozversion application_buildid: 20170322031935 04:48:08 INFO - mozversion application_changeset: 888b1394a1972ee72c04ff45e9cba38f77e49bcb 04:48:08 INFO - mozversion application_display_name: Firefox 04:48:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:08 INFO - mozversion application_name: Firefox 04:48:08 INFO - mozversion application_remotingname: firefox 04:48:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:48:08 INFO - mozversion application_vendor: Mozilla 04:48:08 INFO - mozversion application_version: 53.0 04:48:08 INFO - mozversion platform_buildid: 20170322031935 04:48:08 INFO - mozversion platform_changeset: 888b1394a1972ee72c04ff45e9cba38f77e49bcb 04:48:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:48:08 INFO - mozversion platform_version: 53.0 04:48:08 INFO - using testdate: 1490183288 04:48:08 INFO - actual date: 1490183288 04:48:08 INFO - starting webserver on 'localhost:56950' 04:48:08 INFO - SUITE-START | Running 2 tests 04:48:08 INFO - TEST-START | dromaeo_css 04:48:08 INFO - operating with platform_type : mac_ 04:48:08 INFO - Initialising browser for dromaeo_css test... 04:48:08 INFO - TEST-INFO | started process 2291 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpWIrpxQ/profile http://localhost:56950/getInfo.html) 04:48:11 INFO - PROCESS | 2291 | 2017-03-22 04:48:11.753 plugin-container[2294:33926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 04:48:11 INFO - PROCESS | 2291 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:11 INFO - PROCESS | 2291 | 2017-03-22 04:48:11.753 plugin-container[2294:33926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 04:48:11 INFO - PROCESS | 2291 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:11 INFO - PROCESS | 2291 | 2017-03-22 04:48:11.753 plugin-container[2294:33926] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:11 INFO - PROCESS | 2291 | __metrics Screen width/height:1600/1200 04:48:11 INFO - PROCESS | 2291 | colorDepth:24 04:48:11 INFO - PROCESS | 2291 | Browser inner width/height: 1024/689 04:48:11 INFO - PROCESS | 2291 | __metrics 04:48:11 ERROR - PROCESS | 2291 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:48:12 INFO - TEST-INFO | 2291: exit 0 04:48:12 INFO - Browser initialized. 04:48:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:12 INFO - TEST-INFO | started process 2295 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpWIrpxQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:14 INFO - PROCESS | 2295 | 2017-03-22 04:48:14.733 plugin-container[2296:34057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 04:48:14 INFO - PROCESS | 2295 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:14 INFO - PROCESS | 2295 | 2017-03-22 04:48:14.734 plugin-container[2296:34057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x850f, name = 'com.apple.CFPasteboardClient' 04:48:14 INFO - PROCESS | 2295 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:14 INFO - PROCESS | 2295 | 2017-03-22 04:48:14.734 plugin-container[2296:34057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:24 INFO - PROCESS | 2295 | RSS: Main: 172789760 04:48:24 INFO - PROCESS | 2295 | 04:51:25 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56950/tests/dromaeo/cssquery-ext.html) 04:51:27 INFO - PROCESS | 2295 | RSS: Main: 192548864 04:51:27 INFO - PROCESS | 2295 | 04:54:29 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-ext.html (next: http://localhost:56950/tests/dromaeo/cssquery-jquery.html) 04:54:30 INFO - PROCESS | 2295 | RSS: Main: 201871360 04:54:30 INFO - PROCESS | 2295 | 04:57:32 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56950/tests/dromaeo/cssquery-mootools.html) 04:57:33 INFO - PROCESS | 2295 | RSS: Main: 205651968 04:57:33 INFO - PROCESS | 2295 | 05:00:34 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56950/tests/dromaeo/cssquery-prototype.html) 05:00:35 INFO - PROCESS | 2295 | RSS: Main: 209547264 05:00:35 INFO - PROCESS | 2295 | 05:03:38 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56950/tests/dromaeo/cssquery-yui.html) 05:03:38 INFO - PROCESS | 2295 | RSS: Main: 210673664 05:03:38 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | Cycle 1(1): loaded http://localhost:56950/tests/dromaeo/cssquery-yui.html (next: http://localhost:56950/tests/dromaeo/cssquery-dojo.html) 05:06:41 INFO - PROCESS | 2295 | RSS: Main: 211222528 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | __start_tp_report 05:06:41 INFO - PROCESS | 2295 | _x_x_mozilla_page_load 05:06:41 INFO - PROCESS | 2295 | _x_x_mozilla_page_load_details 05:06:41 INFO - PROCESS | 2295 | |i|pagename|runs| 05:06:41 INFO - PROCESS | 2295 | |0;dojo.html;3201.567788348573;3521.401361768501;3547.7693949893273;3562.5724913010426;3564.6970007549357;5918.348981612019;5927.555433342501;5963.850903727409;5979.252593425822;6062.484688801457;5155.4586768389345;5281.762320695569;5712.743196496764;6029.788957386492;6062.605930614507;5267.05193065249;5346.385165705935;5370.221317908901;5397.487238712316;5402.35171779387;4582.1064892346;4731.692439991407;5172.922406163911;5433.293671822657;5448.591355648323;5286.709230992294;5302.6023048271345;5342.893142137174;5413.918791218135;5414.593905457087;5361.651492652965;5382.004329199091;5430.51125398716;5489.615726899079;5500.80747173851;5216.66091704038;5361.544268737162;5376.623636345457;5391.757370918318;5421.2952316199035;5755.597108202427;5902.085176797603;5978.970105149447;6071.878562428771;6093.786717464912;6173.506119510428;6194.566380353377;6239.064140378934;6243.8126856194385;6285.2457705075685;6292;6301.747930082791;6306.936930630727;6311.147995020659;6348.365163483615;6055.606385584967;6074.392560743934;6074.54440916933;6090.66501342426;6092.695365231721;6195.132681424604;6210.596311239755;6285.43431091204;6288.402601752826;6316.684165791692;6258.468030217483;6305.621662700252;6311.305756366796;6311.621302721759;6342.302346741854;6285.497160227263;6289.182406287245;6301.527385446018;6316.399942005502;6321.652309123042;6206.038064100072;6215.192025036807;6215.285242197115;6246.906296405557;6278.623282603058;6219.40915613016;6245.5628106032145;6262.12330273762;6279.905801412982;6299.433051025429;5107.846764597068;5114.258432527337;5120.48795120485;5126.743662816919;5143.588512918957;4345.478542574911;4392.407025051577;4527.253003254426;4590.770461476913;4598.057398233354;6131.101423540838;6159.952808022647;6183.041628547582;6209.782657606962;6215.689215539205;6322.557420980579;6323.683815809191;6344.111824344595;6369.108324834527;6490.350964903472;6028.065649824284;6088.086786981899;6119.380693981147;6145.740123274718;6195.16365290689;5637.43625637433;5645.491905728504;5663.971680141655;5666.121751128581;5669.036263835075;6590.538662293593;6606.4384527315715;6616.8345791355605;6620.073189753439;6625.701843417058;5185.170372740344;6174.475169610633;6197.597156184834;6223.937760622336;6260.780872669435;5470.234167216672;5616.550675945833;6573.638449885302;6582;6640.601563906181;6251.153694566376;6299.433051025429;6305.306416294296;6305.306416294296;6313.526485513697;6145.4161854623735;6160.322364539987;6199.473044791062;6252.343503932022;6298.1812364392335;6119.8776024478375;6163.753449861955;6172.166757487682;6174.876502469836;6195.008798592384;6133.478654314434;6179;6182.412670796267;6184.196054512884;6215.81352559424;5999.98000339952;6047.5464340173785;6091.847703807441;6156.415140561639;6254.906176407449;6222.191115155001;6254.124422580751;6263.311035785969;6263.874722505433;6300.71646775887;6241.531885108544;6245.7189426476825;6253.405926436982;6258;6286 05:06:41 INFO - PROCESS | 2295 | |1;ext.html;467.81053673262323;488.31635710005986;523.2177894048396;560.182134084237;561.2843624378919;38356.80821595892;41857.372139417894;41939.16121677565;42012.94967625811;42172;42796.78601606988;43208.78395608017;43753.343699844445;43824.780876095654;43865.90335241621;9490.715278541655;9753.853692194623;9767.951160244189;11378.146639002067;11788.7642247155;11495.02292305155;11508.82736758945;11609.187356884979;11646.01008914239;11749.41252937354;11681.59114430995;11734;11773.352465614387;11789.056875449944;11803.586874459395;11633.94183029088;11668.35824029678;11689.181757276994;11708.473118709637;11732.765344693144;11455.198136130473;11576.47905844239;11642.359670218135;11647.825282620683;11687.065034797195;37917.052073698105;38049.23901521982;38300.61699383026;38337.42493862594;38481.61518384808;4065.2479291331197;4089.89775255621;4095;4154.4183814265725;4155.91688166235;27682.307942301402;28347.433051339063;28365.290867728265;28370.432591348264;30958.916437924563;3164.3829453256626;3217.324361884007;3278.37710834943;3278.639349671534;3476.217850983534;486911;488216;490570;491384;492682;56172.157417638766;56774.7161264191;56936.71531642315;56941.43058569361;57147.71426142926;57170.42829571734;58045;58227;58246.12630810541;58252.126218106765;16271.674566508604;16621.50135495937;16788.160591970354;16800.83199168017;16818.24317905697;11965.102617303563;12194.329311887932;13744.450221991008;13955.232462214675;13988.230647314496;21557.029933653022;22520.986555605035;22561.323160304888;22624.321270361914;22635.773642263695;7752.689892404241;8238.970128733908;8423.70517031901;8986.68546600866;9204.907950920538;851.6125163050797;879.612970293069;894.9552522373855;895.9552022398854;924.611663101499;25635.359116021875;29778.40443191124;30525;30626;31356.529652055237;866.7703058689452;983.2478154212033;1047.350642601592;1089.428050273597;1097.4732128578169;13972.72054558903;14507;14653.706925861636;14733.705325893423;14857.628559286104;580574;659323;680002;683329;684362;50223.49776502188;50419.495805042214;50553.747231264344;50620;50658;1460.1531111955042;1464.8608382203886;1465.7801329800402;1496.124767011289;1501.0768377447791;26948.32629184286;27741.722582773913;28234.85882570574;28293.43413131767;28297.009604663737;46989.06021879475;47126.528734712214;47496.52503475059;47499;47626;607655;610118;611402;611407;614673;25925.740742593087;27038.864805675847;27617.585736213572;27650.032248871194;28604.42791144207;43511.56488435075;44181.33727994147;44662.77668611636;45344;45465.54534454613;32060.877869274464;32815.67184328126;35814.820925896245;36141.45787813237;37293.06767330838;33751.32497350089;34911.476327854594;35591.82204088963;36544.26911461809;36741.44887826631;35733.28533429265;35945;36526.817365913;36733.44899826451;36761.632383675824;24697.394669346435;31679.52480712745;32361.838190808896;32383.35233295368;32619.510707338934;22813.885930570243;24367.756322436548;24483.63274550919;24483.777280720602;24550.508989819748 05:06:41 INFO - PROCESS | 2295 | |2;jquery.html;322.5548742735026;355.202570229834;366.5637890909817;413.1282992885014;419.05558209906025;16689.24898379573;20654.070566824492;20818;21269.893650531743;21490.677639835416;21283;21733.45666358342;21742.782572174274;22125.00437480313;22128.33614991549;5869.09029100488;5883;5935.28776546815;6037.969810150943;6041.335453100155;4338.652907767387;4542.568455996675;5941.316748573926;6052.697365131748;6439.130717353168;6633.635150066744;6709.664516774166;6714.697838597275;6732.394084532391;6734.764283250087;6510.5442619016685;6667.966660166692;6705.061291419205;6713.395794378529;6724.293949135344;5827.096799995965;5834.883302333929;5875.794347197828;5922.7927022554;5927.525797936155;26147.869260653766;26409;26531.73468265312;26644;26718.19845404641;2813.957790633142;2821.3792965547545;2825.759810416117;2839.5312740202244;2855.514562524376;10596.311239769468;15075.396984120622;15177.620559485991;15584.376624934988;16380;1880.0149049807478;1880.1163453176985;1894.1855002348984;1894.8010458901892;1906.8093190680959;115009;133850.3307483466;136977.78133312133;146720;152107;10411.635592754217;12037.759244815055;12115.757684846254;12312.50749970009;12461;15657.982231154938;16305.510834674978;16330.346786128423;16335.346586136659;16375.754363684555;8990.056044115405;9156;9168.44989300644;9212;9245.121713437213;8116.188381161836;8284.212999765134;9036.8644470333;9156.404833685789;9241.630334786667;3789.734718569729;4559.52125026874;4895.559399654048;4960.355153830051;4967.801287948441;1718.1323431666929;1820.3446759166688;1831.7985021647607;2075.616011037933;2136.0455183506915;991.5240684471494;1004.3421558878946;1005.3615953869346;1015.2690063154517;1062.490004797686;19778.70331945022;21085.789142108384;21297.467563310733;21300.828454434846;21348.89325553362;1045.3362115056993;1051.3061379489652;1090.1878100814786;1091.8362245663213;1092.186321190718;10616.787664246673;11175.38535380562;11175.83236251457;11187.384693841754;11188.049015833583;732935;753776;756535;767072;769307;120732.18901716481;131047;134218;156371.65442518372;165818;1026.1380440430075;1044.2220545693356;1063.4310643805682;1065.477915821242;1067.514281002142;18081.728774068135;18475.260989560265;18764.530886727942;18919.43241702751;19116.426507204807;55681.72159139178;56101.15848262361;56901.71549142228;57658.423415766985;58071.12893306688;75447.86828197692;81179;83492;84865.57567212124;85302;6591.242007169237;6817.454603631599;7709.036370453693;7736.651850666621;7878.409119315959;11113.166512511432;12951.158174718614;12976.675583110498;13289.734205316034;13350.399232034775;11196.216264861381;11334.489947952527;11376.317420954769;11593.6521904343;11615.419229038345;10883.346999180074;10945.53725411776;11255.943720281346;11296.830547541958;11669.12481563903;11581.942090289494;11628.883711163118;11961.342126182795;11968.46141923598;11996.58011969577;7654.540727556365;7657.387409007378;7770.145284018867;7792.84414311722;8003.879941800761;8540.871886921577;9649.276304277348;10687.51906164209;11753.059755219732;11780.112562854409 05:06:41 INFO - PROCESS | 2295 | |3;mootools.html;1439.1293267573124;1485.4875068101505;1486.5506131521768;1488.665050363668;1493.3578561218678;7727.995360603128;7977.441579089466;8207.343412526998;8630.697925572606;8639.39524233304;8113.63488643011;8459.32325413967;8470.28002619778;8552.187542183497;8564.400491965549;2364.574376612209;2413.78275955164;2418.419579300964;2429.259075981825;2431.3799981004818;2398.268528098929;2424.9151279705216;2425.333033415816;2425.551273014489;2431.586630272849;2018.404570651654;2384.940376490584;2415.2874901903892;2415.7825795678386;2419.1412048722714;2349.8472599281163;2352.517733864562;2367.928962131122;2384;2389.5101504191684;2320.8259380546356;2342.156823543523;2406.097713357491;2411.360989337827;2416.915407960713;7326.560406375635;8476.067632560413;8531.274841638406;8543.658253669846;8554.101819309008;5182.870428239286;5319.441458646825;5341.679499230058;5343.946560534384;5360.91958620621;3028.242939265184;3202.5748541898865;3403.285310084885;3621.764585301947;3639.763415377992;1158.9826152607718;1270.7102291174465;1277.948882044717;1287.1955028107434;1329.7539955108336;10640.733981650521;10877.292975956538;10879.891201087967;10886.891131088747;10905.454727263605;3924.293627147084;4767.427908650984;4830.6135509159185;4849.345338379344;4856.271559266068;9694.854577181348;9698.951505242429;9705.902940970642;9740.512974351253;9762.17021553176;5384.6499977501335;5409.43200963901;5419.485148910847;5426.077566813651;5432.538234250054;4459.687821852439;4488.551144885485;4693.296005599119;4754.572088512051;4933.136701077297;3640.1445660269815;3898;3980.9402858957137;4068.00333918186;4116.670666346685;1299.1036185032297;1319.228251472899;1324.3444494974856;1338.9933050334687;1340.0753480098701;788.5584072919183;800.5196881870947;813.8372325534798;820.3683163963834;825.628467189767;5776.653400795965;5777.1045487948595;5800.332961709373;6083.209182806207;6134.662593557308;996.4917891875051;1007.1338648762058;1007.6120693533043;1021.6832781837654;1022.8005538919988;3744.120131769032;3774.7735135891935;3778.319902417591;3907.199024200032;3935.960640393617;10046.949765251273;10346.482675866328;10352.482375881176;10359.741006474898;10376.948115259376;10155.949220253851;10180.898191017995;10245.74385640365;10270.897291026995;10294.948525257325;7502.887456688154;7899.605019748875;7989.3209077229085;8133.064697559738;8145.104038555754;5738.885222295614;5878.559108066826;5889.911651325319;5985.491233245223;5988.131720900532;8717.476951382938;8898.243649289883;9119.088091190828;9375.671851485165;9450.338476306593;9226.40028398152;9362.95318523403;9406.717798466058;9443.150116489549;9591.952040239754;6768.593884366954;6966.303369662993;7287.380572651384;7435.182129965808;7619.733309334147;7908.604569771604;8151.225633564802;8160.30637395828;8162.591870406574;8193.303569196685;7190.06529151207;7216.314450127229;7260;7341.559506429631;7400.48196626231;7037.190723066913;7075.7523486679775;7076.363127318566;7077.787666370017;7126.5011449198055;6443.838904027437;6501.837454063686;6600.669966501751;6602.40578347951;6604.735810567524;5026.899462010812;5036.459894116589;5100.2349647553165;5126.487351264844;5200.739963001911;5104.132297509506;5137.357830271216;5193.818216362409;5212.9739351303;5267.104592219255 05:06:41 INFO - PROCESS | 2295 | |4;prototype.html;365.92864391443663;370.8590735520502;376.676058589613;378.96210378962127;384.91646016463653;5292.338457692788;5448.346198456181;5455.318085239345;5463.96184724902;5596.888062238752;5199.956042638644;5248.895022099565;5253.44838791927;5360.812371566996;5396.514313711765;3580.445031020196;3746.550413950316;4226.302660061094;4308.815075854134;4311.223979683656;3409.3010932758725;3751.6869095816382;4164.458620379346;4257.169851878885;4344.261475549164;3667.303212389651;4225.176090662322;4254.638355739766;4274.252005898971;4278.679099067567;3384.5092461593176;3456.4815277708294;3533.611302756695;3716.2753263113705;4147;3800.3397621664667;3815.653373175505;3819.2743378757946;3831.1954489556947;3836.462895194675;4040.333344998072;4168.437260969791;5136.43499215086;5241.266222728821;5299.4700529946695;4899.730514821684;4930.211166213388;4947.233178857283;4950.653454258203;4956.2565615157655;4706.5293470653005;4760.952390476085;5685.545156387479;5716.71416429181;5758.452946970031;3381.6449272826244;3645.9270814583824;4170.6871984600975;4217.261979153666;4229.619334259932;5547.389787123413;5965.078809981739;6030.306514750816;6062.454379105901;6086.391360863922;3730.8993846815256;4309.439772829513;4321.805518751601;4324.675649326313;4336.566343365701;3409.147713071732;3609.9639003609627;4136.855210067633;4351.19502891966;4456.598906098467;4632.4672662644225;4853.563179313879;4963.354763880672;4966.801327946841;4980.427250866124;5746.281714785652;5750.763585829067;5773.199998000966;5809.331926828384;5837.102941543931;1244.512807195411;1264.647755175373;1323.6558494791423;1421.4243231491262;1429.463951018368;952.642758965388;1014.6499457687086;1018.5467466977124;1023.8259495885717;1026.9383836969657;4653.906921861544;5051.8380772421815;5204.21936709489;5210;5237.371515418175;4620.468646105741;5330.760115794798;5647.350554686263;5650.576206784508;5732.828015159552;4205.432266644025;4322.524522302544;4645.187092258841;5186.974065129651;5212.713300768418;2533.8099642526886;2651.9469610608066;2939.7354238118246;2965.9110226693233;2981.2248815307744;5044.722540260321;5213.817516386908;5507.642003269774;5875.030619947661;5903.232579764689;5235.952809438128;5293.814716484904;5469.371022332403;5728.971355143198;5758.971205143947;5296.894062118813;5352.100004498425;5354.732263386815;5933.970330148408;5998.820035398945;4954.628402869727;5447.972760136174;5514.0626093565;5594.272744543184;5750.453706897838;5033.219850923113;5238.869028274324;5481.945180548143;5503.284573005644;5515.724213789214;5059.519345662156;5605.383407825218;5620.4660557247;5843.415658434123;5882.558808089496;3298.9608273394765;3470.8611655534505;3952.288588054178;4112.712110152261;4143.7306575072525;5170.97414512925;5202.8959420812125;5482.890342193214;5514.36584792754;5600.635958662674;4678.438587369537;5256.710880901587;5475.5072043516275;5584.246126772834;5712.6286791358425;5484.643498172606;5627.184058311604;5700.600957933071;5703.543716502587;5775.422457754359;5261.631685781958;5303.628745987744;5332.386775520709;5563.860903477445;5593.496585307342;4789.377380940595;5023.6985780853265;5247.265382846328;5430.511253987002;5683.687397193194;5099;5571.832845014656;5575.303087114111;5593.881223755184;5618.7190640467 05:06:41 INFO - PROCESS | 2295 | |5;yui.html;677.9661016949151;696.2271878215183;781.582779423273;860.264473874837;866.9003064647563;15159.848401515996;15372.00081994671;16245.756313655289;16645.50063498094;16682.249298781553;14950.551483455487;15207.23963801811;15213.163276019815;15252.008619439757;15356.61608459789;874.5889431966981;891.8082612238368;899.8695189197563;901.5852707754441;902.2466240689032;724.0587236592435;740.5408646639091;876.4390789894472;876.6975393489267;880.2078129683305;912.3157631776168;928.3455164109321;936.091990768953;947.4552132523792;950.4962369943906;906.7325139083986;913.9588718507683;919.6459363145203;922.547951503765;928.3455164109287;677.9796606101825;680.571240118727;687.0278555843475;687.8074139241021;689.689639662149;15532.767008494882;16031.19844007795;16258;16283.674326513403;16370.263338149813;14078.507252246225;15300.540983770505;15487;15499.690006199926;15550.76673849893;10380.740481487948;11023;11844.644660660195;11984.940075299655;11986.220895641842;3287.6547962463837;3692.7444668812464;3876.3991581304945;3934.921301573981;3976.1451287973223;77820.22179778187;78175.60912195402;97883;100631;101495;11573.479193436315;11628.127890408085;11848.940755296167;11864;12275.508979640714;24261.393465163514;24281.39296517601;24898.50202995966;24910.750892491207;24935;7176.390006849372;8247.422680412314;8256.511032063807;8309.459885107448;8325.791855203548;4476.350929115325;4477.082198149436;4525.434320709911;4528.515355567331;4581.518940511265;3769.509963704701;3855.325318069327;3855.575886652466;3866.8839934802;3959.0696186396176;683.0676127086491;697.2992142896356;702.1293595940996;706.5125063706026;717.4368121025075;632.9620222786648;690.8998195261659;708.9574625522486;724.5507785173227;744.9553026818409;10524.421156836448;10560.841587376195;10596.894031059745;10929.125669946385;10929.945350273358;675.9357861003197;681.4276008153175;696.606417374182;717.7487879242224;728.9963550182321;4548.727076375428;5004.674696144739;5131.409887862869;5143.5113664201845;5147.871303217449;108800;109771;110658;110719;114611.42694286643;75902;94891;98344;100992.94304741167;101720.49139754401;67981.32018679849;71718;73190;77447;79919.20080799234;11233.943830281123;11288.153388495732;11312.60405885823;11471.827922581;11601.709957251136;52250.477495224564;52829.20756188737;53385;54128.188077179664;55213.1718024238;71146.64426677834;71397;71560;72912;75081.24918750742;13408;13539.729205416033;13565.728685426433;13606.183628982293;13995.790063149265;14723;14869.330880110205;14889.776653349993;14955.700885982002;15379.00036497624;13614.115082519604;13922.651933701738;14118.21009810971;14135.081219720685;14161.716765664423;12474.812877807022;12653.493860245488;12930.094893357375;13215.933920330337;13240;13333.133346332457;13558.728825423634;13800.996566493608;13816.861831381557;14351.497697580951;9544.379615324982;10539.473026348498;11087.944560277147;11112.643587571203;11308.943455283;8238.546879921421;9321.257961783547;10292.897071029493;10762.677119686421;11090.500927458444 05:06:41 INFO - PROCESS | 2295 | __end_tp_report 05:06:41 INFO - PROCESS | 2295 | __start_cc_report 05:06:41 INFO - PROCESS | 2295 | _x_x_mozilla_cycle_collect,368 05:06:41 INFO - PROCESS | 2295 | __end_cc_report 05:06:41 INFO - PROCESS | 2295 | __startTimestamp1490184401583__endTimestamp 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | ------- Summary: start ------- 05:06:41 INFO - PROCESS | 2295 | Number of tests: 6 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#0] dojo.html Cycles:165 Average:5873.22 Median:6163.75 stddev:648.59 (10.5%) stddev-sans-first:615.79 05:06:41 INFO - PROCESS | 2295 | Values: 3201.6 3521.4 3547.8 3562.6 3564.7 5918.3 5927.6 5963.9 5979.3 6062.5 5155.5 5281.8 5712.7 6029.8 6062.6 5267.1 5346.4 5370.2 5397.5 5402.4 4582.1 4731.7 5172.9 5433.3 5448.6 5286.7 5302.6 5342.9 5413.9 5414.6 5361.7 5382.0 5430.5 5489.6 5500.8 5216.7 5361.5 5376.6 5391.8 5421.3 5755.6 5902.1 5979.0 6071.9 6093.8 6173.5 6194.6 6239.1 6243.8 6285.2 6292.0 6301.7 6306.9 6311.1 6348.4 6055.6 6074.4 6074.5 6090.7 6092.7 6195.1 6210.6 6285.4 6288.4 6316.7 6258.5 6305.6 6311.3 6311.6 6342.3 6285.5 6289.2 6301.5 6316.4 6321.7 6206.0 6215.2 6215.3 6246.9 6278.6 6219.4 6245.6 6262.1 6279.9 6299.4 5107.8 5114.3 5120.5 5126.7 5143.6 4345.5 4392.4 4527.3 4590.8 4598.1 6131.1 6160.0 6183.0 6209.8 6215.7 6322.6 6323.7 6344.1 6369.1 6490.4 6028.1 6088.1 6119.4 6145.7 6195.2 5637.4 5645.5 5664.0 5666.1 5669.0 6590.5 6606.4 6616.8 6620.1 6625.7 5185.2 6174.5 6197.6 6223.9 6260.8 5470.2 5616.6 6573.6 6582.0 6640.6 6251.2 6299.4 6305.3 6305.3 6313.5 6145.4 6160.3 6199.5 6252.3 6298.2 6119.9 6163.8 6172.2 6174.9 6195.0 6133.5 6179.0 6182.4 6184.2 6215.8 6000.0 6047.5 6091.8 6156.4 6254.9 6222.2 6254.1 6263.3 6263.9 6300.7 6241.5 6245.7 6253.4 6258.0 6286.0 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#1] ext.html Cycles:180 Average:71043.68 Median:35774.05 stddev:158075.60 (441.9%) stddev-sans-first:158430.22 05:06:41 INFO - PROCESS | 2295 | Values: 467.8 488.3 523.2 560.2 561.3 38356.8 41857.4 41939.2 42012.9 42172.0 42796.8 43208.8 43753.3 43824.8 43865.9 9490.7 9753.9 9768.0 11378.1 11788.8 11495.0 11508.8 11609.2 11646.0 11749.4 11681.6 11734.0 11773.4 11789.1 11803.6 11633.9 11668.4 11689.2 11708.5 11732.8 11455.2 11576.5 11642.4 11647.8 11687.1 37917.1 38049.2 38300.6 38337.4 38481.6 4065.2 4089.9 4095.0 4154.4 4155.9 27682.3 28347.4 28365.3 28370.4 30958.9 3164.4 3217.3 3278.4 3278.6 3476.2 486911.0 488216.0 490570.0 491384.0 492682.0 56172.2 56774.7 56936.7 56941.4 57147.7 57170.4 58045.0 58227.0 58246.1 58252.1 16271.7 16621.5 16788.2 16800.8 16818.2 11965.1 12194.3 13744.5 13955.2 13988.2 21557.0 22521.0 22561.3 22624.3 22635.8 7752.7 8239.0 8423.7 8986.7 9204.9 851.6 879.6 895.0 896.0 924.6 25635.4 29778.4 30525.0 30626.0 31356.5 866.8 983.2 1047.4 1089.4 1097.5 13972.7 14507.0 14653.7 14733.7 14857.6 580574.0 659323.0 680002.0 683329.0 684362.0 50223.5 50419.5 50553.7 50620.0 50658.0 1460.2 1464.9 1465.8 1496.1 1501.1 26948.3 27741.7 28234.9 28293.4 28297.0 46989.1 47126.5 47496.5 47499.0 47626.0 607655.0 610118.0 611402.0 611407.0 614673.0 25925.7 27038.9 27617.6 27650.0 28604.4 43511.6 44181.3 44662.8 45344.0 45465.5 32060.9 32815.7 35814.8 36141.5 37293.1 33751.3 34911.5 35591.8 36544.3 36741.4 35733.3 35945.0 36526.8 36733.4 36761.6 24697.4 31679.5 32361.8 32383.4 32619.5 22813.9 24367.8 24483.6 24483.8 24550.5 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#2] jquery.html Cycles:180 Average:40872.90 Median:11742.47 stddev:125625.05 (1069.8%) stddev-sans-first:125940.56 05:06:41 INFO - PROCESS | 2295 | Values: 322.6 355.2 366.6 413.1 419.1 16689.2 20654.1 20818.0 21269.9 21490.7 21283.0 21733.5 21742.8 22125.0 22128.3 5869.1 5883.0 5935.3 6038.0 6041.3 4338.7 4542.6 5941.3 6052.7 6439.1 6633.6 6709.7 6714.7 6732.4 6734.8 6510.5 6668.0 6705.1 6713.4 6724.3 5827.1 5834.9 5875.8 5922.8 5927.5 26147.9 26409.0 26531.7 26644.0 26718.2 2814.0 2821.4 2825.8 2839.5 2855.5 10596.3 15075.4 15177.6 15584.4 16380.0 1880.0 1880.1 1894.2 1894.8 1906.8 115009.0 133850.3 136977.8 146720.0 152107.0 10411.6 12037.8 12115.8 12312.5 12461.0 15658.0 16305.5 16330.3 16335.3 16375.8 8990.1 9156.0 9168.4 9212.0 9245.1 8116.2 8284.2 9036.9 9156.4 9241.6 3789.7 4559.5 4895.6 4960.4 4967.8 1718.1 1820.3 1831.8 2075.6 2136.0 991.5 1004.3 1005.4 1015.3 1062.5 19778.7 21085.8 21297.5 21300.8 21348.9 1045.3 1051.3 1090.2 1091.8 1092.2 10616.8 11175.4 11175.8 11187.4 11188.0 732935.0 753776.0 756535.0 767072.0 769307.0 120732.2 131047.0 134218.0 156371.7 165818.0 1026.1 1044.2 1063.4 1065.5 1067.5 18081.7 18475.3 18764.5 18919.4 19116.4 55681.7 56101.2 56901.7 57658.4 58071.1 75447.9 81179.0 83492.0 84865.6 85302.0 6591.2 6817.5 7709.0 7736.7 7878.4 11113.2 12951.2 12976.7 13289.7 13350.4 11196.2 11334.5 11376.3 11593.7 11615.4 10883.3 10945.5 11255.9 11296.8 11669.1 11581.9 11628.9 11961.3 11968.5 11996.6 7654.5 7657.4 7770.1 7792.8 8003.9 8540.9 9649.3 10687.5 11753.1 11780.1 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#3] mootools.html Cycles:180 Average:5550.27 Median:5188.34 stddev:2995.88 (57.7%) stddev-sans-first:2988.35 05:06:41 INFO - PROCESS | 2295 | Values: 1439.1 1485.5 1486.6 1488.7 1493.4 7728.0 7977.4 8207.3 8630.7 8639.4 8113.6 8459.3 8470.3 8552.2 8564.4 2364.6 2413.8 2418.4 2429.3 2431.4 2398.3 2424.9 2425.3 2425.6 2431.6 2018.4 2384.9 2415.3 2415.8 2419.1 2349.8 2352.5 2367.9 2384.0 2389.5 2320.8 2342.2 2406.1 2411.4 2416.9 7326.6 8476.1 8531.3 8543.7 8554.1 5182.9 5319.4 5341.7 5343.9 5360.9 3028.2 3202.6 3403.3 3621.8 3639.8 1159.0 1270.7 1277.9 1287.2 1329.8 10640.7 10877.3 10879.9 10886.9 10905.5 3924.3 4767.4 4830.6 4849.3 4856.3 9694.9 9699.0 9705.9 9740.5 9762.2 5384.6 5409.4 5419.5 5426.1 5432.5 4459.7 4488.6 4693.3 4754.6 4933.1 3640.1 3898.0 3980.9 4068.0 4116.7 1299.1 1319.2 1324.3 1339.0 1340.1 788.6 800.5 813.8 820.4 825.6 5776.7 5777.1 5800.3 6083.2 6134.7 996.5 1007.1 1007.6 1021.7 1022.8 3744.1 3774.8 3778.3 3907.2 3936.0 10046.9 10346.5 10352.5 10359.7 10376.9 10155.9 10180.9 10245.7 10270.9 10294.9 7502.9 7899.6 7989.3 8133.1 8145.1 5738.9 5878.6 5889.9 5985.5 5988.1 8717.5 8898.2 9119.1 9375.7 9450.3 9226.4 9363.0 9406.7 9443.2 9592.0 6768.6 6966.3 7287.4 7435.2 7619.7 7908.6 8151.2 8160.3 8162.6 8193.3 7190.1 7216.3 7260.0 7341.6 7400.5 7037.2 7075.8 7076.4 7077.8 7126.5 6443.8 6501.8 6600.7 6602.4 6604.7 5026.9 5036.5 5100.2 5126.5 5200.7 5104.1 5137.4 5193.8 5213.0 5267.1 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#4] prototype.html Cycles:180 Average:4560.70 Median:5079.26 stddev:1360.98 (26.8%) stddev-sans-first:1327.88 05:06:41 INFO - PROCESS | 2295 | Values: 365.9 370.9 376.7 379.0 384.9 5292.3 5448.3 5455.3 5464.0 5596.9 5200.0 5248.9 5253.4 5360.8 5396.5 3580.4 3746.6 4226.3 4308.8 4311.2 3409.3 3751.7 4164.5 4257.2 4344.3 3667.3 4225.2 4254.6 4274.3 4278.7 3384.5 3456.5 3533.6 3716.3 4147.0 3800.3 3815.7 3819.3 3831.2 3836.5 4040.3 4168.4 5136.4 5241.3 5299.5 4899.7 4930.2 4947.2 4950.7 4956.3 4706.5 4761.0 5685.5 5716.7 5758.5 3381.6 3645.9 4170.7 4217.3 4229.6 5547.4 5965.1 6030.3 6062.5 6086.4 3730.9 4309.4 4321.8 4324.7 4336.6 3409.1 3610.0 4136.9 4351.2 4456.6 4632.5 4853.6 4963.4 4966.8 4980.4 5746.3 5750.8 5773.2 5809.3 5837.1 1244.5 1264.6 1323.7 1421.4 1429.5 952.6 1014.6 1018.5 1023.8 1026.9 4653.9 5051.8 5204.2 5210.0 5237.4 4620.5 5330.8 5647.4 5650.6 5732.8 4205.4 4322.5 4645.2 5187.0 5212.7 2533.8 2651.9 2939.7 2965.9 2981.2 5044.7 5213.8 5507.6 5875.0 5903.2 5236.0 5293.8 5469.4 5729.0 5759.0 5296.9 5352.1 5354.7 5934.0 5998.8 4954.6 5448.0 5514.1 5594.3 5750.5 5033.2 5238.9 5481.9 5503.3 5515.7 5059.5 5605.4 5620.5 5843.4 5882.6 3299.0 3470.9 3952.3 4112.7 4143.7 5171.0 5202.9 5482.9 5514.4 5600.6 4678.4 5256.7 5475.5 5584.2 5712.6 5484.6 5627.2 5700.6 5703.5 5775.4 5261.6 5303.6 5332.4 5563.9 5593.5 4789.4 5023.7 5247.3 5430.5 5683.7 5099.0 5571.8 5575.3 5593.9 5618.7 05:06:41 INFO - PROCESS | 2295 | 05:06:41 INFO - PROCESS | 2295 | [#5] yui.html Cycles:180 Average:20868.67 Median:3861.23 stddev:29474.70 (763.4%) stddev-sans-first:29518.40 05:06:41 INFO - PROCESS | 2295 | Values: 678.0 696.2 781.6 860.3 866.9 15159.8 15372.0 16245.8 16645.5 16682.2 14950.6 15207.2 15213.2 15252.0 15356.6 874.6 891.8 899.9 901.6 902.2 724.1 740.5 876.4 876.7 880.2 912.3 928.3 936.1 947.5 950.5 906.7 914.0 919.6 922.5 928.3 678.0 680.6 687.0 687.8 689.7 15532.8 16031.2 16258.0 16283.7 16370.3 14078.5 15300.5 15487.0 15499.7 15550.8 10380.7 11023.0 11844.6 11984.9 11986.2 3287.7 3692.7 3876.4 3934.9 3976.1 77820.2 78175.6 97883.0 100631.0 101495.0 11573.5 11628.1 11848.9 11864.0 12275.5 24261.4 24281.4 24898.5 24910.8 24935.0 7176.4 8247.4 8256.5 8309.5 8325.8 4476.4 4477.1 4525.4 4528.5 4581.5 3769.5 3855.3 3855.6 3866.9 3959.1 683.1 697.3 702.1 706.5 717.4 633.0 690.9 709.0 724.6 745.0 10524.4 10560.8 10596.9 10929.1 10929.9 675.9 681.4 696.6 717.7 729.0 4548.7 5004.7 5131.4 5143.5 5147.9 108800.0 109771.0 110658.0 110719.0 114611.4 75902.0 94891.0 98344.0 100992.9 101720.5 67981.3 71718.0 73190.0 77447.0 79919.2 11233.9 11288.2 11312.6 11471.8 11601.7 52250.5 52829.2 53385.0 54128.2 55213.2 71146.6 71397.0 71560.0 72912.0 75081.2 13408.0 13539.7 13565.7 13606.2 13995.8 14723.0 14869.3 14889.8 14955.7 15379.0 13614.1 13922.7 14118.2 14135.1 14161.7 12474.8 12653.5 12930.1 13215.9 13240.0 13333.1 13558.7 13801.0 13816.9 14351.5 9544.4 10539.5 11087.9 11112.6 11308.9 8238.5 9321.3 10292.9 10762.7 11090.5 05:06:41 INFO - PROCESS | 2295 | -------- Summary: end -------- 05:06:41 INFO - PROCESS | 2295 | 05:06:42 INFO - TEST-INFO | 2295: exit 0 05:06:42 INFO - TEST-OK | dromaeo_css | took 1113347ms 05:06:42 INFO - TEST-START | kraken 05:06:42 INFO - operating with platform_type : mac_ 05:06:42 INFO - Initialising browser for kraken test... 05:06:42 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpxZCRFC/profile http://localhost:56950/getInfo.html) 05:06:44 INFO - PROCESS | 2300 | 2017-03-22 05:06:44.874 plugin-container[2302:36122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 05:06:44 INFO - PROCESS | 2300 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:44 INFO - PROCESS | 2300 | 2017-03-22 05:06:44.875 plugin-container[2302:36122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 05:06:44 INFO - PROCESS | 2300 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:44 INFO - PROCESS | 2300 | 2017-03-22 05:06:44.875 plugin-container[2302:36122] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:44 INFO - PROCESS | 2300 | __metrics Screen width/height:1600/1200 05:06:44 INFO - PROCESS | 2300 | colorDepth:24 05:06:44 INFO - PROCESS | 2300 | Browser inner width/height: 1024/689 05:06:44 INFO - PROCESS | 2300 | __metrics 05:06:45 ERROR - PROCESS | 2300 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:06:45 INFO - TEST-INFO | 2300: exit 0 05:06:45 INFO - Browser initialized. 05:06:45 INFO - Running cycle 1/1 for kraken test... 05:06:45 INFO - TEST-INFO | started process 2303 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpxZCRFC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:48 INFO - PROCESS | 2303 | 2017-03-22 05:06:48.337 plugin-container[2304:36245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 05:06:48 INFO - PROCESS | 2303 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:48 INFO - PROCESS | 2303 | 2017-03-22 05:06:48.337 plugin-container[2304:36245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 05:06:48 INFO - PROCESS | 2303 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:48 INFO - PROCESS | 2303 | 2017-03-22 05:06:48.337 plugin-container[2304:36245] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:58 INFO - PROCESS | 2303 | RSS: Main: 173084672 05:06:58 INFO - PROCESS | 2303 | 05:07:01 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56950/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:02 INFO - PROCESS | 2303 | RSS: Main: 185847808 05:07:02 INFO - PROCESS | 2303 | 05:07:04 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56950/tests/kraken/driver.html?testName=audio-dft) 05:07:05 INFO - PROCESS | 2303 | RSS: Main: 194330624 05:07:05 INFO - PROCESS | 2303 | 05:07:08 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56950/tests/kraken/driver.html?testName=audio-fft) 05:07:09 INFO - PROCESS | 2303 | RSS: Main: 197599232 05:07:09 INFO - PROCESS | 2303 | 05:07:11 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56950/tests/kraken/driver.html?testName=audio-oscillator) 05:07:11 INFO - PROCESS | 2303 | RSS: Main: 197840896 05:07:11 INFO - PROCESS | 2303 | 05:07:13 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56950/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:14 INFO - PROCESS | 2303 | RSS: Main: 198406144 05:07:14 INFO - PROCESS | 2303 | 05:07:17 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56950/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:17 INFO - PROCESS | 2303 | RSS: Main: 203911168 05:07:17 INFO - PROCESS | 2303 | 05:07:20 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56950/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:21 INFO - PROCESS | 2303 | RSS: Main: 203800576 05:07:21 INFO - PROCESS | 2303 | 05:07:24 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56950/tests/kraken/driver.html?testName=json-parse-financial) 05:07:25 INFO - PROCESS | 2303 | RSS: Main: 203137024 05:07:25 INFO - PROCESS | 2303 | 05:07:26 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56950/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:27 INFO - PROCESS | 2303 | RSS: Main: 201887744 05:07:27 INFO - PROCESS | 2303 | 05:07:29 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:29 INFO - PROCESS | 2303 | RSS: Main: 201793536 05:07:29 INFO - PROCESS | 2303 | 05:07:32 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:32 INFO - PROCESS | 2303 | RSS: Main: 201904128 05:07:32 INFO - PROCESS | 2303 | 05:07:35 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:36 INFO - PROCESS | 2303 | RSS: Main: 202407936 05:07:36 INFO - PROCESS | 2303 | 05:07:39 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:39 INFO - PROCESS | 2303 | RSS: Main: 210563072 05:07:39 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | Cycle 1(1): loaded http://localhost:56950/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56950/tests/kraken/driver.html?testName=ai-astar) 05:07:41 INFO - PROCESS | 2303 | RSS: Main: 212025344 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | __start_tp_report 05:07:41 INFO - PROCESS | 2303 | _x_x_mozilla_page_load 05:07:41 INFO - PROCESS | 2303 | _x_x_mozilla_page_load_details 05:07:41 INFO - PROCESS | 2303 | |i|pagename|runs| 05:07:41 INFO - PROCESS | 2303 | |0;ai-astar;106;118;119;117;117;122;96;97;132;115 05:07:41 INFO - PROCESS | 2303 | |1;audio-beat-detection;143;111;109;148;114;114;111;112;132;138 05:07:41 INFO - PROCESS | 2303 | |2;audio-dft;194;197;193;195;223;259;198;199;202;194 05:07:41 INFO - PROCESS | 2303 | |3;audio-fft;78;60;59;91;52;65;74;87;96;81 05:07:41 INFO - PROCESS | 2303 | |4;audio-oscillator;86;71;84;76;69;75;81;98;72;83 05:07:41 INFO - PROCESS | 2303 | |5;imaging-gaussian-blur;90;89;91;88;90;100;99;88;89;89 05:07:41 INFO - PROCESS | 2303 | |6;imaging-darkroom;94;95;95;94;97;96;95;124;148;96 05:07:41 INFO - PROCESS | 2303 | |7;imaging-desaturate;110;94;91;100;147;125;97;124;90;143 05:07:41 INFO - PROCESS | 2303 | |8;json-parse-financial;58;58;58;62;62;63;63;63;61;64 05:07:41 INFO - PROCESS | 2303 | |9;json-stringify-tinderbox;69;78;83;75;75;70;71;70;107;104 05:07:41 INFO - PROCESS | 2303 | |10;stanford-crypto-aes;87;86;84;80;86;81;83;84;88;86 05:07:41 INFO - PROCESS | 2303 | |11;stanford-crypto-ccm;135;156;171;121;150;151;152;140;179;156 05:07:41 INFO - PROCESS | 2303 | |12;stanford-crypto-pbkdf2;170;170;165;167;168;165;166;166;167;167 05:07:41 INFO - PROCESS | 2303 | |13;stanford-crypto-sha256-iterative;68;67;65;64;69;65;64;66;59;73 05:07:41 INFO - PROCESS | 2303 | __end_tp_report 05:07:41 INFO - PROCESS | 2303 | __start_cc_report 05:07:41 INFO - PROCESS | 2303 | _x_x_mozilla_cycle_collect,939 05:07:41 INFO - PROCESS | 2303 | __end_cc_report 05:07:41 INFO - PROCESS | 2303 | __startTimestamp1490184461761__endTimestamp 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | ------- Summary: start ------- 05:07:41 INFO - PROCESS | 2303 | Number of tests: 14 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#0] ai-astar Cycles:10 Average:113.90 Median:120.50 stddev:11.18 (9.3%) stddev-sans-first:11.49 05:07:41 INFO - PROCESS | 2303 | Values: 106.0 118.0 119.0 117.0 117.0 122.0 96.0 97.0 132.0 115.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:123.00 stddev:15.27 (12.4%) stddev-sans-first:14.41 05:07:41 INFO - PROCESS | 2303 | Values: 143.0 111.0 109.0 148.0 114.0 114.0 111.0 112.0 132.0 138.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#2] audio-dft Cycles:10 Average:205.40 Median:198.50 stddev:20.77 (10.5%) stddev-sans-first:21.62 05:07:41 INFO - PROCESS | 2303 | Values: 194.0 197.0 193.0 195.0 223.0 259.0 198.0 199.0 202.0 194.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#3] audio-fft Cycles:10 Average:74.30 Median:79.50 stddev:14.88 (18.7%) stddev-sans-first:15.72 05:07:41 INFO - PROCESS | 2303 | Values: 78.0 60.0 59.0 91.0 52.0 65.0 74.0 87.0 96.0 81.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:82.00 stddev:8.76 (10.7%) stddev-sans-first:8.97 05:07:41 INFO - PROCESS | 2303 | Values: 86.0 71.0 84.0 76.0 69.0 75.0 81.0 98.0 72.0 83.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:89.50 stddev:4.42 (4.9%) stddev-sans-first:4.67 05:07:41 INFO - PROCESS | 2303 | Values: 90.0 89.0 91.0 88.0 90.0 100.0 99.0 88.0 89.0 89.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:95.00 stddev:18.11 (19.1%) stddev-sans-first:18.89 05:07:41 INFO - PROCESS | 2303 | Values: 94.0 95.0 95.0 94.0 97.0 96.0 95.0 124.0 148.0 96.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#7] imaging-desaturate Cycles:10 Average:112.10 Median:118.50 stddev:21.40 (18.1%) stddev-sans-first:22.68 05:07:41 INFO - PROCESS | 2303 | Values: 110.0 94.0 91.0 100.0 147.0 125.0 97.0 124.0 90.0 143.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:62.50 stddev:2.35 (3.8%) stddev-sans-first:2.19 05:07:41 INFO - PROCESS | 2303 | Values: 58.0 58.0 58.0 62.0 62.0 63.0 63.0 63.0 61.0 64.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:73.00 stddev:14.02 (19.2%) stddev-sans-first:14.28 05:07:41 INFO - PROCESS | 2303 | Values: 69.0 78.0 83.0 75.0 75.0 70.0 71.0 70.0 107.0 104.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:86.00 stddev:2.59 (3.0%) stddev-sans-first:2.59 05:07:41 INFO - PROCESS | 2303 | Values: 87.0 86.0 84.0 80.0 86.0 81.0 83.0 84.0 88.0 86.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:154.00 stddev:16.71 (10.9%) stddev-sans-first:16.68 05:07:41 INFO - PROCESS | 2303 | Values: 135.0 156.0 171.0 121.0 150.0 151.0 152.0 140.0 179.0 156.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:1.79 (1.1%) stddev-sans-first:1.56 05:07:41 INFO - PROCESS | 2303 | Values: 170.0 170.0 165.0 167.0 168.0 165.0 166.0 166.0 167.0 167.0 05:07:41 INFO - PROCESS | 2303 | 05:07:41 INFO - PROCESS | 2303 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:3.68 (5.5%) stddev-sans-first:3.83 05:07:41 INFO - PROCESS | 2303 | Values: 68.0 67.0 65.0 64.0 69.0 65.0 64.0 66.0 59.0 73.0 05:07:41 INFO - PROCESS | 2303 | -------- Summary: end -------- 05:07:41 INFO - PROCESS | 2303 | 05:07:42 INFO - TEST-INFO | 2303: exit 0 05:07:42 INFO - TEST-OK | kraken | took 60348ms 05:07:42 INFO - SUITE-END | took 1173s 05:07:42 INFO - Completed test suite (00:19:34) 05:07:42 INFO - javascript benchmark 05:07:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7476.8617986403615, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.567788348573, 3521.401361768501, 3547.7693949893273, 3562.5724913010426, 3564.6970007549357, 5918.348981612019, 5927.555433342501, 5963.850903727409, 5979.252593425822, 6062.484688801457, 5155.4586768389345, 5281.762320695569, 5712.743196496764, 6029.788957386492, 6062.605930614507, 5267.05193065249, 5346.385165705935, 5370.221317908901, 5397.487238712316, 5402.35171779387, 4582.1064892346, 4731.692439991407, 5172.922406163911, 5433.293671822657, 5448.591355648323, 5286.709230992294, 5302.6023048271345, 5342.893142137174, 5413.918791218135, 5414.593905457087, 5361.651492652965, 5382.004329199091, 5430.51125398716, 5489.615726899079, 5500.80747173851, 5216.66091704038, 5361.544268737162, 5376.623636345457, 5391.757370918318, 5421.2952316199035, 5755.597108202427, 5902.085176797603, 5978.970105149447, 6071.878562428771, 6093.786717464912, 6173.506119510428, 6194.566380353377, 6239.064140378934, 6243.8126856194385, 6285.2457705075685, 6292.0, 6301.747930082791, 6306.936930630727, 6311.147995020659, 6348.365163483615, 6055.606385584967, 6074.392560743934, 6074.54440916933, 6090.66501342426, 6092.695365231721, 6195.132681424604, 6210.596311239755, 6285.43431091204, 6288.402601752826, 6316.684165791692, 6258.468030217483, 6305.621662700252, 6311.305756366796, 6311.621302721759, 6342.302346741854, 6285.497160227263, 6289.182406287245, 6301.527385446018, 6316.399942005502, 6321.652309123042, 6206.038064100072, 6215.192025036807, 6215.285242197115, 6246.906296405557, 6278.623282603058, 6219.40915613016, 6245.5628106032145, 6262.12330273762, 6279.905801412982, 6299.433051025429, 5107.846764597068, 5114.258432527337, 5120.48795120485, 5126.743662816919, 5143.588512918957, 4345.478542574911, 4392.407025051577, 4527.253003254426, 4590.770461476913, 4598.057398233354, 6131.101423540838, 6159.952808022647, 6183.041628547582, 6209.782657606962, 6215.689215539205, 6322.557420980579, 6323.683815809191, 6344.111824344595, 6369.108324834527, 6490.350964903472, 6028.065649824284, 6088.086786981899, 6119.380693981147, 6145.740123274718, 6195.16365290689, 5637.43625637433, 5645.491905728504, 5663.971680141655, 5666.121751128581, 5669.036263835075, 6590.538662293593, 6606.4384527315715, 6616.8345791355605, 6620.073189753439, 6625.701843417058, 5185.170372740344, 6174.475169610633, 6197.597156184834, 6223.937760622336, 6260.780872669435, 5470.234167216672, 5616.550675945833, 6573.638449885302, 6582.0, 6640.601563906181, 6251.153694566376, 6299.433051025429, 6305.306416294296, 6305.306416294296, 6313.526485513697, 6145.4161854623735, 6160.322364539987, 6199.473044791062, 6252.343503932022, 6298.1812364392335, 6119.8776024478375, 6163.753449861955, 6172.166757487682, 6174.876502469836, 6195.008798592384, 6133.478654314434, 6179.0, 6182.412670796267, 6184.196054512884, 6215.81352559424, 5999.98000339952, 6047.5464340173785, 6091.847703807441, 6156.415140561639, 6254.906176407449, 6222.191115155001, 6254.124422580751, 6263.311035785969, 6263.874722505433, 6300.71646775887, 6241.531885108544, 6245.7189426476825, 6253.405926436982, 6258.0, 6286.0], "value": 5832.944669588916, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.81053673262323, 488.31635710005986, 523.2177894048396, 560.182134084237, 561.2843624378919, 38356.80821595892, 41857.372139417894, 41939.16121677565, 42012.94967625811, 42172.0, 42796.78601606988, 43208.78395608017, 43753.343699844445, 43824.780876095654, 43865.90335241621, 9490.715278541655, 9753.853692194623, 9767.951160244189, 11378.146639002067, 11788.7642247155, 11495.02292305155, 11508.82736758945, 11609.187356884979, 11646.01008914239, 11749.41252937354, 11681.59114430995, 11734.0, 11773.352465614387, 11789.056875449944, 11803.586874459395, 11633.94183029088, 11668.35824029678, 11689.181757276994, 11708.473118709637, 11732.765344693144, 11455.198136130473, 11576.47905844239, 11642.359670218135, 11647.825282620683, 11687.065034797195, 37917.052073698105, 38049.23901521982, 38300.61699383026, 38337.42493862594, 38481.61518384808, 4065.2479291331197, 4089.89775255621, 4095.0, 4154.4183814265725, 4155.91688166235, 27682.307942301402, 28347.433051339063, 28365.290867728265, 28370.432591348264, 30958.916437924563, 3164.3829453256626, 3217.324361884007, 3278.37710834943, 3278.639349671534, 3476.217850983534, 486911.0, 488216.0, 490570.0, 491384.0, 492682.0, 56172.157417638766, 56774.7161264191, 56936.71531642315, 56941.43058569361, 57147.71426142926, 57170.42829571734, 58045.0, 58227.0, 58246.12630810541, 58252.126218106765, 16271.674566508604, 16621.50135495937, 16788.160591970354, 16800.83199168017, 16818.24317905697, 11965.102617303563, 12194.329311887932, 13744.450221991008, 13955.232462214675, 13988.230647314496, 21557.029933653022, 22520.986555605035, 22561.323160304888, 22624.321270361914, 22635.773642263695, 7752.689892404241, 8238.970128733908, 8423.70517031901, 8986.68546600866, 9204.907950920538, 851.6125163050797, 879.612970293069, 894.9552522373855, 895.9552022398854, 924.611663101499, 25635.359116021875, 29778.40443191124, 30525.0, 30626.0, 31356.529652055237, 866.7703058689452, 983.2478154212033, 1047.350642601592, 1089.428050273597, 1097.4732128578169, 13972.72054558903, 14507.0, 14653.706925861636, 14733.705325893423, 14857.628559286104, 580574.0, 659323.0, 680002.0, 683329.0, 684362.0, 50223.49776502188, 50419.495805042214, 50553.747231264344, 50620.0, 50658.0, 1460.1531111955042, 1464.8608382203886, 1465.7801329800402, 1496.124767011289, 1501.0768377447791, 26948.32629184286, 27741.722582773913, 28234.85882570574, 28293.43413131767, 28297.009604663737, 46989.06021879475, 47126.528734712214, 47496.52503475059, 47499.0, 47626.0, 607655.0, 610118.0, 611402.0, 611407.0, 614673.0, 25925.740742593087, 27038.864805675847, 27617.585736213572, 27650.032248871194, 28604.42791144207, 43511.56488435075, 44181.33727994147, 44662.77668611636, 45344.0, 45465.54534454613, 32060.877869274464, 32815.67184328126, 35814.820925896245, 36141.45787813237, 37293.06767330838, 33751.32497350089, 34911.476327854594, 35591.82204088963, 36544.26911461809, 36741.44887826631, 35733.28533429265, 35945.0, 36526.817365913, 36733.44899826451, 36761.632383675824, 24697.394669346435, 31679.52480712745, 32361.838190808896, 32383.35233295368, 32619.510707338934, 22813.885930570243, 24367.756322436548, 24483.63274550919, 24483.777280720602, 24550.508989819748], "value": 20282.269898526138, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.5548742735026, 355.202570229834, 366.5637890909817, 413.1282992885014, 419.05558209906025, 16689.24898379573, 20654.070566824492, 20818.0, 21269.893650531743, 21490.677639835416, 21283.0, 21733.45666358342, 21742.782572174274, 22125.00437480313, 22128.33614991549, 5869.09029100488, 5883.0, 5935.28776546815, 6037.969810150943, 6041.335453100155, 4338.652907767387, 4542.568455996675, 5941.316748573926, 6052.697365131748, 6439.130717353168, 6633.635150066744, 6709.664516774166, 6714.697838597275, 6732.394084532391, 6734.764283250087, 6510.5442619016685, 6667.966660166692, 6705.061291419205, 6713.395794378529, 6724.293949135344, 5827.096799995965, 5834.883302333929, 5875.794347197828, 5922.7927022554, 5927.525797936155, 26147.869260653766, 26409.0, 26531.73468265312, 26644.0, 26718.19845404641, 2813.957790633142, 2821.3792965547545, 2825.759810416117, 2839.5312740202244, 2855.514562524376, 10596.311239769468, 15075.396984120622, 15177.620559485991, 15584.376624934988, 16380.0, 1880.0149049807478, 1880.1163453176985, 1894.1855002348984, 1894.8010458901892, 1906.8093190680959, 115009.0, 133850.3307483466, 136977.78133312133, 146720.0, 152107.0, 10411.635592754217, 12037.759244815055, 12115.757684846254, 12312.50749970009, 12461.0, 15657.982231154938, 16305.510834674978, 16330.346786128423, 16335.346586136659, 16375.754363684555, 8990.056044115405, 9156.0, 9168.44989300644, 9212.0, 9245.121713437213, 8116.188381161836, 8284.212999765134, 9036.8644470333, 9156.404833685789, 9241.630334786667, 3789.734718569729, 4559.52125026874, 4895.559399654048, 4960.355153830051, 4967.801287948441, 1718.1323431666929, 1820.3446759166688, 1831.7985021647607, 2075.616011037933, 2136.0455183506915, 991.5240684471494, 1004.3421558878946, 1005.3615953869346, 1015.2690063154517, 1062.490004797686, 19778.70331945022, 21085.789142108384, 21297.467563310733, 21300.828454434846, 21348.89325553362, 1045.3362115056993, 1051.3061379489652, 1090.1878100814786, 1091.8362245663213, 1092.186321190718, 10616.787664246673, 11175.38535380562, 11175.83236251457, 11187.384693841754, 11188.049015833583, 732935.0, 753776.0, 756535.0, 767072.0, 769307.0, 120732.18901716481, 131047.0, 134218.0, 156371.65442518372, 165818.0, 1026.1380440430075, 1044.2220545693356, 1063.4310643805682, 1065.477915821242, 1067.514281002142, 18081.728774068135, 18475.260989560265, 18764.530886727942, 18919.43241702751, 19116.426507204807, 55681.72159139178, 56101.15848262361, 56901.71549142228, 57658.423415766985, 58071.12893306688, 75447.86828197692, 81179.0, 83492.0, 84865.57567212124, 85302.0, 6591.242007169237, 6817.454603631599, 7709.036370453693, 7736.651850666621, 7878.409119315959, 11113.166512511432, 12951.158174718614, 12976.675583110498, 13289.734205316034, 13350.399232034775, 11196.216264861381, 11334.489947952527, 11376.317420954769, 11593.6521904343, 11615.419229038345, 10883.346999180074, 10945.53725411776, 11255.943720281346, 11296.830547541958, 11669.12481563903, 11581.942090289494, 11628.883711163118, 11961.342126182795, 11968.46141923598, 11996.58011969577, 7654.540727556365, 7657.387409007378, 7770.145284018867, 7792.84414311722, 8003.879941800761, 8540.871886921577, 9649.276304277348, 10687.51906164209, 11753.059755219732, 11780.112562854409], "value": 10128.076278480545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.1293267573124, 1485.4875068101505, 1486.5506131521768, 1488.665050363668, 1493.3578561218678, 7727.995360603128, 7977.441579089466, 8207.343412526998, 8630.697925572606, 8639.39524233304, 8113.63488643011, 8459.32325413967, 8470.28002619778, 8552.187542183497, 8564.400491965549, 2364.574376612209, 2413.78275955164, 2418.419579300964, 2429.259075981825, 2431.3799981004818, 2398.268528098929, 2424.9151279705216, 2425.333033415816, 2425.551273014489, 2431.586630272849, 2018.404570651654, 2384.940376490584, 2415.2874901903892, 2415.7825795678386, 2419.1412048722714, 2349.8472599281163, 2352.517733864562, 2367.928962131122, 2384.0, 2389.5101504191684, 2320.8259380546356, 2342.156823543523, 2406.097713357491, 2411.360989337827, 2416.915407960713, 7326.560406375635, 8476.067632560413, 8531.274841638406, 8543.658253669846, 8554.101819309008, 5182.870428239286, 5319.441458646825, 5341.679499230058, 5343.946560534384, 5360.91958620621, 3028.242939265184, 3202.5748541898865, 3403.285310084885, 3621.764585301947, 3639.763415377992, 1158.9826152607718, 1270.7102291174465, 1277.948882044717, 1287.1955028107434, 1329.7539955108336, 10640.733981650521, 10877.292975956538, 10879.891201087967, 10886.891131088747, 10905.454727263605, 3924.293627147084, 4767.427908650984, 4830.6135509159185, 4849.345338379344, 4856.271559266068, 9694.854577181348, 9698.951505242429, 9705.902940970642, 9740.512974351253, 9762.17021553176, 5384.6499977501335, 5409.43200963901, 5419.485148910847, 5426.077566813651, 5432.538234250054, 4459.687821852439, 4488.551144885485, 4693.296005599119, 4754.572088512051, 4933.136701077297, 3640.1445660269815, 3898.0, 3980.9402858957137, 4068.00333918186, 4116.670666346685, 1299.1036185032297, 1319.228251472899, 1324.3444494974856, 1338.9933050334687, 1340.0753480098701, 788.5584072919183, 800.5196881870947, 813.8372325534798, 820.3683163963834, 825.628467189767, 5776.653400795965, 5777.1045487948595, 5800.332961709373, 6083.209182806207, 6134.662593557308, 996.4917891875051, 1007.1338648762058, 1007.6120693533043, 1021.6832781837654, 1022.8005538919988, 3744.120131769032, 3774.7735135891935, 3778.319902417591, 3907.199024200032, 3935.960640393617, 10046.949765251273, 10346.482675866328, 10352.482375881176, 10359.741006474898, 10376.948115259376, 10155.949220253851, 10180.898191017995, 10245.74385640365, 10270.897291026995, 10294.948525257325, 7502.887456688154, 7899.605019748875, 7989.3209077229085, 8133.064697559738, 8145.104038555754, 5738.885222295614, 5878.559108066826, 5889.911651325319, 5985.491233245223, 5988.131720900532, 8717.476951382938, 8898.243649289883, 9119.088091190828, 9375.671851485165, 9450.338476306593, 9226.40028398152, 9362.95318523403, 9406.717798466058, 9443.150116489549, 9591.952040239754, 6768.593884366954, 6966.303369662993, 7287.380572651384, 7435.182129965808, 7619.733309334147, 7908.604569771604, 8151.225633564802, 8160.30637395828, 8162.591870406574, 8193.303569196685, 7190.06529151207, 7216.314450127229, 7260.0, 7341.559506429631, 7400.48196626231, 7037.190723066913, 7075.7523486679775, 7076.363127318566, 7077.787666370017, 7126.5011449198055, 6443.838904027437, 6501.837454063686, 6600.669966501751, 6602.40578347951, 6604.735810567524, 5026.899462010812, 5036.459894116589, 5100.2349647553165, 5126.487351264844, 5200.739963001911, 5104.132297509506, 5137.357830271216, 5193.818216362409, 5212.9739351303, 5267.104592219255], "value": 4522.411053744529, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.92864391443663, 370.8590735520502, 376.676058589613, 378.96210378962127, 384.91646016463653, 5292.338457692788, 5448.346198456181, 5455.318085239345, 5463.96184724902, 5596.888062238752, 5199.956042638644, 5248.895022099565, 5253.44838791927, 5360.812371566996, 5396.514313711765, 3580.445031020196, 3746.550413950316, 4226.302660061094, 4308.815075854134, 4311.223979683656, 3409.3010932758725, 3751.6869095816382, 4164.458620379346, 4257.169851878885, 4344.261475549164, 3667.303212389651, 4225.176090662322, 4254.638355739766, 4274.252005898971, 4278.679099067567, 3384.5092461593176, 3456.4815277708294, 3533.611302756695, 3716.2753263113705, 4147.0, 3800.3397621664667, 3815.653373175505, 3819.2743378757946, 3831.1954489556947, 3836.462895194675, 4040.333344998072, 4168.437260969791, 5136.43499215086, 5241.266222728821, 5299.4700529946695, 4899.730514821684, 4930.211166213388, 4947.233178857283, 4950.653454258203, 4956.2565615157655, 4706.5293470653005, 4760.952390476085, 5685.545156387479, 5716.71416429181, 5758.452946970031, 3381.6449272826244, 3645.9270814583824, 4170.6871984600975, 4217.261979153666, 4229.619334259932, 5547.389787123413, 5965.078809981739, 6030.306514750816, 6062.454379105901, 6086.391360863922, 3730.8993846815256, 4309.439772829513, 4321.805518751601, 4324.675649326313, 4336.566343365701, 3409.147713071732, 3609.9639003609627, 4136.855210067633, 4351.19502891966, 4456.598906098467, 4632.4672662644225, 4853.563179313879, 4963.354763880672, 4966.801327946841, 4980.427250866124, 5746.281714785652, 5750.763585829067, 5773.199998000966, 5809.331926828384, 5837.102941543931, 1244.512807195411, 1264.647755175373, 1323.6558494791423, 1421.4243231491262, 1429.463951018368, 952.642758965388, 1014.6499457687086, 1018.5467466977124, 1023.8259495885717, 1026.9383836969657, 4653.906921861544, 5051.8380772421815, 5204.21936709489, 5210.0, 5237.371515418175, 4620.468646105741, 5330.760115794798, 5647.350554686263, 5650.576206784508, 5732.828015159552, 4205.432266644025, 4322.524522302544, 4645.187092258841, 5186.974065129651, 5212.713300768418, 2533.8099642526886, 2651.9469610608066, 2939.7354238118246, 2965.9110226693233, 2981.2248815307744, 5044.722540260321, 5213.817516386908, 5507.642003269774, 5875.030619947661, 5903.232579764689, 5235.952809438128, 5293.814716484904, 5469.371022332403, 5728.971355143198, 5758.971205143947, 5296.894062118813, 5352.100004498425, 5354.732263386815, 5933.970330148408, 5998.820035398945, 4954.628402869727, 5447.972760136174, 5514.0626093565, 5594.272744543184, 5750.453706897838, 5033.219850923113, 5238.869028274324, 5481.945180548143, 5503.284573005644, 5515.724213789214, 5059.519345662156, 5605.383407825218, 5620.4660557247, 5843.415658434123, 5882.558808089496, 3298.9608273394765, 3470.8611655534505, 3952.288588054178, 4112.712110152261, 4143.7306575072525, 5170.97414512925, 5202.8959420812125, 5482.890342193214, 5514.36584792754, 5600.635958662674, 4678.438587369537, 5256.710880901587, 5475.5072043516275, 5584.246126772834, 5712.6286791358425, 5484.643498172606, 5627.184058311604, 5700.600957933071, 5703.543716502587, 5775.422457754359, 5261.631685781958, 5303.628745987744, 5332.386775520709, 5563.860903477445, 5593.496585307342, 4789.377380940595, 5023.6985780853265, 5247.265382846328, 5430.511253987002, 5683.687397193194, 5099.0, 5571.832845014656, 5575.303087114111, 5593.881223755184, 5618.7190640467], "value": 4152.892391991141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.9661016949151, 696.2271878215183, 781.582779423273, 860.264473874837, 866.9003064647563, 15159.848401515996, 15372.00081994671, 16245.756313655289, 16645.50063498094, 16682.249298781553, 14950.551483455487, 15207.23963801811, 15213.163276019815, 15252.008619439757, 15356.61608459789, 874.5889431966981, 891.8082612238368, 899.8695189197563, 901.5852707754441, 902.2466240689032, 724.0587236592435, 740.5408646639091, 876.4390789894472, 876.6975393489267, 880.2078129683305, 912.3157631776168, 928.3455164109321, 936.091990768953, 947.4552132523792, 950.4962369943906, 906.7325139083986, 913.9588718507683, 919.6459363145203, 922.547951503765, 928.3455164109287, 677.9796606101825, 680.571240118727, 687.0278555843475, 687.8074139241021, 689.689639662149, 15532.767008494882, 16031.19844007795, 16258.0, 16283.674326513403, 16370.263338149813, 14078.507252246225, 15300.540983770505, 15487.0, 15499.690006199926, 15550.76673849893, 10380.740481487948, 11023.0, 11844.644660660195, 11984.940075299655, 11986.220895641842, 3287.6547962463837, 3692.7444668812464, 3876.3991581304945, 3934.921301573981, 3976.1451287973223, 77820.22179778187, 78175.60912195402, 97883.0, 100631.0, 101495.0, 11573.479193436315, 11628.127890408085, 11848.940755296167, 11864.0, 12275.508979640714, 24261.393465163514, 24281.39296517601, 24898.50202995966, 24910.750892491207, 24935.0, 7176.390006849372, 8247.422680412314, 8256.511032063807, 8309.459885107448, 8325.791855203548, 4476.350929115325, 4477.082198149436, 4525.434320709911, 4528.515355567331, 4581.518940511265, 3769.509963704701, 3855.325318069327, 3855.575886652466, 3866.8839934802, 3959.0696186396176, 683.0676127086491, 697.2992142896356, 702.1293595940996, 706.5125063706026, 717.4368121025075, 632.9620222786648, 690.8998195261659, 708.9574625522486, 724.5507785173227, 744.9553026818409, 10524.421156836448, 10560.841587376195, 10596.894031059745, 10929.125669946385, 10929.945350273358, 675.9357861003197, 681.4276008153175, 696.606417374182, 717.7487879242224, 728.9963550182321, 4548.727076375428, 5004.674696144739, 5131.409887862869, 5143.5113664201845, 5147.871303217449, 108800.0, 109771.0, 110658.0, 110719.0, 114611.42694286643, 75902.0, 94891.0, 98344.0, 100992.94304741167, 101720.49139754401, 67981.32018679849, 71718.0, 73190.0, 77447.0, 79919.20080799234, 11233.943830281123, 11288.153388495732, 11312.60405885823, 11471.827922581, 11601.709957251136, 52250.477495224564, 52829.20756188737, 53385.0, 54128.188077179664, 55213.1718024238, 71146.64426677834, 71397.0, 71560.0, 72912.0, 75081.24918750742, 13408.0, 13539.729205416033, 13565.728685426433, 13606.183628982293, 13995.790063149265, 14723.0, 14869.330880110205, 14889.776653349993, 14955.700885982002, 15379.00036497624, 13614.115082519604, 13922.651933701738, 14118.21009810971, 14135.081219720685, 14161.716765664423, 12474.812877807022, 12653.493860245488, 12930.094893357375, 13215.933920330337, 13240.0, 13333.133346332457, 13558.728825423634, 13800.996566493608, 13816.861831381557, 14351.497697580951, 9544.379615324982, 10539.473026348498, 11087.944560277147, 11112.643587571203, 11308.943455283, 8238.546879921421, 9321.257961783547, 10292.897071029493, 10762.677119686421, 11090.500927458444], "value": 7762.779513928629, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1513.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 119.0, 117.0, 117.0, 122.0, 96.0, 97.0, 132.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 109.0, 148.0, 114.0, 114.0, 111.0, 112.0, 132.0, 138.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 193.0, 195.0, 223.0, 259.0, 198.0, 199.0, 202.0, 194.0], "value": 205.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 59.0, 91.0, 52.0, 65.0, 74.0, 87.0, 96.0, 81.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 84.0, 76.0, 69.0, 75.0, 81.0, 98.0, 72.0, 83.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 88.0, 90.0, 100.0, 99.0, 88.0, 89.0, 89.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 97.0, 96.0, 95.0, 124.0, 148.0, 96.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 94.0, 91.0, 100.0, 147.0, 125.0, 97.0, 124.0, 90.0, 143.0], "value": 112.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 62.0, 62.0, 63.0, 63.0, 63.0, 61.0, 64.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 83.0, 75.0, 75.0, 70.0, 71.0, 70.0, 107.0, 104.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 80.0, 86.0, 81.0, 83.0, 84.0, 88.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 156.0, 171.0, 121.0, 150.0, 151.0, 152.0, 140.0, 179.0, 156.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 165.0, 167.0, 168.0, 165.0, 166.0, 166.0, 167.0, 167.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 65.0, 64.0, 69.0, 65.0, 64.0, 66.0, 59.0, 73.0], "value": 66.0, "unit": "score"}]}]} 05:07:43 INFO - Return code: 0 05:07:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:07:43 ERROR - # TBPL SUCCESS # 05:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:43 INFO - 2017-03-22 05:07:43.074 screenresolution[2308:36658] starting screenresolution argv=screenresolution get 05:07:43 INFO - 2017-03-22 05:07:43.076 screenresolution[2308:36658] Display 0: 1600x1200x32@60 05:07:43 INFO - 2017-03-22 05:07:43.081 screenresolution[2309:36661] starting screenresolution argv=screenresolution list 05:07:43 INFO - Available Modes on Display 0 05:07:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:43 INFO - Intel Iris: 05:07:43 INFO - Chipset Model: Intel Iris 05:07:43 INFO - Type: GPU 05:07:43 INFO - Bus: Built-In 05:07:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:43 INFO - Vendor: Intel (0x8086) 05:07:43 INFO - Device ID: 0x0a2e 05:07:43 INFO - Revision ID: 0x0009 05:07:43 INFO - Displays: 05:07:43 INFO - Display: 05:07:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:43 INFO - Display Serial Number: 1600x1200 60 05:07:43 INFO - Main Display: Yes 05:07:43 INFO - Mirror: Off 05:07:43 INFO - Online: Yes 05:07:43 INFO - Rotation: Supported 05:07:43 INFO - Return code: 0 05:07:43 INFO - Running post-action listener: _resource_record_post_action 05:07:43 INFO - [mozharness: 2017-03-22 12:07:43.313329Z] Finished run-tests step (success) 05:07:43 INFO - Running post-run listener: _resource_record_post_run 05:07:43 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 974848; Write bytes: 426073600; Read time: 9; Write time: 1451 05:07:43 INFO - TinderboxPrint: CPU usage
54.4% 05:07:43 INFO - TinderboxPrint: I/O read bytes / time
974,848 / 9 05:07:43 INFO - TinderboxPrint: I/O write bytes / time
426,073,600 / 1,451 05:07:43 INFO - TinderboxPrint: CPU idle
2,171.9 (45.6%) 05:07:43 INFO - TinderboxPrint: CPU system
957.4 (20.1%) 05:07:43 INFO - TinderboxPrint: CPU user
1,628.8 (34.2%) 05:07:43 INFO - TinderboxPrint: Swap in / out
122,441,728 / 0 05:07:43 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109750784; Write bytes: 102260736; Read time: 9939; Write time: 221 05:07:43 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 974848; Write bytes: 303332864; Read time: 9; Write time: 1169 05:07:43 INFO - Running post-run listener: _upload_blobber_files 05:07:43 INFO - Blob upload gear active. 05:07:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:43 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:07:43 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:43 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:44 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:07:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:07:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:45 INFO - (blobuploader) - INFO - Done attempting. 05:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:07:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:45 INFO - (blobuploader) - INFO - Done attempting. 05:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:46 INFO - (blobuploader) - INFO - Done attempting. 05:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:48 INFO - (blobuploader) - INFO - Done attempting. 05:07:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:48 INFO - Return code: 0 05:07:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a86a93b82d5223e6dd285919a520d8b78ed4fd5b8b408fbcf03bb587e22207e5899abd28e1e541c6e7d4c8f13222dbe386b8072aed8a94e1d31e215db188f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5c17a58fb0e9cbee69cb198bfa15020b4d8d34bb86a3794528580607706c4361f3d6f6b9dfd8ffe46302559db2f3a5de28af5339fd57022519adba4e8db20b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/334e42fcbf49ca809209299b9d04781f8f3aa14f0a7433ad8f6afd2067e163f2f32cb7eab876716b685d7fa0c56159c4c28ec8ad25b074876a764b6466e90f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cdd70cc386f4d56440b9f9d13dabfa2c3cddefeacedf7b251634b721d5b0714f272524124a67685d5d419cbf27eb13bc250566afa3119963ecc37ad10d86756"} 05:07:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:48 INFO - Contents: 05:07:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a86a93b82d5223e6dd285919a520d8b78ed4fd5b8b408fbcf03bb587e22207e5899abd28e1e541c6e7d4c8f13222dbe386b8072aed8a94e1d31e215db188f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5c17a58fb0e9cbee69cb198bfa15020b4d8d34bb86a3794528580607706c4361f3d6f6b9dfd8ffe46302559db2f3a5de28af5339fd57022519adba4e8db20b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/334e42fcbf49ca809209299b9d04781f8f3aa14f0a7433ad8f6afd2067e163f2f32cb7eab876716b685d7fa0c56159c4c28ec8ad25b074876a764b6466e90f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cdd70cc386f4d56440b9f9d13dabfa2c3cddefeacedf7b251634b721d5b0714f272524124a67685d5d419cbf27eb13bc250566afa3119963ecc37ad10d86756"} 05:07:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:48 INFO - Copying logs to upload dir... 05:07:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.704167 ========= master_lag: 0.39 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-03-22 05:07:49.152233) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:07:49.156336) ========= 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.4ibE7nsZSr/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.Qy73mbMhxc/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/Mozilla-Beta/sha512/19a86a93b82d5223e6dd285919a520d8b78ed4fd5b8b408fbcf03bb587e22207e5899abd28e1e541c6e7d4c8f13222dbe386b8072aed8a94e1d31e215db188f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5c17a58fb0e9cbee69cb198bfa15020b4d8d34bb86a3794528580607706c4361f3d6f6b9dfd8ffe46302559db2f3a5de28af5339fd57022519adba4e8db20b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/334e42fcbf49ca809209299b9d04781f8f3aa14f0a7433ad8f6afd2067e163f2f32cb7eab876716b685d7fa0c56159c4c28ec8ad25b074876a764b6466e90f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cdd70cc386f4d56440b9f9d13dabfa2c3cddefeacedf7b251634b721d5b0714f272524124a67685d5d419cbf27eb13bc250566afa3119963ecc37ad10d86756"} build_url:https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/F0Bi62IjRx2rBQqkVwpvfg/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19a86a93b82d5223e6dd285919a520d8b78ed4fd5b8b408fbcf03bb587e22207e5899abd28e1e541c6e7d4c8f13222dbe386b8072aed8a94e1d31e215db188f9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5c17a58fb0e9cbee69cb198bfa15020b4d8d34bb86a3794528580607706c4361f3d6f6b9dfd8ffe46302559db2f3a5de28af5339fd57022519adba4e8db20b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/334e42fcbf49ca809209299b9d04781f8f3aa14f0a7433ad8f6afd2067e163f2f32cb7eab876716b685d7fa0c56159c4c28ec8ad25b074876a764b6466e90f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cdd70cc386f4d56440b9f9d13dabfa2c3cddefeacedf7b251634b721d5b0714f272524124a67685d5d419cbf27eb13bc250566afa3119963ecc37ad10d86756"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:07:49.192615) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:07:49.193076) ========= 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.4ibE7nsZSr/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.Qy73mbMhxc/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.005504 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:07:49.243414) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:07:49.243776) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:07:49.244139) ========= ========= Total master_lag: 0.53 =========