builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0189 starttime: 1493394235.49 results: success (0) buildid: 20170428071320 builduid: a84235e3f6f74d42b5aca54a03a20462 revision: fcc6c261b2c9df3684bc6cd347437dc336ab0335 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.493605) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.494558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.495183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.522411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.523114) ========= 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.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-04-28 08:43:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.85M=0.001s 2017-04-28 08:43:55 (7.85 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097875 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.650928) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.651685) ========= 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.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.021194 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:55.704099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 08:43:55.705028) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fcc6c261b2c9df3684bc6cd347437dc336ab0335 --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 fcc6c261b2c9df3684bc6cd347437dc336ab0335 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-04-28 08:43:55,791 truncating revision to first 12 chars 2017-04-28 08:43:55,792 Setting DEBUG logging. 2017-04-28 08:43:55,792 attempt 1/10 2017-04-28 08:43:55,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fcc6c261b2c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 08:43:56,544 unpacking tar archive at: mozilla-beta-fcc6c261b2c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.003171 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 08:43:56.738218) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:56.739002) ========= script_repo_revision: fcc6c261b2c9df3684bc6cd347437dc336ab0335 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:56.739928) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:56.740644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 08:43:56.762256) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-28 08:43:56.763019) ========= /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.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:43:56 INFO - MultiFileLogger online at 20170428 08:43:56 in /builds/slave/test 08:43:56 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 08:43:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:56 INFO - {'append_to_log': False, 08:43:56 INFO - 'base_work_dir': '/builds/slave/test', 08:43:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:43:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:56 INFO - 'branch': 'Mozilla-Beta', 08:43:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:56 INFO - 'config_files': ('talos/mac_config.py',), 08:43:56 INFO - 'default_actions': ('clobber', 08:43:56 INFO - 'read-buildbot-config', 08:43:56 INFO - 'download-and-extract', 08:43:56 INFO - 'populate-webroot', 08:43:56 INFO - 'create-virtualenv', 08:43:56 INFO - 'install', 08:43:56 INFO - 'run-tests'), 08:43:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:56 INFO - 'download_minidump_stackwalk': True, 08:43:56 INFO - 'download_symbols': 'ondemand', 08:43:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:56 INFO - 'gecko_profile': False, 08:43:56 INFO - 'gecko_profile_interval': 0, 08:43:56 INFO - 'installer_path': 'installer.exe', 08:43:56 INFO - 'log_level': 'info', 08:43:56 INFO - 'log_name': 'talos', 08:43:56 INFO - 'log_to_console': True, 08:43:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:56 INFO - 'opt_config_files': (), 08:43:56 INFO - 'pip_index': False, 08:43:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:56 INFO - 'cmd': ('bash', 08:43:56 INFO - '-c', 08:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:56 INFO - 'enabled': True, 08:43:56 INFO - 'halt_on_failure': False, 08:43:56 INFO - 'name': 'check_screen_resolution'},), 08:43:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:56 INFO - 'cmd': ('bash', 08:43:56 INFO - '-c', 08:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:56 INFO - 'enabled': True, 08:43:56 INFO - 'halt_on_failure': False, 08:43:56 INFO - 'name': 'check_screen_resolution'},), 08:43:56 INFO - 'run_cmd_checks_enabled': True, 08:43:56 INFO - 'suite': 'dromaeojs-e10s', 08:43:56 INFO - 'system_bits': '32', 08:43:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:56 INFO - 'title': 't-yosemite-r7-0189', 08:43:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:56 INFO - 'use_talos_json': True, 08:43:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:56 INFO - 'work_dir': 'build'} 08:43:56 INFO - [mozharness: 2017-04-28 15:43:56.976657Z] Running clobber step. 08:43:56 INFO - Running pre-action listener: _resource_record_pre_action 08:43:56 INFO - Running main action method: clobber 08:43:56 INFO - rmtree: /builds/slave/test/build 08:43:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:58 INFO - Running post-action listener: _resource_record_post_action 08:43:58 INFO - [mozharness: 2017-04-28 15:43:58.517611Z] Finished clobber step (success) 08:43:58 INFO - [mozharness: 2017-04-28 15:43:58.517744Z] Running read-buildbot-config step. 08:43:58 INFO - Running pre-action listener: _resource_record_pre_action 08:43:58 INFO - Running main action method: read_buildbot_config 08:43:58 INFO - Using buildbot properties: 08:43:58 INFO - { 08:43:58 INFO - "project": "", 08:43:58 INFO - "product": "firefox", 08:43:58 INFO - "script_repo_revision": "production", 08:43:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:43:58 INFO - "repository": "", 08:43:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:43:58 INFO - "buildid": "20170428071320", 08:43:58 INFO - "pgo_build": "False", 08:43:58 INFO - "basedir": "/builds/slave/test", 08:43:58 INFO - "buildnumber": 24, 08:43:58 INFO - "slavename": "t-yosemite-r7-0189", 08:43:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:43:58 INFO - "platform": "yosemite_r7", 08:43:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:43:58 INFO - "branch": "mozilla-beta", 08:43:58 INFO - "repo_path": "releases/mozilla-beta", 08:43:58 INFO - "revision": "fcc6c261b2c9df3684bc6cd347437dc336ab0335", 08:43:58 INFO - "stage_platform": "macosx64", 08:43:58 INFO - "builduid": "a84235e3f6f74d42b5aca54a03a20462", 08:43:58 INFO - "slavebuilddir": "test" 08:43:58 INFO - } 08:43:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:43:58 INFO - Running post-action listener: _resource_record_post_action 08:43:58 INFO - [mozharness: 2017-04-28 15:43:58.520987Z] Finished read-buildbot-config step (success) 08:43:58 INFO - [mozharness: 2017-04-28 15:43:58.521104Z] Running download-and-extract step. 08:43:58 INFO - Running pre-action listener: _resource_record_pre_action 08:43:58 INFO - Running main action method: download_and_extract 08:43:58 INFO - mkdir: /builds/slave/test/build/tests 08:43: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')]} 08:43:58 INFO - trying https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:43:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 08:43:59 INFO - Downloaded 1165 bytes. 08:43:59 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:43:59 INFO - Using the following test package requirements: 08:43:59 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:43:59 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:43:59 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:43:59 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:43:59 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:43:59 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:43:59 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:43:59 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:43:59 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:43:59 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:59 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:43:59 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:43:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:43:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:59 INFO - Fetch https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:44:01 INFO - Expected file size: 51222349 08:44:01 INFO - Obtained file size: 51222349 08:44:17 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:44:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:44:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:44:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:44:19 INFO - Expected file size: 51222349 08:44:19 INFO - Obtained file size: 51222349 08:44:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:44:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:44:34 INFO - Fetch https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:44:35 INFO - Expected file size: 14404449 08:44:35 INFO - Obtained file size: 14404449 08:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:35 INFO - trying https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:35 INFO - Downloading https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 08:44:37 INFO - Downloaded 59539122 bytes. 08:44:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:37 INFO - mkdir: /builds/slave/test/properties 08:44:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:37 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:37 INFO - Contents: 08:44:37 INFO - build_url:https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:38 INFO - Running post-action listener: _resource_record_post_action 08:44:38 INFO - Running post-action listener: set_extra_try_arguments 08:44:38 INFO - [mozharness: 2017-04-28 15:44:38.379958Z] Finished download-and-extract step (success) 08:44:38 INFO - [mozharness: 2017-04-28 15:44:38.380106Z] Running populate-webroot step. 08:44:38 INFO - Running pre-action listener: _resource_record_pre_action 08:44:38 INFO - Running main action method: populate_webroot 08:44:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:38 INFO - '--tppagecycles', 08:44:38 INFO - '7'], 08:44:38 INFO - 'tests': ['tsvgm']}}, 08:44:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['damp', 'tps']}, 08:44:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'tests': ['damp', 'tps']}, 08:44:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['dromaeo_dom']}, 08:44:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['basic_compositor_video']}, 08:44:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['a11yr', 08:44:38 INFO - 'ts_paint', 08:44:38 INFO - 'tpaint', 08:44:38 INFO - 'sessionrestore', 08:44:38 INFO - 'sessionrestore_no_auto_restore', 08:44:38 INFO - 'tabpaint']}, 08:44:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['tsvgx', 08:44:38 INFO - 'tsvgr_opacity', 08:44:38 INFO - 'tart', 08:44:38 INFO - 'tscrollx', 08:44:38 INFO - 'cart', 08:44:38 INFO - 'tsvg_static']}, 08:44:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:38 INFO - 'tsvgr_opacity', 08:44:38 INFO - 'tart', 08:44:38 INFO - 'tscrollx', 08:44:38 INFO - 'cart', 08:44:38 INFO - 'tsvg_static']}, 08:44:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'talos_options': ['--disable-e10s'], 08:44:38 INFO - 'tests': ['tp5o']}, 08:44:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'talos_options': ['--disable-e10s', 08:44:38 INFO - '--xperf_path', 08:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:38 INFO - 'tests': ['tp5n']}, 08:44:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:38 INFO - 'talos_options': ['--xperf_path', 08:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:38 INFO - 'tests': ['tp5n']}}, 08:44:38 INFO - 'talos.zip': {'path': '', 08:44:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:38 INFO - Running post-action listener: _resource_record_post_action 08:44:38 INFO - [mozharness: 2017-04-28 15:44:38.399063Z] Finished populate-webroot step (success) 08:44:38 INFO - [mozharness: 2017-04-28 15:44:38.399179Z] Running create-virtualenv step. 08:44:38 INFO - Running pre-action listener: _resource_record_pre_action 08:44:38 INFO - Running main action method: create_virtualenv 08:44:38 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:38 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 08:44:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:38 INFO - Using real prefix '/tools/python27' 08:44:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:39 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:42 INFO - Installing pip.................done. 08:44:42 INFO - Return code: 0 08:44:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:42 INFO - Reading from file tmpfile_stdout 08:44:42 INFO - Output received: 08:44:42 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) 08:44:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:42 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 08:44:42 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 08:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:42 INFO - 'HOME': '/Users/cltbld', 08:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:42 INFO - 'LOGNAME': 'cltbld', 08:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:42 INFO - 'MOZ_NO_REMOTE': '1', 08:44:42 INFO - 'NO_EM_RESTART': '1', 08:44:42 INFO - 'PAGER': '/bin/cat', 08:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:42 INFO - 'PWD': '/builds/slave/test', 08:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:42 INFO - 'SHELL': '/bin/bash', 08:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:42 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:42 INFO - 'USER': 'cltbld', 08:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:42 INFO - 'XPC_FLAGS': '0x0', 08:44:42 INFO - 'XPC_SERVICE_NAME': '0', 08:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:42 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 08:44:42 INFO - Cleaning up... 08:44:42 INFO - Return code: 0 08:44:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:42 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 08:44:42 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 08:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:42 INFO - 'HOME': '/Users/cltbld', 08:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:42 INFO - 'LOGNAME': 'cltbld', 08:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:42 INFO - 'MOZ_NO_REMOTE': '1', 08:44:42 INFO - 'NO_EM_RESTART': '1', 08:44:42 INFO - 'PAGER': '/bin/cat', 08:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:42 INFO - 'PWD': '/builds/slave/test', 08:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:42 INFO - 'SHELL': '/bin/bash', 08:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:42 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:42 INFO - 'USER': 'cltbld', 08:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:42 INFO - 'XPC_FLAGS': '0x0', 08:44:42 INFO - 'XPC_SERVICE_NAME': '0', 08:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:42 INFO - Downloading/unpacking psutil>=3.1.1 08:44:42 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 08:44:42 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 08:44:42 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 08:44:42 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 08:44:42 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 08:44:42 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 08:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:46 INFO - Installing collected packages: psutil 08:44:46 INFO - Running setup.py install for psutil 08:44:46 INFO - building 'psutil._psutil_osx' extension 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 INFO - building 'psutil._psutil_posix' extension 08:44:46 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 08:44:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:47 INFO - ^ 08:44:47 INFO - 1 warning generated. 08:44:47 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 08:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:47 INFO - Successfully installed psutil 08:44:47 INFO - Cleaning up... 08:44:47 INFO - Return code: 0 08:44:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:47 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:47 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 08:44:47 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 08:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:47 INFO - 'HOME': '/Users/cltbld', 08:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:47 INFO - 'LOGNAME': 'cltbld', 08:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:47 INFO - 'MOZ_NO_REMOTE': '1', 08:44:47 INFO - 'NO_EM_RESTART': '1', 08:44:47 INFO - 'PAGER': '/bin/cat', 08:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:47 INFO - 'PWD': '/builds/slave/test', 08:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:47 INFO - 'SHELL': '/bin/bash', 08:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:47 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:47 INFO - 'USER': 'cltbld', 08:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:47 INFO - 'XPC_FLAGS': '0x0', 08:44:47 INFO - 'XPC_SERVICE_NAME': '0', 08:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:47 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 08:44:47 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 08:44:47 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 08:44:47 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 08:44:47 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 08:44:47 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 08:44:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:50 INFO - Installing collected packages: mozsystemmonitor 08:44:50 INFO - Running setup.py install for mozsystemmonitor 08:44:50 INFO - Successfully installed mozsystemmonitor 08:44:50 INFO - Cleaning up... 08:44:50 INFO - Return code: 0 08:44:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:50 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:50 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 08:44:50 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 08:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:50 INFO - 'HOME': '/Users/cltbld', 08:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:50 INFO - 'LOGNAME': 'cltbld', 08:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:50 INFO - 'MOZ_NO_REMOTE': '1', 08:44:50 INFO - 'NO_EM_RESTART': '1', 08:44:50 INFO - 'PAGER': '/bin/cat', 08:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:50 INFO - 'PWD': '/builds/slave/test', 08:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:50 INFO - 'SHELL': '/bin/bash', 08:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:50 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:50 INFO - 'USER': 'cltbld', 08:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:50 INFO - 'XPC_FLAGS': '0x0', 08:44:50 INFO - 'XPC_SERVICE_NAME': '0', 08:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:50 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:50 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 08:44:50 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 08:44:50 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 08:44:50 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 08:44:50 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 08:44:50 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 08:44:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 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 08:44:53 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:53 INFO - warning: no files found matching '*.txt' 08:44:53 INFO - no previously-included directories found matching 'build' 08:44:53 INFO - no previously-included directories found matching 'dist' 08:44:53 INFO - no previously-included directories found matching '.git*' 08:44:53 INFO - Installing collected packages: jsonschema, functools32 08:44:53 INFO - Running setup.py install for functools32 08:44:53 INFO - warning: no files found matching '*.txt' 08:44:53 INFO - no previously-included directories found matching 'build' 08:44:53 INFO - no previously-included directories found matching 'dist' 08:44:53 INFO - no previously-included directories found matching '.git*' 08:44:54 INFO - Successfully installed jsonschema functools32 08:44:54 INFO - Cleaning up... 08:44:54 INFO - Return code: 0 08:44:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:54 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')]} 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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')]} 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:54 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 08:44:54 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 08:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:54 INFO - 'HOME': '/Users/cltbld', 08:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:54 INFO - 'LOGNAME': 'cltbld', 08:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:54 INFO - 'MOZ_NO_REMOTE': '1', 08:44:54 INFO - 'NO_EM_RESTART': '1', 08:44:54 INFO - 'PAGER': '/bin/cat', 08:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:54 INFO - 'PWD': '/builds/slave/test', 08:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:54 INFO - 'SHELL': '/bin/bash', 08:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:54 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:54 INFO - 'USER': 'cltbld', 08:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:54 INFO - 'XPC_FLAGS': '0x0', 08:44:54 INFO - 'XPC_SERVICE_NAME': '0', 08:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:55 INFO - Cleaning up... 08:44:55 INFO - Return code: 0 08:44:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:44:55 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 08:44:55 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 08:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:55 INFO - 'HOME': '/Users/cltbld', 08:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:55 INFO - 'LOGNAME': 'cltbld', 08:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:55 INFO - 'MOZ_NO_REMOTE': '1', 08:44:55 INFO - 'NO_EM_RESTART': '1', 08:44:55 INFO - 'PAGER': '/bin/cat', 08:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:55 INFO - 'PWD': '/builds/slave/test', 08:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:55 INFO - 'SHELL': '/bin/bash', 08:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:44:55 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:55 INFO - 'USER': 'cltbld', 08:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:55 INFO - 'XPC_FLAGS': '0x0', 08:44:55 INFO - 'XPC_SERVICE_NAME': '0', 08:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:45:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:45:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:01 INFO - Downloading docopt-0.6.1.tar.gz 08:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:45:01 INFO - Installing collected packages: blobuploader, requests, docopt 08:45:01 INFO - Running setup.py install for blobuploader 08:45:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:45:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:45:01 INFO - Running setup.py install for requests 08:45:01 INFO - Running setup.py install for docopt 08:45:02 INFO - Successfully installed blobuploader requests docopt 08:45:02 INFO - Cleaning up... 08:45:02 INFO - Return code: 0 08:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:02 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:45:02 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 08:45:02 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 08:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:02 INFO - 'HOME': '/Users/cltbld', 08:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:02 INFO - 'LOGNAME': 'cltbld', 08:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:02 INFO - 'MOZ_NO_REMOTE': '1', 08:45:02 INFO - 'NO_EM_RESTART': '1', 08:45:02 INFO - 'PAGER': '/bin/cat', 08:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:02 INFO - 'PWD': '/builds/slave/test', 08:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:02 INFO - 'SHELL': '/bin/bash', 08:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:45:02 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:02 INFO - 'USER': 'cltbld', 08:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:02 INFO - 'XPC_FLAGS': '0x0', 08:45:02 INFO - 'XPC_SERVICE_NAME': '0', 08:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-wZ76Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-iLPuIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-BRBN1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-cx391j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-mzZKOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-q1pdIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-iRN17Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-TpUfl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-MABDLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-GEOFYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-WhWQLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-ATDDuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-c0xT9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-NZdijc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-mONiKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:03 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-uKeFoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:04 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-u7_ryR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:04 INFO - Running setup.py install for manifestparser 08:45:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozcrash 08:45:04 INFO - Running setup.py install for mozdebug 08:45:04 INFO - Running setup.py install for mozdevice 08:45:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozfile 08:45:04 INFO - Running setup.py install for mozhttpd 08:45:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozinfo 08:45:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozInstall 08:45:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozleak 08:45:05 INFO - Running setup.py install for mozlog 08:45:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for moznetwork 08:45:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozprocess 08:45:05 INFO - Running setup.py install for mozprofile 08:45:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:06 INFO - Running setup.py install for mozrunner 08:45:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:06 INFO - Running setup.py install for mozscreenshot 08:45:06 INFO - Running setup.py install for moztest 08:45:06 INFO - Running setup.py install for mozversion 08:45:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:06 INFO - Cleaning up... 08:45:06 INFO - Return code: 0 08:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:06 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:45:06 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 08:45:06 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 08:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:06 INFO - 'HOME': '/Users/cltbld', 08:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:06 INFO - 'LOGNAME': 'cltbld', 08:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:06 INFO - 'MOZ_NO_REMOTE': '1', 08:45:06 INFO - 'NO_EM_RESTART': '1', 08:45:06 INFO - 'PAGER': '/bin/cat', 08:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:06 INFO - 'PWD': '/builds/slave/test', 08:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:06 INFO - 'SHELL': '/bin/bash', 08:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:45:06 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:06 INFO - 'USER': 'cltbld', 08:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:06 INFO - 'XPC_FLAGS': '0x0', 08:45:06 INFO - 'XPC_SERVICE_NAME': '0', 08:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-F5EoQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-pmbMho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-jg5k5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-jM678z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-oyXnev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-pGkvgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-j83j49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-3Mcma_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-0Exert-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-I9Pvdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-D0T1ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-GrnQ_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-8eA6WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-6nptl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-UmI04r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-cf9Dy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:08 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)) 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:08 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-kN2Cl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 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)) 08:45:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:11 INFO - Downloading blessings-1.6.tar.gz 08:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:11 INFO - Installing collected packages: blessings 08:45:11 INFO - Running setup.py install for blessings 08:45:11 INFO - Successfully installed blessings 08:45:11 INFO - Cleaning up... 08:45:11 INFO - Return code: 0 08:45:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:12 INFO - Reading from file tmpfile_stdout 08:45:12 INFO - Current package versions: 08:45:12 INFO - blessings == 1.6 08:45:12 INFO - blobuploader == 1.2.4 08:45:12 INFO - docopt == 0.6.1 08:45:12 INFO - functools32 == 3.2.3-2 08:45:12 INFO - jsonschema == 2.5.1 08:45:12 INFO - manifestparser == 1.1 08:45:12 INFO - mozInstall == 1.12 08:45:12 INFO - mozcrash == 1.0 08:45:12 INFO - mozdebug == 0.1 08:45:12 INFO - mozdevice == 0.49 08:45:12 INFO - mozfile == 1.2 08:45:12 INFO - mozhttpd == 0.7 08:45:12 INFO - mozinfo == 0.9 08:45:12 INFO - mozleak == 0.1 08:45:12 INFO - mozlog == 3.4 08:45:12 INFO - moznetwork == 0.27 08:45:12 INFO - mozprocess == 0.25 08:45:12 INFO - mozprofile == 0.28 08:45:12 INFO - mozrunner == 6.13 08:45:12 INFO - mozscreenshot == 0.1 08:45:12 INFO - mozsystemmonitor == 0.3 08:45:12 INFO - moztest == 0.8 08:45:12 INFO - mozversion == 1.4 08:45:12 INFO - psutil == 3.1.1 08:45:12 INFO - requests == 1.2.3 08:45:12 INFO - wsgiref == 0.1.2 08:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:12 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')]} 08:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 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')]} 08:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:45:12 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 08:45:12 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 08:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:12 INFO - 'HOME': '/Users/cltbld', 08:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:12 INFO - 'LOGNAME': 'cltbld', 08:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:12 INFO - 'MOZ_NO_REMOTE': '1', 08:45:12 INFO - 'NO_EM_RESTART': '1', 08:45:12 INFO - 'PAGER': '/bin/cat', 08:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:12 INFO - 'PWD': '/builds/slave/test', 08:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:12 INFO - 'SHELL': '/bin/bash', 08:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:45:12 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:12 INFO - 'USER': 'cltbld', 08:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:12 INFO - 'XPC_FLAGS': '0x0', 08:45:12 INFO - 'XPC_SERVICE_NAME': '0', 08:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 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)) 08:45:12 INFO - Cleaning up... 08:45:12 INFO - Return code: 0 08:45:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:12 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')]} 08:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 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')]} 08:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 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 0x10bfaab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd18dac150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, '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.jUrMgHrik7/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.nkUos9GYis/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:45:12 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 08:45:12 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 08:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:12 INFO - 'HOME': '/Users/cltbld', 08:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:12 INFO - 'LOGNAME': 'cltbld', 08:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:12 INFO - 'MOZ_NO_REMOTE': '1', 08:45:12 INFO - 'NO_EM_RESTART': '1', 08:45:12 INFO - 'PAGER': '/bin/cat', 08:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:12 INFO - 'PWD': '/builds/slave/test', 08:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:12 INFO - 'SHELL': '/bin/bash', 08:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:45:12 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:12 INFO - 'USER': 'cltbld', 08:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:12 INFO - 'XPC_FLAGS': '0x0', 08:45:12 INFO - 'XPC_SERVICE_NAME': '0', 08:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:12 INFO - Cleaning up... 08:45:12 INFO - Return code: 0 08:45:12 INFO - Running post-action listener: _resource_record_post_action 08:45:12 INFO - Running post-action listener: _start_resource_monitoring 08:45:12 INFO - Starting resource monitoring. 08:45:12 INFO - [mozharness: 2017-04-28 15:45:12.720597Z] Finished create-virtualenv step (success) 08:45:12 INFO - [mozharness: 2017-04-28 15:45:12.721603Z] Running install step. 08:45:12 INFO - Running pre-action listener: _resource_record_pre_action 08:45:12 INFO - Running main action method: install 08:45:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:12 INFO - Reading from file tmpfile_stdout 08:45:12 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:13 INFO - Reading from file tmpfile_stdout 08:45:13 INFO - Output received: 08:45:13 INFO - Usage: mozinstall [options] installer 08:45:13 INFO - Options: 08:45:13 INFO - -h, --help show this help message and exit 08:45:13 INFO - -d DEST, --destination=DEST 08:45:13 INFO - Directory to install application into. [default: 08:45:13 INFO - "/builds/slave/test"] 08:45:13 INFO - --app=APP Application being installed. [default: firefox] 08:45:13 INFO - mkdir: /builds/slave/test/build/application 08:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:45:27 INFO - Reading from file tmpfile_stdout 08:45:27 INFO - Output received: 08:45:27 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:45:27 INFO - Running post-action listener: _resource_record_post_action 08:45:27 INFO - [mozharness: 2017-04-28 15:45:27.870559Z] Finished install step (success) 08:45:27 INFO - [mozharness: 2017-04-28 15:45:27.870711Z] Running run-tests step. 08:45:27 INFO - Running pre-action listener: _resource_record_pre_action 08:45:27 INFO - Running main action method: run_tests 08:45:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:27 INFO - 2017-04-28 08:45:27.885 screenresolution[1895:14291] starting screenresolution argv=screenresolution get 08:45:27 INFO - 2017-04-28 08:45:27.888 screenresolution[1895:14291] Display 0: 1600x1200x32@60 08:45:27 INFO - 2017-04-28 08:45:27.894 screenresolution[1896:14294] starting screenresolution argv=screenresolution list 08:45:27 INFO - Available Modes on Display 0 08:45:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:28 INFO - Intel Iris: 08:45:28 INFO - Chipset Model: Intel Iris 08:45:28 INFO - Type: GPU 08:45:28 INFO - Bus: Built-In 08:45:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:28 INFO - Vendor: Intel (0x8086) 08:45:28 INFO - Device ID: 0x0a2e 08:45:28 INFO - Revision ID: 0x0009 08:45:28 INFO - Displays: 08:45:28 INFO - Display: 08:45:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:28 INFO - Display Serial Number: 1600x1200 60 08:45:28 INFO - Main Display: Yes 08:45:28 INFO - Mirror: Off 08:45:28 INFO - Online: Yes 08:45:28 INFO - Rotation: Supported 08:45:28 INFO - Return code: 0 08:45:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:28 INFO - Python 2.7.3 08:45:28 INFO - Return code: 0 08:45:28 INFO - grabbing minidump binary from tooltool 08:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:28 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 0x10bd88d30>, '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 0x10bcafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd7dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:28 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 08:45:28 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 08:45:28 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 08:45:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:28 INFO - Return code: 0 08:45:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:28 INFO - ENV: RUST_BACKTRACE is now 1 08:45:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:28 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/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 08:45:28 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/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 08:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jUrMgHrik7/Render', 08:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:28 INFO - 'HOME': '/Users/cltbld', 08:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:28 INFO - 'LOGNAME': 'cltbld', 08:45:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:28 INFO - 'MOZ_NO_REMOTE': '1', 08:45:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:28 INFO - 'NO_EM_RESTART': '1', 08:45:28 INFO - 'PAGER': '/bin/cat', 08:45:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:28 INFO - 'PWD': '/builds/slave/test', 08:45:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:28 INFO - 'RUST_BACKTRACE': '1', 08:45:28 INFO - 'SHELL': '/bin/bash', 08:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkUos9GYis/Listeners', 08:45:28 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:28 INFO - 'USER': 'cltbld', 08:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:28 INFO - 'XPC_FLAGS': '0x0', 08:45:28 INFO - 'XPC_SERVICE_NAME': '0', 08:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:28 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/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 08:45:28 INFO - mozversion application_buildid: 20170428071320 08:45:28 INFO - mozversion application_changeset: fcc6c261b2c9df3684bc6cd347437dc336ab0335 08:45:28 INFO - mozversion application_display_name: Firefox 08:45:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:28 INFO - mozversion application_name: Firefox 08:45:28 INFO - mozversion application_remotingname: firefox 08:45:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:28 INFO - mozversion application_vendor: Mozilla 08:45:28 INFO - mozversion application_version: 54.0 08:45:28 INFO - mozversion platform_buildid: 20170428071320 08:45:28 INFO - mozversion platform_changeset: fcc6c261b2c9df3684bc6cd347437dc336ab0335 08:45:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:28 INFO - mozversion platform_version: 54.0 08:45:28 INFO - using testdate: 1493394328 08:45:28 INFO - actual date: 1493394328 08:45:28 INFO - starting webserver on 'localhost:52652' 08:45:28 INFO - SUITE-START | Running 2 tests 08:45:28 INFO - TEST-START | dromaeo_css 08:45:28 INFO - operating with platform_type : mac_ 08:45:28 INFO - Initialising browser for dromaeo_css test... 08:45:28 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpXMJWF5/profile http://localhost:52652/getInfo.html) 08:45:31 INFO - PROCESS | 1906 | 2017-04-28 08:45:31.399 plugin-container[1909:14446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 08:45:31 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:31 INFO - PROCESS | 1906 | 2017-04-28 08:45:31.400 plugin-container[1909:14446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:45:31 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:31 INFO - PROCESS | 1906 | 2017-04-28 08:45:31.400 plugin-container[1909:14446] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:31 INFO - PROCESS | 1906 | __metrics Screen width/height:1600/1200 08:45:31 INFO - PROCESS | 1906 | colorDepth:24 08:45:31 INFO - PROCESS | 1906 | Browser inner width/height: 1024/689 08:45:31 INFO - PROCESS | 1906 | __metrics 08:45:31 ERROR - PROCESS | 1906 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:45:32 INFO - TEST-INFO | 1906: exit 0 08:45:32 INFO - Browser initialized. 08:45:32 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:32 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpXMJWF5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:34 INFO - PROCESS | 1910 | 2017-04-28 08:45:34.492 plugin-container[1911:14577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:45:34 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:34 INFO - PROCESS | 1910 | 2017-04-28 08:45:34.492 plugin-container[1911:14577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 08:45:34 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:34 INFO - PROCESS | 1910 | 2017-04-28 08:45:34.492 plugin-container[1911:14577] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:44 INFO - PROCESS | 1910 | RSS: Main: 174436352 08:45:44 INFO - PROCESS | 1910 | 08:48:46 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52652/tests/dromaeo/cssquery-ext.html) 08:48:48 INFO - PROCESS | 1910 | RSS: Main: 193961984 08:48:48 INFO - PROCESS | 1910 | 08:51:50 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-ext.html (next: http://localhost:52652/tests/dromaeo/cssquery-jquery.html) 08:51:51 INFO - PROCESS | 1910 | RSS: Main: 205037568 08:51:51 INFO - PROCESS | 1910 | 08:54:53 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52652/tests/dromaeo/cssquery-mootools.html) 08:54:53 INFO - PROCESS | 1910 | RSS: Main: 207618048 08:54:53 INFO - PROCESS | 1910 | 08:57:55 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52652/tests/dromaeo/cssquery-prototype.html) 08:57:56 INFO - PROCESS | 1910 | RSS: Main: 210771968 08:57:56 INFO - PROCESS | 1910 | 09:00:59 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52652/tests/dromaeo/cssquery-yui.html) 09:01:00 INFO - PROCESS | 1910 | RSS: Main: 213233664 09:01:00 INFO - PROCESS | 1910 | 09:04:02 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:52652/tests/dromaeo/cssquery-yui.html (next: http://localhost:52652/tests/dromaeo/cssquery-dojo.html) 09:04:03 INFO - PROCESS | 1910 | RSS: Main: 211054592 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | __start_tp_report 09:04:03 INFO - PROCESS | 1910 | _x_x_mozilla_page_load 09:04:03 INFO - PROCESS | 1910 | _x_x_mozilla_page_load_details 09:04:03 INFO - PROCESS | 1910 | |i|pagename|runs| 09:04:03 INFO - PROCESS | 1910 | |0;dojo.html;3630.945535816963;3632.8001959892213;3641.8907432777028;3647.854085836567;3652.926941461169;6193.8761224775535;6249.968750156243;6250.656213908233;6263.872502949467;6269;6273.9334313166755;6276.466500347476;6305.747770089202;6306;6315.9368406315925;5558.110702287625;5586.91619625706;5604.859878503029;5630.887382252373;5683.5169010634145;5640.887182256352;5668.404817494165;5670.4896559309655;5675.5459563234845;5680.289963754531;5394.973025134869;5411.18832175173;5467.480589344026;5649.49154576088;5659.8585035374035;5492.395836457987;5621.072523033695;5638.097904335327;5687.578105473632;5694.088945768657;5528.640638358494;5606.831795046155;5608.186812912105;5620.971895140539;5644.068728659766;6256.874862502725;6260;6293.433590976788;6306.810795676137;6308.93691063088;6288.213973253343;6338.492920566389;6340.619562826199;6468.41784239416;6653.7005834737065;6679.432248258855;6730.798076057726;6753.088333075021;6755.324467553254;6774.491913106487;5326.97336513315;6251.812445626593;6290.779822706275;6356.173697419352;6356.682165891687;6568.67156642166;6583.3416658334745;6904.585724856524;6926.238113807477;6943.51395402317;6451.806445806634;6456.418922297016;6567.1462709848365;6606.6036037837885;6608.735650574019;5495.258140151109;6709.329067093252;6758.83102922421;6766.18805743314;6777.2883847195335;6286.465650419766;6573.769918052845;6581.638009909501;6665.366790154928;6673.165854268216;6429.485641148697;6488.578242414228;6493.285738568754;6539.08452816606;6544.54188206821;5292.391374991848;5304.761285742151;5309.65487243328;5317.54800841925;5348.652337598121;4691.53084691535;4723.4331880174595;4745.430548334222;4757.191277482836;4777.713337199779;6164.260288765377;6244.438000579969;6283.340249273851;6304.558680892385;6330.588511746721;6744.932550674529;6770.424513916274;6785.830354241085;6793.354631310018;6795.626240556817;4935.3090567320605;6213.161223234805;6216.502679785606;6333.271673757485;6355.269144048401;5868.589198756132;5871.647701137946;5891.1457838614015;5901.232839730807;5931.406859314034;6967.581945083311;7013.824654383681;7014.193367762568;7026.543274687129;7028.156621205488;6383.202099737533;6691.5315927884585;6726.6972986216715;6744.887093629693;6810.795676129724;6715.429188518835;6829.248782634006;6832.180138383425;6856.897146542906;6874.518783685094;5674.659520428788;6479.902801457888;6601.53789234749;6609.504287178577;6623.238327592196;5154.329937108152;6637.966810166112;6682.131322927989;6692.364225398579;6721.059051732664;6249.9062963831375;6296.622202667854;6366.745330186926;6390.520710946605;6403.423691867754;5812.33158186797;6127.908081378694;6480.902786458301;6484.157059582224;6534.1178940844275;6256.749730010931;6308.14839996613;6344.270408903035;6367.681615919279;6373.87252254943;6263.6241825490615;6291.307956124731;6444.871102578016;6448.193975753031;6490.870182596227;6382.648954307558;6390.233172019201;6391.48868090561;6395;6404.679766011587 09:04:03 INFO - PROCESS | 1910 | |1;ext.html;750.8235564642308;820.6635279535391;849.6006876767918;952.4808979106386;964.127464644497;38701.80649096754;39736.40395394068;41316.38025429621;41342;41423.37864932025;34888.476672849865;37392.813035934785;39582.60417395825;39651.40522892152;39664.80167599158;8300.962379702538;8421.24208821206;11143.997040266375;11435.542578296858;11688.064954803594;9487.620495180185;9568.52157392131;11332.036776873934;11389.487473063731;11431.599894003712;9528.380655257386;11387.829182562267;11391.145664075186;11419.600313989013;11421.371824549646;11349.773004539904;11372.431378431129;11397;11413.31520108792;11416.600418985296;11045.282056666372;11062.557497700163;11098;11100.777984440347;11116.221864469491;35026.474602880975;35060.9481715549;35124.12189695278;35133.47299790505;35293.47059794105;3824.541055073409;3859.614038596118;3869.129445874684;3883.456316115746;3896.415537669344;26054.478910421876;26107.608385874228;26580;26619.467610647873;27194.456110877672;3222.581064461605;3278.704916557497;3287.8849240276713;3300.785448945811;3307.5038744188323;335973.64026359667;337159;359660;364084;380227.0988645067;44339.77830110829;44401.77799110984;44770;44809.10381792411;44895.32657010082;46015.30977035347;46227.76886115548;46311.76844115825;46327;46406.767966159954;14231.288435578179;14292.571222863331;14310;14310.926680499004;14343.426262949573;10487.790244195074;12390.938045309716;12624.81062784059;12629.810552841715;12670.936645316899;11980.101492388107;12030.398480076135;12045.457954392074;12100.33448160342;12141.210821296587;4614.400127983341;5511.641743286754;5519.337679478488;5548.306461692288;5614.2701448811395;1108.229780302698;1109.755853712182;1155.1798223261578;1216.4282787090053;1225.3138242584182;26687.733122668913;26810.32974175622;27078.86460567724;27246.318842029108;27291.86354068217;997.3018886779139;1088.5156105532963;1258.6475786779715;1265.7974724044109;1305.419088505606;13500.017390526658;14032.298385080909;14254.002219844511;14274.357653905392;14684.339204735812;430969;431155.68844311155;432447;432632;432716;49326.50673493291;55542.722286389115;55689.44310556923;55963.88072238532;55991.160132598045;1456.2791418247823;1476.963075923089;1509.5169545745475;1512.100300321329;1514.560777374571;24646.753532464445;26612.600810987464;26647.86676066607;26666.86666566655;26679.7332026685;44368.778156109016;45552.316715248635;45709.77145114253;47968;48526;463027;469180;469914;471241;471722;24649.507009860063;25130.12044578431;28913.56629650515;30142.54786178165;30277.697223028368;38861.417078743274;39413.01467463336;39599.20801583894;39791.602083979946;40070.39894401528;27703.58444623292;28425;29016.098390160816;29257.97597084092;29699.10902672923;28745.712542874302;28840.711592884643;29737.405251895274;29795;29837.70162298349;33400.83299583584;33916.982490525326;33981.66018339785;34000;34287.628494860925;27797.58303625407;27828;28403.14790556204;28726.989110762046;28829;22419.43951401163;23259.767402326437;23460.061597535907;23771.52456950886;23848.642270366305 09:04:03 INFO - PROCESS | 1910 | |2;jquery.html;428.27407544212554;429.3924097402176;438.86492215372203;440.93606427068084;453.7164272329794;20631.484212894666;21672.02475888587;22010.559788804236;22496.7626780527;22736.88631556844;22643.886780566074;23064.538709225806;23369.298921032354;23458.530829383402;23710.695911724848;6745.730170793162;6826.24911259761;6964.721411143548;7026.543274687129;7103.928960710378;6944.4444444444325;6999.965000175018;7004.684789184474;7023.157221133446;7365.889511657329;7776.261255180777;7830.060392752849;7841.059072911258;7842.451028428012;7863.882041769378;7697.153313135551;7713.961430192841;7840.059192896861;7873.8031549211155;7890.921090789076;6850.828729281758;7416.703331866773;7660.119086305091;7662.272084151997;7757.495762775401;26098.34754131143;26227.344316392053;26417.867910660516;26661.600075998875;26944.92220311185;3042.224232820639;3045.1016949999803;3150.354177393629;3168.746500279972;3190.9361812763614;15724.685506289925;16469;16561.08914009729;16575.41986030495;16797;2143.217725530186;2151.118041602935;2191.8904054797194;2195.429188411025;2274.0789980058103;167153;169731;175767;180584.19415805806;181322.0933895322;19189.328373507138;20632.484187895123;21209.36371908845;21225.36323910285;21387.8930605346;30760.230994225323;31068.533971990437;31256.374872502423;31261.687383126333;31321.8433907829;10699.090577300862;10753.193510486744;10890;10907.618233361953;11094.611688590861;9176.632934682671;9212.769680758034;9233.21517670992;9284.535773211312;9424.670136545188;9221.400608960395;9477.857832132524;9532.380395274286;9574.180905627909;9579.952100239454;3680.8895733128047;4052.5947405259826;4055.9391609125887;4067.654249388835;4088.386741988666;1079.8488211650376;1097.4238524774398;1099.5821587796745;1106.9335839849634;1161.465725766157;21899.452513686967;22250.261832327502;22426.215082472034;22437.297845855577;22449;1007.4559737741685;1010.751721623794;1103.784761971424;1116.4473585575192;1153.3944679043402;10657.680269591925;10929.781404371868;11589.76820463586;11695.590654327058;11697.649070527897;395169;554253;727975;765174;777402.1129894315;121296.39351803185;122102.72640457719;122296;149195;164217;1235.437875766524;1356.8982326325565;1371.2732251906307;1438.6834896322582;1442.6439147201597;18821.811781882552;19175.616487670446;19428;19431.514212144808;19560.510987225432;64568.031479528785;64807.35192648013;65747.67126164338;65856.34143658694;66565;74600.62699686467;94999.52500237455;95435.52282238821;95467;96526.51736741271;11534.250273732181;11668.066554675775;11756.706762255973;11834.585789497327;11972.461239244081;11301.830472543083;12868.806967895676;12990.805137923127;13172;13331.33343332849;6636.601803891782;9678.322517423712;11030.235162373981;11640.359780211831;11682.415879205835;8637.395382323177;9074.909250907407;10364.896351036394;10463.529141188817;10826;11515.712107197387;11533.365664888512;11663.825042624538;11741.76516469683;11756.294622322688;11519.424028798694;11683.240589361665;11850.170488065753;11894.702632434259;11916.702082448008;9537.856932145885;11174.217804753589;11467.655970320904;11481.827772583585;11570.479328430072 09:04:03 INFO - PROCESS | 1910 | |3;mootools.html;1445.8626430489107;1595.2023988005997;1611.186350892799;1618.0938674342365;1632.8938618989764;9606.759831004229;9823.263255255848;11181.993620574147;11878.643640690781;11901.226420282686;10946.616868409586;11246.831297530543;11521.539138434473;11558.53765849365;11634.476448559813;2472.7403622619636;2500;2505.073122944513;2508.347829564317;2511.4977004599154;2435.7807797298237;2484.763947424992;2485.030837973192;2486.8880900359436;2489.240781561623;2454.0797201049604;2466.2231397109945;2470.22188010776;2472.085328428484;2486.875656217182;2413.746556611548;2413.8793060347;2422.9394265143337;2423.503181847717;2429.0405289910386;2418.1294733895784;2422.200673777667;2423.09134074722;2447.070113356931;2474.183519438581;11492.712682182928;11736.35450050247;11736.88263117375;11753.294802311888;11756.588519401865;5964.612300200516;6057.42454466825;6121.387861213888;6136.355682653302;6149.877002459926;3363.831808409594;3557.3596752584526;3580.8030558319283;3703.370427027412;3780.092777733352;1242.9067819913453;1273.096101767746;1310.2335133946654;1321.823577016456;1338.8795008449285;15712.68574628501;15800;15832.287547060294;15837.208139592974;15881.523554293273;4597.3333866589155;4748.501407352247;4780.904381912412;4800.807967681323;4833.154198015333;11668.649940501798;11676.824847627293;11701;11707.473163707484;11723.706907327385;5832.795852145155;5846.970765146232;5848.707564621752;5866.208061911588;5878.882422351529;4537.8411755588395;5147.867469156779;5434.782608695687;5458.754356053908;5484.040292948718;3433.9354800012075;3958.511382176958;4035.53591337;4124.072083781125;4132.442120313779;995.9153471954965;1051.7738686182508;1057.8519007338978;1061.861957945462;1065.094669530908;822.6133717152928;864.7967727584013;890.2432932007741;908.123660667459;920.2408013388981;6672.466202703772;6757.459403247728;6790.796276111724;6909.585424874524;6926.9307306926285;1042.5360714482135;1062.2776511971933;1069.3263244156133;1074.978500429987;1077.1651969723528;4268.87193384199;4307.978460107742;4366.8034938427845;4457.86626401202;4587.105514424722;13538.661533461742;13703.314834258248;14129.293535323193;14145.646358841112;14162.433502659778;12694.174878632859;12877.4205160768;14003.649908752159;14052.367643455864;14087.788683169761;10483.737906552396;10694.41180735067;10769.730756730987;10787.83818242727;10789.730256743644;6649.168853893263;6652.73389064432;6755.831104222237;6790.42281406086;6855.5886646801355;10967.890321096687;12643.683907902008;12803.679908002374;12835.294058826854;12860.614181574567;11087.667369978913;12968.546100886424;13124.212547247196;13191.934040329737;13275.269860157397;8604.182602652738;8669.219770220458;8812.559372031501;8818.11818818113;8828.779280518038;10437.165026797686;10441;10551.630692925712;10562.577496900345;10589.9470502647;8618.396712230084;8786.472811631322;8839.911600883908;8882.289416846766;8912.910870891208;8547.8717819234;8597.914020859711;8620.13798620133;8656.523891186045;8686.565671716262;7854.332381747616;8043.557604331818;8055.677772889018;8063.992000999875;8064.8790268147195;5872.559558033079;5877.382874798084;5915.792947246826;6051.939480605138;6083.908741368971;6004.849878753066;6132;6184.257889053342;6233.127362169391;6283.3716628338625 09:04:03 INFO - PROCESS | 1910 | |4;prototype.html;343.6666433559447;388.9027743064234;392.8075243130867;402.3341370032595;403.12924083978595;4017.7991100445006;4427.09244604856;4792.6165906727465;4866.513348665132;5289.497497737713;4587.853036740815;4855.878603034926;5183.53348198662;5259.027079990205;5295.834916318413;3743.906402339936;4063.4717486726686;4400.283889283182;4551.7724113794175;4685.390899183101;3989.464056338316;4059.4113853491226;4200.432941552897;4549.135664223803;4709.929351059737;3865.903352416184;4112.403701463286;4269.573042695737;4316.892077698051;4695.3426520287185;4321.58944900234;4371.431713877208;4455.264881294566;4559.703619264737;4626.629869610423;3972.748584195969;4374.321980093091;4380.78096095194;4384.386185933972;4398.3402489626815;5201.323827902348;5221.530062294411;5248.475152484759;5261.736913154327;5264.0523006487465;4811.4707382187935;5133.742233152864;5237.973810130963;5405.5135037846785;5469.808556700536;4895;5067.391912970467;5107.004134193834;5515.621094726318;5738.38903298998;4647.34937108805;4747.8575642730775;4774.686961085695;4831.589314908202;5007.298978143063;5248.8425347239645;5278.97360513195;5395.78416863325;5635.703113678503;5965.91051134229;4649.418822647169;4820.614350851994;4848.660593758403;4850.563449289511;4862.51374862518;4911.975440122848;4922.729249891218;4933;4976.154053810861;5003.9241563064115;4266.613350661048;4308.91382172361;5060.608332708461;5102.744862756922;5232.450592687789;4599.563041511051;4902.90194196114;4986.227134794112;5662.745176467069;5686.232358631614;1482.4811316039516;1483.5104415542844;1483.5845963130341;1666;1779.5817982774038;1291.122037014839;1394.2889126545535;1421.772516397392;1445.8554144585667;1458.5770126663149;4979.975100124549;5406.945930540644;5433.864153396117;5439.694473326374;5472.20653005312;4981.003799240167;5842.766289348463;5873.412658734178;5928.762849486058;5977.432143946318;4689.413823272091;4756.9524304756505;5291.259223708684;5355.544778693777;5427.810026649048;2563.910263140781;2566.691996960377;2776.6251556039674;2907.854607269628;3053.9463884960096;5742.59801813869;5946.732397042057;5964.880702385928;6104.26748790148;6206.658633775095;4392.538783427758;4773.546513081252;4887.266909963463;5378.838634840961;6035.698215089228;5251.947480525146;5351.491608297206;5839.416058394127;5978.641281523122;5984.611000284967;4765.928511072406;5286.709230992333;5427.891442171213;5505.476979686923;5860.853478663067;5021.644156077911;5194.298769666047;5472.480114389126;5480.205370221216;5759.683217423082;5316.521513063688;5586.469285417878;5690.914636280377;6164.825115065834;6168.629882207082;4123.278426275329;5033.597312215087;5043.495650435074;5119.411267704261;5127.4103478099005;5233.220704960332;5600.431879073866;5685.872769976579;5805.680687562224;5829.679367634821;4750.8337208197545;5013.816226696002;5052.953553810713;5115.974420127875;5412.891742165213;4571.977140114278;4582.977085114687;4982.636720213914;5349.197620356822;5713;5505;5575.916361254665;5587.357453892854;5608.308012154107;5633.605647604629;4772.3557319761385;5050.621203409685;5336.839894803162;5468.726563671721;5732.484076433141;4664.813407463664;5045.426832315472;5046.697198168122;5394.919076213781;5549.972250138723 09:04:03 INFO - PROCESS | 1910 | |5;yui.html;751.6429695894451;760.9619519024048;769.788308215241;812.4597142899968;921.2261700171856;15640.84359156408;17045.232964516596;18176.36382726605;19051.809481905177;19104.235830566762;16310.429134980279;18991.715124273112;19050.142743576536;19119.235230590795;19156.521086972833;1006.5068116622875;1041.1306559023224;1041.9114375278111;1042.3329069395593;1043.8695163104612;985.5614251657979;1005.9094681478665;1013.3109485549824;1013.3869009249408;1015.1624909449733;1057.6826951914434;1063.8191507443753;1067.813132701778;1068.0547715272;1068.8931106889327;1055.8363453664695;1059.131512160033;1064.5475672839013;1064.920130990175;1066.6746642274104;786.1391776005265;788.9566073865935;789.8420315936778;792.9730998357142;793.5318162284223;15687.607809804733;17021.063841488714;17504.474865754048;17564.824351756448;18507.537311567183;14466.782998255034;14688.706225875529;14783.408663653441;14939.626509337244;15097.245137743179;11998.220115692453;12008.159428839985;12108;12267.263964162179;12752.234865908074;3837.424386342043;3839.5968423315708;3917.5494818096;3919.470871432377;3937.9803100984313;89977.55011224968;98644.89211352057;99509.99999999927;107936.22223222228;124499.75500245063;11247.550097996153;11446.885531144748;11448.656540303804;11695.532178712927;11765.294082355085;23654;23884;24277;24278.87860560686;24342.148024819046;7901.881471777928;7931.56376399292;7958.522488650699;8000.719974800854;8036.557989310527;4568.70303430276;4663.510331415153;4706.176419126639;4709.434867815884;4720.834770783006;2608.956417433042;3033.7409974860184;3152.810831350126;3237.1259759864706;3371.258323168899;606.2452246952485;609.2810483629271;625.2122325869346;626.5238418801653;632.6488798716664;633.2938773267773;714.2143641993869;724.1527412926888;747.7943565519525;763.8084588042672;10238.744031399274;10727.356358618508;10902.945485272523;11274.549018039186;11299.94350028261;506.8808829924965;586.0447470622858;586.9937675365562;670.7752902777527;750.5534207146738;4571.1543364477675;4927;5287.23335116406;5294.57643388528;5358.5177334039345;73022.26977730262;80269;81251.5937420309;99022.50488747511;101775;62441.687791560755;66357;78749.60625196838;84687.57656211803;84858;64649.353506465275;68796;75930.62034689901;79457.20542794498;81092;10934.125269978224;11426.542938282708;11476.598319058792;11574;11681.941590291994;49033.75483122562;49808;49999.25001124913;52461.21308180457;52815.735921321684;64229;64664.67667661631;65427.69144617176;66313.66843165753;66343;13180.604581862559;13300;13326.666833329244;13408;13765.931170344084;15232.627795527069;15259.923700381427;15272.083674979536;15638.765418518504;16280.592985175464;14480.348384322942;14484;14868.405263789327;14892.776608350667;14954.700905982038;13404.396802144121;14133.858661413255;14510.274486275855;14669.853301466848;14810.703785924006;13715.862841371458;14289.356978935764;14561.34473948696;14634.34145463478;15071.623209419853;10326.741831454274;10539.683809485728;10648.680539583826;10859.782804344028;11056.281341712764;10888.564457421931;11451.083913287084;11815.350155741517;11999.220050696676;12202.267863928193 09:04:03 INFO - PROCESS | 1910 | __end_tp_report 09:04:03 INFO - PROCESS | 1910 | __start_cc_report 09:04:03 INFO - PROCESS | 1910 | _x_x_mozilla_cycle_collect,390 09:04:03 INFO - PROCESS | 1910 | __end_cc_report 09:04:03 INFO - PROCESS | 1910 | __startTimestamp1493395443156__endTimestamp 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | ------- Summary: start ------- 09:04:03 INFO - PROCESS | 1910 | Number of tests: 6 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#0] dojo.html Cycles:165 Average:6141.35 Median:6315.94 stddev:689.98 (10.9%) stddev-sans-first:663.39 09:04:03 INFO - PROCESS | 1910 | Values: 3630.9 3632.8 3641.9 3647.9 3652.9 6193.9 6250.0 6250.7 6263.9 6269.0 6273.9 6276.5 6305.7 6306.0 6315.9 5558.1 5586.9 5604.9 5630.9 5683.5 5640.9 5668.4 5670.5 5675.5 5680.3 5395.0 5411.2 5467.5 5649.5 5659.9 5492.4 5621.1 5638.1 5687.6 5694.1 5528.6 5606.8 5608.2 5621.0 5644.1 6256.9 6260.0 6293.4 6306.8 6308.9 6288.2 6338.5 6340.6 6468.4 6653.7 6679.4 6730.8 6753.1 6755.3 6774.5 5327.0 6251.8 6290.8 6356.2 6356.7 6568.7 6583.3 6904.6 6926.2 6943.5 6451.8 6456.4 6567.1 6606.6 6608.7 5495.3 6709.3 6758.8 6766.2 6777.3 6286.5 6573.8 6581.6 6665.4 6673.2 6429.5 6488.6 6493.3 6539.1 6544.5 5292.4 5304.8 5309.7 5317.5 5348.7 4691.5 4723.4 4745.4 4757.2 4777.7 6164.3 6244.4 6283.3 6304.6 6330.6 6744.9 6770.4 6785.8 6793.4 6795.6 4935.3 6213.2 6216.5 6333.3 6355.3 5868.6 5871.6 5891.1 5901.2 5931.4 6967.6 7013.8 7014.2 7026.5 7028.2 6383.2 6691.5 6726.7 6744.9 6810.8 6715.4 6829.2 6832.2 6856.9 6874.5 5674.7 6479.9 6601.5 6609.5 6623.2 5154.3 6638.0 6682.1 6692.4 6721.1 6249.9 6296.6 6366.7 6390.5 6403.4 5812.3 6127.9 6480.9 6484.2 6534.1 6256.7 6308.1 6344.3 6367.7 6373.9 6263.6 6291.3 6444.9 6448.2 6490.9 6382.6 6390.2 6391.5 6395.0 6404.7 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#1] ext.html Cycles:180 Average:54924.91 Median:29816.35 stddev:111894.10 (375.3%) stddev-sans-first:112134.07 09:04:03 INFO - PROCESS | 1910 | Values: 750.8 820.7 849.6 952.5 964.1 38701.8 39736.4 41316.4 41342.0 41423.4 34888.5 37392.8 39582.6 39651.4 39664.8 8301.0 8421.2 11144.0 11435.5 11688.1 9487.6 9568.5 11332.0 11389.5 11431.6 9528.4 11387.8 11391.1 11419.6 11421.4 11349.8 11372.4 11397.0 11413.3 11416.6 11045.3 11062.6 11098.0 11100.8 11116.2 35026.5 35060.9 35124.1 35133.5 35293.5 3824.5 3859.6 3869.1 3883.5 3896.4 26054.5 26107.6 26580.0 26619.5 27194.5 3222.6 3278.7 3287.9 3300.8 3307.5 335973.6 337159.0 359660.0 364084.0 380227.1 44339.8 44401.8 44770.0 44809.1 44895.3 46015.3 46227.8 46311.8 46327.0 46406.8 14231.3 14292.6 14310.0 14310.9 14343.4 10487.8 12390.9 12624.8 12629.8 12670.9 11980.1 12030.4 12045.5 12100.3 12141.2 4614.4 5511.6 5519.3 5548.3 5614.3 1108.2 1109.8 1155.2 1216.4 1225.3 26687.7 26810.3 27078.9 27246.3 27291.9 997.3 1088.5 1258.6 1265.8 1305.4 13500.0 14032.3 14254.0 14274.4 14684.3 430969.0 431155.7 432447.0 432632.0 432716.0 49326.5 55542.7 55689.4 55963.9 55991.2 1456.3 1477.0 1509.5 1512.1 1514.6 24646.8 26612.6 26647.9 26666.9 26679.7 44368.8 45552.3 45709.8 47968.0 48526.0 463027.0 469180.0 469914.0 471241.0 471722.0 24649.5 25130.1 28913.6 30142.5 30277.7 38861.4 39413.0 39599.2 39791.6 40070.4 27703.6 28425.0 29016.1 29258.0 29699.1 28745.7 28840.7 29737.4 29795.0 29837.7 33400.8 33917.0 33981.7 34000.0 34287.6 27797.6 27828.0 28403.1 28727.0 28829.0 22419.4 23259.8 23460.1 23771.5 23848.6 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#2] jquery.html Cycles:180 Average:40650.42 Median:22443.15 stddev:111508.44 (496.8%) stddev-sans-first:111780.35 09:04:03 INFO - PROCESS | 1910 | Values: 428.3 429.4 438.9 440.9 453.7 20631.5 21672.0 22010.6 22496.8 22736.9 22643.9 23064.5 23369.3 23458.5 23710.7 6745.7 6826.2 6964.7 7026.5 7103.9 6944.4 7000.0 7004.7 7023.2 7365.9 7776.3 7830.1 7841.1 7842.5 7863.9 7697.2 7714.0 7840.1 7873.8 7890.9 6850.8 7416.7 7660.1 7662.3 7757.5 26098.3 26227.3 26417.9 26661.6 26944.9 3042.2 3045.1 3150.4 3168.7 3190.9 15724.7 16469.0 16561.1 16575.4 16797.0 2143.2 2151.1 2191.9 2195.4 2274.1 167153.0 169731.0 175767.0 180584.2 181322.1 19189.3 20632.5 21209.4 21225.4 21387.9 30760.2 31068.5 31256.4 31261.7 31321.8 10699.1 10753.2 10890.0 10907.6 11094.6 9176.6 9212.8 9233.2 9284.5 9424.7 9221.4 9477.9 9532.4 9574.2 9580.0 3680.9 4052.6 4055.9 4067.7 4088.4 1079.8 1097.4 1099.6 1106.9 1161.5 21899.5 22250.3 22426.2 22437.3 22449.0 1007.5 1010.8 1103.8 1116.4 1153.4 10657.7 10929.8 11589.8 11695.6 11697.6 395169.0 554253.0 727975.0 765174.0 777402.1 121296.4 122102.7 122296.0 149195.0 164217.0 1235.4 1356.9 1371.3 1438.7 1442.6 18821.8 19175.6 19428.0 19431.5 19560.5 64568.0 64807.4 65747.7 65856.3 66565.0 74600.6 94999.5 95435.5 95467.0 96526.5 11534.3 11668.1 11756.7 11834.6 11972.5 11301.8 12868.8 12990.8 13172.0 13331.3 6636.6 9678.3 11030.2 11640.4 11682.4 8637.4 9074.9 10364.9 10463.5 10826.0 11515.7 11533.4 11663.8 11741.8 11756.3 11519.4 11683.2 11850.2 11894.7 11916.7 9537.9 11174.2 11467.7 11481.8 11570.5 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#3] mootools.html Cycles:180 Average:6866.67 Median:2486.88 stddev:4295.85 (172.7%) stddev-sans-first:4288.59 09:04:03 INFO - PROCESS | 1910 | Values: 1445.9 1595.2 1611.2 1618.1 1632.9 9606.8 9823.3 11182.0 11878.6 11901.2 10946.6 11246.8 11521.5 11558.5 11634.5 2472.7 2500.0 2505.1 2508.3 2511.5 2435.8 2484.8 2485.0 2486.9 2489.2 2454.1 2466.2 2470.2 2472.1 2486.9 2413.7 2413.9 2422.9 2423.5 2429.0 2418.1 2422.2 2423.1 2447.1 2474.2 11492.7 11736.4 11736.9 11753.3 11756.6 5964.6 6057.4 6121.4 6136.4 6149.9 3363.8 3557.4 3580.8 3703.4 3780.1 1242.9 1273.1 1310.2 1321.8 1338.9 15712.7 15800.0 15832.3 15837.2 15881.5 4597.3 4748.5 4780.9 4800.8 4833.2 11668.6 11676.8 11701.0 11707.5 11723.7 5832.8 5847.0 5848.7 5866.2 5878.9 4537.8 5147.9 5434.8 5458.8 5484.0 3433.9 3958.5 4035.5 4124.1 4132.4 995.9 1051.8 1057.9 1061.9 1065.1 822.6 864.8 890.2 908.1 920.2 6672.5 6757.5 6790.8 6909.6 6926.9 1042.5 1062.3 1069.3 1075.0 1077.2 4268.9 4308.0 4366.8 4457.9 4587.1 13538.7 13703.3 14129.3 14145.6 14162.4 12694.2 12877.4 14003.6 14052.4 14087.8 10483.7 10694.4 10769.7 10787.8 10789.7 6649.2 6652.7 6755.8 6790.4 6855.6 10967.9 12643.7 12803.7 12835.3 12860.6 11087.7 12968.5 13124.2 13191.9 13275.3 8604.2 8669.2 8812.6 8818.1 8828.8 10437.2 10441.0 10551.6 10562.6 10589.9 8618.4 8786.5 8839.9 8882.3 8912.9 8547.9 8597.9 8620.1 8656.5 8686.6 7854.3 8043.6 8055.7 8064.0 8064.9 5872.6 5877.4 5915.8 6051.9 6083.9 6004.8 6132.0 6184.3 6233.1 6283.4 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#4] prototype.html Cycles:180 Average:4703.12 Median:5038.55 stddev:1267.12 (25.1%) stddev-sans-first:1227.71 09:04:03 INFO - PROCESS | 1910 | Values: 343.7 388.9 392.8 402.3 403.1 4017.8 4427.1 4792.6 4866.5 5289.5 4587.9 4855.9 5183.5 5259.0 5295.8 3743.9 4063.5 4400.3 4551.8 4685.4 3989.5 4059.4 4200.4 4549.1 4709.9 3865.9 4112.4 4269.6 4316.9 4695.3 4321.6 4371.4 4455.3 4559.7 4626.6 3972.7 4374.3 4380.8 4384.4 4398.3 5201.3 5221.5 5248.5 5261.7 5264.1 4811.5 5133.7 5238.0 5405.5 5469.8 4895.0 5067.4 5107.0 5515.6 5738.4 4647.3 4747.9 4774.7 4831.6 5007.3 5248.8 5279.0 5395.8 5635.7 5965.9 4649.4 4820.6 4848.7 4850.6 4862.5 4912.0 4922.7 4933.0 4976.2 5003.9 4266.6 4308.9 5060.6 5102.7 5232.5 4599.6 4902.9 4986.2 5662.7 5686.2 1482.5 1483.5 1483.6 1666.0 1779.6 1291.1 1394.3 1421.8 1445.9 1458.6 4980.0 5406.9 5433.9 5439.7 5472.2 4981.0 5842.8 5873.4 5928.8 5977.4 4689.4 4757.0 5291.3 5355.5 5427.8 2563.9 2566.7 2776.6 2907.9 3053.9 5742.6 5946.7 5964.9 6104.3 6206.7 4392.5 4773.5 4887.3 5378.8 6035.7 5251.9 5351.5 5839.4 5978.6 5984.6 4765.9 5286.7 5427.9 5505.5 5860.9 5021.6 5194.3 5472.5 5480.2 5759.7 5316.5 5586.5 5690.9 6164.8 6168.6 4123.3 5033.6 5043.5 5119.4 5127.4 5233.2 5600.4 5685.9 5805.7 5829.7 4750.8 5013.8 5053.0 5116.0 5412.9 4572.0 4583.0 4982.6 5349.2 5713.0 5505.0 5575.9 5587.4 5608.3 5633.6 4772.4 5050.6 5336.8 5468.7 5732.5 4664.8 5045.4 5046.7 5394.9 5550.0 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - PROCESS | 1910 | [#5] yui.html Cycles:180 Average:20070.38 Median:18749.63 stddev:26986.61 (143.9%) stddev-sans-first:27023.33 09:04:03 INFO - PROCESS | 1910 | Values: 751.6 761.0 769.8 812.5 921.2 15640.8 17045.2 18176.4 19051.8 19104.2 16310.4 18991.7 19050.1 19119.2 19156.5 1006.5 1041.1 1041.9 1042.3 1043.9 985.6 1005.9 1013.3 1013.4 1015.2 1057.7 1063.8 1067.8 1068.1 1068.9 1055.8 1059.1 1064.5 1064.9 1066.7 786.1 789.0 789.8 793.0 793.5 15687.6 17021.1 17504.5 17564.8 18507.5 14466.8 14688.7 14783.4 14939.6 15097.2 11998.2 12008.2 12108.0 12267.3 12752.2 3837.4 3839.6 3917.5 3919.5 3938.0 89977.6 98644.9 99510.0 107936.2 124499.8 11247.6 11446.9 11448.7 11695.5 11765.3 23654.0 23884.0 24277.0 24278.9 24342.1 7901.9 7931.6 7958.5 8000.7 8036.6 4568.7 4663.5 4706.2 4709.4 4720.8 2609.0 3033.7 3152.8 3237.1 3371.3 606.2 609.3 625.2 626.5 632.6 633.3 714.2 724.2 747.8 763.8 10238.7 10727.4 10902.9 11274.5 11299.9 506.9 586.0 587.0 670.8 750.6 4571.2 4927.0 5287.2 5294.6 5358.5 73022.3 80269.0 81251.6 99022.5 101775.0 62441.7 66357.0 78749.6 84687.6 84858.0 64649.4 68796.0 75930.6 79457.2 81092.0 10934.1 11426.5 11476.6 11574.0 11681.9 49033.8 49808.0 49999.3 52461.2 52815.7 64229.0 64664.7 65427.7 66313.7 66343.0 13180.6 13300.0 13326.7 13408.0 13765.9 15232.6 15259.9 15272.1 15638.8 16280.6 14480.3 14484.0 14868.4 14892.8 14954.7 13404.4 14133.9 14510.3 14669.9 14810.7 13715.9 14289.4 14561.3 14634.3 15071.6 10326.7 10539.7 10648.7 10859.8 11056.3 10888.6 11451.1 11815.4 11999.2 12202.3 09:04:03 INFO - PROCESS | 1910 | -------- Summary: end -------- 09:04:03 INFO - PROCESS | 1910 | 09:04:03 INFO - TEST-INFO | 1910: exit 0 09:04:03 INFO - TEST-OK | dromaeo_css | took 1115312ms 09:04:03 INFO - TEST-START | kraken 09:04:03 INFO - operating with platform_type : mac_ 09:04:03 INFO - Initialising browser for kraken test... 09:04:03 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmps6vlSc/profile http://localhost:52652/getInfo.html) 09:04:06 INFO - PROCESS | 1916 | 2017-04-28 09:04:06.401 plugin-container[1918:16741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 09:04:06 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:06 INFO - PROCESS | 1916 | 2017-04-28 09:04:06.401 plugin-container[1918:16741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:04:06 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:06 INFO - PROCESS | 1916 | 2017-04-28 09:04:06.401 plugin-container[1918:16741] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:06 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 09:04:06 INFO - PROCESS | 1916 | colorDepth:24 09:04:06 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 09:04:06 INFO - PROCESS | 1916 | __metrics 09:04:06 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:04:07 INFO - TEST-INFO | 1916: exit 0 09:04:07 INFO - Browser initialized. 09:04:07 INFO - Running cycle 1/1 for kraken test... 09:04:07 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmps6vlSc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:09 INFO - PROCESS | 1919 | 2017-04-28 09:04:09.279 plugin-container[1920:16870] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x844f, name = 'com.apple.tsm.portname' 09:04:09 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:09 INFO - PROCESS | 1919 | 2017-04-28 09:04:09.280 plugin-container[1920:16870] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 09:04:09 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:09 INFO - PROCESS | 1919 | 2017-04-28 09:04:09.280 plugin-container[1920:16870] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:19 INFO - PROCESS | 1919 | RSS: Main: 174530560 09:04:19 INFO - PROCESS | 1919 | 09:04:21 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52652/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:22 INFO - PROCESS | 1919 | RSS: Main: 188047360 09:04:22 INFO - PROCESS | 1919 | 09:04:25 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52652/tests/kraken/driver.html?testName=audio-dft) 09:04:26 INFO - PROCESS | 1919 | RSS: Main: 196136960 09:04:26 INFO - PROCESS | 1919 | 09:04:29 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52652/tests/kraken/driver.html?testName=audio-fft) 09:04:30 INFO - PROCESS | 1919 | RSS: Main: 199888896 09:04:30 INFO - PROCESS | 1919 | 09:04:32 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52652/tests/kraken/driver.html?testName=audio-oscillator) 09:04:32 INFO - PROCESS | 1919 | RSS: Main: 200171520 09:04:32 INFO - PROCESS | 1919 | 09:04:34 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52652/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:35 INFO - PROCESS | 1919 | RSS: Main: 200269824 09:04:35 INFO - PROCESS | 1919 | 09:04:38 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52652/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:39 INFO - PROCESS | 1919 | RSS: Main: 204124160 09:04:39 INFO - PROCESS | 1919 | 09:04:42 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52652/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:42 INFO - PROCESS | 1919 | RSS: Main: 205082624 09:04:42 INFO - PROCESS | 1919 | 09:04:45 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52652/tests/kraken/driver.html?testName=json-parse-financial) 09:04:46 INFO - PROCESS | 1919 | RSS: Main: 205148160 09:04:46 INFO - PROCESS | 1919 | 09:04:48 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52652/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:48 INFO - PROCESS | 1919 | RSS: Main: 204468224 09:04:48 INFO - PROCESS | 1919 | 09:04:50 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:51 INFO - PROCESS | 1919 | RSS: Main: 204488704 09:04:51 INFO - PROCESS | 1919 | 09:04:53 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:54 INFO - PROCESS | 1919 | RSS: Main: 204685312 09:04:54 INFO - PROCESS | 1919 | 09:04:56 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:57 INFO - PROCESS | 1919 | RSS: Main: 205107200 09:04:57 INFO - PROCESS | 1919 | 09:05:00 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:01 INFO - PROCESS | 1919 | RSS: Main: 205164544 09:05:01 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52652/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52652/tests/kraken/driver.html?testName=ai-astar) 09:05:03 INFO - PROCESS | 1919 | RSS: Main: 207282176 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | __start_tp_report 09:05:03 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 09:05:03 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 09:05:03 INFO - PROCESS | 1919 | |i|pagename|runs| 09:05:03 INFO - PROCESS | 1919 | |0;ai-astar;106;142;120;122;121;121;122;123;122;122 09:05:03 INFO - PROCESS | 1919 | |1;audio-beat-detection;142;111;111;159;144;139;114;125;130;113 09:05:03 INFO - PROCESS | 1919 | |2;audio-dft;195;198;198;204;197;195;200;194;235;216 09:05:03 INFO - PROCESS | 1919 | |3;audio-fft;112;92;77;88;60;59;62;74;95;52 09:05:03 INFO - PROCESS | 1919 | |4;audio-oscillator;84;70;85;71;72;70;80;70;71;82 09:05:03 INFO - PROCESS | 1919 | |5;imaging-gaussian-blur;109;86;137;136;106;87;91;77;122;133 09:05:03 INFO - PROCESS | 1919 | |6;imaging-darkroom;97;97;119;105;96;96;119;112;111;154 09:05:03 INFO - PROCESS | 1919 | |7;imaging-desaturate;140;120;91;93;91;92;96;93;91;92 09:05:03 INFO - PROCESS | 1919 | |8;json-parse-financial;55;95;54;65;77;106;105;57;73;80 09:05:03 INFO - PROCESS | 1919 | |9;json-stringify-tinderbox;57;62;63;73;90;90;84;59;60;56 09:05:03 INFO - PROCESS | 1919 | |10;stanford-crypto-aes;83;83;81;87;84;85;86;85;84;88 09:05:03 INFO - PROCESS | 1919 | |11;stanford-crypto-ccm;143;156;139;135;136;136;139;139;184;148 09:05:03 INFO - PROCESS | 1919 | |12;stanford-crypto-pbkdf2;164;167;166;166;163;166;157;143;223;164 09:05:03 INFO - PROCESS | 1919 | |13;stanford-crypto-sha256-iterative;66;65;66;67;72;80;83;83;84;84 09:05:03 INFO - PROCESS | 1919 | __end_tp_report 09:05:03 INFO - PROCESS | 1919 | __start_cc_report 09:05:03 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,981 09:05:03 INFO - PROCESS | 1919 | __end_cc_report 09:05:03 INFO - PROCESS | 1919 | __startTimestamp1493395503263__endTimestamp 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | ------- Summary: start ------- 09:05:03 INFO - PROCESS | 1919 | Number of tests: 14 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#0] ai-astar Cycles:10 Average:122.10 Median:122.00 stddev:8.58 (7.0%) stddev-sans-first:6.85 09:05:03 INFO - PROCESS | 1919 | Values: 106.0 142.0 120.0 122.0 121.0 121.0 122.0 123.0 122.0 122.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:134.50 stddev:16.80 (12.5%) stddev-sans-first:17.12 09:05:03 INFO - PROCESS | 1919 | Values: 142.0 111.0 111.0 159.0 144.0 139.0 114.0 125.0 130.0 113.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#2] audio-dft Cycles:10 Average:203.20 Median:199.00 stddev:12.90 (6.5%) stddev-sans-first:13.34 09:05:03 INFO - PROCESS | 1919 | Values: 195.0 198.0 198.0 204.0 197.0 195.0 200.0 194.0 235.0 216.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#3] audio-fft Cycles:10 Average:77.10 Median:75.50 stddev:19.34 (25.6%) stddev-sans-first:15.86 09:05:03 INFO - PROCESS | 1919 | Values: 112.0 92.0 77.0 88.0 60.0 59.0 62.0 74.0 95.0 52.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:6.40 (8.4%) stddev-sans-first:6.00 09:05:03 INFO - PROCESS | 1919 | Values: 84.0 70.0 85.0 71.0 72.0 70.0 80.0 70.0 71.0 82.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#5] imaging-gaussian-blur Cycles:10 Average:108.40 Median:107.00 stddev:22.67 (21.2%) stddev-sans-first:24.04 09:05:03 INFO - PROCESS | 1919 | Values: 109.0 86.0 137.0 136.0 106.0 87.0 91.0 77.0 122.0 133.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:125.00 stddev:17.81 (14.2%) stddev-sans-first:18.20 09:05:03 INFO - PROCESS | 1919 | Values: 97.0 97.0 119.0 105.0 96.0 96.0 119.0 112.0 111.0 154.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#7] imaging-desaturate Cycles:10 Average:99.90 Median:92.00 stddev:16.62 (18.1%) stddev-sans-first:9.34 09:05:03 INFO - PROCESS | 1919 | Values: 140.0 120.0 91.0 93.0 91.0 92.0 96.0 93.0 91.0 92.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#8] json-parse-financial Cycles:10 Average:76.70 Median:69.00 stddev:19.80 (28.7%) stddev-sans-first:19.39 09:05:03 INFO - PROCESS | 1919 | Values: 55.0 95.0 54.0 65.0 77.0 106.0 105.0 57.0 73.0 80.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:68.00 stddev:13.75 (20.2%) stddev-sans-first:13.83 09:05:03 INFO - PROCESS | 1919 | Values: 57.0 62.0 63.0 73.0 90.0 90.0 84.0 59.0 60.0 56.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:2.07 (2.4%) stddev-sans-first:2.11 09:05:03 INFO - PROCESS | 1919 | Values: 83.0 83.0 81.0 87.0 84.0 85.0 86.0 85.0 84.0 88.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:141.00 stddev:14.99 (10.6%) stddev-sans-first:15.87 09:05:03 INFO - PROCESS | 1919 | Values: 143.0 156.0 139.0 135.0 136.0 136.0 139.0 139.0 184.0 148.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:166.00 stddev:20.66 (12.4%) stddev-sans-first:21.86 09:05:03 INFO - PROCESS | 1919 | Values: 164.0 167.0 166.0 166.0 163.0 166.0 157.0 143.0 223.0 164.0 09:05:03 INFO - PROCESS | 1919 | 09:05:03 INFO - PROCESS | 1919 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:81.50 stddev:8.50 (10.4%) stddev-sans-first:8.37 09:05:03 INFO - PROCESS | 1919 | Values: 66.0 65.0 66.0 67.0 72.0 80.0 83.0 83.0 84.0 84.0 09:05:03 INFO - PROCESS | 1919 | -------- Summary: end -------- 09:05:03 INFO - PROCESS | 1919 | 09:05:04 INFO - TEST-INFO | 1919: exit 0 09:05:04 INFO - TEST-OK | kraken | took 60312ms 09:05:04 INFO - SUITE-END | took 1175s 09:05:04 INFO - Completed test suite (00:19:35) 09:05:04 INFO - javascript benchmark 09:05:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7902.266201805842, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3630.945535816963, 3632.8001959892213, 3641.8907432777028, 3647.854085836567, 3652.926941461169, 6193.8761224775535, 6249.968750156243, 6250.656213908233, 6263.872502949467, 6269.0, 6273.9334313166755, 6276.466500347476, 6305.747770089202, 6306.0, 6315.9368406315925, 5558.110702287625, 5586.91619625706, 5604.859878503029, 5630.887382252373, 5683.5169010634145, 5640.887182256352, 5668.404817494165, 5670.4896559309655, 5675.5459563234845, 5680.289963754531, 5394.973025134869, 5411.18832175173, 5467.480589344026, 5649.49154576088, 5659.8585035374035, 5492.395836457987, 5621.072523033695, 5638.097904335327, 5687.578105473632, 5694.088945768657, 5528.640638358494, 5606.831795046155, 5608.186812912105, 5620.971895140539, 5644.068728659766, 6256.874862502725, 6260.0, 6293.433590976788, 6306.810795676137, 6308.93691063088, 6288.213973253343, 6338.492920566389, 6340.619562826199, 6468.41784239416, 6653.7005834737065, 6679.432248258855, 6730.798076057726, 6753.088333075021, 6755.324467553254, 6774.491913106487, 5326.97336513315, 6251.812445626593, 6290.779822706275, 6356.173697419352, 6356.682165891687, 6568.67156642166, 6583.3416658334745, 6904.585724856524, 6926.238113807477, 6943.51395402317, 6451.806445806634, 6456.418922297016, 6567.1462709848365, 6606.6036037837885, 6608.735650574019, 5495.258140151109, 6709.329067093252, 6758.83102922421, 6766.18805743314, 6777.2883847195335, 6286.465650419766, 6573.769918052845, 6581.638009909501, 6665.366790154928, 6673.165854268216, 6429.485641148697, 6488.578242414228, 6493.285738568754, 6539.08452816606, 6544.54188206821, 5292.391374991848, 5304.761285742151, 5309.65487243328, 5317.54800841925, 5348.652337598121, 4691.53084691535, 4723.4331880174595, 4745.430548334222, 4757.191277482836, 4777.713337199779, 6164.260288765377, 6244.438000579969, 6283.340249273851, 6304.558680892385, 6330.588511746721, 6744.932550674529, 6770.424513916274, 6785.830354241085, 6793.354631310018, 6795.626240556817, 4935.3090567320605, 6213.161223234805, 6216.502679785606, 6333.271673757485, 6355.269144048401, 5868.589198756132, 5871.647701137946, 5891.1457838614015, 5901.232839730807, 5931.406859314034, 6967.581945083311, 7013.824654383681, 7014.193367762568, 7026.543274687129, 7028.156621205488, 6383.202099737533, 6691.5315927884585, 6726.6972986216715, 6744.887093629693, 6810.795676129724, 6715.429188518835, 6829.248782634006, 6832.180138383425, 6856.897146542906, 6874.518783685094, 5674.659520428788, 6479.902801457888, 6601.53789234749, 6609.504287178577, 6623.238327592196, 5154.329937108152, 6637.966810166112, 6682.131322927989, 6692.364225398579, 6721.059051732664, 6249.9062963831375, 6296.622202667854, 6366.745330186926, 6390.520710946605, 6403.423691867754, 5812.33158186797, 6127.908081378694, 6480.902786458301, 6484.157059582224, 6534.1178940844275, 6256.749730010931, 6308.14839996613, 6344.270408903035, 6367.681615919279, 6373.87252254943, 6263.6241825490615, 6291.307956124731, 6444.871102578016, 6448.193975753031, 6490.870182596227, 6382.648954307558, 6390.233172019201, 6391.48868090561, 6395.0, 6404.679766011587], "value": 6099.665244503031, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.8235564642308, 820.6635279535391, 849.6006876767918, 952.4808979106386, 964.127464644497, 38701.80649096754, 39736.40395394068, 41316.38025429621, 41342.0, 41423.37864932025, 34888.476672849865, 37392.813035934785, 39582.60417395825, 39651.40522892152, 39664.80167599158, 8300.962379702538, 8421.24208821206, 11143.997040266375, 11435.542578296858, 11688.064954803594, 9487.620495180185, 9568.52157392131, 11332.036776873934, 11389.487473063731, 11431.599894003712, 9528.380655257386, 11387.829182562267, 11391.145664075186, 11419.600313989013, 11421.371824549646, 11349.773004539904, 11372.431378431129, 11397.0, 11413.31520108792, 11416.600418985296, 11045.282056666372, 11062.557497700163, 11098.0, 11100.777984440347, 11116.221864469491, 35026.474602880975, 35060.9481715549, 35124.12189695278, 35133.47299790505, 35293.47059794105, 3824.541055073409, 3859.614038596118, 3869.129445874684, 3883.456316115746, 3896.415537669344, 26054.478910421876, 26107.608385874228, 26580.0, 26619.467610647873, 27194.456110877672, 3222.581064461605, 3278.704916557497, 3287.8849240276713, 3300.785448945811, 3307.5038744188323, 335973.64026359667, 337159.0, 359660.0, 364084.0, 380227.0988645067, 44339.77830110829, 44401.77799110984, 44770.0, 44809.10381792411, 44895.32657010082, 46015.30977035347, 46227.76886115548, 46311.76844115825, 46327.0, 46406.767966159954, 14231.288435578179, 14292.571222863331, 14310.0, 14310.926680499004, 14343.426262949573, 10487.790244195074, 12390.938045309716, 12624.81062784059, 12629.810552841715, 12670.936645316899, 11980.101492388107, 12030.398480076135, 12045.457954392074, 12100.33448160342, 12141.210821296587, 4614.400127983341, 5511.641743286754, 5519.337679478488, 5548.306461692288, 5614.2701448811395, 1108.229780302698, 1109.755853712182, 1155.1798223261578, 1216.4282787090053, 1225.3138242584182, 26687.733122668913, 26810.32974175622, 27078.86460567724, 27246.318842029108, 27291.86354068217, 997.3018886779139, 1088.5156105532963, 1258.6475786779715, 1265.7974724044109, 1305.419088505606, 13500.017390526658, 14032.298385080909, 14254.002219844511, 14274.357653905392, 14684.339204735812, 430969.0, 431155.68844311155, 432447.0, 432632.0, 432716.0, 49326.50673493291, 55542.722286389115, 55689.44310556923, 55963.88072238532, 55991.160132598045, 1456.2791418247823, 1476.963075923089, 1509.5169545745475, 1512.100300321329, 1514.560777374571, 24646.753532464445, 26612.600810987464, 26647.86676066607, 26666.86666566655, 26679.7332026685, 44368.778156109016, 45552.316715248635, 45709.77145114253, 47968.0, 48526.0, 463027.0, 469180.0, 469914.0, 471241.0, 471722.0, 24649.507009860063, 25130.12044578431, 28913.56629650515, 30142.54786178165, 30277.697223028368, 38861.417078743274, 39413.01467463336, 39599.20801583894, 39791.602083979946, 40070.39894401528, 27703.58444623292, 28425.0, 29016.098390160816, 29257.97597084092, 29699.10902672923, 28745.712542874302, 28840.711592884643, 29737.405251895274, 29795.0, 29837.70162298349, 33400.83299583584, 33916.982490525326, 33981.66018339785, 34000.0, 34287.628494860925, 27797.58303625407, 27828.0, 28403.14790556204, 28726.989110762046, 28829.0, 22419.43951401163, 23259.767402326437, 23460.061597535907, 23771.52456950886, 23848.642270366305], "value": 18528.783416318696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.27407544212554, 429.3924097402176, 438.86492215372203, 440.93606427068084, 453.7164272329794, 20631.484212894666, 21672.02475888587, 22010.559788804236, 22496.7626780527, 22736.88631556844, 22643.886780566074, 23064.538709225806, 23369.298921032354, 23458.530829383402, 23710.695911724848, 6745.730170793162, 6826.24911259761, 6964.721411143548, 7026.543274687129, 7103.928960710378, 6944.4444444444325, 6999.965000175018, 7004.684789184474, 7023.157221133446, 7365.889511657329, 7776.261255180777, 7830.060392752849, 7841.059072911258, 7842.451028428012, 7863.882041769378, 7697.153313135551, 7713.961430192841, 7840.059192896861, 7873.8031549211155, 7890.921090789076, 6850.828729281758, 7416.703331866773, 7660.119086305091, 7662.272084151997, 7757.495762775401, 26098.34754131143, 26227.344316392053, 26417.867910660516, 26661.600075998875, 26944.92220311185, 3042.224232820639, 3045.1016949999803, 3150.354177393629, 3168.746500279972, 3190.9361812763614, 15724.685506289925, 16469.0, 16561.08914009729, 16575.41986030495, 16797.0, 2143.217725530186, 2151.118041602935, 2191.8904054797194, 2195.429188411025, 2274.0789980058103, 167153.0, 169731.0, 175767.0, 180584.19415805806, 181322.0933895322, 19189.328373507138, 20632.484187895123, 21209.36371908845, 21225.36323910285, 21387.8930605346, 30760.230994225323, 31068.533971990437, 31256.374872502423, 31261.687383126333, 31321.8433907829, 10699.090577300862, 10753.193510486744, 10890.0, 10907.618233361953, 11094.611688590861, 9176.632934682671, 9212.769680758034, 9233.21517670992, 9284.535773211312, 9424.670136545188, 9221.400608960395, 9477.857832132524, 9532.380395274286, 9574.180905627909, 9579.952100239454, 3680.8895733128047, 4052.5947405259826, 4055.9391609125887, 4067.654249388835, 4088.386741988666, 1079.8488211650376, 1097.4238524774398, 1099.5821587796745, 1106.9335839849634, 1161.465725766157, 21899.452513686967, 22250.261832327502, 22426.215082472034, 22437.297845855577, 22449.0, 1007.4559737741685, 1010.751721623794, 1103.784761971424, 1116.4473585575192, 1153.3944679043402, 10657.680269591925, 10929.781404371868, 11589.76820463586, 11695.590654327058, 11697.649070527897, 395169.0, 554253.0, 727975.0, 765174.0, 777402.1129894315, 121296.39351803185, 122102.72640457719, 122296.0, 149195.0, 164217.0, 1235.437875766524, 1356.8982326325565, 1371.2732251906307, 1438.6834896322582, 1442.6439147201597, 18821.811781882552, 19175.616487670446, 19428.0, 19431.514212144808, 19560.510987225432, 64568.031479528785, 64807.35192648013, 65747.67126164338, 65856.34143658694, 66565.0, 74600.62699686467, 94999.52500237455, 95435.52282238821, 95467.0, 96526.51736741271, 11534.250273732181, 11668.066554675775, 11756.706762255973, 11834.585789497327, 11972.461239244081, 11301.830472543083, 12868.806967895676, 12990.805137923127, 13172.0, 13331.33343332849, 6636.601803891782, 9678.322517423712, 11030.235162373981, 11640.359780211831, 11682.415879205835, 8637.395382323177, 9074.909250907407, 10364.896351036394, 10463.529141188817, 10826.0, 11515.712107197387, 11533.365664888512, 11663.825042624538, 11741.76516469683, 11756.294622322688, 11519.424028798694, 11683.240589361665, 11850.170488065753, 11894.702632434259, 11916.702082448008, 9537.856932145885, 11174.217804753589, 11467.655970320904, 11481.827772583585, 11570.479328430072], "value": 11947.859510795031, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.8626430489107, 1595.2023988005997, 1611.186350892799, 1618.0938674342365, 1632.8938618989764, 9606.759831004229, 9823.263255255848, 11181.993620574147, 11878.643640690781, 11901.226420282686, 10946.616868409586, 11246.831297530543, 11521.539138434473, 11558.53765849365, 11634.476448559813, 2472.7403622619636, 2500.0, 2505.073122944513, 2508.347829564317, 2511.4977004599154, 2435.7807797298237, 2484.763947424992, 2485.030837973192, 2486.8880900359436, 2489.240781561623, 2454.0797201049604, 2466.2231397109945, 2470.22188010776, 2472.085328428484, 2486.875656217182, 2413.746556611548, 2413.8793060347, 2422.9394265143337, 2423.503181847717, 2429.0405289910386, 2418.1294733895784, 2422.200673777667, 2423.09134074722, 2447.070113356931, 2474.183519438581, 11492.712682182928, 11736.35450050247, 11736.88263117375, 11753.294802311888, 11756.588519401865, 5964.612300200516, 6057.42454466825, 6121.387861213888, 6136.355682653302, 6149.877002459926, 3363.831808409594, 3557.3596752584526, 3580.8030558319283, 3703.370427027412, 3780.092777733352, 1242.9067819913453, 1273.096101767746, 1310.2335133946654, 1321.823577016456, 1338.8795008449285, 15712.68574628501, 15800.0, 15832.287547060294, 15837.208139592974, 15881.523554293273, 4597.3333866589155, 4748.501407352247, 4780.904381912412, 4800.807967681323, 4833.154198015333, 11668.649940501798, 11676.824847627293, 11701.0, 11707.473163707484, 11723.706907327385, 5832.795852145155, 5846.970765146232, 5848.707564621752, 5866.208061911588, 5878.882422351529, 4537.8411755588395, 5147.867469156779, 5434.782608695687, 5458.754356053908, 5484.040292948718, 3433.9354800012075, 3958.511382176958, 4035.53591337, 4124.072083781125, 4132.442120313779, 995.9153471954965, 1051.7738686182508, 1057.8519007338978, 1061.861957945462, 1065.094669530908, 822.6133717152928, 864.7967727584013, 890.2432932007741, 908.123660667459, 920.2408013388981, 6672.466202703772, 6757.459403247728, 6790.796276111724, 6909.585424874524, 6926.9307306926285, 1042.5360714482135, 1062.2776511971933, 1069.3263244156133, 1074.978500429987, 1077.1651969723528, 4268.87193384199, 4307.978460107742, 4366.8034938427845, 4457.86626401202, 4587.105514424722, 13538.661533461742, 13703.314834258248, 14129.293535323193, 14145.646358841112, 14162.433502659778, 12694.174878632859, 12877.4205160768, 14003.649908752159, 14052.367643455864, 14087.788683169761, 10483.737906552396, 10694.41180735067, 10769.730756730987, 10787.83818242727, 10789.730256743644, 6649.168853893263, 6652.73389064432, 6755.831104222237, 6790.42281406086, 6855.5886646801355, 10967.890321096687, 12643.683907902008, 12803.679908002374, 12835.294058826854, 12860.614181574567, 11087.667369978913, 12968.546100886424, 13124.212547247196, 13191.934040329737, 13275.269860157397, 8604.182602652738, 8669.219770220458, 8812.559372031501, 8818.11818818113, 8828.779280518038, 10437.165026797686, 10441.0, 10551.630692925712, 10562.577496900345, 10589.9470502647, 8618.396712230084, 8786.472811631322, 8839.911600883908, 8882.289416846766, 8912.910870891208, 8547.8717819234, 8597.914020859711, 8620.13798620133, 8656.523891186045, 8686.565671716262, 7854.332381747616, 8043.557604331818, 8055.677772889018, 8063.992000999875, 8064.8790268147195, 5872.559558033079, 5877.382874798084, 5915.792947246826, 6051.939480605138, 6083.908741368971, 6004.849878753066, 6132.0, 6184.257889053342, 6233.127362169391, 6283.3716628338625], "value": 5244.216491531093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.6666433559447, 388.9027743064234, 392.8075243130867, 402.3341370032595, 403.12924083978595, 4017.7991100445006, 4427.09244604856, 4792.6165906727465, 4866.513348665132, 5289.497497737713, 4587.853036740815, 4855.878603034926, 5183.53348198662, 5259.027079990205, 5295.834916318413, 3743.906402339936, 4063.4717486726686, 4400.283889283182, 4551.7724113794175, 4685.390899183101, 3989.464056338316, 4059.4113853491226, 4200.432941552897, 4549.135664223803, 4709.929351059737, 3865.903352416184, 4112.403701463286, 4269.573042695737, 4316.892077698051, 4695.3426520287185, 4321.58944900234, 4371.431713877208, 4455.264881294566, 4559.703619264737, 4626.629869610423, 3972.748584195969, 4374.321980093091, 4380.78096095194, 4384.386185933972, 4398.3402489626815, 5201.323827902348, 5221.530062294411, 5248.475152484759, 5261.736913154327, 5264.0523006487465, 4811.4707382187935, 5133.742233152864, 5237.973810130963, 5405.5135037846785, 5469.808556700536, 4895.0, 5067.391912970467, 5107.004134193834, 5515.621094726318, 5738.38903298998, 4647.34937108805, 4747.8575642730775, 4774.686961085695, 4831.589314908202, 5007.298978143063, 5248.8425347239645, 5278.97360513195, 5395.78416863325, 5635.703113678503, 5965.91051134229, 4649.418822647169, 4820.614350851994, 4848.660593758403, 4850.563449289511, 4862.51374862518, 4911.975440122848, 4922.729249891218, 4933.0, 4976.154053810861, 5003.9241563064115, 4266.613350661048, 4308.91382172361, 5060.608332708461, 5102.744862756922, 5232.450592687789, 4599.563041511051, 4902.90194196114, 4986.227134794112, 5662.745176467069, 5686.232358631614, 1482.4811316039516, 1483.5104415542844, 1483.5845963130341, 1666.0, 1779.5817982774038, 1291.122037014839, 1394.2889126545535, 1421.772516397392, 1445.8554144585667, 1458.5770126663149, 4979.975100124549, 5406.945930540644, 5433.864153396117, 5439.694473326374, 5472.20653005312, 4981.003799240167, 5842.766289348463, 5873.412658734178, 5928.762849486058, 5977.432143946318, 4689.413823272091, 4756.9524304756505, 5291.259223708684, 5355.544778693777, 5427.810026649048, 2563.910263140781, 2566.691996960377, 2776.6251556039674, 2907.854607269628, 3053.9463884960096, 5742.59801813869, 5946.732397042057, 5964.880702385928, 6104.26748790148, 6206.658633775095, 4392.538783427758, 4773.546513081252, 4887.266909963463, 5378.838634840961, 6035.698215089228, 5251.947480525146, 5351.491608297206, 5839.416058394127, 5978.641281523122, 5984.611000284967, 4765.928511072406, 5286.709230992333, 5427.891442171213, 5505.476979686923, 5860.853478663067, 5021.644156077911, 5194.298769666047, 5472.480114389126, 5480.205370221216, 5759.683217423082, 5316.521513063688, 5586.469285417878, 5690.914636280377, 6164.825115065834, 6168.629882207082, 4123.278426275329, 5033.597312215087, 5043.495650435074, 5119.411267704261, 5127.4103478099005, 5233.220704960332, 5600.431879073866, 5685.872769976579, 5805.680687562224, 5829.679367634821, 4750.8337208197545, 5013.816226696002, 5052.953553810713, 5115.974420127875, 5412.891742165213, 4571.977140114278, 4582.977085114687, 4982.636720213914, 5349.197620356822, 5713.0, 5505.0, 5575.916361254665, 5587.357453892854, 5608.308012154107, 5633.605647604629, 4772.3557319761385, 5050.621203409685, 5336.839894803162, 5468.726563671721, 5732.484076433141, 4664.813407463664, 5045.426832315472, 5046.697198168122, 5394.919076213781, 5549.972250138723], "value": 4342.842555485419, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.6429695894451, 760.9619519024048, 769.788308215241, 812.4597142899968, 921.2261700171856, 15640.84359156408, 17045.232964516596, 18176.36382726605, 19051.809481905177, 19104.235830566762, 16310.429134980279, 18991.715124273112, 19050.142743576536, 19119.235230590795, 19156.521086972833, 1006.5068116622875, 1041.1306559023224, 1041.9114375278111, 1042.3329069395593, 1043.8695163104612, 985.5614251657979, 1005.9094681478665, 1013.3109485549824, 1013.3869009249408, 1015.1624909449733, 1057.6826951914434, 1063.8191507443753, 1067.813132701778, 1068.0547715272, 1068.8931106889327, 1055.8363453664695, 1059.131512160033, 1064.5475672839013, 1064.920130990175, 1066.6746642274104, 786.1391776005265, 788.9566073865935, 789.8420315936778, 792.9730998357142, 793.5318162284223, 15687.607809804733, 17021.063841488714, 17504.474865754048, 17564.824351756448, 18507.537311567183, 14466.782998255034, 14688.706225875529, 14783.408663653441, 14939.626509337244, 15097.245137743179, 11998.220115692453, 12008.159428839985, 12108.0, 12267.263964162179, 12752.234865908074, 3837.424386342043, 3839.5968423315708, 3917.5494818096, 3919.470871432377, 3937.9803100984313, 89977.55011224968, 98644.89211352057, 99509.99999999927, 107936.22223222228, 124499.75500245063, 11247.550097996153, 11446.885531144748, 11448.656540303804, 11695.532178712927, 11765.294082355085, 23654.0, 23884.0, 24277.0, 24278.87860560686, 24342.148024819046, 7901.881471777928, 7931.56376399292, 7958.522488650699, 8000.719974800854, 8036.557989310527, 4568.70303430276, 4663.510331415153, 4706.176419126639, 4709.434867815884, 4720.834770783006, 2608.956417433042, 3033.7409974860184, 3152.810831350126, 3237.1259759864706, 3371.258323168899, 606.2452246952485, 609.2810483629271, 625.2122325869346, 626.5238418801653, 632.6488798716664, 633.2938773267773, 714.2143641993869, 724.1527412926888, 747.7943565519525, 763.8084588042672, 10238.744031399274, 10727.356358618508, 10902.945485272523, 11274.549018039186, 11299.94350028261, 506.8808829924965, 586.0447470622858, 586.9937675365562, 670.7752902777527, 750.5534207146738, 4571.1543364477675, 4927.0, 5287.23335116406, 5294.57643388528, 5358.5177334039345, 73022.26977730262, 80269.0, 81251.5937420309, 99022.50488747511, 101775.0, 62441.687791560755, 66357.0, 78749.60625196838, 84687.57656211803, 84858.0, 64649.353506465275, 68796.0, 75930.62034689901, 79457.20542794498, 81092.0, 10934.125269978224, 11426.542938282708, 11476.598319058792, 11574.0, 11681.941590291994, 49033.75483122562, 49808.0, 49999.25001124913, 52461.21308180457, 52815.735921321684, 64229.0, 64664.67667661631, 65427.69144617176, 66313.66843165753, 66343.0, 13180.604581862559, 13300.0, 13326.666833329244, 13408.0, 13765.931170344084, 15232.627795527069, 15259.923700381427, 15272.083674979536, 15638.765418518504, 16280.592985175464, 14480.348384322942, 14484.0, 14868.405263789327, 14892.776608350667, 14954.700905982038, 13404.396802144121, 14133.858661413255, 14510.274486275855, 14669.853301466848, 14810.703785924006, 13715.862841371458, 14289.356978935764, 14561.34473948696, 14634.34145463478, 15071.623209419853, 10326.741831454274, 10539.683809485728, 10648.680539583826, 10859.782804344028, 11056.281341712764, 10888.564457421931, 11451.083913287084, 11815.350155741517, 11999.220050696676, 12202.267863928193], "value": 7917.23114085119, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 142.0, 120.0, 122.0, 121.0, 121.0, 122.0, 123.0, 122.0, 122.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 111.0, 159.0, 144.0, 139.0, 114.0, 125.0, 130.0, 113.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 198.0, 204.0, 197.0, 195.0, 200.0, 194.0, 235.0, 216.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 92.0, 77.0, 88.0, 60.0, 59.0, 62.0, 74.0, 95.0, 52.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 85.0, 71.0, 72.0, 70.0, 80.0, 70.0, 71.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 86.0, 137.0, 136.0, 106.0, 87.0, 91.0, 77.0, 122.0, 133.0], "value": 108.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 119.0, 105.0, 96.0, 96.0, 119.0, 112.0, 111.0, 154.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 120.0, 91.0, 93.0, 91.0, 92.0, 96.0, 93.0, 91.0, 92.0], "value": 99.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 95.0, 54.0, 65.0, 77.0, 106.0, 105.0, 57.0, 73.0, 80.0], "value": 76.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 63.0, 73.0, 90.0, 90.0, 84.0, 59.0, 60.0, 56.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 87.0, 84.0, 85.0, 86.0, 85.0, 84.0, 88.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 156.0, 139.0, 135.0, 136.0, 136.0, 139.0, 139.0, 184.0, 148.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 166.0, 166.0, 163.0, 166.0, 157.0, 143.0, 223.0, 164.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 67.0, 72.0, 80.0, 83.0, 83.0, 84.0, 84.0], "value": 75.0, "unit": "score"}]}]} 09:05:04 INFO - Return code: 0 09:05:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:04 ERROR - # TBPL SUCCESS # 09:05:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:04 INFO - 2017-04-28 09:05:04.580 screenresolution[1924:17248] starting screenresolution argv=screenresolution get 09:05:04 INFO - 2017-04-28 09:05:04.583 screenresolution[1924:17248] Display 0: 1600x1200x32@60 09:05:04 INFO - 2017-04-28 09:05:04.589 screenresolution[1925:17251] starting screenresolution argv=screenresolution list 09:05:04 INFO - Available Modes on Display 0 09:05:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:04 INFO - Intel Iris: 09:05:04 INFO - Chipset Model: Intel Iris 09:05:04 INFO - Type: GPU 09:05:04 INFO - Bus: Built-In 09:05:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:04 INFO - Vendor: Intel (0x8086) 09:05:04 INFO - Device ID: 0x0a2e 09:05:04 INFO - Revision ID: 0x0009 09:05:04 INFO - Displays: 09:05:04 INFO - Display: 09:05:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:04 INFO - Display Serial Number: 1600x1200 60 09:05:04 INFO - Main Display: Yes 09:05:04 INFO - Mirror: Off 09:05:04 INFO - Online: Yes 09:05:04 INFO - Rotation: Supported 09:05:04 INFO - Return code: 0 09:05:04 INFO - Running post-action listener: _resource_record_post_action 09:05:04 INFO - [mozharness: 2017-04-28 16:05:04.823522Z] Finished run-tests step (success) 09:05:04 INFO - Running post-run listener: _resource_record_post_run 09:05:04 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 1938432; Write bytes: 425054208; Read time: 22; Write time: 1667 09:05:04 INFO - TinderboxPrint: CPU usage
54.7% 09:05:04 INFO - TinderboxPrint: I/O read bytes / time
1,938,432 / 22 09:05:04 INFO - TinderboxPrint: I/O write bytes / time
425,054,208 / 1,667 09:05:04 INFO - TinderboxPrint: CPU idle
2,160.0 (45.3%) 09:05:04 INFO - TinderboxPrint: CPU system
960.4 (20.2%) 09:05:04 INFO - TinderboxPrint: CPU user
1,645.4 (34.5%) 09:05:04 INFO - TinderboxPrint: Swap in / out
124,571,648 / 0 09:05:04 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121383424; Write bytes: 113971200; Read time: 11040; Write time: 221 09:05:04 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1799168; Write bytes: 300863488; Read time: 20; Write time: 1410 09:05:05 INFO - Running post-run listener: _upload_blobber_files 09:05:05 INFO - Blob upload gear active. 09:05:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:05 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 09:05:05 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'] 09:05:05 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 09:05:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:06 INFO - (blobuploader) - INFO - Done attempting. 09:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:07 INFO - (blobuploader) - INFO - Done attempting. 09:05:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:05:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:08 INFO - (blobuploader) - INFO - Done attempting. 09:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:10 INFO - (blobuploader) - INFO - Done attempting. 09:05:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:10 INFO - Return code: 0 09:05:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d447ccdcca7b4c69c9ca35c39cefd1d15129b691d4c858dc70000294ebb0fde276e1bd3b196a6d4d2728046b633ba1c55ed82ce6c97e37af2723fec9087e05c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce9e5da3b2b86faa0c33e34f5ff989d0934504baace36585bfa3429c9233a69d04fca924f7f44368e083470b6be357ffce61954bf7343a2aee068e2f2f55f373", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c657883968a1a29611964ead5d7f2ac7829f941aa20d8e5b0e74a68ffedccd7203ff8c1a10e74c59c93a8f9902459be76633804307481249a3568a5cd9559981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2536b1917285a8bcf201fa7f94f1423349fb0b6f2439e49675e707542e7f428ee41af0151b5b51edbb36dee8c0885bbfdf8a0c129d525c3933e43617b30feea6"} 09:05:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:10 INFO - Contents: 09:05:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d447ccdcca7b4c69c9ca35c39cefd1d15129b691d4c858dc70000294ebb0fde276e1bd3b196a6d4d2728046b633ba1c55ed82ce6c97e37af2723fec9087e05c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce9e5da3b2b86faa0c33e34f5ff989d0934504baace36585bfa3429c9233a69d04fca924f7f44368e083470b6be357ffce61954bf7343a2aee068e2f2f55f373", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c657883968a1a29611964ead5d7f2ac7829f941aa20d8e5b0e74a68ffedccd7203ff8c1a10e74c59c93a8f9902459be76633804307481249a3568a5cd9559981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2536b1917285a8bcf201fa7f94f1423349fb0b6f2439e49675e707542e7f428ee41af0151b5b51edbb36dee8c0885bbfdf8a0c129d525c3933e43617b30feea6"} 09:05:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:10 INFO - Copying logs to upload dir... 09:05:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.770017 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-28 09:05:10.597959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 09:05:10.604048) ========= 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.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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/d447ccdcca7b4c69c9ca35c39cefd1d15129b691d4c858dc70000294ebb0fde276e1bd3b196a6d4d2728046b633ba1c55ed82ce6c97e37af2723fec9087e05c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce9e5da3b2b86faa0c33e34f5ff989d0934504baace36585bfa3429c9233a69d04fca924f7f44368e083470b6be357ffce61954bf7343a2aee068e2f2f55f373", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c657883968a1a29611964ead5d7f2ac7829f941aa20d8e5b0e74a68ffedccd7203ff8c1a10e74c59c93a8f9902459be76633804307481249a3568a5cd9559981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2536b1917285a8bcf201fa7f94f1423349fb0b6f2439e49675e707542e7f428ee41af0151b5b51edbb36dee8c0885bbfdf8a0c129d525c3933e43617b30feea6"} build_url:https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010561 build_url: 'https://queue.taskcluster.net/v1/task/IoLxXRO4TteG2i9lOvYQNg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d447ccdcca7b4c69c9ca35c39cefd1d15129b691d4c858dc70000294ebb0fde276e1bd3b196a6d4d2728046b633ba1c55ed82ce6c97e37af2723fec9087e05c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce9e5da3b2b86faa0c33e34f5ff989d0934504baace36585bfa3429c9233a69d04fca924f7f44368e083470b6be357ffce61954bf7343a2aee068e2f2f55f373", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c657883968a1a29611964ead5d7f2ac7829f941aa20d8e5b0e74a68ffedccd7203ff8c1a10e74c59c93a8f9902459be76633804307481249a3568a5cd9559981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2536b1917285a8bcf201fa7f94f1423349fb0b6f2439e49675e707542e7f428ee41af0151b5b51edbb36dee8c0885bbfdf8a0c129d525c3933e43617b30feea6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 09:05:10.640269) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 09:05:10.640681) ========= 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.jUrMgHrik7/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.nkUos9GYis/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.005291 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 09:05:10.693026) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 09:05:10.693458) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 09:05:10.693848) ========= ========= Total master_lag: 0.23 =========