builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0261 starttime: 1492284612.65 results: success (0) buildid: 20170415110113 builduid: ef2f4dcf96354aad85ace5c4be175bd3 revision: d65b53cf8fd9f7747c7ee4e3ea96f12434917daa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:12.652893) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:12.653345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:12.653691) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:12.747926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:12.748287) ========= 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.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-15 12:30:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-04-15 12:30:13 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.561204 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:13.335242) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:13.335589) ========= 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.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.027388 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:13.384973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-15 12:30:13.385380) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d65b53cf8fd9f7747c7ee4e3ea96f12434917daa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d65b53cf8fd9f7747c7ee4e3ea96f12434917daa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-15 12:30:13,497 truncating revision to first 12 chars 2017-04-15 12:30:13,497 Setting DEBUG logging. 2017-04-15 12:30:13,497 attempt 1/10 2017-04-15 12:30:13,497 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d65b53cf8fd9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-15 12:30:15,612 unpacking tar archive at: mozilla-central-d65b53cf8fd9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.404841 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-15 12:30:15.818394) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:15.818761) ========= script_repo_revision: d65b53cf8fd9f7747c7ee4e3ea96f12434917daa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:15.819207) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:15.819528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 12:30:15.840642) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-15 12:30:15.840976) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:30:16 INFO - MultiFileLogger online at 20170415 12:30:16 in /builds/slave/test 12:30:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:30:16 INFO - {'append_to_log': False, 12:30:16 INFO - 'base_work_dir': '/builds/slave/test', 12:30:16 INFO - 'blob_upload_branch': 'Firefox', 12:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:30:16 INFO - 'branch': 'Firefox', 12:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 12:30:16 INFO - 'config_files': ('talos/mac_config.py',), 12:30:16 INFO - 'default_actions': ('clobber', 12:30:16 INFO - 'read-buildbot-config', 12:30:16 INFO - 'download-and-extract', 12:30:16 INFO - 'populate-webroot', 12:30:16 INFO - 'create-virtualenv', 12:30:16 INFO - 'install', 12:30:16 INFO - 'run-tests'), 12:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:30:16 INFO - 'download_minidump_stackwalk': True, 12:30:16 INFO - 'download_symbols': 'ondemand', 12:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 12:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 12:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:30:16 INFO - 'gecko_profile': False, 12:30:16 INFO - 'gecko_profile_interval': 0, 12:30:16 INFO - 'installer_path': 'installer.exe', 12:30:16 INFO - 'log_level': 'info', 12:30:16 INFO - 'log_name': 'talos', 12:30:16 INFO - 'log_to_console': True, 12:30:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:30:16 INFO - 'opt_config_files': (), 12:30:16 INFO - 'pip_index': False, 12:30:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:30:16 INFO - 'cmd': ('bash', 12:30:16 INFO - '-c', 12:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:30:16 INFO - 'enabled': True, 12:30:16 INFO - 'halt_on_failure': False, 12:30:16 INFO - 'name': 'check_screen_resolution'},), 12:30:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:30:16 INFO - 'cmd': ('bash', 12:30:16 INFO - '-c', 12:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:30:16 INFO - 'enabled': True, 12:30:16 INFO - 'halt_on_failure': False, 12:30:16 INFO - 'name': 'check_screen_resolution'},), 12:30:16 INFO - 'run_cmd_checks_enabled': True, 12:30:16 INFO - 'suite': 'dromaeojs-e10s', 12:30:16 INFO - 'system_bits': '32', 12:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:30:16 INFO - 'title': 't-yosemite-r7-0261', 12:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:30:16 INFO - 'use_talos_json': True, 12:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:30:16 INFO - 'work_dir': 'build'} 12:30:16 INFO - [mozharness: 2017-04-15 19:30:16.055310Z] Running clobber step. 12:30:16 INFO - Running pre-action listener: _resource_record_pre_action 12:30:16 INFO - Running main action method: clobber 12:30:16 INFO - rmtree: /builds/slave/test/build 12:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:30:20 INFO - Running post-action listener: _resource_record_post_action 12:30:20 INFO - [mozharness: 2017-04-15 19:30:20.188806Z] Finished clobber step (success) 12:30:20 INFO - [mozharness: 2017-04-15 19:30:20.189016Z] Running read-buildbot-config step. 12:30:20 INFO - Running pre-action listener: _resource_record_pre_action 12:30:20 INFO - Running main action method: read_buildbot_config 12:30:20 INFO - Using buildbot properties: 12:30:20 INFO - { 12:30:20 INFO - "project": "", 12:30:20 INFO - "product": "firefox", 12:30:20 INFO - "script_repo_revision": "production", 12:30:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:30:20 INFO - "repository": "", 12:30:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:30:20 INFO - "buildid": "20170415110113", 12:30:20 INFO - "pgo_build": "False", 12:30:20 INFO - "basedir": "/builds/slave/test", 12:30:20 INFO - "buildnumber": 37, 12:30:20 INFO - "slavename": "t-yosemite-r7-0261", 12:30:20 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:30:20 INFO - "platform": "yosemite_r7", 12:30:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:30:20 INFO - "branch": "mozilla-central", 12:30:20 INFO - "repo_path": "mozilla-central", 12:30:20 INFO - "revision": "d65b53cf8fd9f7747c7ee4e3ea96f12434917daa", 12:30:20 INFO - "stage_platform": "macosx64", 12:30:20 INFO - "builduid": "ef2f4dcf96354aad85ace5c4be175bd3", 12:30:20 INFO - "slavebuilddir": "test" 12:30:20 INFO - } 12:30:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:30:20 INFO - Running post-action listener: _resource_record_post_action 12:30:20 INFO - [mozharness: 2017-04-15 19:30:20.193491Z] Finished read-buildbot-config step (success) 12:30:20 INFO - [mozharness: 2017-04-15 19:30:20.193626Z] Running download-and-extract step. 12:30:20 INFO - Running pre-action listener: _resource_record_pre_action 12:30:20 INFO - Running main action method: download_and_extract 12:30:20 INFO - mkdir: /builds/slave/test/build/tests 12:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:20 INFO - trying https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:30:20 INFO - Downloading https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:30:21 INFO - Downloaded 1325 bytes. 12:30:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:30:21 INFO - Using the following test package requirements: 12:30:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:30:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:30:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:30:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:30:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'jsshell-mac.zip'], 12:30:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:30:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:30:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:30:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:30:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:30:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:30:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:30:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:30:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:30:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:30:21 INFO - Fetch https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:30:23 INFO - Expected file size: 52664075 12:30:23 INFO - Obtained file size: 52664075 12:30:39 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:30:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:30:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:30:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:30:40 INFO - Expected file size: 52664075 12:30:40 INFO - Obtained file size: 52664075 12:30:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:30:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:30:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:30:57 INFO - Expected file size: 14408802 12:30:57 INFO - Obtained file size: 14408802 12:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:57 INFO - trying https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:30:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:30:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:30:59 INFO - Downloaded 71439909 bytes. 12:30:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:30:59 INFO - mkdir: /builds/slave/test/properties 12:30:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:30:59 INFO - Writing to file /builds/slave/test/properties/build_url 12:30:59 INFO - Contents: 12:30:59 INFO - build_url:https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:31:00 INFO - Running post-action listener: _resource_record_post_action 12:31:00 INFO - Running post-action listener: set_extra_try_arguments 12:31:00 INFO - [mozharness: 2017-04-15 19:31:00.570142Z] Finished download-and-extract step (success) 12:31:00 INFO - [mozharness: 2017-04-15 19:31:00.570279Z] Running populate-webroot step. 12:31:00 INFO - Running pre-action listener: _resource_record_pre_action 12:31:00 INFO - Running main action method: populate_webroot 12:31:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:31:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:31:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:31:00 INFO - '--tppagecycles', 12:31:00 INFO - '7'], 12:31:00 INFO - 'tests': ['tsvgm']}}, 12:31:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:31:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:31:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:31:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:31:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['damp', 'tps']}, 12:31:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'tests': ['damp', 'tps']}, 12:31:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['dromaeo_dom']}, 12:31:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:31:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:31:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:31:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'other-e10s': {'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['a11yr', 12:31:00 INFO - 'ts_paint', 12:31:00 INFO - 'tpaint', 12:31:00 INFO - 'sessionrestore', 12:31:00 INFO - 'sessionrestore_no_auto_restore', 12:31:00 INFO - 'tabpaint']}, 12:31:00 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:31:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:31:00 INFO - 'bloom_basic_ref']}, 12:31:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['tsvgx', 12:31:00 INFO - 'tsvgr_opacity', 12:31:00 INFO - 'tart', 12:31:00 INFO - 'tscrollx', 12:31:00 INFO - 'cart', 12:31:00 INFO - 'tsvg_static']}, 12:31:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:31:00 INFO - 'tsvgr_opacity', 12:31:00 INFO - 'tart', 12:31:00 INFO - 'tscrollx', 12:31:00 INFO - 'cart', 12:31:00 INFO - 'tsvg_static']}, 12:31:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'talos_options': ['--disable-e10s'], 12:31:00 INFO - 'tests': ['tp5o']}, 12:31:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:31:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'talos_options': ['--disable-e10s', 12:31:00 INFO - '--xperf_path', 12:31:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:00 INFO - 'tests': ['tp5n']}, 12:31:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:31:00 INFO - 'talos_options': ['--xperf_path', 12:31:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:00 INFO - 'tests': ['tp5n']}}, 12:31:00 INFO - 'talos.zip': {'path': '', 12:31:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:31:00 INFO - Running post-action listener: _resource_record_post_action 12:31:00 INFO - [mozharness: 2017-04-15 19:31:00.589305Z] Finished populate-webroot step (success) 12:31:00 INFO - [mozharness: 2017-04-15 19:31:00.589414Z] Running create-virtualenv step. 12:31:00 INFO - Running pre-action listener: _resource_record_pre_action 12:31:00 INFO - Running main action method: create_virtualenv 12:31:00 INFO - Creating virtualenv /builds/slave/test/build/venv 12:31:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:31:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:31:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:31:00 INFO - Using real prefix '/tools/python27' 12:31:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:31:01 INFO - Installing distribute.............................................................................................................................................................................................done. 12:31:04 INFO - Installing pip.................done. 12:31:04 INFO - Return code: 0 12:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:31:04 INFO - Reading from file tmpfile_stdout 12:31:04 INFO - Output received: 12:31:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:31:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:04 INFO - 'HOME': '/Users/cltbld', 12:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:04 INFO - 'LOGNAME': 'cltbld', 12:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:04 INFO - 'MOZ_NO_REMOTE': '1', 12:31:04 INFO - 'NO_EM_RESTART': '1', 12:31:04 INFO - 'PAGER': '/bin/cat', 12:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:04 INFO - 'PWD': '/builds/slave/test', 12:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:04 INFO - 'SHELL': '/bin/bash', 12:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:04 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:04 INFO - 'USER': 'cltbld', 12:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:04 INFO - 'XPC_FLAGS': '0x0', 12:31:04 INFO - 'XPC_SERVICE_NAME': '0', 12:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:31:04 INFO - Cleaning up... 12:31:04 INFO - Return code: 0 12:31:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:04 INFO - 'HOME': '/Users/cltbld', 12:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:04 INFO - 'LOGNAME': 'cltbld', 12:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:04 INFO - 'MOZ_NO_REMOTE': '1', 12:31:04 INFO - 'NO_EM_RESTART': '1', 12:31:04 INFO - 'PAGER': '/bin/cat', 12:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:04 INFO - 'PWD': '/builds/slave/test', 12:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:04 INFO - 'SHELL': '/bin/bash', 12:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:04 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:04 INFO - 'USER': 'cltbld', 12:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:04 INFO - 'XPC_FLAGS': '0x0', 12:31:04 INFO - 'XPC_SERVICE_NAME': '0', 12:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:05 INFO - Downloading/unpacking psutil>=3.1.1 12:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:31:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:31:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:31:09 INFO - Installing collected packages: psutil 12:31:09 INFO - Running setup.py install for psutil 12:31:09 INFO - building 'psutil._psutil_osx' extension 12:31:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:31:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:31:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:31:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:31:10 INFO - building 'psutil._psutil_posix' extension 12:31:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:31:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:31:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:31:10 INFO - ^ 12:31:10 INFO - 1 warning generated. 12:31:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:31:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:31:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:31:10 INFO - Successfully installed psutil 12:31:10 INFO - Cleaning up... 12:31:10 INFO - Return code: 0 12:31:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:10 INFO - 'HOME': '/Users/cltbld', 12:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:10 INFO - 'LOGNAME': 'cltbld', 12:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:10 INFO - 'MOZ_NO_REMOTE': '1', 12:31:10 INFO - 'NO_EM_RESTART': '1', 12:31:10 INFO - 'PAGER': '/bin/cat', 12:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:10 INFO - 'PWD': '/builds/slave/test', 12:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:10 INFO - 'SHELL': '/bin/bash', 12:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:10 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:10 INFO - 'USER': 'cltbld', 12:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:10 INFO - 'XPC_FLAGS': '0x0', 12:31:10 INFO - 'XPC_SERVICE_NAME': '0', 12:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:31:13 INFO - Installing collected packages: mozsystemmonitor 12:31:13 INFO - Running setup.py install for mozsystemmonitor 12:31:13 INFO - Successfully installed mozsystemmonitor 12:31:13 INFO - Cleaning up... 12:31:13 INFO - Return code: 0 12:31:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:13 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:13 INFO - 'HOME': '/Users/cltbld', 12:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:13 INFO - 'LOGNAME': 'cltbld', 12:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:13 INFO - 'MOZ_NO_REMOTE': '1', 12:31:13 INFO - 'NO_EM_RESTART': '1', 12:31:13 INFO - 'PAGER': '/bin/cat', 12:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:13 INFO - 'PWD': '/builds/slave/test', 12:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:13 INFO - 'SHELL': '/bin/bash', 12:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:13 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:13 INFO - 'USER': 'cltbld', 12:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:13 INFO - 'XPC_FLAGS': '0x0', 12:31:13 INFO - 'XPC_SERVICE_NAME': '0', 12:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:13 INFO - Downloading/unpacking jsonschema==2.5.1 12:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:31:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:16 INFO - Downloading functools32-3.2.3-2.tar.gz 12:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:31:16 INFO - warning: no files found matching '*.txt' 12:31:16 INFO - no previously-included directories found matching 'build' 12:31:16 INFO - no previously-included directories found matching 'dist' 12:31:16 INFO - no previously-included directories found matching '.git*' 12:31:16 INFO - Installing collected packages: jsonschema, functools32 12:31:17 INFO - Running setup.py install for functools32 12:31:17 INFO - warning: no files found matching '*.txt' 12:31:17 INFO - no previously-included directories found matching 'build' 12:31:17 INFO - no previously-included directories found matching 'dist' 12:31:17 INFO - no previously-included directories found matching '.git*' 12:31:17 INFO - Successfully installed jsonschema functools32 12:31:17 INFO - Cleaning up... 12:31:17 INFO - Return code: 0 12:31:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:17 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:17 INFO - 'HOME': '/Users/cltbld', 12:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:17 INFO - 'LOGNAME': 'cltbld', 12:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:17 INFO - 'MOZ_NO_REMOTE': '1', 12:31:17 INFO - 'NO_EM_RESTART': '1', 12:31:17 INFO - 'PAGER': '/bin/cat', 12:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:17 INFO - 'PWD': '/builds/slave/test', 12:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:17 INFO - 'SHELL': '/bin/bash', 12:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:17 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:17 INFO - 'USER': 'cltbld', 12:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:17 INFO - 'XPC_FLAGS': '0x0', 12:31:17 INFO - 'XPC_SERVICE_NAME': '0', 12:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:31:17 INFO - Cleaning up... 12:31:17 INFO - Return code: 0 12:31:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:17 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:17 INFO - 'HOME': '/Users/cltbld', 12:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:17 INFO - 'LOGNAME': 'cltbld', 12:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:17 INFO - 'MOZ_NO_REMOTE': '1', 12:31:17 INFO - 'NO_EM_RESTART': '1', 12:31:17 INFO - 'PAGER': '/bin/cat', 12:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:17 INFO - 'PWD': '/builds/slave/test', 12:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:17 INFO - 'SHELL': '/bin/bash', 12:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:17 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:17 INFO - 'USER': 'cltbld', 12:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:17 INFO - 'XPC_FLAGS': '0x0', 12:31:17 INFO - 'XPC_SERVICE_NAME': '0', 12:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:17 INFO - Downloading/unpacking blobuploader==1.2.4 12:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:31:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:31:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:21 INFO - Downloading docopt-0.6.1.tar.gz 12:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:31:21 INFO - Installing collected packages: blobuploader, requests, docopt 12:31:21 INFO - Running setup.py install for blobuploader 12:31:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:31:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:31:21 INFO - Running setup.py install for requests 12:31:22 INFO - Running setup.py install for docopt 12:31:22 INFO - Successfully installed blobuploader requests docopt 12:31:22 INFO - Cleaning up... 12:31:22 INFO - Return code: 0 12:31:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:22 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:22 INFO - 'HOME': '/Users/cltbld', 12:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:22 INFO - 'LOGNAME': 'cltbld', 12:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:22 INFO - 'MOZ_NO_REMOTE': '1', 12:31:22 INFO - 'NO_EM_RESTART': '1', 12:31:22 INFO - 'PAGER': '/bin/cat', 12:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:22 INFO - 'PWD': '/builds/slave/test', 12:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:22 INFO - 'SHELL': '/bin/bash', 12:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:22 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:22 INFO - 'USER': 'cltbld', 12:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:22 INFO - 'XPC_FLAGS': '0x0', 12:31:22 INFO - 'XPC_SERVICE_NAME': '0', 12:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:31:22 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-a9desU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-WF7JR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-3MVKLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-26tEw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-l26O9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-RM78a3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-d3Mf7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-jOGrTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-kv_KdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-jHPLmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:31:23 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-z0gjox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-eWdIeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-NmANcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-dSBR73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-iiqqbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-kupfMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:31:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-umYrO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:31:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:31:24 INFO - Running setup.py install for manifestparser 12:31:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:31:24 INFO - Running setup.py install for mozcrash 12:31:24 INFO - Running setup.py install for mozdebug 12:31:25 INFO - Running setup.py install for mozdevice 12:31:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Running setup.py install for mozfile 12:31:25 INFO - Running setup.py install for mozhttpd 12:31:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Running setup.py install for mozinfo 12:31:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Running setup.py install for mozInstall 12:31:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:31:25 INFO - Running setup.py install for mozleak 12:31:25 INFO - Running setup.py install for mozlog 12:31:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Running setup.py install for moznetwork 12:31:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Running setup.py install for mozprocess 12:31:26 INFO - Running setup.py install for mozprofile 12:31:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Running setup.py install for mozrunner 12:31:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:31:26 INFO - Running setup.py install for mozscreenshot 12:31:26 INFO - Running setup.py install for moztest 12:31:27 INFO - Running setup.py install for mozversion 12:31:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:31:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:31:27 INFO - Cleaning up... 12:31:27 INFO - Return code: 0 12:31:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:27 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:27 INFO - 'HOME': '/Users/cltbld', 12:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:27 INFO - 'LOGNAME': 'cltbld', 12:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:27 INFO - 'MOZ_NO_REMOTE': '1', 12:31:27 INFO - 'NO_EM_RESTART': '1', 12:31:27 INFO - 'PAGER': '/bin/cat', 12:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:27 INFO - 'PWD': '/builds/slave/test', 12:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:27 INFO - 'SHELL': '/bin/bash', 12:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:27 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:27 INFO - 'USER': 'cltbld', 12:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:27 INFO - 'XPC_FLAGS': '0x0', 12:31:27 INFO - 'XPC_SERVICE_NAME': '0', 12:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:31:27 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-rNczqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:31:27 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-u7TxL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:31:27 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Bdx08w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:31:27 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-twIgt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:31:27 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-gXrIEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-z6tmSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-7RxQyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-1Fumug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-xmvrX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-utAzsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-iMkAbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Ikpq2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:31:28 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)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-HzOOHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:31:28 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-FmkRXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:29 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ZDBJzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:31:29 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-JnBn0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:31:29 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-jOJATu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:31:29 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:31:29 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:31:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:31 INFO - Downloading blessings-1.6.tar.gz 12:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:31:32 INFO - Installing collected packages: blessings 12:31:32 INFO - Running setup.py install for blessings 12:31:32 INFO - Successfully installed blessings 12:31:32 INFO - Cleaning up... 12:31:32 INFO - Return code: 0 12:31:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:31:32 INFO - Reading from file tmpfile_stdout 12:31:32 INFO - Current package versions: 12:31:32 INFO - blessings == 1.6 12:31:32 INFO - blobuploader == 1.2.4 12:31:32 INFO - docopt == 0.6.1 12:31:32 INFO - functools32 == 3.2.3-2 12:31:32 INFO - jsonschema == 2.5.1 12:31:32 INFO - manifestparser == 1.1 12:31:32 INFO - mozInstall == 1.12 12:31:32 INFO - mozcrash == 1.0 12:31:32 INFO - mozdebug == 0.1 12:31:32 INFO - mozdevice == 0.50 12:31:32 INFO - mozfile == 1.2 12:31:32 INFO - mozhttpd == 0.7 12:31:32 INFO - mozinfo == 0.9 12:31:32 INFO - mozleak == 0.1 12:31:32 INFO - mozlog == 3.4 12:31:32 INFO - moznetwork == 0.27 12:31:32 INFO - mozprocess == 0.25 12:31:32 INFO - mozprofile == 0.28 12:31:32 INFO - mozrunner == 6.13 12:31:32 INFO - mozscreenshot == 0.1 12:31:32 INFO - mozsystemmonitor == 0.3 12:31:32 INFO - moztest == 0.8 12:31:32 INFO - mozversion == 1.4 12:31:32 INFO - psutil == 3.1.1 12:31:32 INFO - requests == 1.2.3 12:31:32 INFO - wsgiref == 0.1.2 12:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:32 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 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:32 INFO - 'HOME': '/Users/cltbld', 12:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:32 INFO - 'LOGNAME': 'cltbld', 12:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:32 INFO - 'MOZ_NO_REMOTE': '1', 12:31:32 INFO - 'NO_EM_RESTART': '1', 12:31:32 INFO - 'PAGER': '/bin/cat', 12:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:32 INFO - 'PWD': '/builds/slave/test', 12:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:32 INFO - 'SHELL': '/bin/bash', 12:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:32 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:32 INFO - 'USER': 'cltbld', 12:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:32 INFO - 'XPC_FLAGS': '0x0', 12:31:32 INFO - 'XPC_SERVICE_NAME': '0', 12:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:31:32 INFO - Cleaning up... 12:31:32 INFO - Return code: 0 12:31:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b39c73350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, '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.30eEBgnnso/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.fGiYF3GuV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:32 INFO - 'HOME': '/Users/cltbld', 12:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:32 INFO - 'LOGNAME': 'cltbld', 12:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:32 INFO - 'MOZ_NO_REMOTE': '1', 12:31:32 INFO - 'NO_EM_RESTART': '1', 12:31:32 INFO - 'PAGER': '/bin/cat', 12:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:32 INFO - 'PWD': '/builds/slave/test', 12:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:32 INFO - 'SHELL': '/bin/bash', 12:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:32 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:32 INFO - 'USER': 'cltbld', 12:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:32 INFO - 'XPC_FLAGS': '0x0', 12:31:32 INFO - 'XPC_SERVICE_NAME': '0', 12:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:31:33 INFO - Cleaning up... 12:31:33 INFO - Return code: 0 12:31:33 INFO - Running post-action listener: _resource_record_post_action 12:31:33 INFO - Running post-action listener: _start_resource_monitoring 12:31:33 INFO - Starting resource monitoring. 12:31:33 INFO - [mozharness: 2017-04-15 19:31:33.214349Z] Finished create-virtualenv step (success) 12:31:33 INFO - [mozharness: 2017-04-15 19:31:33.215292Z] Running install step. 12:31:33 INFO - Running pre-action listener: _resource_record_pre_action 12:31:33 INFO - Running main action method: install 12:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:31:33 INFO - Reading from file tmpfile_stdout 12:31:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:31:33 INFO - Reading from file tmpfile_stdout 12:31:33 INFO - Output received: 12:31:33 INFO - Usage: mozinstall [options] installer 12:31:33 INFO - Options: 12:31:33 INFO - -h, --help show this help message and exit 12:31:33 INFO - -d DEST, --destination=DEST 12:31:33 INFO - Directory to install application into. [default: 12:31:33 INFO - "/builds/slave/test"] 12:31:33 INFO - --app=APP Application being installed. [default: firefox] 12:31:33 INFO - mkdir: /builds/slave/test/build/application 12:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:31:53 INFO - Reading from file tmpfile_stdout 12:31:53 INFO - Output received: 12:31:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:31:53 INFO - Running post-action listener: _resource_record_post_action 12:31:53 INFO - [mozharness: 2017-04-15 19:31:53.634101Z] Finished install step (success) 12:31:53 INFO - [mozharness: 2017-04-15 19:31:53.634233Z] Running run-tests step. 12:31:53 INFO - Running pre-action listener: _resource_record_pre_action 12:31:53 INFO - Running main action method: run_tests 12:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:53 INFO - 2017-04-15 12:31:53.648 screenresolution[1634:9685] starting screenresolution argv=screenresolution get 12:31:53 INFO - 2017-04-15 12:31:53.651 screenresolution[1634:9685] Display 0: 1600x1200x32@60 12:31:53 INFO - 2017-04-15 12:31:53.657 screenresolution[1635:9688] starting screenresolution argv=screenresolution list 12:31:53 INFO - Available Modes on Display 0 12:31:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:53 INFO - Intel Iris: 12:31:53 INFO - Chipset Model: Intel Iris 12:31:53 INFO - Type: GPU 12:31:53 INFO - Bus: Built-In 12:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:53 INFO - Vendor: Intel (0x8086) 12:31:53 INFO - Device ID: 0x0a2e 12:31:53 INFO - Revision ID: 0x0009 12:31:53 INFO - Displays: 12:31:53 INFO - Display: 12:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:53 INFO - Display Serial Number: 1600x1200 60 12:31:53 INFO - Main Display: Yes 12:31:53 INFO - Mirror: Off 12:31:53 INFO - Online: Yes 12:31:53 INFO - Rotation: Supported 12:31:53 INFO - Return code: 0 12:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:31:53 INFO - Python 2.7.3 12:31:53 INFO - Return code: 0 12:31:53 INFO - grabbing minidump binary from tooltool 12:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:53 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 0x10bebdd30>, '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 0x10bde3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beb2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:31:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:31:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:31:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:31:54 INFO - Return code: 0 12:31:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:31:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:31:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:31:54 INFO - ENV: RUST_BACKTRACE is now 1 12:31:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:31:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0261 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.30eEBgnnso/Render', 12:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:54 INFO - 'HOME': '/Users/cltbld', 12:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:54 INFO - 'LOGNAME': 'cltbld', 12:31:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:54 INFO - 'MOZ_NO_REMOTE': '1', 12:31:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:54 INFO - 'NO_EM_RESTART': '1', 12:31:54 INFO - 'PAGER': '/bin/cat', 12:31:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:54 INFO - 'PWD': '/builds/slave/test', 12:31:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:54 INFO - 'RUST_BACKTRACE': '1', 12:31:54 INFO - 'SHELL': '/bin/bash', 12:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fGiYF3GuV0/Listeners', 12:31:54 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 12:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:54 INFO - 'USER': 'cltbld', 12:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:54 INFO - 'XPC_FLAGS': '0x0', 12:31:54 INFO - 'XPC_SERVICE_NAME': '0', 12:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:31:54 INFO - mozversion application_buildid: 20170415110113 12:31:54 INFO - mozversion application_changeset: d65b53cf8fd9f7747c7ee4e3ea96f12434917daa 12:31:54 INFO - mozversion application_display_name: Nightly 12:31:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:31:54 INFO - mozversion application_name: Firefox 12:31:54 INFO - mozversion application_remotingname: firefox 12:31:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:31:54 INFO - mozversion application_vendor: Mozilla 12:31:54 INFO - mozversion application_version: 55.0a1 12:31:54 INFO - mozversion platform_buildid: 20170415110113 12:31:54 INFO - mozversion platform_changeset: d65b53cf8fd9f7747c7ee4e3ea96f12434917daa 12:31:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:31:54 INFO - mozversion platform_version: 55.0a1 12:31:54 INFO - using testdate: 1492284714 12:31:54 INFO - actual date: 1492284714 12:31:54 INFO - starting webserver on 'localhost:49241' 12:31:54 INFO - SUITE-START | Running 2 tests 12:31:54 INFO - TEST-START | dromaeo_css 12:31:54 INFO - operating with platform_type : mac_ 12:31:54 INFO - Initialising browser for dromaeo_css test... 12:31:54 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpxv3xCD/profile http://localhost:49241/getInfo.html) 12:31:57 INFO - PID 1645 | 2017-04-15 12:31:57.133 plugin-container[1647:9875] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 12:31:57 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:57 INFO - PID 1645 | 2017-04-15 12:31:57.135 plugin-container[1647:9875] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9317, name = 'com.apple.CFPasteboardClient' 12:31:57 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:57 INFO - PID 1645 | 2017-04-15 12:31:57.135 plugin-container[1647:9875] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:31:57 INFO - PID 1645 | __metrics Screen width/height:1600/1200 12:31:57 INFO - PID 1645 | colorDepth:24 12:31:57 INFO - PID 1645 | Browser inner width/height: 1024/689 12:31:57 INFO - PID 1645 | __metrics 12:31:57 ERROR - PID 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:31:58 INFO - TEST-INFO | 1645: exit 0 12:31:58 INFO - Browser initialized. 12:31:58 INFO - Running cycle 1/1 for dromaeo_css test... 12:31:58 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpxv3xCD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:32:00 INFO - PID 1649 | 2017-04-15 12:32:00.979 plugin-container[1650:10011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453b, name = 'com.apple.tsm.portname' 12:32:00 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:00 INFO - PID 1649 | 2017-04-15 12:32:00.980 plugin-container[1650:10011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 12:32:00 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:32:00 INFO - PID 1649 | 2017-04-15 12:32:00.980 plugin-container[1650:10011] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:32:10 INFO - PID 1649 | RSS: Main: 236863488 12:32:10 INFO - PID 1649 | 12:35:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 12:35:16 INFO - PID 1649 | RSS: Main: 252297216 12:35:16 INFO - PID 1649 | 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 12:36:58 INFO - PID 1649 | 12:36:58 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:58 INFO - PID 1649 | Full stack: 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 12:36:58 INFO - PID 1649 | 12:36:58 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:58 INFO - PID 1649 | Full stack: 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 12:36:58 INFO - PID 1649 | 12:36:58 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:58 INFO - PID 1649 | Full stack: 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | ************************* 12:36:58 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 12:36:58 INFO - PID 1649 | 12:36:58 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:58 INFO - PID 1649 | Full stack: 12:36:58 INFO - PID 1649 | ************************* 12:38:18 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 12:38:18 INFO - PID 1649 | RSS: Main: 258154496 12:38:18 INFO - PID 1649 | 12:41:20 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 12:41:21 INFO - PID 1649 | RSS: Main: 270921728 12:41:21 INFO - PID 1649 | 12:44:23 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:44:23 INFO - PID 1649 | RSS: Main: 263454720 12:44:23 INFO - PID 1649 | 12:47:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 12:47:27 INFO - PID 1649 | RSS: Main: 272658432 12:47:27 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 12:50:29 INFO - PID 1649 | RSS: Main: 267337728 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | __start_tp_report 12:50:29 INFO - PID 1649 | _x_x_mozilla_page_load 12:50:29 INFO - PID 1649 | _x_x_mozilla_page_load_details 12:50:29 INFO - PID 1649 | |i|pagename|runs| 12:50:29 INFO - PID 1649 | |0;dojo.html;3173.761967852411;3534.1164708822794;3586.982065089674;3601.4417765246394;3605.2248766515195;5652.604317697757;5764.913526297109;6148.600340977833;6284.183056202693;6306.968465157674;5022.522860328263;5145.382554093505;6132.386102851673;6151.538634602401;6214.943459611865;5451.509364157225;5461.972690136554;5500.80791557782;5528.834134975937;5616.63491873029;5498.422665620113;5526.447355264481;5593.300837395325;5614.747336369853;5623.184638227454;5385.569154467633;5483.232347471318;5533.170024496318;5548.278723765925;5566.972165139168;5423.810166644149;5438.564914806806;5484.7531861066345;5486.780528778844;5523.889522209573;5393.352797664266;5402.189671549299;5426.8371948841595;5444.292242008553;5495.670259784426;6046.425589569028;6064.575479716422;6086;6103.29812071613;6215.906761398537;5448.91826622601;6558.573692709959;6784.3554862288;6791.796246112624;6793.116894803693;5982.670953097578;6567.211934567835;6794.728210871559;6903.102596662452;6907.033015377851;6121.910283036266;6283.403076707706;6384.680765961776;6412.871742565169;6421.2615549211505;6820.727170913207;6952.270011648805;6984.196817365966;7001.92998070023;7034.261402552761;6398.584092034003;6487.935120648733;6533.052707357407;6676.833079172962;6684.231313398924;6779.830504237433;6812.93187068123;6856.040154378391;6863.691133898887;6889.621070841152;6492.318306577741;6756.6959486822225;6773.695183716744;6778;6804.081449004321;6579.572327798678;6633.104530888365;6668.499862510331;6703.028060931138;6733.696983635748;5411.810586629449;5416.729163541807;5425.755840987165;5427.674339539641;5473.370562385297;4520.389747384127;4744.501827308152;4758.738269395148;4765.0946320199055;4781.856544303677;6043.758249670052;6160.815175544741;6303.149074874925;6349.174607301114;6356.968215158895;6829.180498340231;6898.0687607173395;6910.48171387148;6920.204176519765;6920.757773478005;6346.650934198663;6346.8413289667205;6359.332270111664;6390.680465976682;6399.13611662429;5819.592628515963;5922.052471604608;5936.881262374728;5948.613340132964;6059.151718759377;5780.9883270428345;6644.568103073285;6844.349786770249;6948.096747423005;6956.339147780953;5613.214150019082;5954.122505846609;6130.448259656652;6825.317468253135;6831.28271531482;6903.792886213421;6941.236463989058;6953.096097507494;6971.372576468142;6977.092977912839;6495.317991610813;6642.568233064835;6729;6754;6761.628110453972;6892.827679307857;6923.861522769617;6933.3066693332285;6942;6956.7217311309005;6275.309715931153;6467.644279564669;6481.189851268591;6570.408663220333;6667.233268174222;5550.611457198119;6555.70499327541;6595.406413422623;6606.53754237218;6612.5040621952585;6165.136880836776;6256.249250090018;6337.65142917144;6389.137466442156;6440.2915679276175;5674.574406919415;6444.968804991179;6450.193725784277;6598.868022639616;6599.670016499252;6385.13800636908;6409.551331406757;6412.294647588855;6444.1622589065;6485.837854053686 12:50:29 INFO - PID 1649 | |1;ext.html;635.4439865118022;1266.461753754654;1271.1292764456348;1278.635588857176;1297.3772589157204;37966.810165949166;38592.42111368328;38775.61224387755;38835.80582097093;38927.80536097316;38158.42762358567;38468.23063538728;38469.65356212533;38479.422808657895;38554.22891542168;8379.874301885462;8595.95702021492;8620.482771033758;8635;8665;8525.95737021314;8576.356773242002;8656.783580410478;8656.956715216415;8660.783480413009;8573.314134869226;8609.526476043815;8671.869921951176;8675.479471231747;8683.956580217091;8593.613287402082;8634.697785577506;8650.524221167832;8680.913190868074;8689.261412779859;8333.499990000559;8414.663413463453;8441.70454034106;8455.95772021136;8458.74623761288;38327;38412;38507.22985540301;38562.421563676566;38646.420303695464;3559.181388280684;3599.8200089995657;3600.4599310103695;3601.729870259715;3616.710663146942;31165.844170779226;31203.219919501968;31262.06213813589;31287.217819554466;31434.842825785952;2672.2784848090905;3149.118246890874;3188.8883889064005;3191.010786656144;3194.9201269968203;298603;301411.9999999978;301434;301567;302536;49038;49068;49425.75287123613;49510;49614.75192624014;42473;50789.23816142761;51243.23135152976;53083.46916530863;53103.34483275821;14318.57044288673;14443.566692999228;14537.127772333693;14545;14565.980381373201;11414.630244370728;11608.709782255512;11614.419279036014;11619.244749091451;11635.59275425373;13820.37808298629;13834.861651383559;13880.640291150528;13887.722245555033;13993.090449120775;5562.943040822231;5717.142428635738;6070.666113363719;6115.694215289218;6122.938770612237;895.9820803583892;943.8772959515356;991.6677912899115;1043.8590790243372;1085.8154113800672;24305.513889722108;24461.633075503883;24723.62914556283;24776.132835350676;24881.253562393158;1129.3336931210624;1140.463981928509;1148.328227986637;1159.0843233845337;1161.7211869151513;10010.849837252446;11006.889931100746;11451.141164412702;12048.698782530508;12642.810357844639;332926;336226.31886840897;338998;339779.60220397974;339879;55004.449955499935;55182.72408638011;55573.722131389084;55588;55645.443545564834;1490.2772155504572;1512.6445285358152;1546.0878081931714;1549.0860392368331;1577.085290531489;19403.320883769564;23864.522709545363;23972.160974365812;24033.519329612962;24110.276691699277;38444.42333365058;38898;39027.60972390353;39086.6091339083;39128.2174356517;279599;282618;283782;284018.73971890024;284818;34350;34591.654083458845;34608.65391346054;34671.82664086663;34729.65270347264;44318.77840610776;45482;45573.316400254684;45591.31613025741;45713.54286457226;29414.705852941195;29937.85031074831;30024.849875750482;30857.845710771304;30890.918817841266;29826.701732982394;30380.84809575938;30597.16417014897;30633.23416914595;30714.77140914428;35813.1046723834;36353;36397.90806275816;36472.452913205794;36855.26289474142;25355;30854.84572577123;31422.842885785423;31508.684913150573;31765;19320.84074955507;23010.769892301534;24047;24170.395740106636;24597.139100131408 12:50:29 INFO - PID 1649 | |2;jquery.html;359.2419993813054;374.887533739878;424.81732854872394;431.79921336578485;458.617054759276;21637.242696505626;21646.24238151665;21647.56704865904;21995.120195192172;22226.444338891535;14804;21767.782322176772;21833.890830545864;22302;22392.552148957013;5983.760649574023;6307.684615769216;6314.936850631504;6325.272593651743;6326;5991.820245392623;6130.816075517741;6155.938440615604;6208.037754148114;6219.657918814463;6147;6147.846303842395;6158.538109641795;6162.630242185461;6176.536759743038;5839.270091238595;6085.665288409135;6087.756489740405;6127.356627554132;6129.325774164839;5387.703676297802;5414.593905457106;5419.620626556142;5438.238646589481;5447.727613619343;27211.455770884473;27549.862250688617;27643.894244230207;27687.584686229722;27793.722062779518;2139.229877244191;2194.868307901515;2214.136486770145;2214.2360885494477;2214.5460180662926;17504.474865754048;17634.382796602058;17781.933084015;17909.910450447795;18011.27954881803;2154.4613846538364;2175.4996350839397;2205.9889700551553;2207.834412419075;2212.8672279663274;157582.63626045617;162180;165446.17276913658;165664;166084;12612.990960723304;12657.493700251887;12848.550300739429;12859.807102893465;13048.673783155307;16266.75599866003;16361.545768134636;16372.263248153862;16393.59016024609;16413.261403236884;8699.478031318014;9024.954875225581;9035.277177825757;9076.410033347944;9122.31582631292;8628.827423451621;8652.826943461096;8653.697120600875;8846.203841654282;8847.982482014522;7723.497972631761;7787.5716835574585;7802.492837965628;7883.172266911892;7885.566293853778;2208.6134926387817;2228.053077442081;2228.4428892776805;2228.8105511031745;2421.188901717909;932.2215949681956;949.3544389814992;965.2960191470172;969.2488321550854;1001.5543083327849;19171.94554299527;19659.508512287306;20091.799082009285;20295;20485.59028819415;969.7042402067297;984.6504490905769;994.9452780097164;1002.7693630465034;1014.5282443663732;10166.390016599027;10549.736256593647;10588.311759735592;10627.937206279465;10629.46852657364;496145;565045;575271;576442;577372;119562.40218799024;125123;138182.3090884539;139058;139406.3029684845;1169.2750494693175;1193.9378575724493;1214.975700486003;1218.0620921890095;1221.2611370120949;19012.23951041943;20691.586168276852;21846.672299915197;21904.342869713935;21944.89027554852;42548.78725606352;56978.43021569897;57213.71393143007;58032;58729.70635146797;78909;79568.80646790419;80761.78857317027;81977;83988;8689.652413903374;8699.956500217459;8729.3889427742;9042;9132.086791320815;11707.88292117102;11884.465199066235;14163.575092747235;14396.35216415243;14555.92722036383;11735.823962640396;11741.589044383405;12005.459754310903;12123.090768192244;12130.150889437655;9110.407823491452;11120.44397780124;11338.489767960296;11485.770284594428;11566.132540059361;12252.448639811408;12267.815982760087;12390.070744694003;12435.44040518197;12460.626181214579;7457.813554661177;8858.468491890508;8873.511956842436;8906.465612063297;8969.955150224207;10067.993200679874;11765.235259708092;11857.40712964331;11968.700782480508;12059.81910271329 12:50:29 INFO - PID 1649 | |3;mootools.html;1418.212891845026;1466.3108339080636;1471.382019551788;1481.3482067890127;1502.8121484814399;11441.370724610153;11856.051515878742;11914.463849126789;11931.224470409417;12353.444095015724;11802.527898884056;11804.937555619992;12005.219660722047;12014.45934932928;12025.458854351553;2501.362152651076;2549.1842610364693;2562.269753120361;2566.2044766122563;2571.537123317802;2505.473850491399;2518.1438310974263;2539.619057141425;2557.181701855407;2557.9895941103255;2494.538510375577;2536.3912660961328;2549.2097449790626;2552.1067626330823;2555.56555385584;2470.0984140788573;2512.208654273907;2526.873656317177;2537.1246919812634;2541.745825417462;2500.1374525788574;2504.098524531167;2511.6483692283095;2514.6479492871013;2523.082457332422;11661;11887.049036077093;11903.464344104535;11918.106142039382;11927.88072119285;6577.572457790228;6798.252192258848;6833.350831670983;6833.692483838189;6844.041834143224;4202.306619407794;4408.272635015219;4453.309736990772;4499;4532.388127602764;1016.5222345497605;1235.8084496902995;1277.5081593586442;1289.9806502902463;1293.2886912198348;16129.354825806953;16393.91803040989;16508.66982660352;16529.421470248602;16747.581310467212;5635.971820140873;5749.68376739275;5835.766569337264;5925.911111333334;5944.940550594525;12069.034477241976;12253.938730306292;12288.631341059783;12292.938535307267;12324.07569432296;5438.483344082306;6605.1083103780375;6639.070530125786;6643.302453242437;6644.767433139817;5959.314678811992;6083.9391606084255;6196.504279657616;6249.500039996789;6288.056791481315;3692.280005398975;3951.229510245532;4426.416379221007;4506.324051392252;4507.729536227836;1295.8704129586965;1309.240640428549;1314.5793346129337;1324.1790090144173;1327.9933600332129;740.8184994676338;786.5831109511968;798.62863768348;815.9877601835977;817.5421763812284;5216.582673386196;6148.846278843064;6161.6611086389785;6236.376362363818;6387.936120638827;583.2738240890119;593.2643522032648;595.8748662780864;605.5246631394334;609.6555446172898;4303.354496825501;4391.736495810262;4438.423005009328;4454.77726113693;4543.863684089482;14723.631909202142;15188.240587970779;15337.30982105808;15346.002509837048;15360.231988400536;14608;15205.391784328724;15206.391744330103;15209.239538023054;15212.391504339923;6804.727810887608;6846.828829279308;7499.2500749924575;7653.732119375795;8607.397482176313;6062.6665533394325;6384.936364495468;7122.358987691133;7330.486865919334;7377.446691498267;13425.194488330731;13442.126261792951;13456.25990570528;13540.661483462993;13541.187528748307;13711.725765484834;13717.17696938187;13892.93053534726;13901.304934753423;14037.789433158307;9544.618215271312;9825.263105267277;9851.458169800442;9855.95072024664;9867.210623150275;10885.455727213766;10917.962793534602;10952.288101273392;10999.505022274177;11074.114070874472;8362.661974084118;9307.72076837696;9342.859857102014;9412;9466.857997130186;7998.880016799636;9084.54577271147;9211.815763684823;9239.39943903644;9340.252779777737;8288.21261980111;8431.494110353398;8432.915670843213;8448.366372522209;8454.535000575028;6076.179715738495;6197.535184861244;6231.283402408781;6250.468710159687;6252.624842509464;4587.449506059161;5913.34953155144;6222.439980401786;6234.3453937337745;6261.4364707174755 12:50:29 INFO - PID 1649 | |4;prototype.html;398.76273617197756;405.23612989514766;407.8884840804688;414.3308556680959;418.10734082733376;5004.524570165833;5380.892382152355;6091.7258723357445;6293.811185664432;6406.903896441557;4420.961074147573;5284.313039304885;5778.6243894146855;6098.695065246731;6144.754209831613;4248.468941382327;4329.155814616143;4504.054148628792;4539.523350048247;4558.133954548625;3734.3091528067257;4513.14250292445;4514.548545145492;4560.749158796265;4565.33802598623;3675.191457879262;3841.9818748031794;3968.868872371375;4280.908368366062;4510.842120525782;3618.6200448952877;3897.395903634941;4237.703360764748;4503.9866030143285;4536.591706746392;2968.554716792477;3048.9481129010455;3728;3869.071422858522;3884.961150388488;4583.839565615188;5495.835124946258;5687.890861282051;6018.307894592134;6035.7887473938645;4673.485916549177;5144;5433.755481003364;5450.645708028966;5487.6158668893195;5966.642001479925;6541.67291635421;6595.406413422767;6624.635645039521;6672.39948404641;3894.435306880515;4417.359482874997;4425.5353187915125;4441.933370999438;4468.218061839165;6558.770443034471;6700.597964122168;6978.337057979484;6986.650667466657;7057.294270573005;4134.503859536875;4287.506936702257;4497.505274419811;4515.119551687455;4537.568930951555;4457.219986502414;4581.312803079565;4584.724916504953;4586.380838586815;4589.173948689201;4228.6617070634275;4710.882227944328;4712.41024515117;5384.165454354581;5397.568194544427;6198.442140207313;6632.303608121078;6660;6666.366695163952;6712.783757460749;1509.7659862721075;1529.8296802945756;1547.3191795609896;1576.3615735627088;1576.4482431149236;1096.9341839489657;1135.28477059452;1184.5202692909386;1193.7194759231575;1211.6365090472823;5450.427705090988;6216.875662486725;6223.906641400383;6235.657038862906;6289.578512272717;5910.468057874812;6715.227748808852;6896.827579310457;6897.862042759117;6948.270431604608;5583.916241256385;5783.421657834183;6072.969635151796;6127.632342059402;6292.842678933063;3441.63862794408;3589.353916295092;3615.1685112423766;3630.8910732678064;3637.454381842692;5870.148828419855;6142.293636231891;6785.355391237825;6986.7205311787975;7085.492681122774;5784.537237021028;5843.766249350064;6893.7931862044215;6897.896531552031;7068.3285087916565;5746.166805813134;6513.023046543057;6662.966685166543;6695.727811715758;7102.4318054555515;5797.8550536236935;6776.695048722918;6923.792286231421;6966.5471744336455;6971.58170509771;5194.74026298676;5978.970105149447;6714.966425167843;6783.93216067833;6873.381395674413;7113.359797618239;7118.572885626879;7134.6490132400695;7147.964260178665;7155.713771449292;4161.459010328638;4215.662746980173;4235.089455767024;4333.479982402006;4515.1195516873895;5645.4072322405555;6471.579347342407;6869.278725733726;6910.479694467209;6951.026856240232;5762.308522977102;6386.808395748135;6570.375814297634;6689.69896354675;6690.899636505554;5433.864153396196;5780.682062486603;6641.435477984425;6657.603262825476;6666.400023997863;6517.543771936109;6530.6081635100145;6544.116544266653;6556.672166391757;6556.737730490918;5691.373948865538;6310.337414571588;6554.672266386567;6639.933600664125;6659.933400666125;6127.264728232641;6478.578892371981;6498.610083395011;6501.707423166064;6514.218293804584 12:50:29 INFO - PID 1649 | |5;yui.html;840.2983508770177;843.9324854011679;864.7547531554561;870.2776695342866;879.8196369744201;14107.153570785735;14754.188519631416;16144.273507692153;16564.25460854263;16623.002619842802;12996.805047924265;13577.117487363315;17686.734698979526;17715.911420442913;17747.201375938082;961.7259081161887;1004.9196064314874;1009.7728011197495;1013.8276492996172;1021.3565453764119;981.0434826044585;1002.2032484175098;1012.1953047327393;1029.6241871716807;1031.2162756305188;1021.1218352217087;1045.1482042135635;1062.4900047976937;1068.9893101068967;1071.8445825355318;1051.4847724614922;1052.9420881851495;1054.7204990677476;1054.852320675106;1058.1534772182263;764.9349805266559;779.2674885607511;780.9804754881117;782.2881178127933;786.1980779604828;16597.83402165975;16911.915440422843;17029.403970861084;17396.39112631052;17554.561135971577;17300.913495432444;17650.646987060314;17733.024683642394;17984.730229046443;18003.18985645649;14183.290835458185;14236.145831250158;14280.85719142816;14325.140491570435;14474.927625361806;3510.4909788080586;3529.0824385659657;3559;3561.0741207286032;3562.340966921102;96689.5165524175;105805.47097264542;113790;120294;126924;13732.313384330942;14311.212883291519;14316.427342906376;14355.497557585435;14399.352029158714;25882.482350352886;25893.611595826078;26135.346616334744;26136.73863261381;26185;8008.879866802003;8055.1542088079905;8159.592020398956;8172.141925097898;8173.264406203351;3340.4989251611964;3629.7459177857295;3800.56293526248;4075.042365044212;4084.632383085477;4356.0198955235455;4447.3773671685985;4490.865274041784;4492.303692927601;4498.50516443191;872.690963554668;940.9482478463614;971.8007808399261;982.1210017034714;985.5170966226498;584.2638275772557;618.5021058048314;672.707372293054;677.7695583501633;683.3166833166833;9426.622935082658;10723.571057157626;10969.890301097046;12037.87962120385;12041.879581204252;661.1933441201726;663.6814329122049;673.9520046327909;678.5013015433652;684.0799125176729;5481;5573.080441727151;5631.605787594829;5633.267675202197;5702.714864256771;107512.92487075186;109815;113633.4318328403;114792;116404.8359516411;81378;86130.56934715286;88823.55588222017;94894;98233;77884.831727523;79010.81483777748;81662;82074;83899.5805020971;9732.610695572097;10797.838032429363;12180.390980451119;12819.807702884651;12929.741405171655;41370.58629413667;41610.58389416067;42009.78995105005;42520.78739606282;44114.55885441104;39087;54511.640928474146;54546;54709;55180;15963.361465541248;16097.839021610103;16332.183390830649;16575.502734917973;16606.584835379213;18133.546661333574;18178.72731909049;18218;18291.26834926588;18485.168167432228;16418.261178247245;16623.3350665972;16728.33086676568;16751.91624041872;16829.495115146565;16382.426615068416;16542.338306467605;16824.57938551497;16826.747598785783;16881.40915067967;13541.59375218745;13896.37466313998;14283.64290892736;14366;16233.51299461018;10422.062014418738;10749.193810464403;10754.73113172177;10923.781524369628;10975.725606859893;11402.429878506207;11702.35637039971;11809.23239989398;12070.034397248375;12159.574414895436 12:50:29 INFO - PID 1649 | __end_tp_report 12:50:29 INFO - PID 1649 | __start_cc_report 12:50:29 INFO - PID 1649 | _x_x_mozilla_cycle_collect,418 12:50:29 INFO - PID 1649 | __end_cc_report 12:50:29 INFO - PID 1649 | __startTimestamp1492285829454__endTimestamp 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | ------- Summary: start ------- 12:50:29 INFO - PID 1649 | Number of tests: 6 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6153.21 Median:6384.68 stddev:752.29 (11.8%) stddev-sans-first:717.37 12:50:29 INFO - PID 1649 | Values: 3173.8 3534.1 3587.0 3601.4 3605.2 5652.6 5764.9 6148.6 6284.2 6307.0 5022.5 5145.4 6132.4 6151.5 6214.9 5451.5 5462.0 5500.8 5528.8 5616.6 5498.4 5526.4 5593.3 5614.7 5623.2 5385.6 5483.2 5533.2 5548.3 5567.0 5423.8 5438.6 5484.8 5486.8 5523.9 5393.4 5402.2 5426.8 5444.3 5495.7 6046.4 6064.6 6086.0 6103.3 6215.9 5448.9 6558.6 6784.4 6791.8 6793.1 5982.7 6567.2 6794.7 6903.1 6907.0 6121.9 6283.4 6384.7 6412.9 6421.3 6820.7 6952.3 6984.2 7001.9 7034.3 6398.6 6487.9 6533.1 6676.8 6684.2 6779.8 6812.9 6856.0 6863.7 6889.6 6492.3 6756.7 6773.7 6778.0 6804.1 6579.6 6633.1 6668.5 6703.0 6733.7 5411.8 5416.7 5425.8 5427.7 5473.4 4520.4 4744.5 4758.7 4765.1 4781.9 6043.8 6160.8 6303.1 6349.2 6357.0 6829.2 6898.1 6910.5 6920.2 6920.8 6346.7 6346.8 6359.3 6390.7 6399.1 5819.6 5922.1 5936.9 5948.6 6059.2 5781.0 6644.6 6844.3 6948.1 6956.3 5613.2 5954.1 6130.4 6825.3 6831.3 6903.8 6941.2 6953.1 6971.4 6977.1 6495.3 6642.6 6729.0 6754.0 6761.6 6892.8 6923.9 6933.3 6942.0 6956.7 6275.3 6467.6 6481.2 6570.4 6667.2 5550.6 6555.7 6595.4 6606.5 6612.5 6165.1 6256.2 6337.7 6389.1 6440.3 5674.6 6445.0 6450.2 6598.9 6599.7 6385.1 6409.6 6412.3 6444.2 6485.8 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:45883.64 Median:187114.50 stddev:80845.79 (43.2%) stddev-sans-first:81001.20 12:50:29 INFO - PID 1649 | Values: 635.4 1266.5 1271.1 1278.6 1297.4 37966.8 38592.4 38775.6 38835.8 38927.8 38158.4 38468.2 38469.7 38479.4 38554.2 8379.9 8596.0 8620.5 8635.0 8665.0 8526.0 8576.4 8656.8 8657.0 8660.8 8573.3 8609.5 8671.9 8675.5 8684.0 8593.6 8634.7 8650.5 8680.9 8689.3 8333.5 8414.7 8441.7 8456.0 8458.7 38327.0 38412.0 38507.2 38562.4 38646.4 3559.2 3599.8 3600.5 3601.7 3616.7 31165.8 31203.2 31262.1 31287.2 31434.8 2672.3 3149.1 3188.9 3191.0 3194.9 298603.0 301412.0 301434.0 301567.0 302536.0 49038.0 49068.0 49425.8 49510.0 49614.8 42473.0 50789.2 51243.2 53083.5 53103.3 14318.6 14443.6 14537.1 14545.0 14566.0 11414.6 11608.7 11614.4 11619.2 11635.6 13820.4 13834.9 13880.6 13887.7 13993.1 5562.9 5717.1 6070.7 6115.7 6122.9 896.0 943.9 991.7 1043.9 1085.8 24305.5 24461.6 24723.6 24776.1 24881.3 1129.3 1140.5 1148.3 1159.1 1161.7 10010.8 11006.9 11451.1 12048.7 12642.8 332926.0 336226.3 338998.0 339779.6 339879.0 55004.4 55182.7 55573.7 55588.0 55645.4 1490.3 1512.6 1546.1 1549.1 1577.1 19403.3 23864.5 23972.2 24033.5 24110.3 38444.4 38898.0 39027.6 39086.6 39128.2 279599.0 282618.0 283782.0 284018.7 284818.0 34350.0 34591.7 34608.7 34671.8 34729.7 44318.8 45482.0 45573.3 45591.3 45713.5 29414.7 29937.9 30024.8 30857.8 30890.9 29826.7 30380.8 30597.2 30633.2 30714.8 35813.1 36353.0 36397.9 36472.5 36855.3 25355.0 30854.8 31422.8 31508.7 31765.0 19320.8 23010.8 24047.0 24170.4 24597.1 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:36147.82 Median:12227.25 stddev:95119.14 (777.9%) stddev-sans-first:95348.02 12:50:29 INFO - PID 1649 | Values: 359.2 374.9 424.8 431.8 458.6 21637.2 21646.2 21647.6 21995.1 22226.4 14804.0 21767.8 21833.9 22302.0 22392.6 5983.8 6307.7 6314.9 6325.3 6326.0 5991.8 6130.8 6155.9 6208.0 6219.7 6147.0 6147.8 6158.5 6162.6 6176.5 5839.3 6085.7 6087.8 6127.4 6129.3 5387.7 5414.6 5419.6 5438.2 5447.7 27211.5 27549.9 27643.9 27687.6 27793.7 2139.2 2194.9 2214.1 2214.2 2214.5 17504.5 17634.4 17781.9 17909.9 18011.3 2154.5 2175.5 2206.0 2207.8 2212.9 157582.6 162180.0 165446.2 165664.0 166084.0 12613.0 12657.5 12848.6 12859.8 13048.7 16266.8 16361.5 16372.3 16393.6 16413.3 8699.5 9025.0 9035.3 9076.4 9122.3 8628.8 8652.8 8653.7 8846.2 8848.0 7723.5 7787.6 7802.5 7883.2 7885.6 2208.6 2228.1 2228.4 2228.8 2421.2 932.2 949.4 965.3 969.2 1001.6 19171.9 19659.5 20091.8 20295.0 20485.6 969.7 984.7 994.9 1002.8 1014.5 10166.4 10549.7 10588.3 10627.9 10629.5 496145.0 565045.0 575271.0 576442.0 577372.0 119562.4 125123.0 138182.3 139058.0 139406.3 1169.3 1193.9 1215.0 1218.1 1221.3 19012.2 20691.6 21846.7 21904.3 21944.9 42548.8 56978.4 57213.7 58032.0 58729.7 78909.0 79568.8 80761.8 81977.0 83988.0 8689.7 8700.0 8729.4 9042.0 9132.1 11707.9 11884.5 14163.6 14396.4 14555.9 11735.8 11741.6 12005.5 12123.1 12130.2 9110.4 11120.4 11338.5 11485.8 11566.1 12252.4 12267.8 12390.1 12435.4 12460.6 7457.8 8858.5 8873.5 8906.5 8970.0 10068.0 11765.2 11857.4 11968.7 12059.8 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:7166.56 Median:3821.75 stddev:4572.45 (119.6%) stddev-sans-first:4564.88 12:50:29 INFO - PID 1649 | Values: 1418.2 1466.3 1471.4 1481.3 1502.8 11441.4 11856.1 11914.5 11931.2 12353.4 11802.5 11804.9 12005.2 12014.5 12025.5 2501.4 2549.2 2562.3 2566.2 2571.5 2505.5 2518.1 2539.6 2557.2 2558.0 2494.5 2536.4 2549.2 2552.1 2555.6 2470.1 2512.2 2526.9 2537.1 2541.7 2500.1 2504.1 2511.6 2514.6 2523.1 11661.0 11887.0 11903.5 11918.1 11927.9 6577.6 6798.3 6833.4 6833.7 6844.0 4202.3 4408.3 4453.3 4499.0 4532.4 1016.5 1235.8 1277.5 1290.0 1293.3 16129.4 16393.9 16508.7 16529.4 16747.6 5636.0 5749.7 5835.8 5925.9 5944.9 12069.0 12253.9 12288.6 12292.9 12324.1 5438.5 6605.1 6639.1 6643.3 6644.8 5959.3 6083.9 6196.5 6249.5 6288.1 3692.3 3951.2 4426.4 4506.3 4507.7 1295.9 1309.2 1314.6 1324.2 1328.0 740.8 786.6 798.6 816.0 817.5 5216.6 6148.8 6161.7 6236.4 6387.9 583.3 593.3 595.9 605.5 609.7 4303.4 4391.7 4438.4 4454.8 4543.9 14723.6 15188.2 15337.3 15346.0 15360.2 14608.0 15205.4 15206.4 15209.2 15212.4 6804.7 6846.8 7499.3 7653.7 8607.4 6062.7 6384.9 7122.4 7330.5 7377.4 13425.2 13442.1 13456.3 13540.7 13541.2 13711.7 13717.2 13892.9 13901.3 14037.8 9544.6 9825.3 9851.5 9856.0 9867.2 10885.5 10918.0 10952.3 10999.5 11074.1 8362.7 9307.7 9342.9 9412.0 9466.9 7998.9 9084.5 9211.8 9239.4 9340.3 8288.2 8431.5 8432.9 8448.4 8454.5 6076.2 6197.5 6231.3 6250.5 6252.6 4587.4 5913.3 6222.4 6234.3 6261.4 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5245.16 Median:5779.65 stddev:1680.01 (29.1%) stddev-sans-first:1644.87 12:50:29 INFO - PID 1649 | Values: 398.8 405.2 407.9 414.3 418.1 5004.5 5380.9 6091.7 6293.8 6406.9 4421.0 5284.3 5778.6 6098.7 6144.8 4248.5 4329.2 4504.1 4539.5 4558.1 3734.3 4513.1 4514.5 4560.7 4565.3 3675.2 3842.0 3968.9 4280.9 4510.8 3618.6 3897.4 4237.7 4504.0 4536.6 2968.6 3048.9 3728.0 3869.1 3885.0 4583.8 5495.8 5687.9 6018.3 6035.8 4673.5 5144.0 5433.8 5450.6 5487.6 5966.6 6541.7 6595.4 6624.6 6672.4 3894.4 4417.4 4425.5 4441.9 4468.2 6558.8 6700.6 6978.3 6986.7 7057.3 4134.5 4287.5 4497.5 4515.1 4537.6 4457.2 4581.3 4584.7 4586.4 4589.2 4228.7 4710.9 4712.4 5384.2 5397.6 6198.4 6632.3 6660.0 6666.4 6712.8 1509.8 1529.8 1547.3 1576.4 1576.4 1096.9 1135.3 1184.5 1193.7 1211.6 5450.4 6216.9 6223.9 6235.7 6289.6 5910.5 6715.2 6896.8 6897.9 6948.3 5583.9 5783.4 6073.0 6127.6 6292.8 3441.6 3589.4 3615.2 3630.9 3637.5 5870.1 6142.3 6785.4 6986.7 7085.5 5784.5 5843.8 6893.8 6897.9 7068.3 5746.2 6513.0 6663.0 6695.7 7102.4 5797.9 6776.7 6923.8 6966.5 6971.6 5194.7 5979.0 6715.0 6783.9 6873.4 7113.4 7118.6 7134.6 7148.0 7155.7 4161.5 4215.7 4235.1 4333.5 4515.1 5645.4 6471.6 6869.3 6910.5 6951.0 5762.3 6386.8 6570.4 6689.7 6690.9 5433.9 5780.7 6641.4 6657.6 6666.4 6517.5 6530.6 6544.1 6556.7 6556.7 5691.4 6310.3 6554.7 6639.9 6659.9 6127.3 6478.6 6498.6 6501.7 6514.2 12:50:29 INFO - PID 1649 | 12:50:29 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:21447.57 Median:17724.47 stddev:30037.38 (169.5%) stddev-sans-first:30081.79 12:50:29 INFO - PID 1649 | Values: 840.3 843.9 864.8 870.3 879.8 14107.2 14754.2 16144.3 16564.3 16623.0 12996.8 13577.1 17686.7 17715.9 17747.2 961.7 1004.9 1009.8 1013.8 1021.4 981.0 1002.2 1012.2 1029.6 1031.2 1021.1 1045.1 1062.5 1069.0 1071.8 1051.5 1052.9 1054.7 1054.9 1058.2 764.9 779.3 781.0 782.3 786.2 16597.8 16911.9 17029.4 17396.4 17554.6 17300.9 17650.6 17733.0 17984.7 18003.2 14183.3 14236.1 14280.9 14325.1 14474.9 3510.5 3529.1 3559.0 3561.1 3562.3 96689.5 105805.5 113790.0 120294.0 126924.0 13732.3 14311.2 14316.4 14355.5 14399.4 25882.5 25893.6 26135.3 26136.7 26185.0 8008.9 8055.2 8159.6 8172.1 8173.3 3340.5 3629.7 3800.6 4075.0 4084.6 4356.0 4447.4 4490.9 4492.3 4498.5 872.7 940.9 971.8 982.1 985.5 584.3 618.5 672.7 677.8 683.3 9426.6 10723.6 10969.9 12037.9 12041.9 661.2 663.7 674.0 678.5 684.1 5481.0 5573.1 5631.6 5633.3 5702.7 107512.9 109815.0 113633.4 114792.0 116404.8 81378.0 86130.6 88823.6 94894.0 98233.0 77884.8 79010.8 81662.0 82074.0 83899.6 9732.6 10797.8 12180.4 12819.8 12929.7 41370.6 41610.6 42009.8 42520.8 44114.6 39087.0 54511.6 54546.0 54709.0 55180.0 15963.4 16097.8 16332.2 16575.5 16606.6 18133.5 18178.7 18218.0 18291.3 18485.2 16418.3 16623.3 16728.3 16751.9 16829.5 16382.4 16542.3 16824.6 16826.7 16881.4 13541.6 13896.4 14283.6 14366.0 16233.5 10422.1 10749.2 10754.7 10923.8 10975.7 11402.4 11702.4 11809.2 12070.0 12159.6 12:50:29 INFO - PID 1649 | -------- Summary: end -------- 12:50:29 INFO - PID 1649 | 12:50:30 INFO - TEST-INFO | 1649: exit 0 12:50:30 INFO - TEST-OK | dromaeo_css | took 1115900ms 12:50:30 INFO - TEST-START | kraken 12:50:30 INFO - operating with platform_type : mac_ 12:50:30 INFO - Initialising browser for kraken test... 12:50:30 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpEoT1NK/profile http://localhost:49241/getInfo.html) 12:50:33 INFO - PID 1658 | 2017-04-15 12:50:33.066 plugin-container[1659:12225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 12:50:33 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:33 INFO - PID 1658 | 2017-04-15 12:50:33.069 plugin-container[1659:12225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 12:50:33 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:33 INFO - PID 1658 | 2017-04-15 12:50:33.069 plugin-container[1659:12225] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:50:33 INFO - PID 1658 | __metrics Screen width/height:1600/1200 12:50:33 INFO - PID 1658 | colorDepth:24 12:50:33 INFO - PID 1658 | Browser inner width/height: 1024/689 12:50:33 INFO - PID 1658 | __metrics 12:50:33 ERROR - PID 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:50:34 INFO - TEST-INFO | 1658: exit 0 12:50:34 INFO - Browser initialized. 12:50:34 INFO - Running cycle 1/1 for kraken test... 12:50:34 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpEoT1NK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:36 INFO - PID 1660 | 2017-04-15 12:50:36.886 plugin-container[1661:12361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 12:50:36 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:36 INFO - PID 1660 | 2017-04-15 12:50:36.886 plugin-container[1661:12361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 12:50:36 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:36 INFO - PID 1660 | 2017-04-15 12:50:36.886 plugin-container[1661:12361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:50:46 INFO - PID 1660 | RSS: Main: 238002176 12:50:46 INFO - PID 1660 | 12:50:49 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 12:50:50 INFO - PID 1660 | RSS: Main: 249937920 12:50:50 INFO - PID 1660 | 12:50:52 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 12:50:53 INFO - PID 1660 | RSS: Main: 258043904 12:50:53 INFO - PID 1660 | 12:50:56 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 12:50:57 INFO - PID 1660 | RSS: Main: 260337664 12:50:57 INFO - PID 1660 | 12:50:59 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 12:51:00 INFO - PID 1660 | RSS: Main: 262746112 12:51:00 INFO - PID 1660 | 12:51:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:51:02 INFO - PID 1660 | RSS: Main: 263176192 12:51:02 INFO - PID 1660 | 12:51:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 12:51:06 INFO - PID 1660 | RSS: Main: 268922880 12:51:06 INFO - PID 1660 | 12:51:09 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 12:51:10 INFO - PID 1660 | RSS: Main: 269852672 12:51:10 INFO - PID 1660 | 12:51:13 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 12:51:14 INFO - PID 1660 | RSS: Main: 269623296 12:51:14 INFO - PID 1660 | 12:51:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:51:16 INFO - PID 1660 | RSS: Main: 268955648 12:51:16 INFO - PID 1660 | 12:51:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:51:18 INFO - PID 1660 | RSS: Main: 269148160 12:51:18 INFO - PID 1660 | 12:51:21 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:51:21 INFO - PID 1660 | RSS: Main: 269000704 12:51:21 INFO - PID 1660 | 12:51:24 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:51:25 INFO - PID 1660 | RSS: Main: 269295616 12:51:25 INFO - PID 1660 | 12:51:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:51:29 INFO - PID 1660 | RSS: Main: 269164544 12:51:29 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 12:51:31 INFO - PID 1660 | RSS: Main: 270864384 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | __start_tp_report 12:51:31 INFO - PID 1660 | _x_x_mozilla_page_load 12:51:31 INFO - PID 1660 | _x_x_mozilla_page_load_details 12:51:31 INFO - PID 1660 | |i|pagename|runs| 12:51:31 INFO - PID 1660 | |0;ai-astar;103;117;116;115;114;116;114;115;116;114 12:51:31 INFO - PID 1660 | |1;audio-beat-detection;142;112;116;174;96;132;120;111;135;108 12:51:31 INFO - PID 1660 | |2;audio-dft;194;198;205;208;209;207;208;277;242;194 12:51:31 INFO - PID 1660 | |3;audio-fft;85;58;62;85;59;53;60;79;93;85 12:51:31 INFO - PID 1660 | |4;audio-oscillator;89;68;82;82;58;76;104;101;101;106 12:51:31 INFO - PID 1660 | |5;imaging-gaussian-blur;91;93;91;90;92;93;105;90;84;92 12:51:31 INFO - PID 1660 | |6;imaging-darkroom;98;105;100;99;98;98;104;99;109;129 12:51:31 INFO - PID 1660 | |7;imaging-desaturate;103;77;116;126;141;152;87;144;149;92 12:51:31 INFO - PID 1660 | |8;json-parse-financial;52;52;53;53;54;54;49;46;75;75 12:51:31 INFO - PID 1660 | |9;json-stringify-tinderbox;59;69;99;95;55;67;80;83;58;49 12:51:31 INFO - PID 1660 | |10;stanford-crypto-aes;85;104;77;102;108;107;83;84;82;84 12:51:31 INFO - PID 1660 | |11;stanford-crypto-ccm;140;140;135;139;147;150;149;147;187;138 12:51:31 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;183;190;186;201;209;187;188;218;187;185 12:51:31 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;70;68;81;66;77;97;83;70;67;69 12:51:31 INFO - PID 1660 | __end_tp_report 12:51:31 INFO - PID 1660 | __start_cc_report 12:51:31 INFO - PID 1660 | _x_x_mozilla_cycle_collect,998 12:51:31 INFO - PID 1660 | __end_cc_report 12:51:31 INFO - PID 1660 | __startTimestamp1492285891283__endTimestamp 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | ------- Summary: start ------- 12:51:31 INFO - PID 1660 | Number of tests: 14 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.50 stddev:4.00 (3.5%) stddev-sans-first:1.09 12:51:31 INFO - PID 1660 | Values: 103.0 117.0 116.0 115.0 114.0 116.0 114.0 115.0 116.0 114.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:133.50 stddev:22.21 (16.6%) stddev-sans-first:22.64 12:51:31 INFO - PID 1660 | Values: 142.0 112.0 116.0 174.0 96.0 132.0 120.0 111.0 135.0 108.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:214.20 Median:208.00 stddev:25.90 (12.5%) stddev-sans-first:26.42 12:51:31 INFO - PID 1660 | Values: 194.0 198.0 205.0 208.0 209.0 207.0 208.0 277.0 242.0 194.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:71.90 Median:82.00 stddev:14.78 (18.0%) stddev-sans-first:14.90 12:51:31 INFO - PID 1660 | Values: 85.0 58.0 62.0 85.0 59.0 53.0 60.0 79.0 93.0 85.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:72.00 stddev:16.39 (22.8%) stddev-sans-first:17.36 12:51:31 INFO - PID 1660 | Values: 89.0 68.0 82.0 82.0 58.0 76.0 104.0 101.0 101.0 106.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:91.50 stddev:5.22 (5.7%) stddev-sans-first:5.52 12:51:31 INFO - PID 1660 | Values: 91.0 93.0 91.0 90.0 92.0 93.0 105.0 90.0 84.0 92.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:98.00 stddev:9.57 (9.8%) stddev-sans-first:9.91 12:51:31 INFO - PID 1660 | Values: 98.0 105.0 100.0 99.0 98.0 98.0 104.0 99.0 109.0 129.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:118.70 Median:150.50 stddev:27.75 (18.4%) stddev-sans-first:28.84 12:51:31 INFO - PID 1660 | Values: 103.0 77.0 116.0 126.0 141.0 152.0 87.0 144.0 149.0 92.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:53.50 stddev:10.15 (19.0%) stddev-sans-first:10.65 12:51:31 INFO - PID 1660 | Values: 52.0 52.0 53.0 53.0 54.0 54.0 49.0 46.0 75.0 75.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:74.50 stddev:17.18 (23.1%) stddev-sans-first:17.63 12:51:31 INFO - PID 1660 | Values: 59.0 69.0 99.0 95.0 55.0 67.0 80.0 83.0 58.0 49.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:82.50 stddev:12.05 (14.6%) stddev-sans-first:12.54 12:51:31 INFO - PID 1660 | Values: 85.0 104.0 77.0 102.0 108.0 107.0 83.0 84.0 82.0 84.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:147.00 stddev:14.91 (10.1%) stddev-sans-first:15.58 12:51:31 INFO - PID 1660 | Values: 140.0 140.0 135.0 139.0 147.0 150.0 149.0 147.0 187.0 138.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:189.00 stddev:11.84 (6.3%) stddev-sans-first:11.95 12:51:31 INFO - PID 1660 | Values: 183.0 190.0 186.0 201.0 209.0 187.0 188.0 218.0 187.0 185.0 12:51:31 INFO - PID 1660 | 12:51:31 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:73.50 stddev:9.82 (13.4%) stddev-sans-first:10.26 12:51:31 INFO - PID 1660 | Values: 70.0 68.0 81.0 66.0 77.0 97.0 83.0 70.0 67.0 69.0 12:51:31 INFO - PID 1660 | -------- Summary: end -------- 12:51:31 INFO - PID 1660 | 12:51:32 INFO - TEST-INFO | 1660: exit 0 12:51:32 INFO - TEST-OK | kraken | took 62391ms 12:51:32 INFO - SUITE-END | took 1178s 12:51:32 INFO - Completed test suite (00:19:38) 12:51:32 INFO - javascript benchmark 12:51:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7883.037933709389, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.761967852411, 3534.1164708822794, 3586.982065089674, 3601.4417765246394, 3605.2248766515195, 5652.604317697757, 5764.913526297109, 6148.600340977833, 6284.183056202693, 6306.968465157674, 5022.522860328263, 5145.382554093505, 6132.386102851673, 6151.538634602401, 6214.943459611865, 5451.509364157225, 5461.972690136554, 5500.80791557782, 5528.834134975937, 5616.63491873029, 5498.422665620113, 5526.447355264481, 5593.300837395325, 5614.747336369853, 5623.184638227454, 5385.569154467633, 5483.232347471318, 5533.170024496318, 5548.278723765925, 5566.972165139168, 5423.810166644149, 5438.564914806806, 5484.7531861066345, 5486.780528778844, 5523.889522209573, 5393.352797664266, 5402.189671549299, 5426.8371948841595, 5444.292242008553, 5495.670259784426, 6046.425589569028, 6064.575479716422, 6086.0, 6103.29812071613, 6215.906761398537, 5448.91826622601, 6558.573692709959, 6784.3554862288, 6791.796246112624, 6793.116894803693, 5982.670953097578, 6567.211934567835, 6794.728210871559, 6903.102596662452, 6907.033015377851, 6121.910283036266, 6283.403076707706, 6384.680765961776, 6412.871742565169, 6421.2615549211505, 6820.727170913207, 6952.270011648805, 6984.196817365966, 7001.92998070023, 7034.261402552761, 6398.584092034003, 6487.935120648733, 6533.052707357407, 6676.833079172962, 6684.231313398924, 6779.830504237433, 6812.93187068123, 6856.040154378391, 6863.691133898887, 6889.621070841152, 6492.318306577741, 6756.6959486822225, 6773.695183716744, 6778.0, 6804.081449004321, 6579.572327798678, 6633.104530888365, 6668.499862510331, 6703.028060931138, 6733.696983635748, 5411.810586629449, 5416.729163541807, 5425.755840987165, 5427.674339539641, 5473.370562385297, 4520.389747384127, 4744.501827308152, 4758.738269395148, 4765.0946320199055, 4781.856544303677, 6043.758249670052, 6160.815175544741, 6303.149074874925, 6349.174607301114, 6356.968215158895, 6829.180498340231, 6898.0687607173395, 6910.48171387148, 6920.204176519765, 6920.757773478005, 6346.650934198663, 6346.8413289667205, 6359.332270111664, 6390.680465976682, 6399.13611662429, 5819.592628515963, 5922.052471604608, 5936.881262374728, 5948.613340132964, 6059.151718759377, 5780.9883270428345, 6644.568103073285, 6844.349786770249, 6948.096747423005, 6956.339147780953, 5613.214150019082, 5954.122505846609, 6130.448259656652, 6825.317468253135, 6831.28271531482, 6903.792886213421, 6941.236463989058, 6953.096097507494, 6971.372576468142, 6977.092977912839, 6495.317991610813, 6642.568233064835, 6729.0, 6754.0, 6761.628110453972, 6892.827679307857, 6923.861522769617, 6933.3066693332285, 6942.0, 6956.7217311309005, 6275.309715931153, 6467.644279564669, 6481.189851268591, 6570.408663220333, 6667.233268174222, 5550.611457198119, 6555.70499327541, 6595.406413422623, 6606.53754237218, 6612.5040621952585, 6165.136880836776, 6256.249250090018, 6337.65142917144, 6389.137466442156, 6440.2915679276175, 5674.574406919415, 6444.968804991179, 6450.193725784277, 6598.868022639616, 6599.670016499252, 6385.13800636908, 6409.551331406757, 6412.294647588855, 6444.1622589065, 6485.837854053686], "value": 6103.524929988918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4439865118022, 1266.461753754654, 1271.1292764456348, 1278.635588857176, 1297.3772589157204, 37966.810165949166, 38592.42111368328, 38775.61224387755, 38835.80582097093, 38927.80536097316, 38158.42762358567, 38468.23063538728, 38469.65356212533, 38479.422808657895, 38554.22891542168, 8379.874301885462, 8595.95702021492, 8620.482771033758, 8635.0, 8665.0, 8525.95737021314, 8576.356773242002, 8656.783580410478, 8656.956715216415, 8660.783480413009, 8573.314134869226, 8609.526476043815, 8671.869921951176, 8675.479471231747, 8683.956580217091, 8593.613287402082, 8634.697785577506, 8650.524221167832, 8680.913190868074, 8689.261412779859, 8333.499990000559, 8414.663413463453, 8441.70454034106, 8455.95772021136, 8458.74623761288, 38327.0, 38412.0, 38507.22985540301, 38562.421563676566, 38646.420303695464, 3559.181388280684, 3599.8200089995657, 3600.4599310103695, 3601.729870259715, 3616.710663146942, 31165.844170779226, 31203.219919501968, 31262.06213813589, 31287.217819554466, 31434.842825785952, 2672.2784848090905, 3149.118246890874, 3188.8883889064005, 3191.010786656144, 3194.9201269968203, 298603.0, 301411.9999999978, 301434.0, 301567.0, 302536.0, 49038.0, 49068.0, 49425.75287123613, 49510.0, 49614.75192624014, 42473.0, 50789.23816142761, 51243.23135152976, 53083.46916530863, 53103.34483275821, 14318.57044288673, 14443.566692999228, 14537.127772333693, 14545.0, 14565.980381373201, 11414.630244370728, 11608.709782255512, 11614.419279036014, 11619.244749091451, 11635.59275425373, 13820.37808298629, 13834.861651383559, 13880.640291150528, 13887.722245555033, 13993.090449120775, 5562.943040822231, 5717.142428635738, 6070.666113363719, 6115.694215289218, 6122.938770612237, 895.9820803583892, 943.8772959515356, 991.6677912899115, 1043.8590790243372, 1085.8154113800672, 24305.513889722108, 24461.633075503883, 24723.62914556283, 24776.132835350676, 24881.253562393158, 1129.3336931210624, 1140.463981928509, 1148.328227986637, 1159.0843233845337, 1161.7211869151513, 10010.849837252446, 11006.889931100746, 11451.141164412702, 12048.698782530508, 12642.810357844639, 332926.0, 336226.31886840897, 338998.0, 339779.60220397974, 339879.0, 55004.449955499935, 55182.72408638011, 55573.722131389084, 55588.0, 55645.443545564834, 1490.2772155504572, 1512.6445285358152, 1546.0878081931714, 1549.0860392368331, 1577.085290531489, 19403.320883769564, 23864.522709545363, 23972.160974365812, 24033.519329612962, 24110.276691699277, 38444.42333365058, 38898.0, 39027.60972390353, 39086.6091339083, 39128.2174356517, 279599.0, 282618.0, 283782.0, 284018.73971890024, 284818.0, 34350.0, 34591.654083458845, 34608.65391346054, 34671.82664086663, 34729.65270347264, 44318.77840610776, 45482.0, 45573.316400254684, 45591.31613025741, 45713.54286457226, 29414.705852941195, 29937.85031074831, 30024.849875750482, 30857.845710771304, 30890.918817841266, 29826.701732982394, 30380.84809575938, 30597.16417014897, 30633.23416914595, 30714.77140914428, 35813.1046723834, 36353.0, 36397.90806275816, 36472.452913205794, 36855.26289474142, 25355.0, 30854.84572577123, 31422.842885785423, 31508.684913150573, 31765.0, 19320.84074955507, 23010.769892301534, 24047.0, 24170.395740106636, 24597.139100131408], "value": 17746.70283102257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.2419993813054, 374.887533739878, 424.81732854872394, 431.79921336578485, 458.617054759276, 21637.242696505626, 21646.24238151665, 21647.56704865904, 21995.120195192172, 22226.444338891535, 14804.0, 21767.782322176772, 21833.890830545864, 22302.0, 22392.552148957013, 5983.760649574023, 6307.684615769216, 6314.936850631504, 6325.272593651743, 6326.0, 5991.820245392623, 6130.816075517741, 6155.938440615604, 6208.037754148114, 6219.657918814463, 6147.0, 6147.846303842395, 6158.538109641795, 6162.630242185461, 6176.536759743038, 5839.270091238595, 6085.665288409135, 6087.756489740405, 6127.356627554132, 6129.325774164839, 5387.703676297802, 5414.593905457106, 5419.620626556142, 5438.238646589481, 5447.727613619343, 27211.455770884473, 27549.862250688617, 27643.894244230207, 27687.584686229722, 27793.722062779518, 2139.229877244191, 2194.868307901515, 2214.136486770145, 2214.2360885494477, 2214.5460180662926, 17504.474865754048, 17634.382796602058, 17781.933084015, 17909.910450447795, 18011.27954881803, 2154.4613846538364, 2175.4996350839397, 2205.9889700551553, 2207.834412419075, 2212.8672279663274, 157582.63626045617, 162180.0, 165446.17276913658, 165664.0, 166084.0, 12612.990960723304, 12657.493700251887, 12848.550300739429, 12859.807102893465, 13048.673783155307, 16266.75599866003, 16361.545768134636, 16372.263248153862, 16393.59016024609, 16413.261403236884, 8699.478031318014, 9024.954875225581, 9035.277177825757, 9076.410033347944, 9122.31582631292, 8628.827423451621, 8652.826943461096, 8653.697120600875, 8846.203841654282, 8847.982482014522, 7723.497972631761, 7787.5716835574585, 7802.492837965628, 7883.172266911892, 7885.566293853778, 2208.6134926387817, 2228.053077442081, 2228.4428892776805, 2228.8105511031745, 2421.188901717909, 932.2215949681956, 949.3544389814992, 965.2960191470172, 969.2488321550854, 1001.5543083327849, 19171.94554299527, 19659.508512287306, 20091.799082009285, 20295.0, 20485.59028819415, 969.7042402067297, 984.6504490905769, 994.9452780097164, 1002.7693630465034, 1014.5282443663732, 10166.390016599027, 10549.736256593647, 10588.311759735592, 10627.937206279465, 10629.46852657364, 496145.0, 565045.0, 575271.0, 576442.0, 577372.0, 119562.40218799024, 125123.0, 138182.3090884539, 139058.0, 139406.3029684845, 1169.2750494693175, 1193.9378575724493, 1214.975700486003, 1218.0620921890095, 1221.2611370120949, 19012.23951041943, 20691.586168276852, 21846.672299915197, 21904.342869713935, 21944.89027554852, 42548.78725606352, 56978.43021569897, 57213.71393143007, 58032.0, 58729.70635146797, 78909.0, 79568.80646790419, 80761.78857317027, 81977.0, 83988.0, 8689.652413903374, 8699.956500217459, 8729.3889427742, 9042.0, 9132.086791320815, 11707.88292117102, 11884.465199066235, 14163.575092747235, 14396.35216415243, 14555.92722036383, 11735.823962640396, 11741.589044383405, 12005.459754310903, 12123.090768192244, 12130.150889437655, 9110.407823491452, 11120.44397780124, 11338.489767960296, 11485.770284594428, 11566.132540059361, 12252.448639811408, 12267.815982760087, 12390.070744694003, 12435.44040518197, 12460.626181214579, 7457.813554661177, 8858.468491890508, 8873.511956842436, 8906.465612063297, 8969.955150224207, 10067.993200679874, 11765.235259708092, 11857.40712964331, 11968.700782480508, 12059.81910271329], "value": 10462.512800958346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.212891845026, 1466.3108339080636, 1471.382019551788, 1481.3482067890127, 1502.8121484814399, 11441.370724610153, 11856.051515878742, 11914.463849126789, 11931.224470409417, 12353.444095015724, 11802.527898884056, 11804.937555619992, 12005.219660722047, 12014.45934932928, 12025.458854351553, 2501.362152651076, 2549.1842610364693, 2562.269753120361, 2566.2044766122563, 2571.537123317802, 2505.473850491399, 2518.1438310974263, 2539.619057141425, 2557.181701855407, 2557.9895941103255, 2494.538510375577, 2536.3912660961328, 2549.2097449790626, 2552.1067626330823, 2555.56555385584, 2470.0984140788573, 2512.208654273907, 2526.873656317177, 2537.1246919812634, 2541.745825417462, 2500.1374525788574, 2504.098524531167, 2511.6483692283095, 2514.6479492871013, 2523.082457332422, 11661.0, 11887.049036077093, 11903.464344104535, 11918.106142039382, 11927.88072119285, 6577.572457790228, 6798.252192258848, 6833.350831670983, 6833.692483838189, 6844.041834143224, 4202.306619407794, 4408.272635015219, 4453.309736990772, 4499.0, 4532.388127602764, 1016.5222345497605, 1235.8084496902995, 1277.5081593586442, 1289.9806502902463, 1293.2886912198348, 16129.354825806953, 16393.91803040989, 16508.66982660352, 16529.421470248602, 16747.581310467212, 5635.971820140873, 5749.68376739275, 5835.766569337264, 5925.911111333334, 5944.940550594525, 12069.034477241976, 12253.938730306292, 12288.631341059783, 12292.938535307267, 12324.07569432296, 5438.483344082306, 6605.1083103780375, 6639.070530125786, 6643.302453242437, 6644.767433139817, 5959.314678811992, 6083.9391606084255, 6196.504279657616, 6249.500039996789, 6288.056791481315, 3692.280005398975, 3951.229510245532, 4426.416379221007, 4506.324051392252, 4507.729536227836, 1295.8704129586965, 1309.240640428549, 1314.5793346129337, 1324.1790090144173, 1327.9933600332129, 740.8184994676338, 786.5831109511968, 798.62863768348, 815.9877601835977, 817.5421763812284, 5216.582673386196, 6148.846278843064, 6161.6611086389785, 6236.376362363818, 6387.936120638827, 583.2738240890119, 593.2643522032648, 595.8748662780864, 605.5246631394334, 609.6555446172898, 4303.354496825501, 4391.736495810262, 4438.423005009328, 4454.77726113693, 4543.863684089482, 14723.631909202142, 15188.240587970779, 15337.30982105808, 15346.002509837048, 15360.231988400536, 14608.0, 15205.391784328724, 15206.391744330103, 15209.239538023054, 15212.391504339923, 6804.727810887608, 6846.828829279308, 7499.2500749924575, 7653.732119375795, 8607.397482176313, 6062.6665533394325, 6384.936364495468, 7122.358987691133, 7330.486865919334, 7377.446691498267, 13425.194488330731, 13442.126261792951, 13456.25990570528, 13540.661483462993, 13541.187528748307, 13711.725765484834, 13717.17696938187, 13892.93053534726, 13901.304934753423, 14037.789433158307, 9544.618215271312, 9825.263105267277, 9851.458169800442, 9855.95072024664, 9867.210623150275, 10885.455727213766, 10917.962793534602, 10952.288101273392, 10999.505022274177, 11074.114070874472, 8362.661974084118, 9307.72076837696, 9342.859857102014, 9412.0, 9466.857997130186, 7998.880016799636, 9084.54577271147, 9211.815763684823, 9239.39943903644, 9340.252779777737, 8288.21261980111, 8431.494110353398, 8432.915670843213, 8448.366372522209, 8454.535000575028, 6076.179715738495, 6197.535184861244, 6231.283402408781, 6250.468710159687, 6252.624842509464, 4587.449506059161, 5913.34953155144, 6222.439980401786, 6234.3453937337745, 6261.4364707174755], "value": 5362.003650572592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.76273617197756, 405.23612989514766, 407.8884840804688, 414.3308556680959, 418.10734082733376, 5004.524570165833, 5380.892382152355, 6091.7258723357445, 6293.811185664432, 6406.903896441557, 4420.961074147573, 5284.313039304885, 5778.6243894146855, 6098.695065246731, 6144.754209831613, 4248.468941382327, 4329.155814616143, 4504.054148628792, 4539.523350048247, 4558.133954548625, 3734.3091528067257, 4513.14250292445, 4514.548545145492, 4560.749158796265, 4565.33802598623, 3675.191457879262, 3841.9818748031794, 3968.868872371375, 4280.908368366062, 4510.842120525782, 3618.6200448952877, 3897.395903634941, 4237.703360764748, 4503.9866030143285, 4536.591706746392, 2968.554716792477, 3048.9481129010455, 3728.0, 3869.071422858522, 3884.961150388488, 4583.839565615188, 5495.835124946258, 5687.890861282051, 6018.307894592134, 6035.7887473938645, 4673.485916549177, 5144.0, 5433.755481003364, 5450.645708028966, 5487.6158668893195, 5966.642001479925, 6541.67291635421, 6595.406413422767, 6624.635645039521, 6672.39948404641, 3894.435306880515, 4417.359482874997, 4425.5353187915125, 4441.933370999438, 4468.218061839165, 6558.770443034471, 6700.597964122168, 6978.337057979484, 6986.650667466657, 7057.294270573005, 4134.503859536875, 4287.506936702257, 4497.505274419811, 4515.119551687455, 4537.568930951555, 4457.219986502414, 4581.312803079565, 4584.724916504953, 4586.380838586815, 4589.173948689201, 4228.6617070634275, 4710.882227944328, 4712.41024515117, 5384.165454354581, 5397.568194544427, 6198.442140207313, 6632.303608121078, 6660.0, 6666.366695163952, 6712.783757460749, 1509.7659862721075, 1529.8296802945756, 1547.3191795609896, 1576.3615735627088, 1576.4482431149236, 1096.9341839489657, 1135.28477059452, 1184.5202692909386, 1193.7194759231575, 1211.6365090472823, 5450.427705090988, 6216.875662486725, 6223.906641400383, 6235.657038862906, 6289.578512272717, 5910.468057874812, 6715.227748808852, 6896.827579310457, 6897.862042759117, 6948.270431604608, 5583.916241256385, 5783.421657834183, 6072.969635151796, 6127.632342059402, 6292.842678933063, 3441.63862794408, 3589.353916295092, 3615.1685112423766, 3630.8910732678064, 3637.454381842692, 5870.148828419855, 6142.293636231891, 6785.355391237825, 6986.7205311787975, 7085.492681122774, 5784.537237021028, 5843.766249350064, 6893.7931862044215, 6897.896531552031, 7068.3285087916565, 5746.166805813134, 6513.023046543057, 6662.966685166543, 6695.727811715758, 7102.4318054555515, 5797.8550536236935, 6776.695048722918, 6923.792286231421, 6966.5471744336455, 6971.58170509771, 5194.74026298676, 5978.970105149447, 6714.966425167843, 6783.93216067833, 6873.381395674413, 7113.359797618239, 7118.572885626879, 7134.6490132400695, 7147.964260178665, 7155.713771449292, 4161.459010328638, 4215.662746980173, 4235.089455767024, 4333.479982402006, 4515.1195516873895, 5645.4072322405555, 6471.579347342407, 6869.278725733726, 6910.479694467209, 6951.026856240232, 5762.308522977102, 6386.808395748135, 6570.375814297634, 6689.69896354675, 6690.899636505554, 5433.864153396196, 5780.682062486603, 6641.435477984425, 6657.603262825476, 6666.400023997863, 6517.543771936109, 6530.6081635100145, 6544.116544266653, 6556.672166391757, 6556.737730490918, 5691.373948865538, 6310.337414571588, 6554.672266386567, 6639.933600664125, 6659.933400666125, 6127.264728232641, 6478.578892371981, 6498.610083395011, 6501.707423166064, 6514.218293804584], "value": 4743.460033663673, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.2983508770177, 843.9324854011679, 864.7547531554561, 870.2776695342866, 879.8196369744201, 14107.153570785735, 14754.188519631416, 16144.273507692153, 16564.25460854263, 16623.002619842802, 12996.805047924265, 13577.117487363315, 17686.734698979526, 17715.911420442913, 17747.201375938082, 961.7259081161887, 1004.9196064314874, 1009.7728011197495, 1013.8276492996172, 1021.3565453764119, 981.0434826044585, 1002.2032484175098, 1012.1953047327393, 1029.6241871716807, 1031.2162756305188, 1021.1218352217087, 1045.1482042135635, 1062.4900047976937, 1068.9893101068967, 1071.8445825355318, 1051.4847724614922, 1052.9420881851495, 1054.7204990677476, 1054.852320675106, 1058.1534772182263, 764.9349805266559, 779.2674885607511, 780.9804754881117, 782.2881178127933, 786.1980779604828, 16597.83402165975, 16911.915440422843, 17029.403970861084, 17396.39112631052, 17554.561135971577, 17300.913495432444, 17650.646987060314, 17733.024683642394, 17984.730229046443, 18003.18985645649, 14183.290835458185, 14236.145831250158, 14280.85719142816, 14325.140491570435, 14474.927625361806, 3510.4909788080586, 3529.0824385659657, 3559.0, 3561.0741207286032, 3562.340966921102, 96689.5165524175, 105805.47097264542, 113790.0, 120294.0, 126924.0, 13732.313384330942, 14311.212883291519, 14316.427342906376, 14355.497557585435, 14399.352029158714, 25882.482350352886, 25893.611595826078, 26135.346616334744, 26136.73863261381, 26185.0, 8008.879866802003, 8055.1542088079905, 8159.592020398956, 8172.141925097898, 8173.264406203351, 3340.4989251611964, 3629.7459177857295, 3800.56293526248, 4075.042365044212, 4084.632383085477, 4356.0198955235455, 4447.3773671685985, 4490.865274041784, 4492.303692927601, 4498.50516443191, 872.690963554668, 940.9482478463614, 971.8007808399261, 982.1210017034714, 985.5170966226498, 584.2638275772557, 618.5021058048314, 672.707372293054, 677.7695583501633, 683.3166833166833, 9426.622935082658, 10723.571057157626, 10969.890301097046, 12037.87962120385, 12041.879581204252, 661.1933441201726, 663.6814329122049, 673.9520046327909, 678.5013015433652, 684.0799125176729, 5481.0, 5573.080441727151, 5631.605787594829, 5633.267675202197, 5702.714864256771, 107512.92487075186, 109815.0, 113633.4318328403, 114792.0, 116404.8359516411, 81378.0, 86130.56934715286, 88823.55588222017, 94894.0, 98233.0, 77884.831727523, 79010.81483777748, 81662.0, 82074.0, 83899.5805020971, 9732.610695572097, 10797.838032429363, 12180.390980451119, 12819.807702884651, 12929.741405171655, 41370.58629413667, 41610.58389416067, 42009.78995105005, 42520.78739606282, 44114.55885441104, 39087.0, 54511.640928474146, 54546.0, 54709.0, 55180.0, 15963.361465541248, 16097.839021610103, 16332.183390830649, 16575.502734917973, 16606.584835379213, 18133.546661333574, 18178.72731909049, 18218.0, 18291.26834926588, 18485.168167432228, 16418.261178247245, 16623.3350665972, 16728.33086676568, 16751.91624041872, 16829.495115146565, 16382.426615068416, 16542.338306467605, 16824.57938551497, 16826.747598785783, 16881.40915067967, 13541.59375218745, 13896.37466313998, 14283.64290892736, 14366.0, 16233.51299461018, 10422.062014418738, 10749.193810464403, 10754.73113172177, 10923.781524369628, 10975.725606859893, 11402.429878506207, 11702.35637039971, 11809.23239989398, 12070.034397248375, 12159.574414895436], "value": 8324.796970914927, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 116.0, 115.0, 114.0, 116.0, 114.0, 115.0, 116.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 116.0, 174.0, 96.0, 132.0, 120.0, 111.0, 135.0, 108.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 198.0, 205.0, 208.0, 209.0, 207.0, 208.0, 277.0, 242.0, 194.0], "value": 214.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 62.0, 85.0, 59.0, 53.0, 60.0, 79.0, 93.0, 85.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 82.0, 82.0, 58.0, 76.0, 104.0, 101.0, 101.0, 106.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 91.0, 90.0, 92.0, 93.0, 105.0, 90.0, 84.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 100.0, 99.0, 98.0, 98.0, 104.0, 99.0, 109.0, 129.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 77.0, 116.0, 126.0, 141.0, 152.0, 87.0, 144.0, 149.0, 92.0], "value": 118.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 53.0, 54.0, 54.0, 49.0, 46.0, 75.0, 75.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 69.0, 99.0, 95.0, 55.0, 67.0, 80.0, 83.0, 58.0, 49.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 104.0, 77.0, 102.0, 108.0, 107.0, 83.0, 84.0, 82.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 135.0, 139.0, 147.0, 150.0, 149.0, 147.0, 187.0, 138.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 190.0, 186.0, 201.0, 209.0, 187.0, 188.0, 218.0, 187.0, 185.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 81.0, 66.0, 77.0, 97.0, 83.0, 70.0, 67.0, 69.0], "value": 74.8, "unit": "score"}]}]} 12:51:33 INFO - Return code: 0 12:51:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:51:33 ERROR - # TBPL SUCCESS # 12:51:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:51:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:51:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:51:33 INFO - 2017-04-15 12:51:33.098 screenresolution[1666:12758] starting screenresolution argv=screenresolution get 12:51:33 INFO - 2017-04-15 12:51:33.101 screenresolution[1666:12758] Display 0: 1600x1200x32@60 12:51:33 INFO - 2017-04-15 12:51:33.107 screenresolution[1667:12761] starting screenresolution argv=screenresolution list 12:51:33 INFO - Available Modes on Display 0 12:51:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:51:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:51:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:51:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:51:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:51:33 INFO - Intel Iris: 12:51:33 INFO - Chipset Model: Intel Iris 12:51:33 INFO - Type: GPU 12:51:33 INFO - Bus: Built-In 12:51:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:51:33 INFO - Vendor: Intel (0x8086) 12:51:33 INFO - Device ID: 0x0a2e 12:51:33 INFO - Revision ID: 0x0009 12:51:33 INFO - Displays: 12:51:33 INFO - Display: 12:51:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:51:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:51:33 INFO - Display Serial Number: 1600x1200 60 12:51:33 INFO - Main Display: Yes 12:51:33 INFO - Mirror: Off 12:51:33 INFO - Online: Yes 12:51:33 INFO - Rotation: Supported 12:51:33 INFO - Return code: 0 12:51:33 INFO - Running post-action listener: _resource_record_post_action 12:51:33 INFO - [mozharness: 2017-04-15 19:51:33.343311Z] Finished run-tests step (success) 12:51:33 INFO - Running post-run listener: _resource_record_post_run 12:51:33 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 33739776; Write bytes: 521016320; Read time: 206; Write time: 1689 12:51:33 INFO - TinderboxPrint: CPU usage
54.0% 12:51:33 INFO - TinderboxPrint: I/O read bytes / time
33,739,776 / 206 12:51:33 INFO - TinderboxPrint: I/O write bytes / time
521,016,320 / 1,689 12:51:33 INFO - TinderboxPrint: CPU idle
2,205.2 (46.0%) 12:51:33 INFO - TinderboxPrint: CPU system
961.2 (20.0%) 12:51:33 INFO - TinderboxPrint: CPU user
1,631.3 (34.0%) 12:51:33 INFO - TinderboxPrint: Swap in / out
238,137,344 / 0 12:51:33 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204796416; Write bytes: 203374592; Read time: 15965; Write time: 439 12:51:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31671296; Write bytes: 310719488; Read time: 187; Write time: 1223 12:51:33 INFO - Running post-run listener: _upload_blobber_files 12:51:33 INFO - Blob upload gear active. 12:51:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:51:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:51:34 INFO - (blobuploader) - INFO - Open directory for files ... 12:51:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:51:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:35 INFO - (blobuploader) - INFO - Done attempting. 12:51:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:51:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:35 INFO - (blobuploader) - INFO - Done attempting. 12:51:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:51:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:51:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:36 INFO - (blobuploader) - INFO - Done attempting. 12:51:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:51:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:51:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:38 INFO - (blobuploader) - INFO - Done attempting. 12:51:38 INFO - (blobuploader) - INFO - Iteration through files over. 12:51:38 INFO - Return code: 0 12:51:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:51:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:51:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29237d70e45bc33014093676ab3b9600ad79e218c158345b2524161cf0e01d4a4693dda8e175f43c143a95b00312bf166a681de934e79e2cb235a837d896d7f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3ed8c97779f0baa998423d778976d0fc9ca32f7e69cefbeec588ec3db691a598123185f6c7e9237783a67b8d70cb80e3bcc680c7b64779318d5af27a1cc241", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a2debd2e45f695c8c18dbd9a18d40a631f158cd0225ea14347127c2912142de82d66ab233fa4366edb02ee50fbcf2d5516411dd9150e78f6fc9921f537f2c2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d61094f6977162219ef3886fdf3cb8249a9d13eb184cf0f6420dda32c5618d398e3486c89b7e6c7c67a0b49884f87317a4c2af51e18f9bd1527891d91b208f"} 12:51:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:51:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:51:38 INFO - Contents: 12:51:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29237d70e45bc33014093676ab3b9600ad79e218c158345b2524161cf0e01d4a4693dda8e175f43c143a95b00312bf166a681de934e79e2cb235a837d896d7f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3ed8c97779f0baa998423d778976d0fc9ca32f7e69cefbeec588ec3db691a598123185f6c7e9237783a67b8d70cb80e3bcc680c7b64779318d5af27a1cc241", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a2debd2e45f695c8c18dbd9a18d40a631f158cd0225ea14347127c2912142de82d66ab233fa4366edb02ee50fbcf2d5516411dd9150e78f6fc9921f537f2c2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d61094f6977162219ef3886fdf3cb8249a9d13eb184cf0f6420dda32c5618d398e3486c89b7e6c7c67a0b49884f87317a4c2af51e18f9bd1527891d91b208f"} 12:51:38 INFO - Running post-run listener: copy_logs_to_upload_dir 12:51:38 INFO - Copying logs to upload dir... 12:51:38 INFO - mkdir: /builds/slave/test/build/upload/logs 12:51:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.955951 ========= master_lag: 1.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-15 12:51:39.873258) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 12:51:39.876485) ========= 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.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29237d70e45bc33014093676ab3b9600ad79e218c158345b2524161cf0e01d4a4693dda8e175f43c143a95b00312bf166a681de934e79e2cb235a837d896d7f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3ed8c97779f0baa998423d778976d0fc9ca32f7e69cefbeec588ec3db691a598123185f6c7e9237783a67b8d70cb80e3bcc680c7b64779318d5af27a1cc241", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a2debd2e45f695c8c18dbd9a18d40a631f158cd0225ea14347127c2912142de82d66ab233fa4366edb02ee50fbcf2d5516411dd9150e78f6fc9921f537f2c2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d61094f6977162219ef3886fdf3cb8249a9d13eb184cf0f6420dda32c5618d398e3486c89b7e6c7c67a0b49884f87317a4c2af51e18f9bd1527891d91b208f"} build_url:https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/ftlu35rQRwe-_2L247fcRw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29237d70e45bc33014093676ab3b9600ad79e218c158345b2524161cf0e01d4a4693dda8e175f43c143a95b00312bf166a681de934e79e2cb235a837d896d7f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3ed8c97779f0baa998423d778976d0fc9ca32f7e69cefbeec588ec3db691a598123185f6c7e9237783a67b8d70cb80e3bcc680c7b64779318d5af27a1cc241", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a2debd2e45f695c8c18dbd9a18d40a631f158cd0225ea14347127c2912142de82d66ab233fa4366edb02ee50fbcf2d5516411dd9150e78f6fc9921f537f2c2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d61094f6977162219ef3886fdf3cb8249a9d13eb184cf0f6420dda32c5618d398e3486c89b7e6c7c67a0b49884f87317a4c2af51e18f9bd1527891d91b208f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 12:51:39.912123) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:51:39.912449) ========= 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.30eEBgnnso/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.fGiYF3GuV0/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.005292 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:51:39.961157) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 12:51:39.961506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 12:51:39.961906) ========= ========= Total master_lag: 1.22 =========