builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0206 starttime: 1489518747.26 results: success (0) buildid: 20170314110837 builduid: 972abf3a5bf04890bfbdd66fabf5e2c5 revision: 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.264640) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.265179) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.265508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.293384) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.293843) ========= 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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-14 12:12:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2017-03-14 12:12:27 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.431980) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.432308) ========= 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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.027084 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:27.482147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-14 12:12:27.482623) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b --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 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-14 12:12:27,590 truncating revision to first 12 chars 2017-03-14 12:12:27,590 Setting DEBUG logging. 2017-03-14 12:12:27,590 attempt 1/10 2017-03-14 12:12:27,590 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5359d7b1d4cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 12:12:29,663 unpacking tar archive at: mozilla-beta-5359d7b1d4cd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.348696 ========= master_lag: 1.94 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-14 12:12:31.775401) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:31.775736) ========= script_repo_revision: 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:31.776124) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:31.776482) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 12:12:31.798572) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-14 12:12:31.798847) ========= /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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:12:32 INFO - MultiFileLogger online at 20170314 12:12:32 in /builds/slave/test 12:12:32 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 12:12:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:12:32 INFO - {'append_to_log': False, 12:12:32 INFO - 'base_work_dir': '/builds/slave/test', 12:12:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:12:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:12:32 INFO - 'branch': 'Mozilla-Beta', 12:12:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:12:32 INFO - 'config_files': ('talos/mac_config.py',), 12:12:32 INFO - 'default_actions': ('clobber', 12:12:32 INFO - 'read-buildbot-config', 12:12:32 INFO - 'download-and-extract', 12:12:32 INFO - 'populate-webroot', 12:12:32 INFO - 'create-virtualenv', 12:12:32 INFO - 'install', 12:12:32 INFO - 'run-tests'), 12:12:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:12:32 INFO - 'download_minidump_stackwalk': True, 12:12:32 INFO - 'download_symbols': 'ondemand', 12:12:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:12:32 INFO - 'tooltool.py': '/tools/tooltool.py', 12:12:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:12:32 INFO - '/tools/misc-python/virtualenv.py')}, 12:12:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:12:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:12:32 INFO - 'installer_path': 'installer.exe', 12:12:32 INFO - 'log_level': 'info', 12:12:32 INFO - 'log_name': 'talos', 12:12:32 INFO - 'log_to_console': True, 12:12:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:12:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:12:32 INFO - 'opt_config_files': (), 12:12:32 INFO - 'pip_index': False, 12:12:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:32 INFO - 'cmd': ('bash', 12:12:32 INFO - '-c', 12:12:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:32 INFO - 'enabled': True, 12:12:32 INFO - 'halt_on_failure': False, 12:12:32 INFO - 'name': 'check_screen_resolution'},), 12:12:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:32 INFO - 'cmd': ('bash', 12:12:32 INFO - '-c', 12:12:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:32 INFO - 'enabled': True, 12:12:32 INFO - 'halt_on_failure': False, 12:12:32 INFO - 'name': 'check_screen_resolution'},), 12:12:32 INFO - 'run_cmd_checks_enabled': True, 12:12:32 INFO - 'sps_profile': False, 12:12:32 INFO - 'sps_profile_interval': 0, 12:12:32 INFO - 'suite': 'dromaeojs-e10s', 12:12:32 INFO - 'system_bits': '32', 12:12:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:12:32 INFO - 'title': 't-yosemite-r7-0206', 12:12:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:12:32 INFO - 'use_talos_json': True, 12:12:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:12:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:12:32 INFO - 'work_dir': 'build'} 12:12:32 INFO - [mozharness: 2017-03-14 19:12:32.014275Z] Running clobber step. 12:12:32 INFO - Running pre-action listener: _resource_record_pre_action 12:12:32 INFO - Running main action method: clobber 12:12:32 INFO - rmtree: /builds/slave/test/build 12:12:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:12:34 INFO - Running post-action listener: _resource_record_post_action 12:12:34 INFO - [mozharness: 2017-03-14 19:12:34.364619Z] Finished clobber step (success) 12:12:34 INFO - [mozharness: 2017-03-14 19:12:34.364782Z] Running read-buildbot-config step. 12:12:34 INFO - Running pre-action listener: _resource_record_pre_action 12:12:34 INFO - Running main action method: read_buildbot_config 12:12:34 INFO - Using buildbot properties: 12:12:34 INFO - { 12:12:34 INFO - "project": "", 12:12:34 INFO - "product": "firefox", 12:12:34 INFO - "script_repo_revision": "production", 12:12:34 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:12:34 INFO - "repository": "", 12:12:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:12:34 INFO - "buildid": "20170314110837", 12:12:34 INFO - "pgo_build": "False", 12:12:34 INFO - "basedir": "/builds/slave/test", 12:12:34 INFO - "buildnumber": 2, 12:12:34 INFO - "slavename": "t-yosemite-r7-0206", 12:12:34 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:12:34 INFO - "platform": "yosemite_r7", 12:12:34 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:12:34 INFO - "branch": "mozilla-beta", 12:12:34 INFO - "repo_path": "releases/mozilla-beta", 12:12:34 INFO - "revision": "5359d7b1d4cdb6807595d21d8b233f58ec5dd84b", 12:12:34 INFO - "stage_platform": "macosx64", 12:12:34 INFO - "builduid": "972abf3a5bf04890bfbdd66fabf5e2c5", 12:12:34 INFO - "slavebuilddir": "test" 12:12:34 INFO - } 12:12:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 12:12:34 INFO - Running post-action listener: _resource_record_post_action 12:12:34 INFO - [mozharness: 2017-03-14 19:12:34.368304Z] Finished read-buildbot-config step (success) 12:12:34 INFO - [mozharness: 2017-03-14 19:12:34.368407Z] Running download-and-extract step. 12:12:34 INFO - Running pre-action listener: _resource_record_pre_action 12:12:34 INFO - Running main action method: download_and_extract 12:12:34 INFO - mkdir: /builds/slave/test/build/tests 12:12:34 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')]} 12:12:34 INFO - trying https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 12:12:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/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 12:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/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 12:12:35 INFO - Downloaded 1165 bytes. 12:12:35 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 12:12:35 INFO - Using the following test package requirements: 12:12:35 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 12:12:35 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 12:12:35 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 12:12:35 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:12:35 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 12:12:35 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 12:12:35 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 12:12:35 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 12:12:35 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 12:12:35 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 12:12:35 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 12:12:35 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 12:12:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 12:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 12:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 12:12:36 INFO - Expected file size: 16105354 12:12:36 INFO - Obtained file size: 16105354 12:12:43 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 12:12:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 12:12:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 12:12:43 INFO - Fetch https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 12:12:44 INFO - Expected file size: 16105354 12:12:44 INFO - Obtained file size: 16105354 12:12:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 12:12:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 12:12:50 INFO - Fetch https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 12:12:51 INFO - Expected file size: 14403650 12:12:51 INFO - Obtained file size: 14403650 12:12: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')]} 12:12:52 INFO - trying https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 12:12:52 INFO - Downloading https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 12:12:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/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 12:12:53 INFO - Downloaded 58693940 bytes. 12:12:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 12:12:53 INFO - mkdir: /builds/slave/test/properties 12:12:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:12:53 INFO - Writing to file /builds/slave/test/properties/build_url 12:12:53 INFO - Contents: 12:12:53 INFO - build_url:https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 12:12:54 INFO - Running post-action listener: _resource_record_post_action 12:12:54 INFO - Running post-action listener: set_extra_try_arguments 12:12:54 INFO - [mozharness: 2017-03-14 19:12:54.425998Z] Finished download-and-extract step (success) 12:12:54 INFO - [mozharness: 2017-03-14 19:12:54.426130Z] Running populate-webroot step. 12:12:54 INFO - Running pre-action listener: _resource_record_pre_action 12:12:54 INFO - Running main action method: populate_webroot 12:12:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:12:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:12:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:12:54 INFO - '--tppagecycles', 12:12:54 INFO - '7'], 12:12:54 INFO - 'tests': ['tsvgm']}}, 12:12:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:12:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:12:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:12:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:12:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['damp', 'tps']}, 12:12:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'tests': ['damp', 'tps']}, 12:12:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['dromaeo_dom']}, 12:12:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:12:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['basic_compositor_video']}, 12:12:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:12:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'other-e10s': {'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['a11yr', 12:12:54 INFO - 'ts_paint', 12:12:54 INFO - 'tpaint', 12:12:54 INFO - 'sessionrestore', 12:12:54 INFO - 'sessionrestore_no_auto_restore', 12:12:54 INFO - 'tabpaint']}, 12:12:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['tsvgx', 12:12:54 INFO - 'tsvgr_opacity', 12:12:54 INFO - 'tart', 12:12:54 INFO - 'tscrollx', 12:12:54 INFO - 'cart', 12:12:54 INFO - 'tsvg_static']}, 12:12:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:12:54 INFO - 'tsvgr_opacity', 12:12:54 INFO - 'tart', 12:12:54 INFO - 'tscrollx', 12:12:54 INFO - 'cart', 12:12:54 INFO - 'tsvg_static']}, 12:12:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'talos_options': ['--disable-e10s'], 12:12:54 INFO - 'tests': ['tp5o']}, 12:12:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:12:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'talos_options': ['--disable-e10s', 12:12:54 INFO - '--xperf_path', 12:12:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:54 INFO - 'tests': ['tp5n']}, 12:12:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:12:54 INFO - 'talos_options': ['--xperf_path', 12:12:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:54 INFO - 'tests': ['tp5n']}}, 12:12:54 INFO - 'talos.zip': {'path': '', 12:12:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:12:54 INFO - Running post-action listener: _resource_record_post_action 12:12:54 INFO - [mozharness: 2017-03-14 19:12:54.444978Z] Finished populate-webroot step (success) 12:12:54 INFO - [mozharness: 2017-03-14 19:12:54.445066Z] Running create-virtualenv step. 12:12:54 INFO - Running pre-action listener: _resource_record_pre_action 12:12:54 INFO - Running main action method: create_virtualenv 12:12:54 INFO - Creating virtualenv /builds/slave/test/build/venv 12:12:54 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 12:12:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:12:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:12:54 INFO - Using real prefix '/tools/python27' 12:12:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:12:55 INFO - Installing distribute.............................................................................................................................................................................................done. 12:12:58 INFO - Installing pip.................done. 12:12:58 INFO - Return code: 0 12:12:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:12:58 INFO - Reading from file tmpfile_stdout 12:12:58 INFO - Output received: 12:12:58 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) 12:12:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:12:58 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')]} 12:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:58 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')]} 12:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:58 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:12:58 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 12:12:58 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 12:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:58 INFO - 'HOME': '/Users/cltbld', 12:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:58 INFO - 'LOGNAME': 'cltbld', 12:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:58 INFO - 'MOZ_NO_REMOTE': '1', 12:12:58 INFO - 'NO_EM_RESTART': '1', 12:12:58 INFO - 'PAGER': '/bin/cat', 12:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:58 INFO - 'PWD': '/builds/slave/test', 12:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:58 INFO - 'SHELL': '/bin/bash', 12:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:12:58 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:58 INFO - 'USER': 'cltbld', 12:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:58 INFO - 'XPC_FLAGS': '0x0', 12:12:58 INFO - 'XPC_SERVICE_NAME': '0', 12:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:58 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 12:12:58 INFO - Cleaning up... 12:12:58 INFO - Return code: 0 12:12:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:12:58 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')]} 12:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:58 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')]} 12:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:58 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:12:58 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 12:12:58 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 12:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:58 INFO - 'HOME': '/Users/cltbld', 12:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:58 INFO - 'LOGNAME': 'cltbld', 12:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:58 INFO - 'MOZ_NO_REMOTE': '1', 12:12:58 INFO - 'NO_EM_RESTART': '1', 12:12:58 INFO - 'PAGER': '/bin/cat', 12:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:58 INFO - 'PWD': '/builds/slave/test', 12:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:58 INFO - 'SHELL': '/bin/bash', 12:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:12:58 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:58 INFO - 'USER': 'cltbld', 12:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:58 INFO - 'XPC_FLAGS': '0x0', 12:12:58 INFO - 'XPC_SERVICE_NAME': '0', 12:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:58 INFO - Downloading/unpacking psutil>=3.1.1 12:12:58 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 12:12:58 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 12:12:58 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 12:12:58 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 12:12:58 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 12:12:58 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 12:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:02 INFO - Installing collected packages: psutil 12:13:02 INFO - Running setup.py install for psutil 12:13:02 INFO - building 'psutil._psutil_osx' extension 12:13:02 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 12:13:03 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 12:13:03 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 12:13:03 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 12:13:03 INFO - building 'psutil._psutil_posix' extension 12:13:03 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 12:13:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:13:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:13:03 INFO - ^ 12:13:03 INFO - 1 warning generated. 12:13:03 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 12:13:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:03 INFO - Successfully installed psutil 12:13:03 INFO - Cleaning up... 12:13:03 INFO - Return code: 0 12:13:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13:03 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')]} 12:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:03 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')]} 12:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:03 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:03 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 12:13:03 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 12:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:03 INFO - 'HOME': '/Users/cltbld', 12:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:03 INFO - 'LOGNAME': 'cltbld', 12:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:03 INFO - 'MOZ_NO_REMOTE': '1', 12:13:03 INFO - 'NO_EM_RESTART': '1', 12:13:03 INFO - 'PAGER': '/bin/cat', 12:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:03 INFO - 'PWD': '/builds/slave/test', 12:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:03 INFO - 'SHELL': '/bin/bash', 12:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:03 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:03 INFO - 'USER': 'cltbld', 12:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:03 INFO - 'XPC_FLAGS': '0x0', 12:13:03 INFO - 'XPC_SERVICE_NAME': '0', 12:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:06 INFO - Installing collected packages: mozsystemmonitor 12:13:06 INFO - Running setup.py install for mozsystemmonitor 12:13:06 INFO - Successfully installed mozsystemmonitor 12:13:06 INFO - Cleaning up... 12:13:06 INFO - Return code: 0 12:13:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:06 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')]} 12:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:06 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')]} 12:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:06 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:06 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 12:13:06 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 12:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:06 INFO - 'HOME': '/Users/cltbld', 12:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:06 INFO - 'LOGNAME': 'cltbld', 12:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:06 INFO - 'MOZ_NO_REMOTE': '1', 12:13:06 INFO - 'NO_EM_RESTART': '1', 12:13:06 INFO - 'PAGER': '/bin/cat', 12:13:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:06 INFO - 'PWD': '/builds/slave/test', 12:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:06 INFO - 'SHELL': '/bin/bash', 12:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:06 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:06 INFO - 'USER': 'cltbld', 12:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:06 INFO - 'XPC_FLAGS': '0x0', 12:13:06 INFO - 'XPC_SERVICE_NAME': '0', 12:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:07 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:10 INFO - warning: no files found matching '*.txt' 12:13:10 INFO - no previously-included directories found matching 'build' 12:13:10 INFO - no previously-included directories found matching 'dist' 12:13:10 INFO - no previously-included directories found matching '.git*' 12:13:10 INFO - Installing collected packages: jsonschema, functools32 12:13:10 INFO - Running setup.py install for functools32 12:13:10 INFO - warning: no files found matching '*.txt' 12:13:10 INFO - no previously-included directories found matching 'build' 12:13:10 INFO - no previously-included directories found matching 'dist' 12:13:10 INFO - no previously-included directories found matching '.git*' 12:13:10 INFO - Successfully installed jsonschema functools32 12:13:10 INFO - Cleaning up... 12:13:10 INFO - Return code: 0 12:13:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:13:10 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')]} 12:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:10 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')]} 12:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:10 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:10 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 12:13:10 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 12:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:10 INFO - 'HOME': '/Users/cltbld', 12:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:10 INFO - 'LOGNAME': 'cltbld', 12:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:10 INFO - 'MOZ_NO_REMOTE': '1', 12:13:10 INFO - 'NO_EM_RESTART': '1', 12:13:10 INFO - 'PAGER': '/bin/cat', 12:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:10 INFO - 'PWD': '/builds/slave/test', 12:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:10 INFO - 'SHELL': '/bin/bash', 12:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:10 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:10 INFO - 'USER': 'cltbld', 12:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:10 INFO - 'XPC_FLAGS': '0x0', 12:13:10 INFO - 'XPC_SERVICE_NAME': '0', 12:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:13:11 INFO - Cleaning up... 12:13:11 INFO - Return code: 0 12:13:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:11 INFO - 'HOME': '/Users/cltbld', 12:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:11 INFO - 'LOGNAME': 'cltbld', 12:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:11 INFO - 'MOZ_NO_REMOTE': '1', 12:13:11 INFO - 'NO_EM_RESTART': '1', 12:13:11 INFO - 'PAGER': '/bin/cat', 12:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:11 INFO - 'PWD': '/builds/slave/test', 12:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:11 INFO - 'SHELL': '/bin/bash', 12:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:11 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:11 INFO - 'USER': 'cltbld', 12:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:11 INFO - 'XPC_FLAGS': '0x0', 12:13:11 INFO - 'XPC_SERVICE_NAME': '0', 12:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:11 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:14 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:15 INFO - Downloading docopt-0.6.1.tar.gz 12:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:15 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:15 INFO - Running setup.py install for blobuploader 12:13:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:15 INFO - Running setup.py install for requests 12:13:15 INFO - Running setup.py install for docopt 12:13:16 INFO - Successfully installed blobuploader requests docopt 12:13:16 INFO - Cleaning up... 12:13:16 INFO - Return code: 0 12:13:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:13:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:16 INFO - 'HOME': '/Users/cltbld', 12:13:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:16 INFO - 'LOGNAME': 'cltbld', 12:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:16 INFO - 'MOZ_NO_REMOTE': '1', 12:13:16 INFO - 'NO_EM_RESTART': '1', 12:13:16 INFO - 'PAGER': '/bin/cat', 12:13:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:16 INFO - 'PWD': '/builds/slave/test', 12:13:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:16 INFO - 'SHELL': '/bin/bash', 12:13:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:16 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:16 INFO - 'USER': 'cltbld', 12:13:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:16 INFO - 'XPC_FLAGS': '0x0', 12:13:16 INFO - 'XPC_SERVICE_NAME': '0', 12:13:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Cywyzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-DyVC3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-o7vjO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-xSdAak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-RVidwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:16 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-d_lPZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-AaUyUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-CQjbFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-coT8aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-joykNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pPOpsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-I6rSJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-clomqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pj6y2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:17 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-sdn9jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:18 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-kvFn2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:18 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-0pcIcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:18 INFO - Running setup.py install for manifestparser 12:13:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:18 INFO - Running setup.py install for mozcrash 12:13:18 INFO - Running setup.py install for mozdebug 12:13:18 INFO - Running setup.py install for mozdevice 12:13:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:18 INFO - Running setup.py install for mozfile 12:13:18 INFO - Running setup.py install for mozhttpd 12:13:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Running setup.py install for mozinfo 12:13:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Running setup.py install for mozInstall 12:13:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Running setup.py install for mozleak 12:13:19 INFO - Running setup.py install for mozlog 12:13:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Running setup.py install for moznetwork 12:13:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:19 INFO - Running setup.py install for mozprocess 12:13:20 INFO - Running setup.py install for mozprofile 12:13:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:20 INFO - Running setup.py install for mozrunner 12:13:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:20 INFO - Running setup.py install for mozscreenshot 12:13:20 INFO - Running setup.py install for moztest 12:13:20 INFO - Running setup.py install for mozversion 12:13:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:20 INFO - Cleaning up... 12:13:20 INFO - Return code: 0 12:13:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:21 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')]} 12:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:21 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:21 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 12:13:21 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 12:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:21 INFO - 'HOME': '/Users/cltbld', 12:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:21 INFO - 'LOGNAME': 'cltbld', 12:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:21 INFO - 'MOZ_NO_REMOTE': '1', 12:13:21 INFO - 'NO_EM_RESTART': '1', 12:13:21 INFO - 'PAGER': '/bin/cat', 12:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:21 INFO - 'PWD': '/builds/slave/test', 12:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:21 INFO - 'SHELL': '/bin/bash', 12:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:21 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:21 INFO - 'USER': 'cltbld', 12:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:21 INFO - 'XPC_FLAGS': '0x0', 12:13:21 INFO - 'XPC_SERVICE_NAME': '0', 12:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-05Yf67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-o0XRyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-xMPwBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fHF53J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-EXRNB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-5t_l5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-L4skjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:22 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)) 12:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:22 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-0fBzbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-uUNbro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-AYMjBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-dP8NoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-dN6E9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-PESIS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-C9hnw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pRGtHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-4ktlw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:23 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)) 12:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:23 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-TSmbLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:24 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)) 12:13:24 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)) 12:13:24 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)) 12:13:24 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)) 12:13:24 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)) 12:13:24 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)) 12:13:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13:24 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 12:13:24 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 12:13:24 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 12:13:24 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 12:13:24 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 12:13:24 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 12:13:26 INFO - Downloading blessings-1.6.tar.gz 12:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:26 INFO - Installing collected packages: blessings 12:13:26 INFO - Running setup.py install for blessings 12:13:26 INFO - Successfully installed blessings 12:13:26 INFO - Cleaning up... 12:13:27 INFO - Return code: 0 12:13:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:27 INFO - Reading from file tmpfile_stdout 12:13:27 INFO - Current package versions: 12:13:27 INFO - blessings == 1.6 12:13:27 INFO - blobuploader == 1.2.4 12:13:27 INFO - docopt == 0.6.1 12:13:27 INFO - functools32 == 3.2.3-2 12:13:27 INFO - jsonschema == 2.5.1 12:13:27 INFO - manifestparser == 1.1 12:13:27 INFO - mozInstall == 1.12 12:13:27 INFO - mozcrash == 1.0 12:13:27 INFO - mozdebug == 0.1 12:13:27 INFO - mozdevice == 0.49 12:13:27 INFO - mozfile == 1.2 12:13:27 INFO - mozhttpd == 0.7 12:13:27 INFO - mozinfo == 0.9 12:13:27 INFO - mozleak == 0.1 12:13:27 INFO - mozlog == 3.4 12:13:27 INFO - moznetwork == 0.27 12:13:27 INFO - mozprocess == 0.24 12:13:27 INFO - mozprofile == 0.28 12:13:27 INFO - mozrunner == 6.13 12:13:27 INFO - mozscreenshot == 0.1 12:13:27 INFO - mozsystemmonitor == 0.3 12:13:27 INFO - moztest == 0.8 12:13:27 INFO - mozversion == 1.4 12:13:27 INFO - psutil == 3.1.1 12:13:27 INFO - requests == 1.2.3 12:13:27 INFO - wsgiref == 0.1.2 12:13:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:27 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 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:27 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 12:13:27 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 12:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:27 INFO - 'HOME': '/Users/cltbld', 12:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:27 INFO - 'LOGNAME': 'cltbld', 12:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:27 INFO - 'MOZ_NO_REMOTE': '1', 12:13:27 INFO - 'NO_EM_RESTART': '1', 12:13:27 INFO - 'PAGER': '/bin/cat', 12:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:27 INFO - 'PWD': '/builds/slave/test', 12:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:27 INFO - 'SHELL': '/bin/bash', 12:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:27 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:27 INFO - 'USER': 'cltbld', 12:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:27 INFO - 'XPC_FLAGS': '0x0', 12:13:27 INFO - 'XPC_SERVICE_NAME': '0', 12:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 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)) 12:13:27 INFO - Cleaning up... 12:13:27 INFO - Return code: 0 12:13:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108983b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108986768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd51b5e1110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, '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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 12:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:27 INFO - 'HOME': '/Users/cltbld', 12:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:27 INFO - 'LOGNAME': 'cltbld', 12:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:27 INFO - 'MOZ_NO_REMOTE': '1', 12:13:27 INFO - 'NO_EM_RESTART': '1', 12:13:27 INFO - 'PAGER': '/bin/cat', 12:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:27 INFO - 'PWD': '/builds/slave/test', 12:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:27 INFO - 'SHELL': '/bin/bash', 12:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:27 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:27 INFO - 'USER': 'cltbld', 12:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:27 INFO - 'XPC_FLAGS': '0x0', 12:13:27 INFO - 'XPC_SERVICE_NAME': '0', 12:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:13:27 INFO - Cleaning up... 12:13:27 INFO - Return code: 0 12:13:27 INFO - Running post-action listener: _resource_record_post_action 12:13:27 INFO - Running post-action listener: _start_resource_monitoring 12:13:27 INFO - Starting resource monitoring. 12:13:27 INFO - [mozharness: 2017-03-14 19:13:27.921714Z] Finished create-virtualenv step (success) 12:13:27 INFO - [mozharness: 2017-03-14 19:13:27.922674Z] Running install step. 12:13:27 INFO - Running pre-action listener: _resource_record_pre_action 12:13:27 INFO - Running main action method: install 12:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:28 INFO - Reading from file tmpfile_stdout 12:13:28 INFO - Detecting whether we're running mozinstall >=1.0... 12:13:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:13:28 INFO - Reading from file tmpfile_stdout 12:13:28 INFO - Output received: 12:13:28 INFO - Usage: mozinstall [options] installer 12:13:28 INFO - Options: 12:13:28 INFO - -h, --help show this help message and exit 12:13:28 INFO - -d DEST, --destination=DEST 12:13:28 INFO - Directory to install application into. [default: 12:13:28 INFO - "/builds/slave/test"] 12:13:28 INFO - --app=APP Application being installed. [default: firefox] 12:13:28 INFO - mkdir: /builds/slave/test/build/application 12:13:28 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'] 12:13:28 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 12:13:42 INFO - Reading from file tmpfile_stdout 12:13:42 INFO - Output received: 12:13:42 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:13:42 INFO - Running post-action listener: _resource_record_post_action 12:13:42 INFO - [mozharness: 2017-03-14 19:13:42.901365Z] Finished install step (success) 12:13:42 INFO - [mozharness: 2017-03-14 19:13:42.901504Z] Running run-tests step. 12:13:42 INFO - Running pre-action listener: _resource_record_pre_action 12:13:42 INFO - Running main action method: run_tests 12:13:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:13:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:13:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:13:42 INFO - 2017-03-14 12:13:42.916 screenresolution[1854:8385] starting screenresolution argv=screenresolution get 12:13:42 INFO - 2017-03-14 12:13:42.919 screenresolution[1854:8385] Display 0: 1600x1200x32@60 12:13:42 INFO - 2017-03-14 12:13:42.924 screenresolution[1855:8387] starting screenresolution argv=screenresolution list 12:13:42 INFO - Available Modes on Display 0 12:13:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:13:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:13:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:13:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:13:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:13:43 INFO - Intel Iris: 12:13:43 INFO - Chipset Model: Intel Iris 12:13:43 INFO - Type: GPU 12:13:43 INFO - Bus: Built-In 12:13:43 INFO - VRAM (Dynamic, Max): 1536 MB 12:13:43 INFO - Vendor: Intel (0x8086) 12:13:43 INFO - Device ID: 0x0a2e 12:13:43 INFO - Revision ID: 0x0009 12:13:43 INFO - Displays: 12:13:43 INFO - Display: 12:13:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:13:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:13:43 INFO - Display Serial Number: 1600x1200 60 12:13:43 INFO - Main Display: Yes 12:13:43 INFO - Mirror: Off 12:13:43 INFO - Online: Yes 12:13:43 INFO - Rotation: Supported 12:13:43 INFO - Return code: 0 12:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:13:43 INFO - Python 2.7.3 12:13:43 INFO - Return code: 0 12:13:43 INFO - grabbing minidump binary from tooltool 12:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:43 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 0x108761d30>, '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 0x108688db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108756be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:13:43 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 12:13:43 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 12:13:43 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 12:13:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:13:43 INFO - Return code: 0 12:13:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:13:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:13:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:13:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:13:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:13:43 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/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 12:13:43 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/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --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 12:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d3DiBDL2Lp/Render', 12:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:43 INFO - 'HOME': '/Users/cltbld', 12:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:43 INFO - 'LOGNAME': 'cltbld', 12:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:43 INFO - 'MOZ_NO_REMOTE': '1', 12:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:13:43 INFO - 'NO_EM_RESTART': '1', 12:13:43 INFO - 'PAGER': '/bin/cat', 12:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:43 INFO - 'PWD': '/builds/slave/test', 12:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:43 INFO - 'SHELL': '/bin/bash', 12:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z51iEtpuvE/Listeners', 12:13:43 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 12:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:43 INFO - 'USER': 'cltbld', 12:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:43 INFO - 'XPC_FLAGS': '0x0', 12:13:43 INFO - 'XPC_SERVICE_NAME': '0', 12:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:43 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/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 12:13:43 INFO - mozversion application_buildid: 20170314110837 12:13:43 INFO - mozversion application_changeset: 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b 12:13:43 INFO - mozversion application_display_name: Firefox 12:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:13:43 INFO - mozversion application_name: Firefox 12:13:43 INFO - mozversion application_remotingname: firefox 12:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:13:43 INFO - mozversion application_vendor: Mozilla 12:13:43 INFO - mozversion application_version: 53.0 12:13:43 INFO - mozversion platform_buildid: 20170314110837 12:13:43 INFO - mozversion platform_changeset: 5359d7b1d4cdb6807595d21d8b233f58ec5dd84b 12:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:13:43 INFO - mozversion platform_version: 53.0 12:13:43 INFO - using testdate: 1489518823 12:13:43 INFO - actual date: 1489518823 12:13:43 INFO - starting webserver on 'localhost:49252' 12:13:43 INFO - SUITE-START | Running 2 tests 12:13:43 INFO - TEST-START | dromaeo_css 12:13:43 INFO - operating with platform_type : mac_ 12:13:43 INFO - Initialising browser for dromaeo_css test... 12:13:43 INFO - TEST-INFO | started process 1865 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpx7oDmT/profile http://localhost:49252/getInfo.html) 12:13:46 INFO - PROCESS | 1865 | 2017-03-14 12:13:46.485 plugin-container[1868:8552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 12:13:46 INFO - PROCESS | 1865 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:13:46 INFO - PROCESS | 1865 | 2017-03-14 12:13:46.486 plugin-container[1868:8552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 12:13:46 INFO - PROCESS | 1865 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:13:46 INFO - PROCESS | 1865 | 2017-03-14 12:13:46.486 plugin-container[1868:8552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:13:46 INFO - PROCESS | 1865 | __metrics Screen width/height:1600/1200 12:13:46 INFO - PROCESS | 1865 | colorDepth:24 12:13:46 INFO - PROCESS | 1865 | Browser inner width/height: 1024/689 12:13:46 INFO - PROCESS | 1865 | __metrics 12:13:46 ERROR - PROCESS | 1865 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 12:13:47 INFO - TEST-INFO | 1865: exit 0 12:13:47 INFO - Browser initialized. 12:13:47 INFO - Running cycle 1/1 for dromaeo_css test... 12:13:47 INFO - TEST-INFO | started process 1869 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpx7oDmT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:49 INFO - PROCESS | 1869 | 2017-03-14 12:13:49.831 plugin-container[1870:8685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 12:13:49 INFO - PROCESS | 1869 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:13:49 INFO - PROCESS | 1869 | 2017-03-14 12:13:49.832 plugin-container[1870:8685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:13:49 INFO - PROCESS | 1869 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:13:49 INFO - PROCESS | 1869 | 2017-03-14 12:13:49.832 plugin-container[1870:8685] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:13:59 INFO - PROCESS | 1869 | RSS: Main: 172265472 12:13:59 INFO - PROCESS | 1869 | 12:17:01 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 12:17:02 INFO - PROCESS | 1869 | RSS: Main: 194400256 12:17:02 INFO - PROCESS | 1869 | 12:20:05 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 12:20:05 INFO - PROCESS | 1869 | RSS: Main: 203042816 12:20:05 INFO - PROCESS | 1869 | 12:23:07 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 12:23:08 INFO - PROCESS | 1869 | RSS: Main: 204644352 12:23:08 INFO - PROCESS | 1869 | 12:26:10 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 12:26:11 INFO - PROCESS | 1869 | RSS: Main: 208687104 12:26:11 INFO - PROCESS | 1869 | 12:29:13 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 12:29:14 INFO - PROCESS | 1869 | RSS: Main: 209788928 12:29:14 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 12:32:17 INFO - PROCESS | 1869 | RSS: Main: 210874368 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | __start_tp_report 12:32:17 INFO - PROCESS | 1869 | _x_x_mozilla_page_load 12:32:17 INFO - PROCESS | 1869 | _x_x_mozilla_page_load_details 12:32:17 INFO - PROCESS | 1869 | |i|pagename|runs| 12:32:17 INFO - PROCESS | 1869 | |0;dojo.html;2666.133506610352;3476.5828100627928;3491.127218195451;3561.2343346180573;3566.643335666432;5918.171455996164;5919.792807251747;5943.910841337383;5944.851378715529;6068.453839154476;6018.15745795589;6060.939390606103;6071;6088.995315772903;6093.086037094438;5349.625526213166;5371.086915224401;5444.101723215665;5455.0453670607685;5455.236266922634;4582.19811532982;5470.042742520063;5482.91775623366;5494.203340515643;5509.669419834822;4447.822087116511;4454.955450445486;5317.468253174671;5497.065498865202;5542.889142217154;4851.611871050342;5052.797888084472;5305.522502974713;5365.7853685852515;5510.0632892408385;5314.548263397616;5457.317835270591;5482.945170548283;5516.7517461714315;5521.282233309684;6090.33006369299;6112.694365281763;6130.693465326761;6179.3820617937845;6184.381561843825;6159.4456498915315;6197.318294987548;6340.587861789015;6372.808815735489;6379.87240255197;6320.178376811031;6323;6371.681415929185;6453.257875344349;6493.675316234169;6193.1329613854095;6208.875822483525;6210.0063989761875;6212.596181248205;6234.625922444668;6278.246610406689;6472.126262954535;6542.54202205841;6547.574407663486;6577.3422657733845;6208.441240288395;6210.254769427697;6244.594101383395;6289.496840252768;6300.086487459293;6409.230892292955;6419.935800641933;6423.197100362455;6434.613923164625;6440.452561532228;6272.372762723691;6304.243490781044;6324.33594472583;6333.778317758856;6337.588056776295;6174.753009879554;6193.566450348522;6227.159333490012;6247.843803904848;6256.374362563798;5129.153689641167;5165.354330708661;5182.196759502283;5182.766775495112;5189.922151167735;4219.586438543122;4566.178087944202;4598.172328980816;4620.491745907947;4622.653301002439;5937.851553711105;6001.12983617373;6071.666058366833;6122.051082082285;6172.166757487771;6231.532635052389;6335.303116657164;6337.366263373718;6351.079093531412;6356.45970092538;5965.582409231399;6075.453209211192;6097.786577469856;6105.358937311518;6127;5590.993621148233;5660.349059858087;5673.0923052312255;5679.574031947538;5739.827805165852;6553.967230163818;6557.868842623217;6600.636964966877;6609.372109649768;6638.701258443381;6434.742610295536;6443.355664433519;6446.033095035782;6458.7093580789915;6494.188226471691;6318.273398559225;6435.259945106185;6544.901826472702;6550.475961922939;6576.638284894186;6367.204099487564;6372.74509019634;6384.936150638434;6399.8800209964;6400.583962042452;6399.872002560016;6432.903506447501;6445.677716114082;6476.481881449378;6492.383223593751;6159.4456498915315;6179.969100154471;6221.5333849962335;6238.75044998195;6262.499000079892;5208.9479105209975;5239.397469291081;5536.695481748542;6104.420080092562;6292;6170.722317495633;6207.068939658907;6257.7184026718;6264.561480696308;6282.340354262737;5084.3898732152375;6186.907196391968;6204.317525072148;6215.720292586935;6297.559170857996;5019.874503137451;6250.937490625036;6258.937410625836;6267.059941008885;6284.905726414016 12:32:17 INFO - PROCESS | 1869 | |1;ext.html;498.16059939002776;499.3757802746567;501.0605115408609;520.4223312123545;540.2328693255575;32247.838760806193;44121.999999999956;44204.557954420445;47058;47122.7643861781;46655;47072.529274707245;47366.815829604275;47383.526164738345;47471;9250.35247532673;9684.176844968168;9896.35673681212;9898.9505052475;9934.850977235346;9241.537923103851;12024.038076953822;12158.878411215863;12363.258204507714;12448.190867593623;12193.634190974284;12261.080418968568;12342.568010119647;12347.444365003552;12372.13395062346;10990.670279891616;11469.025132863717;12173;12240;12248.877511224908;11917.404129793562;12097.637070887888;12099.395030248452;12112.09159313055;12117;38204.42693359602;43036.56963430357;44317.33523997143;44487.777561112314;44696.553034469565;3773.433984902259;4098.959010409917;4118.155778065519;4179.665626749853;4203.348480985452;31381.215469613442;31630.051098467084;32135.11479674455;32355.51466728001;32502.67497325044;2810.8875644974178;3317;3505.4391297392294;3545.539079919594;3546.5389499365174;527967;530646.9999999962;531216;532950;533233;70599.94100088502;70742.2925770746;71332;71747.64126179335;71785;74642.88035679469;74953.25046749462;75200.24799751933;75337;75443.49113017709;13437.462501500026;15952.680946381008;16178.75731864023;16634.75047874283;16681.499555013368;10428.687139385831;12880.420381082873;13710.903127749756;13711.657208569666;13848.307584620728;19804.801951980295;21550.46123846885;21783.673244901023;22145.446363841034;22785.088596455957;8892.199702026848;9133.538633818556;9197;9197.586108625128;9205.493697846548;883.4081165619051;886.1847100667272;889.7375274294099;900.945893304835;937.3344925103116;31380.529292060637;32034.359312813613;32345.35309293801;32400.027999160062;32608.67391326104;931.3387494878712;949.0699114867331;1092.5411327242577;1133.8072527670315;1165.318288801044;13390.665233369049;17151.485455436356;17361;17566;17759.911200443916;662692;666575;668544;668628.6568567127;672031;67758.3224167752;68410.9738353925;68583.65708171527;68649.65675171593;68769.65615171992;1461.298576683198;1498.9850101499064;1514.1974753380505;1519.468186134844;1527.2745445913324;32527;33392.831250907075;33649.66350336465;33653.831730841186;34165.48751768676;57339.71330143323;58028.70985645045;58294.41705583059;58378;58379.4162058374;506475;506829;508161;509283;510605;29092.395014726135;29321.560176597795;29469;29734.702652973192;29842;47589.52410475851;48050.03899922052;49846.75076624594;50065.49934500609;53488.197677035656;38705.225895482494;39175.216495670495;39441.40837887376;40085.79957100196;40743.98140046404;38667.2266554673;38934.61065389423;39171.433142675116;39761;40321.395179072926;37524.6247537532;38572.614273858024;38606;38665.226695466496;38846;26315.342116447242;30762.769489221075;33653.3269334607;34949.65050349464;35174.648253518164;21854.672179916997;25608.359791005372;26113.608295875958;26167.73832261653;26600.600990984763 12:32:17 INFO - PROCESS | 1869 | |2;jquery.html;336.51763979669556;395.6913607386238;402.4978543312912;406.5588836112818;428.28904019327905;16427.178641067927;20099.899500502495;20793.584128317423;21049;21196.788032119694;21783.45541361467;22236.332910012687;22568.66147007796;22768.886155569242;23043.654345184837;5768.163616275638;5926.585139040269;5963.045912653976;6051.0318349064155;6068.241469816273;5763.308402991647;5823.708814559276;5850.970745146268;5907.113932910055;5978.641281523101;5868.207791948096;5887.264091988502;5889.499392551638;5894.145348924403;5896.793612223573;5689.2319536862615;5779.884402311951;5811.215485909391;5876.500497457721;5914.822555323347;5203.479652034766;5216.895662086775;5216.9739151304;5224.294720212799;5236.188390799432;20278.49303767403;25923.703814809185;26313.605295920577;26339.946402144084;26441.206763797116;2743.6433263675594;2786.9442611147865;2818.506761316761;2825.2795537138104;2835.6171916791177;15480.225988700633;15655.452059177875;15679.216039198109;16037.35850565976;16394;1818.5362732503127;1844.5019844641872;1864.8041955594604;1864.9067546622614;1871.3824437935448;101809;123107;127312;127368.36315818454;128816;9988.600455981825;11282.43587820622;11298.983091521803;11712.824307635392;11837.46731397072;13322.66693332655;13895.930520347536;14730.189839558712;14767.556973290819;14772.852271477363;8792;9056.184943355129;9111.635534578676;9156.679516217033;9287.953560232156;8231.917680823235;8582.7555004524;9210.355275130676;9236.538173091318;9403.952980235192;7228.494005419675;7259.963700181465;7352.889706654404;7355.411567074621;7357.852842943111;1999.0509253478033;2033.4916270932267;2034.8779073255653;2037.7758446570865;2049.139361468187;1088.5754555723274;1089.4824958144818;1106.9778604427866;1108.38484641025;1158.7103224193952;20363.083661234985;21454.78545214528;22428.10287588511;23041.769582304296;23262.65106023411;1109.5728144664363;1137.647329327911;1144.6373739032658;1150.1431433582036;1158.7856246594238;11663.941680291544;12102.515899364103;12111.697207569705;12199.329036903055;12219.69450763738;809591;814607;824803;836308;846076;150534.49465505205;159846;161698;164695;166970;1043.7181960870673;1063.9468026598795;1087.8368244763349;1098.340995402768;1108.7837871615118;16192.514224573282;19935;20107.698384524538;20335.983200840194;20354.389368318974;63245.36754632395;63517;63642;63852.68073659602;65308;91177.544112279;92176;92461;93127;93740.65648358845;6677.899831502628;7498.437617178843;7654.885176722242;7818.413618978486;8203.220694034057;12117.575884843987;13353.79969300442;13461.057725959481;13767.036307458382;13770.036097473481;11319.358692989636;11320.433978300887;11673.416329183678;11676.649700508999;11957.282563046245;5374.946250537445;8339.291160251447;10987.450627468754;11153.498092586015;11170.832437513282;6181.598196117238;7686;11505.424728763695;11527.09802882515;11946.701332466759;6498.447631951337;8422.326213902996;8487.830243395221;8858.955705221433;8927.24118449939;8767.561621919007;9689.352810022172;11414.828777568177;11512.136589755635;11983.400829958642 12:32:17 INFO - PROCESS | 1869 | |3;mootools.html;1298.279454902529;1444.0397383856528;1491.3065424577576;1493.1638282561767;1502.0086742749788;7812.843743125134;8070.192980701927;8316.251537361637;8715.128487151282;8760.780980475483;7240.710371585144;8231.547264900428;8549.743507694779;8669.349798765103;8680.131986801332;2412.7949124324455;2448.375664205634;2453.6196889482158;2454.7667971542674;2458.397692565324;2431.8540887546715;2450.534398464295;2457.4839283750434;2458.91393801217;2473.183849329717;2406.097713357491;2408.6868707068143;2432.598621227495;2438.3538362333998;2445.4986727720775;2378.631312146603;2386.916457923973;2407.434252950555;2409.3374322061486;2413.3966508372905;2371.9525609487887;2390.5218956208655;2402.531506356261;2447.3025187821386;2451.6445115458337;8577.957110214471;8627.051024387312;8631.956840215822;8645.697400591012;8660.913390866073;5206.687598744087;5383.407825139231;5389.595780316492;5389.892202155974;5391.164369522691;3630.2211916161154;3639.0894780240587;3663.9084022899374;3697.371446854041;3727.422249551324;1248.6691026877884;1267.3726505379896;1291.5156816193928;1293.9353032348345;1319.26121372031;11277.887221127765;11324.886751132466;11325.093992480583;11331.66005019859;11396;4732.810687572459;4832.323474713543;4838.588719958772;4899.093667671492;4910.582600478928;9788.461634610023;9864.457454839909;9893.208543316661;9898.554565044591;9916.057974492413;5366.731663416814;5450.5639548836;5460.6177567569885;5467.043267428184;5481.780728770805;4478.888027799331;4714.905701885943;4920.778564964585;4962.230854217602;5002.449730529639;3956.287868183697;4022.054817117975;4051.655609273245;4053.6959728020515;4094.28350038742;1308.6924572725402;1323.6558494791423;1324.8277723895833;1330.973380532384;1336.9933150334186;762.7177944160696;899.3659470073583;899.4648184330313;899.7975455522455;912.8356895758827;5305.151175811851;5347.385050719217;5510.283663123768;5516.696581687966;5681.261436013292;929.3215952354819;978.1196922769423;1003.0571263012745;1011.6155860772859;1019.0370100255187;3360.260742636616;3604.9098772530897;3622.203115314679;3639.2721455708966;3650.014496086042;7240.710371585077;10321.74195645115;10394.948025259826;10473.057424831803;10483.947580262049;10176.643817466502;10208.979102089732;10338.224633152544;10357.482125893675;10492.685219443429;7351.595662238628;7579.317861392501;8112;8258.587070646563;8259.967504061991;4394.011347446799;5296.576273898156;5300.5229529341;5311.282976798083;5533.6403133796175;7087.362137407658;8449.915500844912;8998.685046023358;9014.368994170345;9215.170634642915;8747.431416957877;8787.03342632297;9560.187384072431;9844.852327214954;9891.55488003056;6853.965730171517;7031.156261248683;7254.310840470147;7410.96294518524;7596.164421913474;8201.589920503831;8208.712695055645;8271;8285;8294.460860044077;7186.38915692172;7202.89195662055;7353.669084891086;7417.1841097478155;7427.034485517064;7127.893081603676;7231.602261875647;7277.708891644486;7279.708811647475;7372.967784510066;6576.704048317933;6692.598444093175;6696.0960270362875;6708.6310252936555;6717.193936727428;4712.575868171881;4806.014766972692;4824.49342818999;4869.537393947569;4897.755112244302;4111.97944010278;4819.975900120618;4851.733154676526;4922.92615610759;4979.800807967641 12:32:17 INFO - PROCESS | 1869 | |4;prototype.html;309.5789725972677;353.2634457156828;364.96897763690083;374.54492791258616;378.64029172286325;4231.70378073535;5410.48600382963;5496.9450305496985;5591.692456914868;5614.3543492498375;4450.998525331799;5312.787488500465;5461.836144915649;5491.450854914507;5522.282103326562;4314.223439780831;4356.760378179199;4362.716423432483;4370.912581748363;4384.736915785047;4153.7507749535125;4293.914121717564;4375.365571992075;4378.576962487188;4403.4495688039;3747.4378843173604;4087.202995415895;4294.20557196918;4308.43990281263;4337.978310108445;3451.913702157441;3670.52109626763;4245.193413251488;4257.3613957906255;4287.721298115613;3332.8668252793923;3838.9424158637644;3842.7117966152373;3845.8653947111998;3862.748921320105;4919.483454237289;5282.339707536558;5334.839954801362;5364;5381;4163.854265100737;4741.241401375763;4755.857324280242;4764.595009424203;4768.2370820668875;5819.53443724501;5838.64968101911;5847.3275573308765;5854.5023672987845;5870.765169393219;3714.368557345231;3718.8698395556025;3955.0705584187695;3974.972763890408;3986.720929534934;4770.499097594772;5196.558292545138;5316.707581083039;5873.1190321451695;6075.544334174954;3204.951925721116;3948.506436695413;4010.258102251093;4035.4552135461336;4035.535913369941;3421.9144521387166;3670.137517683342;3762.2287431076566;4035.4955630546183;4081.2449696806184;4765.9523404765505;4772;4789.473157952622;4819.49395313494;4820.132376172252;4899.828506002273;4930.531599498114;5693.231413759172;5855.355910849803;5919.378465261172;1254.2286493806243;1271.2753730373597;1301.7331447053332;1307.5096838685492;1332.4270563657526;805.7784109369853;869.5434896679168;893.8659201119754;901.7430032440855;910.3263584947091;5113.233015047698;5168.3539557555305;5170.405403378584;5178.533931946143;5214.869628259324;4735.195016847144;5167.974160129251;5283.9471605284225;5699.544036477072;5716.399778023331;4065.4348075990365;4343.588333791467;4659.673822832369;5128.820491282861;5232.979568984011;2725.6047873058296;2831.40540486496;2954.9556756648667;3009.714077162666;3023.9546406803915;5131.589472842164;5886.911696324559;5950.7917222896995;5973.49225315853;5973.581849270509;5140.357455318085;5154.046501397253;5560.884945140567;5649.4633009864;5943.435373639497;5005.649604527648;5272.420033796279;5749.9875043734355;5922.466977971917;5954.37479064692;4965.31179399006;5614.775408983758;5736.368999409978;5759.9136012959;5785.3636100028125;4852.660313778143;5590.105583106846;5652.3217213934595;5654.547636189177;5705.600607957404;5063.468335824833;5213.661112027706;5743.9138412923;5755.971220143872;5931.228940237742;3055.7402620777484;3803.277377298415;3844.461775351397;3847.6729477993563;3865.347246851698;5470.261514695465;5792.710364481674;5966.403359663999;6045.851288255218;6057.878842423215;5345.877365753236;5364.168553874156;5429.429909859408;5580.748866301107;5777.035807825359;4499.188007495315;5119.897602048012;5136.768845401891;5307.982966472716;5737.713114344349;4768.85547468612;5584.4084435935565;5719.141279084231;5781.739821707949;5839.364977806221;5094.7962081516325;5470.617056806144;5834.18321435008;5834.883302334015;5867.295924488918;4714.7406892619865;5159.17453207485;5787.392323806108;5799.217105690678;5822.214001109796 12:32:17 INFO - PROCESS | 1869 | |5;yui.html;724.6811402982687;781.2616185400917;798.0782196562972;811.2779626132743;867.3624885709005;13935;14970.17664028478;15521.379144834207;16231.431899883506;16288.51134465965;14722.779158312635;14943.476978305762;14954.177520236382;14957;14980.7003859923;712.8823744082221;733.9302766237199;844.9788755281106;878.5870640798846;909.0182602789005;893.7542175901647;896.8296023755498;908.4458480326995;909.5543183839937;912.0924679943465;970.3595626886256;973.095021629884;974.4396971741277;974.7465658928694;975.4001289207157;969.0454901921566;969.1471505075524;970.2626004236831;971.1065819446127;972.5963725054094;678.0845858091586;695.7843068648684;702.4626160986849;719.4855678190066;722.0793488302404;14513.911456640793;15024.624384390369;15059.472918447911;15605.921970390076;16570.92289001211;14916.105033697904;14920.925395372953;14921.477748278867;14931.776023359658;15281.694366112615;11661;11756.764864702744;11775.7056073598;11914;11970.461329240117;3789.2989796887387;3796.316663000659;3810.768141978312;3834.3481608126685;3905.5508616508896;72008.6399568004;103968;106630.46684766526;107494.46252768765;109194.45402772936;11412.31526108436;11462;11676.182667213383;11730.530778768754;12063.879361206451;23344.533109337717;23783.167589134297;24382.756172438403;24390.26829195127;24462.388440289134;8010;8055.436119471581;8077.434579579491;8099.068607110258;8164.550949697705;4308.202982448257;4327.242732521859;4436.64506839452;4457.2199865023495;4542.568455996675;3561.0919215599856;3631.074076110575;3659.1583935694407;3660.7071434285726;3664.9633503665154;653.950953678476;662.1359126340076;677.2550194785696;680.7685386968355;688.7486067585395;565.4967079299427;659.7163219815526;663.8074958262147;675.6486626954055;694.7568351077184;9401.388909720983;9762.853557196648;9825.4104753715;9895;10271.640492582725;645.6674812471581;645.7255666341786;662.2549631664377;677.9525433219726;694.8054544727485;4367.411473404711;4627.791749371219;4878.634102442331;4939.604831613462;5013.699178049329;91189.5440522793;98390.01609983951;111642;113907;114931;99956.00043999612;100424;100745;102491;102804.97195028103;88334.55832720795;88725;90334;90937;91268;8921.009711062263;9724.993192901038;9779.511024448891;9841.852372214566;9847.162991145833;54573.45426545842;59183.40816591779;59971;60047.69976150091;60461.39538604557;72179;76202.47595048179;77221.84167237383;78520.42959140899;79815.2018479831;11253.549858005588;11679.299242045505;11704.473298701409;11741.882581174421;11932.940335298268;12447.626571202878;12547.372631368213;12671.683207919506;12806.935965320114;13250.403731832284;10662.413567253876;11052.115830733683;11407.600733974272;11816.586419475278;12103.031757459557;11761.647150585497;12038.097142714156;12157.939210303892;12327.93836030814;12379.504819807467;11776.411179440822;11854.229475084092;11876.168668193144;12266.570670026505;12432.254064756144;9564.808703826024;9591.376560523544;9932.453715045453;10008.349457285252;10039.648612298535;9310.394824336396;9795.412275263507;9820.214382849497;9834.262430317613;9891.505424728879 12:32:17 INFO - PROCESS | 1869 | __end_tp_report 12:32:17 INFO - PROCESS | 1869 | __start_cc_report 12:32:17 INFO - PROCESS | 1869 | _x_x_mozilla_cycle_collect,383 12:32:17 INFO - PROCESS | 1869 | __end_cc_report 12:32:17 INFO - PROCESS | 1869 | __startTimestamp1489519937606__endTimestamp 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | ------- Summary: start ------- 12:32:17 INFO - PROCESS | 1869 | Number of tests: 6 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#0] dojo.html Cycles:165 Average:5905.58 Median:6193.13 stddev:698.78 (11.3%) stddev-sans-first:653.08 12:32:17 INFO - PROCESS | 1869 | Values: 2666.1 3476.6 3491.1 3561.2 3566.6 5918.2 5919.8 5943.9 5944.9 6068.5 6018.2 6060.9 6071.0 6089.0 6093.1 5349.6 5371.1 5444.1 5455.0 5455.2 4582.2 5470.0 5482.9 5494.2 5509.7 4447.8 4455.0 5317.5 5497.1 5542.9 4851.6 5052.8 5305.5 5365.8 5510.1 5314.5 5457.3 5482.9 5516.8 5521.3 6090.3 6112.7 6130.7 6179.4 6184.4 6159.4 6197.3 6340.6 6372.8 6379.9 6320.2 6323.0 6371.7 6453.3 6493.7 6193.1 6208.9 6210.0 6212.6 6234.6 6278.2 6472.1 6542.5 6547.6 6577.3 6208.4 6210.3 6244.6 6289.5 6300.1 6409.2 6419.9 6423.2 6434.6 6440.5 6272.4 6304.2 6324.3 6333.8 6337.6 6174.8 6193.6 6227.2 6247.8 6256.4 5129.2 5165.4 5182.2 5182.8 5189.9 4219.6 4566.2 4598.2 4620.5 4622.7 5937.9 6001.1 6071.7 6122.1 6172.2 6231.5 6335.3 6337.4 6351.1 6356.5 5965.6 6075.5 6097.8 6105.4 6127.0 5591.0 5660.3 5673.1 5679.6 5739.8 6554.0 6557.9 6600.6 6609.4 6638.7 6434.7 6443.4 6446.0 6458.7 6494.2 6318.3 6435.3 6544.9 6550.5 6576.6 6367.2 6372.7 6384.9 6399.9 6400.6 6399.9 6432.9 6445.7 6476.5 6492.4 6159.4 6180.0 6221.5 6238.8 6262.5 5208.9 5239.4 5536.7 6104.4 6292.0 6170.7 6207.1 6257.7 6264.6 6282.3 5084.4 6186.9 6204.3 6215.7 6297.6 5019.9 6250.9 6258.9 6267.1 6284.9 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#1] ext.html Cycles:180 Average:72499.81 Median:38775.61 stddev:152870.38 (394.2%) stddev-sans-first:153203.63 12:32:17 INFO - PROCESS | 1869 | Values: 498.2 499.4 501.1 520.4 540.2 32247.8 44122.0 44204.6 47058.0 47122.8 46655.0 47072.5 47366.8 47383.5 47471.0 9250.4 9684.2 9896.4 9899.0 9934.9 9241.5 12024.0 12158.9 12363.3 12448.2 12193.6 12261.1 12342.6 12347.4 12372.1 10990.7 11469.0 12173.0 12240.0 12248.9 11917.4 12097.6 12099.4 12112.1 12117.0 38204.4 43036.6 44317.3 44487.8 44696.6 3773.4 4099.0 4118.2 4179.7 4203.3 31381.2 31630.1 32135.1 32355.5 32502.7 2810.9 3317.0 3505.4 3545.5 3546.5 527967.0 530647.0 531216.0 532950.0 533233.0 70599.9 70742.3 71332.0 71747.6 71785.0 74642.9 74953.3 75200.2 75337.0 75443.5 13437.5 15952.7 16178.8 16634.8 16681.5 10428.7 12880.4 13710.9 13711.7 13848.3 19804.8 21550.5 21783.7 22145.4 22785.1 8892.2 9133.5 9197.0 9197.6 9205.5 883.4 886.2 889.7 900.9 937.3 31380.5 32034.4 32345.4 32400.0 32608.7 931.3 949.1 1092.5 1133.8 1165.3 13390.7 17151.5 17361.0 17566.0 17759.9 662692.0 666575.0 668544.0 668628.7 672031.0 67758.3 68411.0 68583.7 68649.7 68769.7 1461.3 1499.0 1514.2 1519.5 1527.3 32527.0 33392.8 33649.7 33653.8 34165.5 57339.7 58028.7 58294.4 58378.0 58379.4 506475.0 506829.0 508161.0 509283.0 510605.0 29092.4 29321.6 29469.0 29734.7 29842.0 47589.5 48050.0 49846.8 50065.5 53488.2 38705.2 39175.2 39441.4 40085.8 40744.0 38667.2 38934.6 39171.4 39761.0 40321.4 37524.6 38572.6 38606.0 38665.2 38846.0 26315.3 30762.8 33653.3 34949.7 35174.6 21854.7 25608.4 26113.6 26167.7 26600.6 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#2] jquery.html Cycles:180 Average:43404.28 Median:22905.33 stddev:137089.50 (598.5%) stddev-sans-first:137435.92 12:32:17 INFO - PROCESS | 1869 | Values: 336.5 395.7 402.5 406.6 428.3 16427.2 20099.9 20793.6 21049.0 21196.8 21783.5 22236.3 22568.7 22768.9 23043.7 5768.2 5926.6 5963.0 6051.0 6068.2 5763.3 5823.7 5851.0 5907.1 5978.6 5868.2 5887.3 5889.5 5894.1 5896.8 5689.2 5779.9 5811.2 5876.5 5914.8 5203.5 5216.9 5217.0 5224.3 5236.2 20278.5 25923.7 26313.6 26339.9 26441.2 2743.6 2786.9 2818.5 2825.3 2835.6 15480.2 15655.5 15679.2 16037.4 16394.0 1818.5 1844.5 1864.8 1864.9 1871.4 101809.0 123107.0 127312.0 127368.4 128816.0 9988.6 11282.4 11299.0 11712.8 11837.5 13322.7 13895.9 14730.2 14767.6 14772.9 8792.0 9056.2 9111.6 9156.7 9288.0 8231.9 8582.8 9210.4 9236.5 9404.0 7228.5 7260.0 7352.9 7355.4 7357.9 1999.1 2033.5 2034.9 2037.8 2049.1 1088.6 1089.5 1107.0 1108.4 1158.7 20363.1 21454.8 22428.1 23041.8 23262.7 1109.6 1137.6 1144.6 1150.1 1158.8 11663.9 12102.5 12111.7 12199.3 12219.7 809591.0 814607.0 824803.0 836308.0 846076.0 150534.5 159846.0 161698.0 164695.0 166970.0 1043.7 1063.9 1087.8 1098.3 1108.8 16192.5 19935.0 20107.7 20336.0 20354.4 63245.4 63517.0 63642.0 63852.7 65308.0 91177.5 92176.0 92461.0 93127.0 93740.7 6677.9 7498.4 7654.9 7818.4 8203.2 12117.6 13353.8 13461.1 13767.0 13770.0 11319.4 11320.4 11673.4 11676.6 11957.3 5374.9 8339.3 10987.5 11153.5 11170.8 6181.6 7686.0 11505.4 11527.1 11946.7 6498.4 8422.3 8487.8 8859.0 8927.2 8767.6 9689.4 11414.8 11512.1 11983.4 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#3] mootools.html Cycles:180 Average:5530.53 Median:4971.02 stddev:3008.05 (60.5%) stddev-sans-first:2999.67 12:32:17 INFO - PROCESS | 1869 | Values: 1298.3 1444.0 1491.3 1493.2 1502.0 7812.8 8070.2 8316.3 8715.1 8760.8 7240.7 8231.5 8549.7 8669.3 8680.1 2412.8 2448.4 2453.6 2454.8 2458.4 2431.9 2450.5 2457.5 2458.9 2473.2 2406.1 2408.7 2432.6 2438.4 2445.5 2378.6 2386.9 2407.4 2409.3 2413.4 2372.0 2390.5 2402.5 2447.3 2451.6 8578.0 8627.1 8632.0 8645.7 8660.9 5206.7 5383.4 5389.6 5389.9 5391.2 3630.2 3639.1 3663.9 3697.4 3727.4 1248.7 1267.4 1291.5 1293.9 1319.3 11277.9 11324.9 11325.1 11331.7 11396.0 4732.8 4832.3 4838.6 4899.1 4910.6 9788.5 9864.5 9893.2 9898.6 9916.1 5366.7 5450.6 5460.6 5467.0 5481.8 4478.9 4714.9 4920.8 4962.2 5002.4 3956.3 4022.1 4051.7 4053.7 4094.3 1308.7 1323.7 1324.8 1331.0 1337.0 762.7 899.4 899.5 899.8 912.8 5305.2 5347.4 5510.3 5516.7 5681.3 929.3 978.1 1003.1 1011.6 1019.0 3360.3 3604.9 3622.2 3639.3 3650.0 7240.7 10321.7 10394.9 10473.1 10483.9 10176.6 10209.0 10338.2 10357.5 10492.7 7351.6 7579.3 8112.0 8258.6 8260.0 4394.0 5296.6 5300.5 5311.3 5533.6 7087.4 8449.9 8998.7 9014.4 9215.2 8747.4 8787.0 9560.2 9844.9 9891.6 6854.0 7031.2 7254.3 7411.0 7596.2 8201.6 8208.7 8271.0 8285.0 8294.5 7186.4 7202.9 7353.7 7417.2 7427.0 7127.9 7231.6 7277.7 7279.7 7373.0 6576.7 6692.6 6696.1 6708.6 6717.2 4712.6 4806.0 4824.5 4869.5 4897.8 4112.0 4820.0 4851.7 4922.9 4979.8 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#4] prototype.html Cycles:180 Average:4581.25 Median:5130.20 stddev:1390.22 (27.1%) stddev-sans-first:1356.65 12:32:17 INFO - PROCESS | 1869 | Values: 309.6 353.3 365.0 374.5 378.6 4231.7 5410.5 5496.9 5591.7 5614.4 4451.0 5312.8 5461.8 5491.5 5522.3 4314.2 4356.8 4362.7 4370.9 4384.7 4153.8 4293.9 4375.4 4378.6 4403.4 3747.4 4087.2 4294.2 4308.4 4338.0 3451.9 3670.5 4245.2 4257.4 4287.7 3332.9 3838.9 3842.7 3845.9 3862.7 4919.5 5282.3 5334.8 5364.0 5381.0 4163.9 4741.2 4755.9 4764.6 4768.2 5819.5 5838.6 5847.3 5854.5 5870.8 3714.4 3718.9 3955.1 3975.0 3986.7 4770.5 5196.6 5316.7 5873.1 6075.5 3205.0 3948.5 4010.3 4035.5 4035.5 3421.9 3670.1 3762.2 4035.5 4081.2 4766.0 4772.0 4789.5 4819.5 4820.1 4899.8 4930.5 5693.2 5855.4 5919.4 1254.2 1271.3 1301.7 1307.5 1332.4 805.8 869.5 893.9 901.7 910.3 5113.2 5168.4 5170.4 5178.5 5214.9 4735.2 5168.0 5283.9 5699.5 5716.4 4065.4 4343.6 4659.7 5128.8 5233.0 2725.6 2831.4 2955.0 3009.7 3024.0 5131.6 5886.9 5950.8 5973.5 5973.6 5140.4 5154.0 5560.9 5649.5 5943.4 5005.6 5272.4 5750.0 5922.5 5954.4 4965.3 5614.8 5736.4 5759.9 5785.4 4852.7 5590.1 5652.3 5654.5 5705.6 5063.5 5213.7 5743.9 5756.0 5931.2 3055.7 3803.3 3844.5 3847.7 3865.3 5470.3 5792.7 5966.4 6045.9 6057.9 5345.9 5364.2 5429.4 5580.7 5777.0 4499.2 5119.9 5136.8 5308.0 5737.7 4768.9 5584.4 5719.1 5781.7 5839.4 5094.8 5470.6 5834.2 5834.9 5867.3 4714.7 5159.2 5787.4 5799.2 5822.2 12:32:17 INFO - PROCESS | 1869 | 12:32:17 INFO - PROCESS | 1869 | [#5] yui.html Cycles:180 Average:21438.80 Median:4753.21 stddev:31573.56 (664.3%) stddev-sans-first:31623.82 12:32:17 INFO - PROCESS | 1869 | Values: 724.7 781.3 798.1 811.3 867.4 13935.0 14970.2 15521.4 16231.4 16288.5 14722.8 14943.5 14954.2 14957.0 14980.7 712.9 733.9 845.0 878.6 909.0 893.8 896.8 908.4 909.6 912.1 970.4 973.1 974.4 974.7 975.4 969.0 969.1 970.3 971.1 972.6 678.1 695.8 702.5 719.5 722.1 14513.9 15024.6 15059.5 15605.9 16570.9 14916.1 14920.9 14921.5 14931.8 15281.7 11661.0 11756.8 11775.7 11914.0 11970.5 3789.3 3796.3 3810.8 3834.3 3905.6 72008.6 103968.0 106630.5 107494.5 109194.5 11412.3 11462.0 11676.2 11730.5 12063.9 23344.5 23783.2 24382.8 24390.3 24462.4 8010.0 8055.4 8077.4 8099.1 8164.6 4308.2 4327.2 4436.6 4457.2 4542.6 3561.1 3631.1 3659.2 3660.7 3665.0 654.0 662.1 677.3 680.8 688.7 565.5 659.7 663.8 675.6 694.8 9401.4 9762.9 9825.4 9895.0 10271.6 645.7 645.7 662.3 678.0 694.8 4367.4 4627.8 4878.6 4939.6 5013.7 91189.5 98390.0 111642.0 113907.0 114931.0 99956.0 100424.0 100745.0 102491.0 102805.0 88334.6 88725.0 90334.0 90937.0 91268.0 8921.0 9725.0 9779.5 9841.9 9847.2 54573.5 59183.4 59971.0 60047.7 60461.4 72179.0 76202.5 77221.8 78520.4 79815.2 11253.5 11679.3 11704.5 11741.9 11932.9 12447.6 12547.4 12671.7 12806.9 13250.4 10662.4 11052.1 11407.6 11816.6 12103.0 11761.6 12038.1 12157.9 12327.9 12379.5 11776.4 11854.2 11876.2 12266.6 12432.3 9564.8 9591.4 9932.5 10008.3 10039.6 9310.4 9795.4 9820.2 9834.3 9891.5 12:32:17 INFO - PROCESS | 1869 | -------- Summary: end -------- 12:32:17 INFO - PROCESS | 1869 | 12:32:18 INFO - TEST-INFO | 1869: exit 0 12:32:18 INFO - TEST-OK | dromaeo_css | took 1114678ms 12:32:18 INFO - TEST-START | kraken 12:32:18 INFO - operating with platform_type : mac_ 12:32:18 INFO - Initialising browser for kraken test... 12:32:18 INFO - TEST-INFO | started process 1878 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmp2xaSfs/profile http://localhost:49252/getInfo.html) 12:32:20 INFO - PROCESS | 1878 | 2017-03-14 12:32:20.834 plugin-container[1880:10925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 12:32:20 INFO - PROCESS | 1878 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:20 INFO - PROCESS | 1878 | 2017-03-14 12:32:20.835 plugin-container[1880:10925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 12:32:20 INFO - PROCESS | 1878 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:20 INFO - PROCESS | 1878 | 2017-03-14 12:32:20.835 plugin-container[1880:10925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:32:20 INFO - PROCESS | 1878 | __metrics Screen width/height:1600/1200 12:32:20 INFO - PROCESS | 1878 | colorDepth:24 12:32:20 INFO - PROCESS | 1878 | Browser inner width/height: 1024/689 12:32:20 INFO - PROCESS | 1878 | __metrics 12:32:20 ERROR - PROCESS | 1878 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 12:32:21 INFO - TEST-INFO | 1878: exit 0 12:32:21 INFO - Browser initialized. 12:32:21 INFO - Running cycle 1/1 for kraken test... 12:32:21 INFO - TEST-INFO | started process 1881 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmp2xaSfs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:32:24 INFO - PROCESS | 1881 | 2017-03-14 12:32:24.022 plugin-container[1882:11046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 12:32:24 INFO - PROCESS | 1881 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:24 INFO - PROCESS | 1881 | 2017-03-14 12:32:24.022 plugin-container[1882:11046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:32:24 INFO - PROCESS | 1881 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:24 INFO - PROCESS | 1881 | 2017-03-14 12:32:24.022 plugin-container[1882:11046] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:32:33 INFO - PROCESS | 1881 | RSS: Main: 172089344 12:32:33 INFO - PROCESS | 1881 | 12:32:36 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 12:32:37 INFO - PROCESS | 1881 | RSS: Main: 185139200 12:32:37 INFO - PROCESS | 1881 | 12:32:40 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 12:32:41 INFO - PROCESS | 1881 | RSS: Main: 194113536 12:32:41 INFO - PROCESS | 1881 | 12:32:44 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 12:32:44 INFO - PROCESS | 1881 | RSS: Main: 197320704 12:32:44 INFO - PROCESS | 1881 | 12:32:46 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 12:32:47 INFO - PROCESS | 1881 | RSS: Main: 198561792 12:32:47 INFO - PROCESS | 1881 | 12:32:49 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:32:50 INFO - PROCESS | 1881 | RSS: Main: 198569984 12:32:50 INFO - PROCESS | 1881 | 12:32:52 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 12:32:53 INFO - PROCESS | 1881 | RSS: Main: 202768384 12:32:53 INFO - PROCESS | 1881 | 12:32:56 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 12:32:57 INFO - PROCESS | 1881 | RSS: Main: 202977280 12:32:57 INFO - PROCESS | 1881 | 12:32:59 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 12:33:00 INFO - PROCESS | 1881 | RSS: Main: 202039296 12:33:00 INFO - PROCESS | 1881 | 12:33:02 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:33:02 INFO - PROCESS | 1881 | RSS: Main: 201494528 12:33:02 INFO - PROCESS | 1881 | 12:33:04 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:33:05 INFO - PROCESS | 1881 | RSS: Main: 201461760 12:33:05 INFO - PROCESS | 1881 | 12:33:07 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:33:08 INFO - PROCESS | 1881 | RSS: Main: 201400320 12:33:08 INFO - PROCESS | 1881 | 12:33:11 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:33:11 INFO - PROCESS | 1881 | RSS: Main: 201461760 12:33:11 INFO - PROCESS | 1881 | 12:33:14 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:33:15 INFO - PROCESS | 1881 | RSS: Main: 201809920 12:33:15 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 12:33:17 INFO - PROCESS | 1881 | RSS: Main: 203452416 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | __start_tp_report 12:33:17 INFO - PROCESS | 1881 | _x_x_mozilla_page_load 12:33:17 INFO - PROCESS | 1881 | _x_x_mozilla_page_load_details 12:33:17 INFO - PROCESS | 1881 | |i|pagename|runs| 12:33:17 INFO - PROCESS | 1881 | |0;ai-astar;142;177;127;119;119;118;120;118;122;118 12:33:17 INFO - PROCESS | 1881 | |1;audio-beat-detection;171;145;142;142;113;112;115;125;131;114 12:33:17 INFO - PROCESS | 1881 | |2;audio-dft;197;195;196;201;198;194;200;198;198;192 12:33:17 INFO - PROCESS | 1881 | |3;audio-fft;83;60;59;88;59;59;58;58;61;81 12:33:17 INFO - PROCESS | 1881 | |4;audio-oscillator;88;76;106;66;75;103;67;91;103;112 12:33:17 INFO - PROCESS | 1881 | |5;imaging-gaussian-blur;90;87;89;86;91;86;105;93;132;89 12:33:17 INFO - PROCESS | 1881 | |6;imaging-darkroom;121;119;95;99;98;96;97;96;95;95 12:33:17 INFO - PROCESS | 1881 | |7;imaging-desaturate;92;95;100;93;93;92;110;103;93;92 12:33:17 INFO - PROCESS | 1881 | |8;json-parse-financial;57;58;58;57;60;61;60;59;64;69 12:33:17 INFO - PROCESS | 1881 | |9;json-stringify-tinderbox;69;79;105;81;80;75;71;72;73;72 12:33:17 INFO - PROCESS | 1881 | |10;stanford-crypto-aes;86;82;86;82;84;89;102;101;86;89 12:33:17 INFO - PROCESS | 1881 | |11;stanford-crypto-ccm;158;147;136;142;124;134;133;149;216;185 12:33:17 INFO - PROCESS | 1881 | |12;stanford-crypto-pbkdf2;228;209;164;167;165;162;170;164;166;192 12:33:17 INFO - PROCESS | 1881 | |13;stanford-crypto-sha256-iterative;65;67;65;66;63;65;66;68;77;83 12:33:17 INFO - PROCESS | 1881 | __end_tp_report 12:33:17 INFO - PROCESS | 1881 | __start_cc_report 12:33:17 INFO - PROCESS | 1881 | _x_x_mozilla_cycle_collect,928 12:33:17 INFO - PROCESS | 1881 | __end_cc_report 12:33:17 INFO - PROCESS | 1881 | __startTimestamp1489519997348__endTimestamp 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | ------- Summary: start ------- 12:33:17 INFO - PROCESS | 1881 | Number of tests: 14 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#0] ai-astar Cycles:10 Average:128.00 Median:121.00 stddev:18.74 (15.5%) stddev-sans-first:19.18 12:33:17 INFO - PROCESS | 1881 | Values: 142.0 177.0 127.0 119.0 119.0 118.0 120.0 118.0 122.0 118.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:136.50 stddev:19.16 (14.0%) stddev-sans-first:13.81 12:33:17 INFO - PROCESS | 1881 | Values: 171.0 145.0 142.0 142.0 113.0 112.0 115.0 125.0 131.0 114.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#2] audio-dft Cycles:10 Average:196.90 Median:198.00 stddev:2.73 (1.4%) stddev-sans-first:2.89 12:33:17 INFO - PROCESS | 1881 | Values: 197.0 195.0 196.0 201.0 198.0 194.0 200.0 198.0 198.0 192.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.16 (20.1%) stddev-sans-first:11.36 12:33:17 INFO - PROCESS | 1881 | Values: 83.0 60.0 59.0 88.0 59.0 59.0 58.0 58.0 61.0 81.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:71.00 stddev:16.97 (23.9%) stddev-sans-first:18.00 12:33:17 INFO - PROCESS | 1881 | Values: 88.0 76.0 106.0 66.0 75.0 103.0 67.0 91.0 103.0 112.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:89.00 stddev:14.19 (15.9%) stddev-sans-first:14.94 12:33:17 INFO - PROCESS | 1881 | Values: 90.0 87.0 89.0 86.0 91.0 86.0 105.0 93.0 132.0 89.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:96.00 stddev:10.06 (10.5%) stddev-sans-first:7.67 12:33:17 INFO - PROCESS | 1881 | Values: 121.0 119.0 95.0 99.0 98.0 96.0 97.0 96.0 95.0 95.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:92.50 stddev:6.11 (6.6%) stddev-sans-first:6.28 12:33:17 INFO - PROCESS | 1881 | Values: 92.0 95.0 100.0 93.0 93.0 92.0 110.0 103.0 93.0 92.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:60.00 stddev:3.71 (6.2%) stddev-sans-first:3.74 12:33:17 INFO - PROCESS | 1881 | Values: 57.0 58.0 58.0 57.0 60.0 61.0 60.0 59.0 64.0 69.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:74.00 stddev:10.42 (14.1%) stddev-sans-first:10.57 12:33:17 INFO - PROCESS | 1881 | Values: 69.0 79.0 105.0 81.0 80.0 75.0 71.0 72.0 73.0 72.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:86.00 stddev:7.17 (8.3%) stddev-sans-first:7.53 12:33:17 INFO - PROCESS | 1881 | Values: 86.0 82.0 86.0 82.0 84.0 89.0 102.0 101.0 86.0 89.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:148.00 stddev:28.04 (18.9%) stddev-sans-first:29.67 12:33:17 INFO - PROCESS | 1881 | Values: 158.0 147.0 136.0 142.0 124.0 134.0 133.0 149.0 216.0 185.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:168.50 stddev:23.09 (13.7%) stddev-sans-first:16.19 12:33:17 INFO - PROCESS | 1881 | Values: 228.0 209.0 164.0 167.0 165.0 162.0 170.0 164.0 166.0 192.0 12:33:17 INFO - PROCESS | 1881 | 12:33:17 INFO - PROCESS | 1881 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:66.50 stddev:6.36 (9.6%) stddev-sans-first:6.62 12:33:17 INFO - PROCESS | 1881 | Values: 65.0 67.0 65.0 66.0 63.0 65.0 66.0 68.0 77.0 83.0 12:33:17 INFO - PROCESS | 1881 | -------- Summary: end -------- 12:33:17 INFO - PROCESS | 1881 | 12:33:18 INFO - TEST-INFO | 1881: exit 0 12:33:18 INFO - TEST-OK | kraken | took 59978ms 12:33:18 INFO - SUITE-END | took 1174s 12:33:18 INFO - Completed test suite (00:19:34) 12:33:18 INFO - javascript benchmark 12:33:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7548.964372434055, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.133506610352, 3476.5828100627928, 3491.127218195451, 3561.2343346180573, 3566.643335666432, 5918.171455996164, 5919.792807251747, 5943.910841337383, 5944.851378715529, 6068.453839154476, 6018.15745795589, 6060.939390606103, 6071.0, 6088.995315772903, 6093.086037094438, 5349.625526213166, 5371.086915224401, 5444.101723215665, 5455.0453670607685, 5455.236266922634, 4582.19811532982, 5470.042742520063, 5482.91775623366, 5494.203340515643, 5509.669419834822, 4447.822087116511, 4454.955450445486, 5317.468253174671, 5497.065498865202, 5542.889142217154, 4851.611871050342, 5052.797888084472, 5305.522502974713, 5365.7853685852515, 5510.0632892408385, 5314.548263397616, 5457.317835270591, 5482.945170548283, 5516.7517461714315, 5521.282233309684, 6090.33006369299, 6112.694365281763, 6130.693465326761, 6179.3820617937845, 6184.381561843825, 6159.4456498915315, 6197.318294987548, 6340.587861789015, 6372.808815735489, 6379.87240255197, 6320.178376811031, 6323.0, 6371.681415929185, 6453.257875344349, 6493.675316234169, 6193.1329613854095, 6208.875822483525, 6210.0063989761875, 6212.596181248205, 6234.625922444668, 6278.246610406689, 6472.126262954535, 6542.54202205841, 6547.574407663486, 6577.3422657733845, 6208.441240288395, 6210.254769427697, 6244.594101383395, 6289.496840252768, 6300.086487459293, 6409.230892292955, 6419.935800641933, 6423.197100362455, 6434.613923164625, 6440.452561532228, 6272.372762723691, 6304.243490781044, 6324.33594472583, 6333.778317758856, 6337.588056776295, 6174.753009879554, 6193.566450348522, 6227.159333490012, 6247.843803904848, 6256.374362563798, 5129.153689641167, 5165.354330708661, 5182.196759502283, 5182.766775495112, 5189.922151167735, 4219.586438543122, 4566.178087944202, 4598.172328980816, 4620.491745907947, 4622.653301002439, 5937.851553711105, 6001.12983617373, 6071.666058366833, 6122.051082082285, 6172.166757487771, 6231.532635052389, 6335.303116657164, 6337.366263373718, 6351.079093531412, 6356.45970092538, 5965.582409231399, 6075.453209211192, 6097.786577469856, 6105.358937311518, 6127.0, 5590.993621148233, 5660.349059858087, 5673.0923052312255, 5679.574031947538, 5739.827805165852, 6553.967230163818, 6557.868842623217, 6600.636964966877, 6609.372109649768, 6638.701258443381, 6434.742610295536, 6443.355664433519, 6446.033095035782, 6458.7093580789915, 6494.188226471691, 6318.273398559225, 6435.259945106185, 6544.901826472702, 6550.475961922939, 6576.638284894186, 6367.204099487564, 6372.74509019634, 6384.936150638434, 6399.8800209964, 6400.583962042452, 6399.872002560016, 6432.903506447501, 6445.677716114082, 6476.481881449378, 6492.383223593751, 6159.4456498915315, 6179.969100154471, 6221.5333849962335, 6238.75044998195, 6262.499000079892, 5208.9479105209975, 5239.397469291081, 5536.695481748542, 6104.420080092562, 6292.0, 6170.722317495633, 6207.068939658907, 6257.7184026718, 6264.561480696308, 6282.340354262737, 5084.3898732152375, 6186.907196391968, 6204.317525072148, 6215.720292586935, 6297.559170857996, 5019.874503137451, 6250.937490625036, 6258.937410625836, 6267.059941008885, 6284.905726414016], "value": 5859.7823522950985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.16059939002776, 499.3757802746567, 501.0605115408609, 520.4223312123545, 540.2328693255575, 32247.838760806193, 44121.999999999956, 44204.557954420445, 47058.0, 47122.7643861781, 46655.0, 47072.529274707245, 47366.815829604275, 47383.526164738345, 47471.0, 9250.35247532673, 9684.176844968168, 9896.35673681212, 9898.9505052475, 9934.850977235346, 9241.537923103851, 12024.038076953822, 12158.878411215863, 12363.258204507714, 12448.190867593623, 12193.634190974284, 12261.080418968568, 12342.568010119647, 12347.444365003552, 12372.13395062346, 10990.670279891616, 11469.025132863717, 12173.0, 12240.0, 12248.877511224908, 11917.404129793562, 12097.637070887888, 12099.395030248452, 12112.09159313055, 12117.0, 38204.42693359602, 43036.56963430357, 44317.33523997143, 44487.777561112314, 44696.553034469565, 3773.433984902259, 4098.959010409917, 4118.155778065519, 4179.665626749853, 4203.348480985452, 31381.215469613442, 31630.051098467084, 32135.11479674455, 32355.51466728001, 32502.67497325044, 2810.8875644974178, 3317.0, 3505.4391297392294, 3545.539079919594, 3546.5389499365174, 527967.0, 530646.9999999962, 531216.0, 532950.0, 533233.0, 70599.94100088502, 70742.2925770746, 71332.0, 71747.64126179335, 71785.0, 74642.88035679469, 74953.25046749462, 75200.24799751933, 75337.0, 75443.49113017709, 13437.462501500026, 15952.680946381008, 16178.75731864023, 16634.75047874283, 16681.499555013368, 10428.687139385831, 12880.420381082873, 13710.903127749756, 13711.657208569666, 13848.307584620728, 19804.801951980295, 21550.46123846885, 21783.673244901023, 22145.446363841034, 22785.088596455957, 8892.199702026848, 9133.538633818556, 9197.0, 9197.586108625128, 9205.493697846548, 883.4081165619051, 886.1847100667272, 889.7375274294099, 900.945893304835, 937.3344925103116, 31380.529292060637, 32034.359312813613, 32345.35309293801, 32400.027999160062, 32608.67391326104, 931.3387494878712, 949.0699114867331, 1092.5411327242577, 1133.8072527670315, 1165.318288801044, 13390.665233369049, 17151.485455436356, 17361.0, 17566.0, 17759.911200443916, 662692.0, 666575.0, 668544.0, 668628.6568567127, 672031.0, 67758.3224167752, 68410.9738353925, 68583.65708171527, 68649.65675171593, 68769.65615171992, 1461.298576683198, 1498.9850101499064, 1514.1974753380505, 1519.468186134844, 1527.2745445913324, 32527.0, 33392.831250907075, 33649.66350336465, 33653.831730841186, 34165.48751768676, 57339.71330143323, 58028.70985645045, 58294.41705583059, 58378.0, 58379.4162058374, 506475.0, 506829.0, 508161.0, 509283.0, 510605.0, 29092.395014726135, 29321.560176597795, 29469.0, 29734.702652973192, 29842.0, 47589.52410475851, 48050.03899922052, 49846.75076624594, 50065.49934500609, 53488.197677035656, 38705.225895482494, 39175.216495670495, 39441.40837887376, 40085.79957100196, 40743.98140046404, 38667.2266554673, 38934.61065389423, 39171.433142675116, 39761.0, 40321.395179072926, 37524.6247537532, 38572.614273858024, 38606.0, 38665.226695466496, 38846.0, 26315.342116447242, 30762.769489221075, 33653.3269334607, 34949.65050349464, 35174.648253518164, 21854.672179916997, 25608.359791005372, 26113.608295875958, 26167.73832261653, 26600.600990984763], "value": 21599.735669985184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.51763979669556, 395.6913607386238, 402.4978543312912, 406.5588836112818, 428.28904019327905, 16427.178641067927, 20099.899500502495, 20793.584128317423, 21049.0, 21196.788032119694, 21783.45541361467, 22236.332910012687, 22568.66147007796, 22768.886155569242, 23043.654345184837, 5768.163616275638, 5926.585139040269, 5963.045912653976, 6051.0318349064155, 6068.241469816273, 5763.308402991647, 5823.708814559276, 5850.970745146268, 5907.113932910055, 5978.641281523101, 5868.207791948096, 5887.264091988502, 5889.499392551638, 5894.145348924403, 5896.793612223573, 5689.2319536862615, 5779.884402311951, 5811.215485909391, 5876.500497457721, 5914.822555323347, 5203.479652034766, 5216.895662086775, 5216.9739151304, 5224.294720212799, 5236.188390799432, 20278.49303767403, 25923.703814809185, 26313.605295920577, 26339.946402144084, 26441.206763797116, 2743.6433263675594, 2786.9442611147865, 2818.506761316761, 2825.2795537138104, 2835.6171916791177, 15480.225988700633, 15655.452059177875, 15679.216039198109, 16037.35850565976, 16394.0, 1818.5362732503127, 1844.5019844641872, 1864.8041955594604, 1864.9067546622614, 1871.3824437935448, 101809.0, 123107.0, 127312.0, 127368.36315818454, 128816.0, 9988.600455981825, 11282.43587820622, 11298.983091521803, 11712.824307635392, 11837.46731397072, 13322.66693332655, 13895.930520347536, 14730.189839558712, 14767.556973290819, 14772.852271477363, 8792.0, 9056.184943355129, 9111.635534578676, 9156.679516217033, 9287.953560232156, 8231.917680823235, 8582.7555004524, 9210.355275130676, 9236.538173091318, 9403.952980235192, 7228.494005419675, 7259.963700181465, 7352.889706654404, 7355.411567074621, 7357.852842943111, 1999.0509253478033, 2033.4916270932267, 2034.8779073255653, 2037.7758446570865, 2049.139361468187, 1088.5754555723274, 1089.4824958144818, 1106.9778604427866, 1108.38484641025, 1158.7103224193952, 20363.083661234985, 21454.78545214528, 22428.10287588511, 23041.769582304296, 23262.65106023411, 1109.5728144664363, 1137.647329327911, 1144.6373739032658, 1150.1431433582036, 1158.7856246594238, 11663.941680291544, 12102.515899364103, 12111.697207569705, 12199.329036903055, 12219.69450763738, 809591.0, 814607.0, 824803.0, 836308.0, 846076.0, 150534.49465505205, 159846.0, 161698.0, 164695.0, 166970.0, 1043.7181960870673, 1063.9468026598795, 1087.8368244763349, 1098.340995402768, 1108.7837871615118, 16192.514224573282, 19935.0, 20107.698384524538, 20335.983200840194, 20354.389368318974, 63245.36754632395, 63517.0, 63642.0, 63852.68073659602, 65308.0, 91177.544112279, 92176.0, 92461.0, 93127.0, 93740.65648358845, 6677.899831502628, 7498.437617178843, 7654.885176722242, 7818.413618978486, 8203.220694034057, 12117.575884843987, 13353.79969300442, 13461.057725959481, 13767.036307458382, 13770.036097473481, 11319.358692989636, 11320.433978300887, 11673.416329183678, 11676.649700508999, 11957.282563046245, 5374.946250537445, 8339.291160251447, 10987.450627468754, 11153.498092586015, 11170.832437513282, 6181.598196117238, 7686.0, 11505.424728763695, 11527.09802882515, 11946.701332466759, 6498.447631951337, 8422.326213902996, 8487.830243395221, 8858.955705221433, 8927.24118449939, 8767.561621919007, 9689.352810022172, 11414.828777568177, 11512.136589755635, 11983.400829958642], "value": 10291.096962260344, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.279454902529, 1444.0397383856528, 1491.3065424577576, 1493.1638282561767, 1502.0086742749788, 7812.843743125134, 8070.192980701927, 8316.251537361637, 8715.128487151282, 8760.780980475483, 7240.710371585144, 8231.547264900428, 8549.743507694779, 8669.349798765103, 8680.131986801332, 2412.7949124324455, 2448.375664205634, 2453.6196889482158, 2454.7667971542674, 2458.397692565324, 2431.8540887546715, 2450.534398464295, 2457.4839283750434, 2458.91393801217, 2473.183849329717, 2406.097713357491, 2408.6868707068143, 2432.598621227495, 2438.3538362333998, 2445.4986727720775, 2378.631312146603, 2386.916457923973, 2407.434252950555, 2409.3374322061486, 2413.3966508372905, 2371.9525609487887, 2390.5218956208655, 2402.531506356261, 2447.3025187821386, 2451.6445115458337, 8577.957110214471, 8627.051024387312, 8631.956840215822, 8645.697400591012, 8660.913390866073, 5206.687598744087, 5383.407825139231, 5389.595780316492, 5389.892202155974, 5391.164369522691, 3630.2211916161154, 3639.0894780240587, 3663.9084022899374, 3697.371446854041, 3727.422249551324, 1248.6691026877884, 1267.3726505379896, 1291.5156816193928, 1293.9353032348345, 1319.26121372031, 11277.887221127765, 11324.886751132466, 11325.093992480583, 11331.66005019859, 11396.0, 4732.810687572459, 4832.323474713543, 4838.588719958772, 4899.093667671492, 4910.582600478928, 9788.461634610023, 9864.457454839909, 9893.208543316661, 9898.554565044591, 9916.057974492413, 5366.731663416814, 5450.5639548836, 5460.6177567569885, 5467.043267428184, 5481.780728770805, 4478.888027799331, 4714.905701885943, 4920.778564964585, 4962.230854217602, 5002.449730529639, 3956.287868183697, 4022.054817117975, 4051.655609273245, 4053.6959728020515, 4094.28350038742, 1308.6924572725402, 1323.6558494791423, 1324.8277723895833, 1330.973380532384, 1336.9933150334186, 762.7177944160696, 899.3659470073583, 899.4648184330313, 899.7975455522455, 912.8356895758827, 5305.151175811851, 5347.385050719217, 5510.283663123768, 5516.696581687966, 5681.261436013292, 929.3215952354819, 978.1196922769423, 1003.0571263012745, 1011.6155860772859, 1019.0370100255187, 3360.260742636616, 3604.9098772530897, 3622.203115314679, 3639.2721455708966, 3650.014496086042, 7240.710371585077, 10321.74195645115, 10394.948025259826, 10473.057424831803, 10483.947580262049, 10176.643817466502, 10208.979102089732, 10338.224633152544, 10357.482125893675, 10492.685219443429, 7351.595662238628, 7579.317861392501, 8112.0, 8258.587070646563, 8259.967504061991, 4394.011347446799, 5296.576273898156, 5300.5229529341, 5311.282976798083, 5533.6403133796175, 7087.362137407658, 8449.915500844912, 8998.685046023358, 9014.368994170345, 9215.170634642915, 8747.431416957877, 8787.03342632297, 9560.187384072431, 9844.852327214954, 9891.55488003056, 6853.965730171517, 7031.156261248683, 7254.310840470147, 7410.96294518524, 7596.164421913474, 8201.589920503831, 8208.712695055645, 8271.0, 8285.0, 8294.460860044077, 7186.38915692172, 7202.89195662055, 7353.669084891086, 7417.1841097478155, 7427.034485517064, 7127.893081603676, 7231.602261875647, 7277.708891644486, 7279.708811647475, 7372.967784510066, 6576.704048317933, 6692.598444093175, 6696.0960270362875, 6708.6310252936555, 6717.193936727428, 4712.575868171881, 4806.014766972692, 4824.49342818999, 4869.537393947569, 4897.755112244302, 4111.97944010278, 4819.975900120618, 4851.733154676526, 4922.92615610759, 4979.800807967641], "value": 4517.391843204449, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.5789725972677, 353.2634457156828, 364.96897763690083, 374.54492791258616, 378.64029172286325, 4231.70378073535, 5410.48600382963, 5496.9450305496985, 5591.692456914868, 5614.3543492498375, 4450.998525331799, 5312.787488500465, 5461.836144915649, 5491.450854914507, 5522.282103326562, 4314.223439780831, 4356.760378179199, 4362.716423432483, 4370.912581748363, 4384.736915785047, 4153.7507749535125, 4293.914121717564, 4375.365571992075, 4378.576962487188, 4403.4495688039, 3747.4378843173604, 4087.202995415895, 4294.20557196918, 4308.43990281263, 4337.978310108445, 3451.913702157441, 3670.52109626763, 4245.193413251488, 4257.3613957906255, 4287.721298115613, 3332.8668252793923, 3838.9424158637644, 3842.7117966152373, 3845.8653947111998, 3862.748921320105, 4919.483454237289, 5282.339707536558, 5334.839954801362, 5364.0, 5381.0, 4163.854265100737, 4741.241401375763, 4755.857324280242, 4764.595009424203, 4768.2370820668875, 5819.53443724501, 5838.64968101911, 5847.3275573308765, 5854.5023672987845, 5870.765169393219, 3714.368557345231, 3718.8698395556025, 3955.0705584187695, 3974.972763890408, 3986.720929534934, 4770.499097594772, 5196.558292545138, 5316.707581083039, 5873.1190321451695, 6075.544334174954, 3204.951925721116, 3948.506436695413, 4010.258102251093, 4035.4552135461336, 4035.535913369941, 3421.9144521387166, 3670.137517683342, 3762.2287431076566, 4035.4955630546183, 4081.2449696806184, 4765.9523404765505, 4772.0, 4789.473157952622, 4819.49395313494, 4820.132376172252, 4899.828506002273, 4930.531599498114, 5693.231413759172, 5855.355910849803, 5919.378465261172, 1254.2286493806243, 1271.2753730373597, 1301.7331447053332, 1307.5096838685492, 1332.4270563657526, 805.7784109369853, 869.5434896679168, 893.8659201119754, 901.7430032440855, 910.3263584947091, 5113.233015047698, 5168.3539557555305, 5170.405403378584, 5178.533931946143, 5214.869628259324, 4735.195016847144, 5167.974160129251, 5283.9471605284225, 5699.544036477072, 5716.399778023331, 4065.4348075990365, 4343.588333791467, 4659.673822832369, 5128.820491282861, 5232.979568984011, 2725.6047873058296, 2831.40540486496, 2954.9556756648667, 3009.714077162666, 3023.9546406803915, 5131.589472842164, 5886.911696324559, 5950.7917222896995, 5973.49225315853, 5973.581849270509, 5140.357455318085, 5154.046501397253, 5560.884945140567, 5649.4633009864, 5943.435373639497, 5005.649604527648, 5272.420033796279, 5749.9875043734355, 5922.466977971917, 5954.37479064692, 4965.31179399006, 5614.775408983758, 5736.368999409978, 5759.9136012959, 5785.3636100028125, 4852.660313778143, 5590.105583106846, 5652.3217213934595, 5654.547636189177, 5705.600607957404, 5063.468335824833, 5213.661112027706, 5743.9138412923, 5755.971220143872, 5931.228940237742, 3055.7402620777484, 3803.277377298415, 3844.461775351397, 3847.6729477993563, 3865.347246851698, 5470.261514695465, 5792.710364481674, 5966.403359663999, 6045.851288255218, 6057.878842423215, 5345.877365753236, 5364.168553874156, 5429.429909859408, 5580.748866301107, 5777.035807825359, 4499.188007495315, 5119.897602048012, 5136.768845401891, 5307.982966472716, 5737.713114344349, 4768.85547468612, 5584.4084435935565, 5719.141279084231, 5781.739821707949, 5839.364977806221, 5094.7962081516325, 5470.617056806144, 5834.18321435008, 5834.883302334015, 5867.295924488918, 4714.7406892619865, 5159.17453207485, 5787.392323806108, 5799.217105690678, 5822.214001109796], "value": 4148.827782593814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.6811402982687, 781.2616185400917, 798.0782196562972, 811.2779626132743, 867.3624885709005, 13935.0, 14970.17664028478, 15521.379144834207, 16231.431899883506, 16288.51134465965, 14722.779158312635, 14943.476978305762, 14954.177520236382, 14957.0, 14980.7003859923, 712.8823744082221, 733.9302766237199, 844.9788755281106, 878.5870640798846, 909.0182602789005, 893.7542175901647, 896.8296023755498, 908.4458480326995, 909.5543183839937, 912.0924679943465, 970.3595626886256, 973.095021629884, 974.4396971741277, 974.7465658928694, 975.4001289207157, 969.0454901921566, 969.1471505075524, 970.2626004236831, 971.1065819446127, 972.5963725054094, 678.0845858091586, 695.7843068648684, 702.4626160986849, 719.4855678190066, 722.0793488302404, 14513.911456640793, 15024.624384390369, 15059.472918447911, 15605.921970390076, 16570.92289001211, 14916.105033697904, 14920.925395372953, 14921.477748278867, 14931.776023359658, 15281.694366112615, 11661.0, 11756.764864702744, 11775.7056073598, 11914.0, 11970.461329240117, 3789.2989796887387, 3796.316663000659, 3810.768141978312, 3834.3481608126685, 3905.5508616508896, 72008.6399568004, 103968.0, 106630.46684766526, 107494.46252768765, 109194.45402772936, 11412.31526108436, 11462.0, 11676.182667213383, 11730.530778768754, 12063.879361206451, 23344.533109337717, 23783.167589134297, 24382.756172438403, 24390.26829195127, 24462.388440289134, 8010.0, 8055.436119471581, 8077.434579579491, 8099.068607110258, 8164.550949697705, 4308.202982448257, 4327.242732521859, 4436.64506839452, 4457.2199865023495, 4542.568455996675, 3561.0919215599856, 3631.074076110575, 3659.1583935694407, 3660.7071434285726, 3664.9633503665154, 653.950953678476, 662.1359126340076, 677.2550194785696, 680.7685386968355, 688.7486067585395, 565.4967079299427, 659.7163219815526, 663.8074958262147, 675.6486626954055, 694.7568351077184, 9401.388909720983, 9762.853557196648, 9825.4104753715, 9895.0, 10271.640492582725, 645.6674812471581, 645.7255666341786, 662.2549631664377, 677.9525433219726, 694.8054544727485, 4367.411473404711, 4627.791749371219, 4878.634102442331, 4939.604831613462, 5013.699178049329, 91189.5440522793, 98390.01609983951, 111642.0, 113907.0, 114931.0, 99956.00043999612, 100424.0, 100745.0, 102491.0, 102804.97195028103, 88334.55832720795, 88725.0, 90334.0, 90937.0, 91268.0, 8921.009711062263, 9724.993192901038, 9779.511024448891, 9841.852372214566, 9847.162991145833, 54573.45426545842, 59183.40816591779, 59971.0, 60047.69976150091, 60461.39538604557, 72179.0, 76202.47595048179, 77221.84167237383, 78520.42959140899, 79815.2018479831, 11253.549858005588, 11679.299242045505, 11704.473298701409, 11741.882581174421, 11932.940335298268, 12447.626571202878, 12547.372631368213, 12671.683207919506, 12806.935965320114, 13250.403731832284, 10662.413567253876, 11052.115830733683, 11407.600733974272, 11816.586419475278, 12103.031757459557, 11761.647150585497, 12038.097142714156, 12157.939210303892, 12327.93836030814, 12379.504819807467, 11776.411179440822, 11854.229475084092, 11876.168668193144, 12266.570670026505, 12432.254064756144, 9564.808703826024, 9591.376560523544, 9932.453715045453, 10008.349457285252, 10039.648612298535, 9310.394824336396, 9795.412275263507, 9820.214382849497, 9834.262430317613, 9891.505424728879], "value": 7580.016526411301, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 177.0, 127.0, 119.0, 119.0, 118.0, 120.0, 118.0, 122.0, 118.0], "value": 128.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 145.0, 142.0, 142.0, 113.0, 112.0, 115.0, 125.0, 131.0, 114.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 195.0, 196.0, 201.0, 198.0, 194.0, 200.0, 198.0, 198.0, 192.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 59.0, 88.0, 59.0, 59.0, 58.0, 58.0, 61.0, 81.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 76.0, 106.0, 66.0, 75.0, 103.0, 67.0, 91.0, 103.0, 112.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 91.0, 86.0, 105.0, 93.0, 132.0, 89.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 95.0, 99.0, 98.0, 96.0, 97.0, 96.0, 95.0, 95.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 100.0, 93.0, 93.0, 92.0, 110.0, 103.0, 93.0, 92.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 60.0, 61.0, 60.0, 59.0, 64.0, 69.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 105.0, 81.0, 80.0, 75.0, 71.0, 72.0, 73.0, 72.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 82.0, 84.0, 89.0, 102.0, 101.0, 86.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 147.0, 136.0, 142.0, 124.0, 134.0, 133.0, 149.0, 216.0, 185.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 209.0, 164.0, 167.0, 165.0, 162.0, 170.0, 164.0, 166.0, 192.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 66.0, 63.0, 65.0, 66.0, 68.0, 77.0, 83.0], "value": 68.5, "unit": "score"}]}]} 12:33:18 INFO - Return code: 0 12:33:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:33:18 ERROR - # TBPL SUCCESS # 12:33:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:18 INFO - 2017-03-14 12:33:18.637 screenresolution[1886:11433] starting screenresolution argv=screenresolution get 12:33:18 INFO - 2017-03-14 12:33:18.640 screenresolution[1886:11433] Display 0: 1600x1200x32@60 12:33:18 INFO - 2017-03-14 12:33:18.646 screenresolution[1887:11436] starting screenresolution argv=screenresolution list 12:33:18 INFO - Available Modes on Display 0 12:33:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:18 INFO - Intel Iris: 12:33:18 INFO - Chipset Model: Intel Iris 12:33:18 INFO - Type: GPU 12:33:18 INFO - Bus: Built-In 12:33:18 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:18 INFO - Vendor: Intel (0x8086) 12:33:18 INFO - Device ID: 0x0a2e 12:33:18 INFO - Revision ID: 0x0009 12:33:18 INFO - Displays: 12:33:18 INFO - Display: 12:33:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:18 INFO - Display Serial Number: 1600x1200 60 12:33:18 INFO - Main Display: Yes 12:33:18 INFO - Mirror: Off 12:33:18 INFO - Online: Yes 12:33:18 INFO - Rotation: Supported 12:33:18 INFO - Return code: 0 12:33:18 INFO - Running post-action listener: _resource_record_post_action 12:33:18 INFO - [mozharness: 2017-03-14 19:33:18.884819Z] Finished run-tests step (success) 12:33:18 INFO - Running post-run listener: _resource_record_post_run 12:33:18 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 34054144; Write bytes: 422433280; Read time: 206; Write time: 1412 12:33:18 INFO - TinderboxPrint: CPU usage
54.3% 12:33:18 INFO - TinderboxPrint: I/O read bytes / time
34,054,144 / 206 12:33:18 INFO - TinderboxPrint: I/O write bytes / time
422,433,280 / 1,412 12:33:18 INFO - TinderboxPrint: CPU idle
2,176.5 (45.7%) 12:33:18 INFO - TinderboxPrint: CPU system
963.0 (20.2%) 12:33:18 INFO - TinderboxPrint: CPU user
1,622.2 (34.1%) 12:33:18 INFO - TinderboxPrint: Swap in / out
152,072,192 / 0 12:33:18 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109941248; Write bytes: 102293504; Read time: 9919; Write time: 219 12:33:18 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 33124352; Write bytes: 299663872; Read time: 195; Write time: 1134 12:33:19 INFO - Running post-run listener: _upload_blobber_files 12:33:19 INFO - Blob upload gear active. 12:33:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:33:19 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 12:33:19 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'] 12:33:19 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 12:33:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:20 INFO - (blobuploader) - INFO - Done attempting. 12:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:33:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:21 INFO - (blobuploader) - INFO - Done attempting. 12:33:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:33:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:22 INFO - (blobuploader) - INFO - Done attempting. 12:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:33:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:24 INFO - (blobuploader) - INFO - Done attempting. 12:33:24 INFO - (blobuploader) - INFO - Iteration through files over. 12:33:24 INFO - Return code: 0 12:33:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:33:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:33:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/215f4630b9021cb6c638846fdd2c577b6e61fdc568516ae0f58394aa7982885fe68690c9ec08ebdf8b77dc41d338b676d16f91c188559baacc001562e5d96586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec2426165aea57934b17ea9cc9e56d39605485af3bf22be60bbb7b5c3df01958bf95ef7cb8f5ea30c4adfaf8fd4e015036ad25a15c7d628320e79ef22ea4f830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcaae9c28168a977d74cbcbf61c331e3973f17587b71c0b79cea9506df89f33deb581fc64bef5b745e992cadcc0cdc87ac6fa1f4bf9c2e188ef554c89f7db4da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05716fb6fbdbfa75e50463124e97211cfa6cccd220fb4564ea0394ea81f4749922f105d4abf8cd8653a3c6b11d0d4ea4327a30b916e44e5cac7543b28d44a0b7"} 12:33:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:33:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:33:24 INFO - Contents: 12:33:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/215f4630b9021cb6c638846fdd2c577b6e61fdc568516ae0f58394aa7982885fe68690c9ec08ebdf8b77dc41d338b676d16f91c188559baacc001562e5d96586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec2426165aea57934b17ea9cc9e56d39605485af3bf22be60bbb7b5c3df01958bf95ef7cb8f5ea30c4adfaf8fd4e015036ad25a15c7d628320e79ef22ea4f830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcaae9c28168a977d74cbcbf61c331e3973f17587b71c0b79cea9506df89f33deb581fc64bef5b745e992cadcc0cdc87ac6fa1f4bf9c2e188ef554c89f7db4da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05716fb6fbdbfa75e50463124e97211cfa6cccd220fb4564ea0394ea81f4749922f105d4abf8cd8653a3c6b11d0d4ea4327a30b916e44e5cac7543b28d44a0b7"} 12:33:24 INFO - Running post-run listener: copy_logs_to_upload_dir 12:33:24 INFO - Copying logs to upload dir... 12:33:24 INFO - mkdir: /builds/slave/test/build/upload/logs 12:33:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.029194 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-14 12:33:24.865938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 12:33:24.869617) ========= 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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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/215f4630b9021cb6c638846fdd2c577b6e61fdc568516ae0f58394aa7982885fe68690c9ec08ebdf8b77dc41d338b676d16f91c188559baacc001562e5d96586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec2426165aea57934b17ea9cc9e56d39605485af3bf22be60bbb7b5c3df01958bf95ef7cb8f5ea30c4adfaf8fd4e015036ad25a15c7d628320e79ef22ea4f830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcaae9c28168a977d74cbcbf61c331e3973f17587b71c0b79cea9506df89f33deb581fc64bef5b745e992cadcc0cdc87ac6fa1f4bf9c2e188ef554c89f7db4da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05716fb6fbdbfa75e50463124e97211cfa6cccd220fb4564ea0394ea81f4749922f105d4abf8cd8653a3c6b11d0d4ea4327a30b916e44e5cac7543b28d44a0b7"} build_url:https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/VIONsWV7S327b8jhqi1thw/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/215f4630b9021cb6c638846fdd2c577b6e61fdc568516ae0f58394aa7982885fe68690c9ec08ebdf8b77dc41d338b676d16f91c188559baacc001562e5d96586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec2426165aea57934b17ea9cc9e56d39605485af3bf22be60bbb7b5c3df01958bf95ef7cb8f5ea30c4adfaf8fd4e015036ad25a15c7d628320e79ef22ea4f830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dcaae9c28168a977d74cbcbf61c331e3973f17587b71c0b79cea9506df89f33deb581fc64bef5b745e992cadcc0cdc87ac6fa1f4bf9c2e188ef554c89f7db4da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05716fb6fbdbfa75e50463124e97211cfa6cccd220fb4564ea0394ea81f4749922f105d4abf8cd8653a3c6b11d0d4ea4327a30b916e44e5cac7543b28d44a0b7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 12:33:24.904562) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:33:24.904918) ========= 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.d3DiBDL2Lp/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.Z51iEtpuvE/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 12:33:24.954131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 12:33:24.954449) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 12:33:24.954776) ========= ========= Total master_lag: 2.10 =========