builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0289 starttime: 1493913219.96 results: success (0) buildid: 20170504074819 builduid: 559598d21ecc42628a19321802e7f15e revision: 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:39.957980) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:39.958490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:39.958813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:39.986794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:39.987146) ========= 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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-05-04 08:53:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-05-04 08:53:40 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.622796 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:40.641028) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:40.641354) ========= 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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.026115 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:40.690185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-04 08:53:40.690535) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-05-04 08:53:40,799 truncating revision to first 12 chars 2017-05-04 08:53:40,799 Setting DEBUG logging. 2017-05-04 08:53:40,799 attempt 1/10 2017-05-04 08:53:40,799 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/94d16b8d4a52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 08:53:42,823 unpacking tar archive at: mozilla-beta-94d16b8d4a52/testing/mozharness/ program finished with exit code 0 elapsedTime=2.301047 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-04 08:53:43.018083) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:43.018449) ========= script_repo_revision: 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:43.018971) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:43.019792) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 08:53:43.040852) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-04 08:53:43.041249) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:53:43 INFO - MultiFileLogger online at 20170504 08:53:43 in /builds/slave/test 08:53:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:53:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:43 INFO - {'append_to_log': False, 08:53:43 INFO - 'base_work_dir': '/builds/slave/test', 08:53:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:53:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:43 INFO - 'branch': 'Mozilla-Beta', 08:53:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:43 INFO - 'config_files': ('talos/mac_config.py',), 08:53:43 INFO - 'default_actions': ('clobber', 08:53:43 INFO - 'read-buildbot-config', 08:53:43 INFO - 'download-and-extract', 08:53:43 INFO - 'populate-webroot', 08:53:43 INFO - 'create-virtualenv', 08:53:43 INFO - 'install', 08:53:43 INFO - 'run-tests'), 08:53:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:43 INFO - 'download_minidump_stackwalk': True, 08:53:43 INFO - 'download_symbols': 'ondemand', 08:53:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:53:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:53:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:53:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:53:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:43 INFO - 'gecko_profile': False, 08:53:43 INFO - 'gecko_profile_interval': 0, 08:53:43 INFO - 'installer_path': 'installer.exe', 08:53:43 INFO - 'log_level': 'info', 08:53:43 INFO - 'log_name': 'talos', 08:53:43 INFO - 'log_to_console': True, 08:53:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:53:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:53:43 INFO - 'opt_config_files': (), 08:53:43 INFO - 'pip_index': False, 08:53:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:43 INFO - 'cmd': ('bash', 08:53:43 INFO - '-c', 08:53:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:43 INFO - 'enabled': True, 08:53:43 INFO - 'halt_on_failure': False, 08:53:43 INFO - 'name': 'check_screen_resolution'},), 08:53:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:43 INFO - 'cmd': ('bash', 08:53:43 INFO - '-c', 08:53:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:43 INFO - 'enabled': True, 08:53:43 INFO - 'halt_on_failure': False, 08:53:43 INFO - 'name': 'check_screen_resolution'},), 08:53:43 INFO - 'run_cmd_checks_enabled': True, 08:53:43 INFO - 'suite': 'dromaeojs-e10s', 08:53:43 INFO - 'system_bits': '32', 08:53:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:43 INFO - 'title': 't-yosemite-r7-0289', 08:53:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:43 INFO - 'use_talos_json': True, 08:53:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:43 INFO - 'work_dir': 'build'} 08:53:43 INFO - [mozharness: 2017-05-04 15:53:43.261559Z] Running clobber step. 08:53:43 INFO - Running pre-action listener: _resource_record_pre_action 08:53:43 INFO - Running main action method: clobber 08:53:43 INFO - rmtree: /builds/slave/test/build 08:53:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:45 INFO - Running post-action listener: _resource_record_post_action 08:53:45 INFO - [mozharness: 2017-05-04 15:53:45.899464Z] Finished clobber step (success) 08:53:45 INFO - [mozharness: 2017-05-04 15:53:45.899596Z] Running read-buildbot-config step. 08:53:45 INFO - Running pre-action listener: _resource_record_pre_action 08:53:45 INFO - Running main action method: read_buildbot_config 08:53:45 INFO - Using buildbot properties: 08:53:45 INFO - { 08:53:45 INFO - "project": "", 08:53:45 INFO - "product": "firefox", 08:53:45 INFO - "script_repo_revision": "production", 08:53:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:53:45 INFO - "repository": "", 08:53:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:53:45 INFO - "buildid": "20170504074819", 08:53:45 INFO - "pgo_build": "False", 08:53:45 INFO - "basedir": "/builds/slave/test", 08:53:45 INFO - "buildnumber": 40, 08:53:45 INFO - "slavename": "t-yosemite-r7-0289", 08:53:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:53:45 INFO - "platform": "yosemite_r7", 08:53:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:53:45 INFO - "branch": "mozilla-beta", 08:53:45 INFO - "repo_path": "releases/mozilla-beta", 08:53:45 INFO - "revision": "94d16b8d4a52f8a325888249ea77d98c6f6ebe18", 08:53:45 INFO - "stage_platform": "macosx64", 08:53:45 INFO - "builduid": "559598d21ecc42628a19321802e7f15e", 08:53:45 INFO - "slavebuilddir": "test" 08:53:45 INFO - } 08:53:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:53:45 INFO - Running post-action listener: _resource_record_post_action 08:53:45 INFO - [mozharness: 2017-05-04 15:53:45.902895Z] Finished read-buildbot-config step (success) 08:53:45 INFO - [mozharness: 2017-05-04 15:53:45.903031Z] Running download-and-extract step. 08:53:45 INFO - Running pre-action listener: _resource_record_pre_action 08:53:45 INFO - Running main action method: download_and_extract 08:53:45 INFO - mkdir: /builds/slave/test/build/tests 08:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:45 INFO - trying https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:53:45 INFO - Downloading https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:53:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 08:53:46 INFO - Downloaded 1287 bytes. 08:53:46 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:53:46 INFO - Using the following test package requirements: 08:53:46 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:53:46 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:53:46 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:53:46 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:53:46 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:53:46 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:53:46 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:53:46 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:53:46 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:53:46 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:53:46 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:53:46 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:53:46 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:53:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:53:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:53:46 INFO - Fetch https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:53:48 INFO - Expected file size: 51223838 08:53:48 INFO - Obtained file size: 51223838 08:54:04 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:54:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:54:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:54:04 INFO - Fetch https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:54:07 INFO - Expected file size: 51223838 08:54:07 INFO - Obtained file size: 51223838 08:54:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:54:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:54:22 INFO - Fetch https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:54:23 INFO - Expected file size: 14404449 08:54:23 INFO - Obtained file size: 14404449 08:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:23 INFO - trying https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:54:23 INFO - Downloading https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:54:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 08:54:25 INFO - Downloaded 59562141 bytes. 08:54:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:54:25 INFO - mkdir: /builds/slave/test/properties 08:54:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:54:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:54:25 INFO - Contents: 08:54:25 INFO - build_url:https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:54:26 INFO - Running post-action listener: _resource_record_post_action 08:54:26 INFO - Running post-action listener: set_extra_try_arguments 08:54:26 INFO - [mozharness: 2017-05-04 15:54:26.366637Z] Finished download-and-extract step (success) 08:54:26 INFO - [mozharness: 2017-05-04 15:54:26.366763Z] Running populate-webroot step. 08:54:26 INFO - Running pre-action listener: _resource_record_pre_action 08:54:26 INFO - Running main action method: populate_webroot 08:54:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:54:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:54:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:54:26 INFO - '--tppagecycles', 08:54:26 INFO - '7'], 08:54:26 INFO - 'tests': ['tsvgm']}}, 08:54:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:54:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:54:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:54:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:54:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['damp', 'tps']}, 08:54:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'tests': ['damp', 'tps']}, 08:54:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['dromaeo_dom']}, 08:54:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:54:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['basic_compositor_video']}, 08:54:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:54:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['a11yr', 08:54:26 INFO - 'ts_paint', 08:54:26 INFO - 'tpaint', 08:54:26 INFO - 'sessionrestore', 08:54:26 INFO - 'sessionrestore_no_auto_restore', 08:54:26 INFO - 'tabpaint']}, 08:54:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['tsvgx', 08:54:26 INFO - 'tsvgr_opacity', 08:54:26 INFO - 'tart', 08:54:26 INFO - 'tscrollx', 08:54:26 INFO - 'cart', 08:54:26 INFO - 'tsvg_static']}, 08:54:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:54:26 INFO - 'tsvgr_opacity', 08:54:26 INFO - 'tart', 08:54:26 INFO - 'tscrollx', 08:54:26 INFO - 'cart', 08:54:26 INFO - 'tsvg_static']}, 08:54:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'talos_options': ['--disable-e10s'], 08:54:26 INFO - 'tests': ['tp5o']}, 08:54:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:54:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'talos_options': ['--disable-e10s', 08:54:26 INFO - '--xperf_path', 08:54:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:26 INFO - 'tests': ['tp5n']}, 08:54:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:54:26 INFO - 'talos_options': ['--xperf_path', 08:54:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:26 INFO - 'tests': ['tp5n']}}, 08:54:26 INFO - 'talos.zip': {'path': '', 08:54:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:54:26 INFO - Running post-action listener: _resource_record_post_action 08:54:26 INFO - [mozharness: 2017-05-04 15:54:26.384845Z] Finished populate-webroot step (success) 08:54:26 INFO - [mozharness: 2017-05-04 15:54:26.384955Z] Running create-virtualenv step. 08:54:26 INFO - Running pre-action listener: _resource_record_pre_action 08:54:26 INFO - Running main action method: create_virtualenv 08:54:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:54:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:54:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:54:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:54:26 INFO - Using real prefix '/tools/python27' 08:54:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:54:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:54:30 INFO - Installing pip.................done. 08:54:30 INFO - Return code: 0 08:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:54:30 INFO - Reading from file tmpfile_stdout 08:54:30 INFO - Output received: 08:54:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:54:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:30 INFO - 'HOME': '/Users/cltbld', 08:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:30 INFO - 'LOGNAME': 'cltbld', 08:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:30 INFO - 'MOZ_NO_REMOTE': '1', 08:54:30 INFO - 'NO_EM_RESTART': '1', 08:54:30 INFO - 'PAGER': '/bin/cat', 08:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:30 INFO - 'PWD': '/builds/slave/test', 08:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:30 INFO - 'SHELL': '/bin/bash', 08:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:30 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:30 INFO - 'USER': 'cltbld', 08:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:30 INFO - 'XPC_FLAGS': '0x0', 08:54:30 INFO - 'XPC_SERVICE_NAME': '0', 08:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:54:30 INFO - Cleaning up... 08:54:30 INFO - Return code: 0 08:54:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:30 INFO - 'HOME': '/Users/cltbld', 08:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:30 INFO - 'LOGNAME': 'cltbld', 08:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:30 INFO - 'MOZ_NO_REMOTE': '1', 08:54:30 INFO - 'NO_EM_RESTART': '1', 08:54:30 INFO - 'PAGER': '/bin/cat', 08:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:30 INFO - 'PWD': '/builds/slave/test', 08:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:30 INFO - 'SHELL': '/bin/bash', 08:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:30 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:30 INFO - 'USER': 'cltbld', 08:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:30 INFO - 'XPC_FLAGS': '0x0', 08:54:30 INFO - 'XPC_SERVICE_NAME': '0', 08:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:30 INFO - Downloading/unpacking psutil>=3.1.1 08:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:54:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:33 INFO - Installing collected packages: psutil 08:54:33 INFO - Running setup.py install for psutil 08:54:33 INFO - building 'psutil._psutil_osx' extension 08:54:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:54:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:54:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:54:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:54:34 INFO - building 'psutil._psutil_posix' extension 08:54:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:54:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:54:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:54:34 INFO - ^ 08:54:34 INFO - 1 warning generated. 08:54:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:54:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:35 INFO - Successfully installed psutil 08:54:35 INFO - Cleaning up... 08:54:35 INFO - Return code: 0 08:54:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:35 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:35 INFO - 'HOME': '/Users/cltbld', 08:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:35 INFO - 'LOGNAME': 'cltbld', 08:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:35 INFO - 'MOZ_NO_REMOTE': '1', 08:54:35 INFO - 'NO_EM_RESTART': '1', 08:54:35 INFO - 'PAGER': '/bin/cat', 08:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:35 INFO - 'PWD': '/builds/slave/test', 08:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:35 INFO - 'SHELL': '/bin/bash', 08:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:35 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:35 INFO - 'USER': 'cltbld', 08:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:35 INFO - 'XPC_FLAGS': '0x0', 08:54:35 INFO - 'XPC_SERVICE_NAME': '0', 08:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:54:36 INFO - Installing collected packages: mozsystemmonitor 08:54:36 INFO - Running setup.py install for mozsystemmonitor 08:54:36 INFO - Successfully installed mozsystemmonitor 08:54:36 INFO - Cleaning up... 08:54:36 INFO - Return code: 0 08:54:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:36 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:54:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:36 INFO - 'HOME': '/Users/cltbld', 08:54:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:36 INFO - 'LOGNAME': 'cltbld', 08:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:36 INFO - 'MOZ_NO_REMOTE': '1', 08:54:36 INFO - 'NO_EM_RESTART': '1', 08:54:36 INFO - 'PAGER': '/bin/cat', 08:54:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:36 INFO - 'PWD': '/builds/slave/test', 08:54:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:36 INFO - 'SHELL': '/bin/bash', 08:54:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:36 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:36 INFO - 'USER': 'cltbld', 08:54:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:36 INFO - 'XPC_FLAGS': '0x0', 08:54:36 INFO - 'XPC_SERVICE_NAME': '0', 08:54:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:54:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:38 INFO - Downloading functools32-3.2.3-2.tar.gz 08:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:54:38 INFO - warning: no files found matching '*.txt' 08:54:38 INFO - no previously-included directories found matching 'build' 08:54:38 INFO - no previously-included directories found matching 'dist' 08:54:38 INFO - no previously-included directories found matching '.git*' 08:54:38 INFO - Installing collected packages: jsonschema, functools32 08:54:38 INFO - Running setup.py install for functools32 08:54:39 INFO - warning: no files found matching '*.txt' 08:54:39 INFO - no previously-included directories found matching 'build' 08:54:39 INFO - no previously-included directories found matching 'dist' 08:54:39 INFO - no previously-included directories found matching '.git*' 08:54:39 INFO - Successfully installed jsonschema functools32 08:54:39 INFO - Cleaning up... 08:54:39 INFO - Return code: 0 08:54:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:39 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:54:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:39 INFO - 'HOME': '/Users/cltbld', 08:54:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:39 INFO - 'LOGNAME': 'cltbld', 08:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:39 INFO - 'MOZ_NO_REMOTE': '1', 08:54:39 INFO - 'NO_EM_RESTART': '1', 08:54:39 INFO - 'PAGER': '/bin/cat', 08:54:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:39 INFO - 'PWD': '/builds/slave/test', 08:54:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:39 INFO - 'SHELL': '/bin/bash', 08:54:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:39 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:39 INFO - 'USER': 'cltbld', 08:54:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:39 INFO - 'XPC_FLAGS': '0x0', 08:54:39 INFO - 'XPC_SERVICE_NAME': '0', 08:54:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:54:39 INFO - Cleaning up... 08:54:39 INFO - Return code: 0 08:54:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:39 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:54:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:39 INFO - 'HOME': '/Users/cltbld', 08:54:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:39 INFO - 'LOGNAME': 'cltbld', 08:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:39 INFO - 'MOZ_NO_REMOTE': '1', 08:54:39 INFO - 'NO_EM_RESTART': '1', 08:54:39 INFO - 'PAGER': '/bin/cat', 08:54:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:39 INFO - 'PWD': '/builds/slave/test', 08:54:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:39 INFO - 'SHELL': '/bin/bash', 08:54:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:39 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:39 INFO - 'USER': 'cltbld', 08:54:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:39 INFO - 'XPC_FLAGS': '0x0', 08:54:39 INFO - 'XPC_SERVICE_NAME': '0', 08:54:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:39 INFO - Downloading/unpacking blobuploader==1.2.4 08:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:41 INFO - Downloading blobuploader-1.2.4.tar.gz 08:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:54:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:54:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:41 INFO - Downloading docopt-0.6.1.tar.gz 08:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:54:41 INFO - Installing collected packages: blobuploader, requests, docopt 08:54:41 INFO - Running setup.py install for blobuploader 08:54:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:54:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:54:42 INFO - Running setup.py install for requests 08:54:42 INFO - Running setup.py install for docopt 08:54:42 INFO - Successfully installed blobuploader requests docopt 08:54:42 INFO - Cleaning up... 08:54:42 INFO - Return code: 0 08:54:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:54:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:42 INFO - 'HOME': '/Users/cltbld', 08:54:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:42 INFO - 'LOGNAME': 'cltbld', 08:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:42 INFO - 'MOZ_NO_REMOTE': '1', 08:54:42 INFO - 'NO_EM_RESTART': '1', 08:54:42 INFO - 'PAGER': '/bin/cat', 08:54:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:42 INFO - 'PWD': '/builds/slave/test', 08:54:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:42 INFO - 'SHELL': '/bin/bash', 08:54:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:42 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:42 INFO - 'USER': 'cltbld', 08:54:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:42 INFO - 'XPC_FLAGS': '0x0', 08:54:42 INFO - 'XPC_SERVICE_NAME': '0', 08:54:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-qSwZKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-eaXyVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-yQcoWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-IWXrNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ZJT51G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-so0CWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-7MMVNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-g_S0cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:43 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-2Jrgj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-CGOrOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Q0i3Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-uDpYEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ee6GMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-iXbY1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-qDx_zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ddrNwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:44 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-S_3OaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:54:44 INFO - Running setup.py install for manifestparser 08:54:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:54:45 INFO - Running setup.py install for mozcrash 08:54:45 INFO - Running setup.py install for mozdebug 08:54:45 INFO - Running setup.py install for mozdevice 08:54:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:54:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:54:45 INFO - Running setup.py install for mozfile 08:54:45 INFO - Running setup.py install for mozhttpd 08:54:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:54:45 INFO - Running setup.py install for mozinfo 08:54:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Running setup.py install for mozInstall 08:54:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Running setup.py install for mozleak 08:54:46 INFO - Running setup.py install for mozlog 08:54:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Running setup.py install for moznetwork 08:54:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Running setup.py install for mozprocess 08:54:46 INFO - Running setup.py install for mozprofile 08:54:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:54:46 INFO - Running setup.py install for mozrunner 08:54:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:54:47 INFO - Running setup.py install for mozscreenshot 08:54:47 INFO - Running setup.py install for moztest 08:54:47 INFO - Running setup.py install for mozversion 08:54:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:54:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:54:47 INFO - Cleaning up... 08:54:47 INFO - Return code: 0 08:54:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:47 INFO - 'HOME': '/Users/cltbld', 08:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:47 INFO - 'LOGNAME': 'cltbld', 08:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:47 INFO - 'MOZ_NO_REMOTE': '1', 08:54:47 INFO - 'NO_EM_RESTART': '1', 08:54:47 INFO - 'PAGER': '/bin/cat', 08:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:47 INFO - 'PWD': '/builds/slave/test', 08:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:47 INFO - 'SHELL': '/bin/bash', 08:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:47 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:47 INFO - 'USER': 'cltbld', 08:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:47 INFO - 'XPC_FLAGS': '0x0', 08:54:47 INFO - 'XPC_SERVICE_NAME': '0', 08:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:47 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-UItRFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-LBhftw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-VXwWSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-43ZuEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-tFJrug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-AAFYVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-C1O5bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-VVV_Pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-VVYEaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OQAJDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:48 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-SYTFuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-8tvB8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ODxksS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-SgFYaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-XwNzAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-_9aujX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:49 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-GVmpCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:54:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:51 INFO - Downloading blessings-1.6.tar.gz 08:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:51 INFO - Installing collected packages: blessings 08:54:51 INFO - Running setup.py install for blessings 08:54:51 INFO - Successfully installed blessings 08:54:51 INFO - Cleaning up... 08:54:51 INFO - Return code: 0 08:54:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:51 INFO - Reading from file tmpfile_stdout 08:54:51 INFO - Current package versions: 08:54:51 INFO - blessings == 1.6 08:54:51 INFO - blobuploader == 1.2.4 08:54:51 INFO - docopt == 0.6.1 08:54:51 INFO - functools32 == 3.2.3-2 08:54:51 INFO - jsonschema == 2.5.1 08:54:51 INFO - manifestparser == 1.1 08:54:51 INFO - mozInstall == 1.12 08:54:51 INFO - mozcrash == 1.0 08:54:51 INFO - mozdebug == 0.1 08:54:51 INFO - mozdevice == 0.49 08:54:51 INFO - mozfile == 1.2 08:54:51 INFO - mozhttpd == 0.7 08:54:51 INFO - mozinfo == 0.9 08:54:51 INFO - mozleak == 0.1 08:54:51 INFO - mozlog == 3.4 08:54:51 INFO - moznetwork == 0.27 08:54:51 INFO - mozprocess == 0.25 08:54:51 INFO - mozprofile == 0.28 08:54:51 INFO - mozrunner == 6.13 08:54:51 INFO - mozscreenshot == 0.1 08:54:51 INFO - mozsystemmonitor == 0.3 08:54:51 INFO - moztest == 0.8 08:54:51 INFO - mozversion == 1.4 08:54:51 INFO - psutil == 3.1.1 08:54:51 INFO - requests == 1.2.3 08:54:51 INFO - wsgiref == 0.1.2 08:54:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:51 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:51 INFO - 'HOME': '/Users/cltbld', 08:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:51 INFO - 'LOGNAME': 'cltbld', 08:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:51 INFO - 'MOZ_NO_REMOTE': '1', 08:54:51 INFO - 'NO_EM_RESTART': '1', 08:54:51 INFO - 'PAGER': '/bin/cat', 08:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:51 INFO - 'PWD': '/builds/slave/test', 08:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:51 INFO - 'SHELL': '/bin/bash', 08:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:51 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:51 INFO - 'USER': 'cltbld', 08:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:51 INFO - 'XPC_FLAGS': '0x0', 08:54:51 INFO - 'XPC_SERVICE_NAME': '0', 08:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:51 INFO - Cleaning up... 08:54:51 INFO - Return code: 0 08:54:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:51 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 0x1077d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff20dcbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, '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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:51 INFO - 'HOME': '/Users/cltbld', 08:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:51 INFO - 'LOGNAME': 'cltbld', 08:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:51 INFO - 'MOZ_NO_REMOTE': '1', 08:54:51 INFO - 'NO_EM_RESTART': '1', 08:54:51 INFO - 'PAGER': '/bin/cat', 08:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:51 INFO - 'PWD': '/builds/slave/test', 08:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:51 INFO - 'SHELL': '/bin/bash', 08:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:54:51 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:51 INFO - 'USER': 'cltbld', 08:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:51 INFO - 'XPC_FLAGS': '0x0', 08:54:51 INFO - 'XPC_SERVICE_NAME': '0', 08:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:52 INFO - Cleaning up... 08:54:52 INFO - Return code: 0 08:54:52 INFO - Running post-action listener: _resource_record_post_action 08:54:52 INFO - Running post-action listener: _start_resource_monitoring 08:54:52 INFO - Starting resource monitoring. 08:54:52 INFO - [mozharness: 2017-05-04 15:54:52.266722Z] Finished create-virtualenv step (success) 08:54:52 INFO - [mozharness: 2017-05-04 15:54:52.267776Z] Running install step. 08:54:52 INFO - Running pre-action listener: _resource_record_pre_action 08:54:52 INFO - Running main action method: install 08:54:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:52 INFO - Reading from file tmpfile_stdout 08:54:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:52 INFO - Reading from file tmpfile_stdout 08:54:52 INFO - Output received: 08:54:52 INFO - Usage: mozinstall [options] installer 08:54:52 INFO - Options: 08:54:52 INFO - -h, --help show this help message and exit 08:54:52 INFO - -d DEST, --destination=DEST 08:54:52 INFO - Directory to install application into. [default: 08:54:52 INFO - "/builds/slave/test"] 08:54:52 INFO - --app=APP Application being installed. [default: firefox] 08:54:52 INFO - mkdir: /builds/slave/test/build/application 08:54:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:55:07 INFO - Reading from file tmpfile_stdout 08:55:07 INFO - Output received: 08:55:07 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:55:07 INFO - Running post-action listener: _resource_record_post_action 08:55:07 INFO - [mozharness: 2017-05-04 15:55:07.382050Z] Finished install step (success) 08:55:07 INFO - [mozharness: 2017-05-04 15:55:07.382234Z] Running run-tests step. 08:55:07 INFO - Running pre-action listener: _resource_record_pre_action 08:55:07 INFO - Running main action method: run_tests 08:55:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:55:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:55:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:55:07 INFO - 2017-05-04 08:55:07.396 screenresolution[1629:8763] starting screenresolution argv=screenresolution get 08:55:07 INFO - 2017-05-04 08:55:07.399 screenresolution[1629:8763] Display 0: 1600x1200x32@60 08:55:07 INFO - 2017-05-04 08:55:07.405 screenresolution[1630:8765] starting screenresolution argv=screenresolution list 08:55:07 INFO - Available Modes on Display 0 08:55:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:55:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:55:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:55:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:55:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:55:07 INFO - Intel Iris: 08:55:07 INFO - Chipset Model: Intel Iris 08:55:07 INFO - Type: GPU 08:55:07 INFO - Bus: Built-In 08:55:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:55:07 INFO - Vendor: Intel (0x8086) 08:55:07 INFO - Device ID: 0x0a2e 08:55:07 INFO - Revision ID: 0x0009 08:55:07 INFO - Displays: 08:55:07 INFO - Display: 08:55:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:55:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:55:07 INFO - Display Serial Number: 1600x1200 60 08:55:07 INFO - Main Display: Yes 08:55:07 INFO - Mirror: Off 08:55:07 INFO - Online: Yes 08:55:07 INFO - Rotation: Supported 08:55:07 INFO - Return code: 0 08:55:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:55:07 INFO - Python 2.7.3 08:55:07 INFO - Return code: 0 08:55:07 INFO - grabbing minidump binary from tooltool 08:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:07 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 0x1075afd30>, '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 0x1074d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075a4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:55:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:55:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:55:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:55:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:55:07 INFO - Return code: 0 08:55:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:55:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:55:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:55:07 INFO - ENV: RUST_BACKTRACE is now 1 08:55:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:55:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:55:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0289 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:55:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bDuYYUHXtu/Render', 08:55:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:07 INFO - 'HOME': '/Users/cltbld', 08:55:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:07 INFO - 'LOGNAME': 'cltbld', 08:55:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:55:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:07 INFO - 'MOZ_NO_REMOTE': '1', 08:55:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:55:07 INFO - 'NO_EM_RESTART': '1', 08:55:07 INFO - 'PAGER': '/bin/cat', 08:55:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:07 INFO - 'PWD': '/builds/slave/test', 08:55:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:55:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:07 INFO - 'RUST_BACKTRACE': '1', 08:55:07 INFO - 'SHELL': '/bin/bash', 08:55:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hKi8vvSQTM/Listeners', 08:55:07 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 08:55:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:07 INFO - 'USER': 'cltbld', 08:55:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:07 INFO - 'XPC_FLAGS': '0x0', 08:55:07 INFO - 'XPC_SERVICE_NAME': '0', 08:55:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:55:08 INFO - mozversion application_buildid: 20170504074819 08:55:08 INFO - mozversion application_changeset: 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 08:55:08 INFO - mozversion application_display_name: Firefox 08:55:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:55:08 INFO - mozversion application_name: Firefox 08:55:08 INFO - mozversion application_remotingname: firefox 08:55:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:55:08 INFO - mozversion application_vendor: Mozilla 08:55:08 INFO - mozversion application_version: 54.0 08:55:08 INFO - mozversion platform_buildid: 20170504074819 08:55:08 INFO - mozversion platform_changeset: 94d16b8d4a52f8a325888249ea77d98c6f6ebe18 08:55:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:55:08 INFO - mozversion platform_version: 54.0 08:55:08 INFO - using testdate: 1493913308 08:55:08 INFO - actual date: 1493913308 08:55:08 INFO - starting webserver on 'localhost:49245' 08:55:08 INFO - SUITE-START | Running 2 tests 08:55:08 INFO - TEST-START | dromaeo_css 08:55:08 INFO - operating with platform_type : mac_ 08:55:08 INFO - Initialising browser for dromaeo_css test... 08:55:08 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpKCpGkj/profile http://localhost:49245/getInfo.html) 08:55:10 INFO - PROCESS | 1640 | 2017-05-04 08:55:10.961 plugin-container[1643:8955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 08:55:10 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:10 INFO - PROCESS | 1640 | 2017-05-04 08:55:10.962 plugin-container[1643:8955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:55:10 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:10 INFO - PROCESS | 1640 | 2017-05-04 08:55:10.962 plugin-container[1643:8955] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:55:11 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:55:11 INFO - PROCESS | 1640 | colorDepth:24 08:55:11 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:55:11 INFO - PROCESS | 1640 | __metrics 08:55:11 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:55:11 INFO - TEST-INFO | 1640: exit 0 08:55:11 INFO - Browser initialized. 08:55:11 INFO - Running cycle 1/1 for dromaeo_css test... 08:55:11 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpKCpGkj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:14 INFO - PROCESS | 1645 | 2017-05-04 08:55:14.077 plugin-container[1646:9098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 08:55:14 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:14 INFO - PROCESS | 1645 | 2017-05-04 08:55:14.078 plugin-container[1646:9098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 08:55:14 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:14 INFO - PROCESS | 1645 | 2017-05-04 08:55:14.078 plugin-container[1646:9098] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:55:23 INFO - PROCESS | 1645 | RSS: Main: 174383104 08:55:23 INFO - PROCESS | 1645 | 08:58:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:58:28 INFO - PROCESS | 1645 | RSS: Main: 191856640 08:58:28 INFO - PROCESS | 1645 | 09:01:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 09:01:31 INFO - PROCESS | 1645 | RSS: Main: 202076160 09:01:31 INFO - PROCESS | 1645 | 09:04:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 09:04:34 INFO - PROCESS | 1645 | RSS: Main: 203620352 09:04:34 INFO - PROCESS | 1645 | 09:07:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 09:07:36 INFO - PROCESS | 1645 | RSS: Main: 207708160 09:07:36 INFO - PROCESS | 1645 | 09:10:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 09:10:40 INFO - PROCESS | 1645 | RSS: Main: 210247680 09:10:40 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:13:43 INFO - PROCESS | 1645 | RSS: Main: 205778944 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | __start_tp_report 09:13:43 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 09:13:43 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 09:13:43 INFO - PROCESS | 1645 | |i|pagename|runs| 09:13:43 INFO - PROCESS | 1645 | |0;dojo.html;3596.40659291217;3607.6572725591077;3621.692156166726;3629.4737263096845;3640.5995340512554;6107.297660769007;6151.538634602412;6153.50771938245;6153.753849846012;6181.969090154555;6108.480779133768;6138.263408390988;6139.017757158856;6170.660613666247;6205.720742566584;4699.271612900006;5534.640248383852;5584.776608935658;5619.887602247953;5691.9146212806845;5653.971730141344;5659.632123911953;5689.203511508392;5690.715464226792;5703.372629010805;5435.1847222916485;5448.863778405532;5644.209810626516;5654.236678048473;5655;4613.400257966459;5523.7790488380415;5556.583256255756;5568.610197286192;5589.552835773128;4697.765111744433;5433.9675461662555;5616.606837521375;5624.943750562483;5650.943490565124;6177.66022868742;6195.96902015487;6214.378562143795;6218.31598524162;6231.6572588507615;6301.495880329563;6338.302786693506;6434.227892652865;6451.51613628975;6624.6025238485845;5720.370759216483;6678.799636010927;6685.565438246547;6700.128983232197;6726.260111387744;6170.568060235785;6220.471259942956;6232.0963460298;6233.438990490878;6235.064740288947;6536.215654121535;6561.179852518435;6705.497087718343;6716.429103526158;6743.2919543448215;6448.290688024314;6486.416222539994;6508.219013718384;6512.934870651327;6524.314946930599;6636.170478690164;6668.033135195369;6671.566348187353;6686.933130668728;6703.296153903867;6215.720292586844;6258.593191442542;6268.404501572343;6518.217813862367;6538.215414150237;6370.617762934146;6426.678666066678;6472.741090356333;6501.804945851632;6535.3464653534265;5158.664686795346;5163.4062082860955;5188.299579556711;5200.531952124327;5263.789448422097;4663.997240593289;4667.32323813052;4676.596084061083;4684.367610372624;4694.131585656664;6117.908231376533;6197.96901015492;6205.720742566596;6208.379162083846;6215.533834962396;6452.612843229421;6510;6516.674166291667;6584.967075164594;6703.363180497845;6211;6233.625982441068;6235.781747638902;6240.188775459162;6252.843678907972;5904.350521442638;5909.468376586948;5912.615679980788;5922.407759224129;5934.7922822701;5744.913826292693;6545.14913061299;6604.867902641824;6775.62734049632;6844.623545705034;6647.169103862017;6677.599344039373;6688.632125233159;6692.665366731547;6698.83252918681;6721.22705888829;6880.105586273752;6886.586804791728;6906.758263460755;6917.204521479893;5439.048166570755;6082.087686846831;6225.28409232944;6555.770548030987;6606.8348291293105;6322.810315690536;6384.808455746335;6479.092926990319;6562.475001999924;6592.17597800275;6207.348228435949;6396.328385519453;6396.872062558629;6428.4857211423905;6442.420182183626;5582.692951887685;6333.493320534254;6371.522135839923;6406.711697973696;6411.198600174978;6085.2393450818645;6146.508279337731;6239.844003899939;6242.53181011435;6276.246850377984;6407.455366293917;6407.647579383179;6429.067785171031;6446.258680251831;6495.415412612699;6151.477124444294;6170.660613666291;6189.876202476015;6204.968975155095;6259.968700156653 09:13:43 INFO - PROCESS | 1645 | |1;ext.html;787.3386355461412;827.441477003023;852.1648784191494;986.955586998585;990.6037584966017;40473.79763101184;41190;42455;42585.78707106464;42611.14777704444;38188.80905595475;38372.61627383725;38416;38418.23163536727;38440.61559384405;8337.24964753172;8436.28291595212;11292.774144517105;11543.595974140906;11766.529338826436;11449.084073274118;11506.942465287662;11511.194216404854;11522.02062824661;11560;11481.827772583418;11506.482208300604;11519;11522.769544609102;11527.538898444052;11427.942860285728;11469.197156198987;11469.713257168554;11478.081753459745;11531.884681153166;11072.335659860435;11084.113270938306;11110;11138.443077846156;11169.776604467947;35523.64476355255;35689.572417103285;35736.46395304072;35827.82086089579;35839.745608903824;3947;3965.960340396588;3989.501312335958;4005;4047.150098479323;26625.467490650273;26627.73372266272;26643.600345994826;28378;28549.28626784326;3218.5011323244935;3270.444024515838;3310.3875782980226;3311.93376132476;3406.591209054929;350620;354907;363175;366754.1662291698;381728.0913595442;44816;45006.54993450024;45132.54867451349;45185;45186.54813451889;46754.298685519745;46811.82970425767;46970.82572935704;47033.76483117563;47059.529404705514;13079.215247085205;13909.374078166507;14469.710605787825;14630.341634626468;14664.926675366769;12718;12723.363831808372;12727.93636031814;12736.61790146297;12739.980801535856;10184.777826660847;12138.817917731241;12161.148719589544;12211.755764884652;12295.385230738428;4549.658775591844;4806.2310030394965;4863.440704318978;5701.173329867229;5703.686297253608;1102.8897110289067;1145.7823013627385;1153.3541216918552;1181.3266438130183;1213.6116442738416;27349.86325068402;27774.583381249297;27776;27795.85349204543;27916.581251281248;1079.9784004320027;1296.2093123194843;1298.4351806964;1350.4868150103096;1355.213975893979;14847;15372.846271537364;16678.416255431002;16862.566681831937;17023.06373149464;496057;496854;497734;498517;501003;59761;60111.39888601058;60404.39595604075;60561.394386055574;60812.39187608156;1477.3573495529265;1519.5973067136997;1520.6694142625204;1527.4348491058379;1528.0907859823387;25906.70466476631;28600.856995714887;29128.12615621535;29899;29970.400591988473;49450;49612.51162465132;49632;49671;49812.00375992387;476327.618361906;477210;478472.82290766365;479204;480006;35849.74525891685;36871.26257474889;36991.630083698816;37011.259774804894;37053.62946370609;45229.773851130536;45349.773251133534;45641.543584563726;46872.531274686815;47092.529074708815;28567.857160714062;31599.684003159673;31970.68029319677;32587.18532036718;32677.18307042343;31650.366992660478;31741.8412907934;32287.35425291528;32465.513017304285;32572.348553029282;30452.695473044987;31660.683393165775;37218.23974512168;37420;38177.236455270184;31797.20506987344;32211.838940805144;32409.189770255933;32701.672983269862;33081.17297067496;22360.881955902467;23842.64236036426;23950.401239968443;25086.37284067913;25729.742702572734 09:13:43 INFO - PROCESS | 1645 | |2;jquery.html;410.1387087117054;428.293316028553;442.7033887295511;444.7528933375039;448.3767563087307;18295.634087318245;19466.221351145938;20545.691814622773;21531.6770248446;21726.565468690616;21535.569288614217;23253.418664533394;23714;23767.643485347733;23830;6822.863542729155;6879.140107486564;7166.784996450088;7216.494845360827;7235.710571577157;5989.131575921489;7109.289071092901;7114.146302443714;7122.074130363046;7127.03784989025;6713.194416669982;7004.5096843221;7062.717491300367;7084.0436541067065;7150.821229469253;6724.3611856874;7021.71913123477;7077.964610176968;7091.290870912919;7102.502824802266;6885.724571017154;6887.139107611549;6892.758753443655;6897.965510172417;6903.9989201565995;20614.896925515426;22961.885190574107;23058;23385;27240.774165162617;2613.8562379069144;3033.8179709217297;3050.679678633756;3111.5021596544443;3120.7503399728193;15441.459548915735;16223.026618402933;17226.310947562084;17293.962362258306;17351.392701255394;2115.3336698939784;2131.5523740014614;2131.989340053305;2159.4601349662585;2180.716506854115;130534;136034;145443;170389.14805425893;189817.10182897994;18215.72676409855;18621.44135675932;19990.649743365157;20062.699059514118;21552.568948621254;28810.991615293366;31241.843790780902;32400.67599324024;32877.671223287936;32976.835115824266;10346.430946297874;10768.138548916068;10915.781684366268;10943.28868623537;11101.056410205061;7778.455508114378;8618;9054.366194366332;9142.222911052502;9215.63137474507;7874.842503150019;7967.960160199162;9621.18219951298;9680.370775899684;9780.532920061905;3994.7868032218234;4043.838246470108;4253.745145182179;4338.240807858612;4362.432883725159;1029.5881647340973;1031.664708969588;1041.9664387571754;1074.3714926767773;1084.2514226436103;17358.91320543389;21245.78754212438;21281.382878552333;21283.89358053231;21513.569728605653;1052.5947510208625;1056.1286938275953;1058.1059005140644;1086.098538213281;1094.2176343914139;10604.098651614699;11065.280756750946;11074.778504429867;11131.998120169063;11212.831807522894;636093;725281.1207831887;761528;787452;789730;109863;117634;131684;147144;156153;1257.8239046533492;1275.354792317918;1292.5734507612465;1296.7989961555975;1300.5903140510898;15502.844971550592;17888;18553.072346382898;18737.531561711065;18787.624247515247;46708.832279193295;49588.26441074545;50410.49589504205;61535.6923215381;62345.37654623396;61626.69186654038;85396;85968.57015715132;86763;87795.12204878125;10857.13142948578;11337.829932551183;11427.60003399877;11799.174057816213;11799.469023894117;12702.983761298889;12711.55509557161;12733.172343797623;13012.763787440179;13108.803367949298;10489.580416783243;10765.677029689123;11254.21220514556;11384.203105782515;11446.198766086294;9446.480443575669;9531.189848862925;9678;9709.854352184864;9821.901780982385;9456.196223321094;11282.153838461983;11537.653870383901;11674.824877626672;11692.94153529227;11425.771484570096;11435.485403156712;11542.653720388402;11724.824127637921;11776.352300623548;9374.484403357881;11280.21038527328;11336;11440.256383335056;11444.02725768319 09:13:43 INFO - PROCESS | 1645 | |3;mootools.html;1582.5727053695502;1612.9516114516568;1653.1816750708401;1656.6024154202992;1674.179651970534;9835.557399917003;10108.74728131797;11189.49647265873;11678.883211167886;11837.7632447351;10060;11010.119190464764;11354.943225283883;11395.259308144965;11523.942380288086;2456.361346050022;2491.165459571046;2491.816387216076;2494.3639371960126;2500.712418071918;2398.268528098929;2419.443527988564;2441.1455990403306;2441.951160976779;2446.804255659552;2449.5713250181234;2456.299954512964;2468.358226861011;2472.5178590174924;2485.1798906360946;2072.336852207294;2417.3231495181376;2428.3929017745563;2429.817763667732;2435.0990133650484;2417.721961974378;2424.660547523348;2424.7454017328278;2441.3286346254745;2467.062516243816;11303.886961130365;11435;11444.313341199555;11454.885451145465;11506.137039722054;5882.147088672162;6050.9092363614145;6056.091586262052;6057.3336932937345;6065.848353791146;3590.0845284452557;3653.5433070866143;3661.286049220403;3810.161764411825;3879.650831425185;1239.3493415956605;1241.8633950265464;1250.2623452163177;1287.8003909394058;1290.4580076367947;15818;15835.920820395824;15844.603884902854;15868.603284917854;15937.840621593752;5020.397552293675;5150.896982060338;5275.4724527547705;5278.102722537178;5301.628885977944;12038.518459261706;12157.270563766202;12163.2702037878;12181.269123852597;12221.572244971383;6002.279726432856;6055.6063855849225;6099.695015249308;6101.633901965896;6114.57197996136;4818.469968303484;5040.76501257187;5506.64206826555;5526.640768350045;5529.253550770594;3985.580304229975;4056.371262454324;4084.7957602119777;4127.834886604563;4134.255833949918;957.3155194036298;1108.9889101109047;1110.4281295133012;1112.193659596786;1126.1385040444068;867.9522626255616;981.3326937682302;1000.9199264058858;1004.6634377483479;1044.8380501022355;6567.835804104936;6595.142631457976;6639.701213445406;6825.419839313714;6827.146606674166;986.4032260482448;1030.845373194012;1040.6045702633103;1041.734357738787;1091.1489038549944;3456.7753096048677;3498.4927185557954;3820.9235815283537;4280.464941882265;4435.090806384684;11895.286282823059;13934.512292069729;14110;14129.858701412853;14187.64530886736;13853.16880987144;14049.086809357359;14188.716225675429;14211;14310.28448577567;9361.672341468016;10457.790844183073;10476.161907047417;10575.894241057646;10581.682549523526;6038.75844966214;6547.476201903932;6581.177352830896;6639.734410623521;6791.185057793096;12292.446839892405;12648.873511265137;12698.746025079261;12704.618861434172;12744.61766147017;12687.429065691882;12872.742545149233;12909.35453227316;12957.481700732242;13089.738205236032;8518.190771876662;8574.742757717278;8651.13488651155;8661.52361620117;8665.523396213268;8905.821883562423;10012.799744004933;10394.58421663125;10434.269601127848;10535.63125290611;8932.598033088656;9041.23149532297;9061;9183.127602877716;9205.953970230106;8718.738437846874;8817.867731984154;8849.734507964771;8871.822563548822;8993.190612844613;6991.44068474531;8192.30365418946;8259.545724985184;8267.793305167419;8319.708810191614;5735.168400581976;5756.94243057564;5787.971060144672;5855.443732845373;5939.287285525765;6029.125776762257;6044.848878778066;6153.200083989232;6176.598521096114;6331.746730130743 09:13:43 INFO - PROCESS | 1645 | |4;prototype.html;397.703710735502;398.72805749658585;399.8960270329717;401.7448919935841;407.17343792102025;4975.651704380691;5068.1130802109665;5315.574754019669;5330;5417.295751552298;4576.496585375606;4833.371661683985;4897.755112244391;5137.152369858969;5246.816361427351;3778.1310298631333;3779.81100944953;4155.39746736723;4170.416141740159;4373.147236288917;3954.9802250988705;4329.956700433003;4371.7922449163125;4378.40891479651;4395.318725597537;4124.876253712393;4198.958010419888;4323.308270676693;4370.344448332759;4379.408779814716;4284;4298.376735373385;4320.802606071516;4333.393324934512;4357.629601483878;3961.861334853295;3996.2606917720314;4002.9777517973744;4013.819378127991;4019.4975628046495;4374.190774706658;4572.451305843287;4780.6973723649;4923.458419573844;5160;4252.893677658083;4549.545045495457;4876.366072410599;4938.209886418192;5068.822591209327;5049.6212784041345;5632.197696737046;5641.774329026834;5644.661320320753;5657.943420565783;4098.897527561805;4262.87211383659;4495.280755079204;4500.437445319335;4546.908741901952;4769.976150119262;5145.30538377318;5413.593980451482;5667.979763642543;5722.54219662426;4398.582134697199;4475.395821564047;4587.564181402761;4606.792694328763;4632.841789552612;4614.838480653161;4636.9536304637195;4675.18184317743;4685.484596694361;4718.617098365304;4335.544767799335;4503.662225333048;4601.631869450436;4672.892526469927;4990.431841233535;4576;5100.872478188001;5518.558515318765;5530.834074977757;5560.527355174774;1380.2615600653803;1410.6825964158188;1544.3436539470904;1571.677806049757;1586.341668207704;1040.0379648824808;1168.4391492083848;1200.963971080869;1211.6001719432566;1214.4656351205442;4790.538885639913;5117.769700363493;5211.166213405837;5253.557095950325;5338.172583249602;5435.7825686972865;5647.37878833328;5763;5797.391273916262;5808.622439541501;4484.282514797616;4897.897972956056;4962.950370496321;5288.312519372457;5379.327584051994;2789.804713670024;2849.6580410350475;2863.2126165304708;3043.5195315817537;3064.7394971427234;5422.53908417781;5534.564791491731;5679.819301191346;5770.6249093808765;5997.070454079625;4425.800838962255;5301.522862942276;5522.806701765499;5637;6076.635401875901;5516.393196748354;6037.698115094316;6056.727447264796;6129.295131059896;6135.9079613805825;5670.291213598301;5873.56247157442;5887.1757953885635;5920.585559010828;5956.5532585055425;5437.293151890229;5753.338366087786;5826.5724897400405;5849.678267695147;5866.67733274674;5752.61266574367;5752.884942301047;5942.702864856654;6067.240500255053;6072.726727297194;3737.5701794294005;4417.425734654603;4576.336431217522;4641.953580464152;4674.485806561208;4942.357493525818;5625.662460252398;5873.911891321719;5904.852378690567;5931.970340148272;5088.168259426529;5295.8878635485435;5486.341639003345;5547.223388725501;5652.491275785199;5696.373398926032;5698.914516282176;5702;5705.486506214461;5792.768289268382;5339.718467567678;5540.529055030368;5574.498295153294;5596.804111856229;5608.887822243614;5431.09911530961;5604.271444712162;5621.943780562306;5627.18405831144;5649.903819730663;4804.879878002938;5371.221172929825;5513.696746678971;5537.2900156894175;5580.414056524007 09:13:43 INFO - PROCESS | 1645 | |5;yui.html;752.82684982454;756.4553521464544;759.6277823866303;874.1171416868963;880.5729221327657;16360.427385041527;16942.3223071077;17173.7423938641;17182.14089295535;17196.82803171968;15890.443834465796;15924.920375398136;16061.83938160618;16067.678646427064;16071;972.333951243397;988.906053924876;1007.969760907174;1027.342500799489;1033.3489901362132;1026.9332493387906;1028.9073983341498;1029.0943969307;1029.7580068683853;1031.0823367203195;981.8429051351785;1074.7796701676139;1090.8091084060297;1091.476091476092;1093.5297821936565;1076.8330908709163;1077.439731339699;1078.2667785905621;1082.832161015044;1083.2958576924982;788.562347896917;795.9204079592052;805.7099444200142;807.4186585658316;809.0291650019999;16437.93153445022;17022.404215852384;17416.825831741648;17637.294508219657;17863.642727145514;14642;14714.484993025193;14803.333849976776;14870.70258594833;14991.32539035746;11508.482118304697;11846.466908989116;11899.167058305922;12005.339706316148;12347.876521234763;3801.2967600993907;3842.7886466244345;3857.614238576148;3948.9012774680864;4034.536028356747;122220;125022.37488812498;126203.36898315449;127243.36378318141;127570;10468.581256749798;11139.944300278446;11307.547698092148;11356.48895799691;11793;23421.53156936852;23914.282571522883;24265.636015459782;24328;24343.269701908972;7877.645505952246;8118.570183086312;8149.470284431493;8167.305779008732;8403.537805420761;4160.791960401968;4553.901956583528;4681.485036645966;4731.455882573479;4789.137955168103;3671.7980511071614;3718.2377612589353;3728.382121733944;3772.264408440383;3804.31522325984;663.9800805975829;671.1342368344781;678.1489231015045;686.087503620184;687.8658661560947;705.2312978853075;725.9056322678018;726.7565365602583;743.9665215065335;777.5684495105271;10489.028927564714;10700.518476668569;11134.552508174047;11151.163662725328;11153.219274650697;620.4230066038627;712.4407340237992;736.9447291453163;739.3696898339149;742.6138408027904;4938.43208031081;5237.711925844039;5253.816116435906;5278.630495865253;5315.973420132952;100644;101550;102516.48741756393;104313;105165.47417262864;62724.372756271856;74138;78184;79783;80116.59941700254;78605.60697196478;79314.81027784589;79318.20681793224;80587.7911831323;81612.77580836292;9549.665761698307;10179.7455063624;10205.84691229647;10281.588736450758;10292.845607315747;51919.74040129775;52183.43449696515;52276.47723522716;54168.45831541792;54695.72652136714;66817.66591167013;67013;67127.32872671405;67220.6638966802;67552;11599.594014209462;11840.171188016757;11843.170978031456;12040;12297.508099675913;13457.596272111852;13548.661283467993;13654.795178072138;14286.571402857931;14431.711365772415;12431.440585173508;12566.246025238515;12763.80854287205;12907.290099044267;13344.466221351036;12451.626451206479;12468.314242716737;12536.74926501483;12616.873831261937;13056.673583160496;11974.846266180713;12360;12448.128630995743;12639.684007899876;13052.54316098932;9220.631174752934;9766.974467681075;9768.951155244178;9774.360922551014;9967.202623790225;10558.736031599272;10660.388204889852;10742.516586753458;10755.193360497837;10808.783824323627 09:13:43 INFO - PROCESS | 1645 | __end_tp_report 09:13:43 INFO - PROCESS | 1645 | __start_cc_report 09:13:43 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,392 09:13:43 INFO - PROCESS | 1645 | __end_cc_report 09:13:43 INFO - PROCESS | 1645 | __startTimestamp1493914423291__endTimestamp 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | ------- Summary: start ------- 09:13:43 INFO - PROCESS | 1645 | Number of tests: 6 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:6068.47 Median:6232.10 stddev:676.10 (10.8%) stddev-sans-first:649.76 09:13:43 INFO - PROCESS | 1645 | Values: 3596.4 3607.7 3621.7 3629.5 3640.6 6107.3 6151.5 6153.5 6153.8 6182.0 6108.5 6138.3 6139.0 6170.7 6205.7 4699.3 5534.6 5584.8 5619.9 5691.9 5654.0 5659.6 5689.2 5690.7 5703.4 5435.2 5448.9 5644.2 5654.2 5655.0 4613.4 5523.8 5556.6 5568.6 5589.6 4697.8 5434.0 5616.6 5624.9 5650.9 6177.7 6196.0 6214.4 6218.3 6231.7 6301.5 6338.3 6434.2 6451.5 6624.6 5720.4 6678.8 6685.6 6700.1 6726.3 6170.6 6220.5 6232.1 6233.4 6235.1 6536.2 6561.2 6705.5 6716.4 6743.3 6448.3 6486.4 6508.2 6512.9 6524.3 6636.2 6668.0 6671.6 6686.9 6703.3 6215.7 6258.6 6268.4 6518.2 6538.2 6370.6 6426.7 6472.7 6501.8 6535.3 5158.7 5163.4 5188.3 5200.5 5263.8 4664.0 4667.3 4676.6 4684.4 4694.1 6117.9 6198.0 6205.7 6208.4 6215.5 6452.6 6510.0 6516.7 6585.0 6703.4 6211.0 6233.6 6235.8 6240.2 6252.8 5904.4 5909.5 5912.6 5922.4 5934.8 5744.9 6545.1 6604.9 6775.6 6844.6 6647.2 6677.6 6688.6 6692.7 6698.8 6721.2 6880.1 6886.6 6906.8 6917.2 5439.0 6082.1 6225.3 6555.8 6606.8 6322.8 6384.8 6479.1 6562.5 6592.2 6207.3 6396.3 6396.9 6428.5 6442.4 5582.7 6333.5 6371.5 6406.7 6411.2 6085.2 6146.5 6239.8 6242.5 6276.2 6407.5 6407.6 6429.1 6446.3 6495.4 6151.5 6170.7 6189.9 6205.0 6260.0 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:58539.73 Median:32518.93 stddev:119659.58 (368.0%) stddev-sans-first:119916.69 09:13:43 INFO - PROCESS | 1645 | Values: 787.3 827.4 852.2 987.0 990.6 40473.8 41190.0 42455.0 42585.8 42611.1 38188.8 38372.6 38416.0 38418.2 38440.6 8337.2 8436.3 11292.8 11543.6 11766.5 11449.1 11506.9 11511.2 11522.0 11560.0 11481.8 11506.5 11519.0 11522.8 11527.5 11427.9 11469.2 11469.7 11478.1 11531.9 11072.3 11084.1 11110.0 11138.4 11169.8 35523.6 35689.6 35736.5 35827.8 35839.7 3947.0 3966.0 3989.5 4005.0 4047.2 26625.5 26627.7 26643.6 28378.0 28549.3 3218.5 3270.4 3310.4 3311.9 3406.6 350620.0 354907.0 363175.0 366754.2 381728.1 44816.0 45006.5 45132.5 45185.0 45186.5 46754.3 46811.8 46970.8 47033.8 47059.5 13079.2 13909.4 14469.7 14630.3 14664.9 12718.0 12723.4 12727.9 12736.6 12740.0 10184.8 12138.8 12161.1 12211.8 12295.4 4549.7 4806.2 4863.4 5701.2 5703.7 1102.9 1145.8 1153.4 1181.3 1213.6 27349.9 27774.6 27776.0 27795.9 27916.6 1080.0 1296.2 1298.4 1350.5 1355.2 14847.0 15372.8 16678.4 16862.6 17023.1 496057.0 496854.0 497734.0 498517.0 501003.0 59761.0 60111.4 60404.4 60561.4 60812.4 1477.4 1519.6 1520.7 1527.4 1528.1 25906.7 28600.9 29128.1 29899.0 29970.4 49450.0 49612.5 49632.0 49671.0 49812.0 476327.6 477210.0 478472.8 479204.0 480006.0 35849.7 36871.3 36991.6 37011.3 37053.6 45229.8 45349.8 45641.5 46872.5 47092.5 28567.9 31599.7 31970.7 32587.2 32677.2 31650.4 31741.8 32287.4 32465.5 32572.3 30452.7 31660.7 37218.2 37420.0 38177.2 31797.2 32211.8 32409.2 32701.7 33081.2 22360.9 23842.6 23950.4 25086.4 25729.7 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:41731.98 Median:21544.07 stddev:123449.96 (573.0%) stddev-sans-first:123757.28 09:13:43 INFO - PROCESS | 1645 | Values: 410.1 428.3 442.7 444.8 448.4 18295.6 19466.2 20545.7 21531.7 21726.6 21535.6 23253.4 23714.0 23767.6 23830.0 6822.9 6879.1 7166.8 7216.5 7235.7 5989.1 7109.3 7114.1 7122.1 7127.0 6713.2 7004.5 7062.7 7084.0 7150.8 6724.4 7021.7 7078.0 7091.3 7102.5 6885.7 6887.1 6892.8 6898.0 6904.0 20614.9 22961.9 23058.0 23385.0 27240.8 2613.9 3033.8 3050.7 3111.5 3120.8 15441.5 16223.0 17226.3 17294.0 17351.4 2115.3 2131.6 2132.0 2159.5 2180.7 130534.0 136034.0 145443.0 170389.1 189817.1 18215.7 18621.4 19990.6 20062.7 21552.6 28811.0 31241.8 32400.7 32877.7 32976.8 10346.4 10768.1 10915.8 10943.3 11101.1 7778.5 8618.0 9054.4 9142.2 9215.6 7874.8 7968.0 9621.2 9680.4 9780.5 3994.8 4043.8 4253.7 4338.2 4362.4 1029.6 1031.7 1042.0 1074.4 1084.3 17358.9 21245.8 21281.4 21283.9 21513.6 1052.6 1056.1 1058.1 1086.1 1094.2 10604.1 11065.3 11074.8 11132.0 11212.8 636093.0 725281.1 761528.0 787452.0 789730.0 109863.0 117634.0 131684.0 147144.0 156153.0 1257.8 1275.4 1292.6 1296.8 1300.6 15502.8 17888.0 18553.1 18737.5 18787.6 46708.8 49588.3 50410.5 61535.7 62345.4 61626.7 85396.0 85968.6 86763.0 87795.1 10857.1 11337.8 11427.6 11799.2 11799.5 12703.0 12711.6 12733.2 13012.8 13108.8 10489.6 10765.7 11254.2 11384.2 11446.2 9446.5 9531.2 9678.0 9709.9 9821.9 9456.2 11282.2 11537.7 11674.8 11692.9 11425.8 11435.5 11542.7 11724.8 11776.4 9374.5 11280.2 11336.0 11440.3 11444.0 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:6868.53 Median:2467.71 stddev:4307.12 (174.5%) stddev-sans-first:4300.89 09:13:43 INFO - PROCESS | 1645 | Values: 1582.6 1613.0 1653.2 1656.6 1674.2 9835.6 10108.7 11189.5 11678.9 11837.8 10060.0 11010.1 11354.9 11395.3 11523.9 2456.4 2491.2 2491.8 2494.4 2500.7 2398.3 2419.4 2441.1 2442.0 2446.8 2449.6 2456.3 2468.4 2472.5 2485.2 2072.3 2417.3 2428.4 2429.8 2435.1 2417.7 2424.7 2424.7 2441.3 2467.1 11303.9 11435.0 11444.3 11454.9 11506.1 5882.1 6050.9 6056.1 6057.3 6065.8 3590.1 3653.5 3661.3 3810.2 3879.7 1239.3 1241.9 1250.3 1287.8 1290.5 15818.0 15835.9 15844.6 15868.6 15937.8 5020.4 5150.9 5275.5 5278.1 5301.6 12038.5 12157.3 12163.3 12181.3 12221.6 6002.3 6055.6 6099.7 6101.6 6114.6 4818.5 5040.8 5506.6 5526.6 5529.3 3985.6 4056.4 4084.8 4127.8 4134.3 957.3 1109.0 1110.4 1112.2 1126.1 868.0 981.3 1000.9 1004.7 1044.8 6567.8 6595.1 6639.7 6825.4 6827.1 986.4 1030.8 1040.6 1041.7 1091.1 3456.8 3498.5 3820.9 4280.5 4435.1 11895.3 13934.5 14110.0 14129.9 14187.6 13853.2 14049.1 14188.7 14211.0 14310.3 9361.7 10457.8 10476.2 10575.9 10581.7 6038.8 6547.5 6581.2 6639.7 6791.2 12292.4 12648.9 12698.7 12704.6 12744.6 12687.4 12872.7 12909.4 12957.5 13089.7 8518.2 8574.7 8651.1 8661.5 8665.5 8905.8 10012.8 10394.6 10434.3 10535.6 8932.6 9041.2 9061.0 9183.1 9206.0 8718.7 8817.9 8849.7 8871.8 8993.2 6991.4 8192.3 8259.5 8267.8 8319.7 5735.2 5756.9 5788.0 5855.4 5939.3 6029.1 6044.8 6153.2 6176.6 6331.7 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4694.62 Median:5020.03 stddev:1317.66 (26.2%) stddev-sans-first:1281.28 09:13:43 INFO - PROCESS | 1645 | Values: 397.7 398.7 399.9 401.7 407.2 4975.7 5068.1 5315.6 5330.0 5417.3 4576.5 4833.4 4897.8 5137.2 5246.8 3778.1 3779.8 4155.4 4170.4 4373.1 3955.0 4330.0 4371.8 4378.4 4395.3 4124.9 4199.0 4323.3 4370.3 4379.4 4284.0 4298.4 4320.8 4333.4 4357.6 3961.9 3996.3 4003.0 4013.8 4019.5 4374.2 4572.5 4780.7 4923.5 5160.0 4252.9 4549.5 4876.4 4938.2 5068.8 5049.6 5632.2 5641.8 5644.7 5657.9 4098.9 4262.9 4495.3 4500.4 4546.9 4770.0 5145.3 5413.6 5668.0 5722.5 4398.6 4475.4 4587.6 4606.8 4632.8 4614.8 4637.0 4675.2 4685.5 4718.6 4335.5 4503.7 4601.6 4672.9 4990.4 4576.0 5100.9 5518.6 5530.8 5560.5 1380.3 1410.7 1544.3 1571.7 1586.3 1040.0 1168.4 1201.0 1211.6 1214.5 4790.5 5117.8 5211.2 5253.6 5338.2 5435.8 5647.4 5763.0 5797.4 5808.6 4484.3 4897.9 4963.0 5288.3 5379.3 2789.8 2849.7 2863.2 3043.5 3064.7 5422.5 5534.6 5679.8 5770.6 5997.1 4425.8 5301.5 5522.8 5637.0 6076.6 5516.4 6037.7 6056.7 6129.3 6135.9 5670.3 5873.6 5887.2 5920.6 5956.6 5437.3 5753.3 5826.6 5849.7 5866.7 5752.6 5752.9 5942.7 6067.2 6072.7 3737.6 4417.4 4576.3 4642.0 4674.5 4942.4 5625.7 5873.9 5904.9 5932.0 5088.2 5295.9 5486.3 5547.2 5652.5 5696.4 5698.9 5702.0 5705.5 5792.8 5339.7 5540.5 5574.5 5596.8 5608.9 5431.1 5604.3 5621.9 5627.2 5649.9 4804.9 5371.2 5513.7 5537.3 5580.4 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20956.66 Median:17177.94 stddev:30470.12 (177.4%) stddev-sans-first:30517.83 09:13:43 INFO - PROCESS | 1645 | Values: 752.8 756.5 759.6 874.1 880.6 16360.4 16942.3 17173.7 17182.1 17196.8 15890.4 15924.9 16061.8 16067.7 16071.0 972.3 988.9 1008.0 1027.3 1033.3 1026.9 1028.9 1029.1 1029.8 1031.1 981.8 1074.8 1090.8 1091.5 1093.5 1076.8 1077.4 1078.3 1082.8 1083.3 788.6 795.9 805.7 807.4 809.0 16437.9 17022.4 17416.8 17637.3 17863.6 14642.0 14714.5 14803.3 14870.7 14991.3 11508.5 11846.5 11899.2 12005.3 12347.9 3801.3 3842.8 3857.6 3948.9 4034.5 122220.0 125022.4 126203.4 127243.4 127570.0 10468.6 11139.9 11307.5 11356.5 11793.0 23421.5 23914.3 24265.6 24328.0 24343.3 7877.6 8118.6 8149.5 8167.3 8403.5 4160.8 4553.9 4681.5 4731.5 4789.1 3671.8 3718.2 3728.4 3772.3 3804.3 664.0 671.1 678.1 686.1 687.9 705.2 725.9 726.8 744.0 777.6 10489.0 10700.5 11134.6 11151.2 11153.2 620.4 712.4 736.9 739.4 742.6 4938.4 5237.7 5253.8 5278.6 5316.0 100644.0 101550.0 102516.5 104313.0 105165.5 62724.4 74138.0 78184.0 79783.0 80116.6 78605.6 79314.8 79318.2 80587.8 81612.8 9549.7 10179.7 10205.8 10281.6 10292.8 51919.7 52183.4 52276.5 54168.5 54695.7 66817.7 67013.0 67127.3 67220.7 67552.0 11599.6 11840.2 11843.2 12040.0 12297.5 13457.6 13548.7 13654.8 14286.6 14431.7 12431.4 12566.2 12763.8 12907.3 13344.5 12451.6 12468.3 12536.7 12616.9 13056.7 11974.8 12360.0 12448.1 12639.7 13052.5 9220.6 9767.0 9769.0 9774.4 9967.2 10558.7 10660.4 10742.5 10755.2 10808.8 09:13:43 INFO - PROCESS | 1645 | -------- Summary: end -------- 09:13:43 INFO - PROCESS | 1645 | 09:13:43 INFO - TEST-INFO | 1645: exit 0 09:13:43 INFO - TEST-OK | dromaeo_css | took 1115827ms 09:13:43 INFO - TEST-START | kraken 09:13:43 INFO - operating with platform_type : mac_ 09:13:43 INFO - Initialising browser for kraken test... 09:13:43 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpZY1qf2/profile http://localhost:49245/getInfo.html) 09:13:46 INFO - PROCESS | 1653 | 2017-05-04 09:13:46.331 plugin-container[1655:11372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:13:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:46 INFO - PROCESS | 1653 | 2017-05-04 09:13:46.332 plugin-container[1655:11372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8813, name = 'com.apple.CFPasteboardClient' 09:13:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:46 INFO - PROCESS | 1653 | 2017-05-04 09:13:46.332 plugin-container[1655:11372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:46 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:13:46 INFO - PROCESS | 1653 | colorDepth:24 09:13:46 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:13:46 INFO - PROCESS | 1653 | __metrics 09:13:46 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:13:47 INFO - TEST-INFO | 1653: exit 0 09:13:47 INFO - Browser initialized. 09:13:47 INFO - Running cycle 1/1 for kraken test... 09:13:47 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpZY1qf2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:49 INFO - PROCESS | 1656 | 2017-05-04 09:13:49.285 plugin-container[1657:11499] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:13:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:49 INFO - PROCESS | 1656 | 2017-05-04 09:13:49.286 plugin-container[1657:11499] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:13:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:49 INFO - PROCESS | 1656 | 2017-05-04 09:13:49.286 plugin-container[1657:11499] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:59 INFO - PROCESS | 1656 | RSS: Main: 174313472 09:13:59 INFO - PROCESS | 1656 | 09:14:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 09:14:02 INFO - PROCESS | 1656 | RSS: Main: 187863040 09:14:02 INFO - PROCESS | 1656 | 09:14:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 09:14:06 INFO - PROCESS | 1656 | RSS: Main: 195735552 09:14:06 INFO - PROCESS | 1656 | 09:14:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 09:14:09 INFO - PROCESS | 1656 | RSS: Main: 199516160 09:14:09 INFO - PROCESS | 1656 | 09:14:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 09:14:12 INFO - PROCESS | 1656 | RSS: Main: 201060352 09:14:12 INFO - PROCESS | 1656 | 09:14:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:14:14 INFO - PROCESS | 1656 | RSS: Main: 201232384 09:14:14 INFO - PROCESS | 1656 | 09:14:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 09:14:18 INFO - PROCESS | 1656 | RSS: Main: 205262848 09:14:18 INFO - PROCESS | 1656 | 09:14:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 09:14:22 INFO - PROCESS | 1656 | RSS: Main: 205303808 09:14:22 INFO - PROCESS | 1656 | 09:14:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 09:14:25 INFO - PROCESS | 1656 | RSS: Main: 205475840 09:14:25 INFO - PROCESS | 1656 | 09:14:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:14:28 INFO - PROCESS | 1656 | RSS: Main: 204705792 09:14:28 INFO - PROCESS | 1656 | 09:14:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:30 INFO - PROCESS | 1656 | RSS: Main: 204709888 09:14:30 INFO - PROCESS | 1656 | 09:14:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:33 INFO - PROCESS | 1656 | RSS: Main: 204656640 09:14:33 INFO - PROCESS | 1656 | 09:14:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:36 INFO - PROCESS | 1656 | RSS: Main: 204652544 09:14:36 INFO - PROCESS | 1656 | 09:14:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:40 INFO - PROCESS | 1656 | RSS: Main: 205029376 09:14:40 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 09:14:42 INFO - PROCESS | 1656 | RSS: Main: 207224832 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | __start_tp_report 09:14:42 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:14:42 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:14:42 INFO - PROCESS | 1656 | |i|pagename|runs| 09:14:42 INFO - PROCESS | 1656 | |0;ai-astar;107;118;118;120;120;119;120;119;119;120 09:14:42 INFO - PROCESS | 1656 | |1;audio-beat-detection;165;136;134;143;110;112;113;139;154;110 09:14:42 INFO - PROCESS | 1656 | |2;audio-dft;200;199;194;193;195;197;198;200;200;195 09:14:42 INFO - PROCESS | 1656 | |3;audio-fft;81;59;59;82;59;63;77;64;72;60 09:14:42 INFO - PROCESS | 1656 | |4;audio-oscillator;88;69;83;72;70;68;81;68;69;81 09:14:42 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;90;89;88;89;87;88;90;89;88 09:14:42 INFO - PROCESS | 1656 | |6;imaging-darkroom;123;146;140;97;95;97;96;100;96;112 09:14:42 INFO - PROCESS | 1656 | |7;imaging-desaturate;90;93;89;91;97;90;91;122;140;131 09:14:42 INFO - PROCESS | 1656 | |8;json-parse-financial;63;67;63;65;69;68;66;66;74;67 09:14:42 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;57;63;57;59;65;62;59;56;62;65 09:14:42 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;87;85;85;83;86;92;86;74;96 09:14:42 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;140;146;138;135;137;132;136;153;190;148 09:14:42 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;164;163;168;164;162;164;163;162;159;164 09:14:42 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;64;63;65;63;65;66;64;63;63 09:14:42 INFO - PROCESS | 1656 | __end_tp_report 09:14:42 INFO - PROCESS | 1656 | __start_cc_report 09:14:42 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,977 09:14:42 INFO - PROCESS | 1656 | __end_cc_report 09:14:42 INFO - PROCESS | 1656 | __startTimestamp1493914482269__endTimestamp 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:14:42 INFO - PROCESS | 1656 | Number of tests: 14 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:118.00 Median:119.50 stddev:3.94 (3.3%) stddev-sans-first:0.83 09:14:42 INFO - PROCESS | 1656 | Values: 107.0 118.0 118.0 120.0 120.0 119.0 120.0 119.0 119.0 120.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:137.50 stddev:19.69 (14.3%) stddev-sans-first:16.77 09:14:42 INFO - PROCESS | 1656 | Values: 165.0 136.0 134.0 143.0 110.0 112.0 113.0 139.0 154.0 110.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:197.10 Median:198.50 stddev:2.69 (1.4%) stddev-sans-first:2.64 09:14:42 INFO - PROCESS | 1656 | Values: 200.0 199.0 194.0 193.0 195.0 197.0 198.0 200.0 200.0 195.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:67.60 Median:68.00 stddev:9.48 (13.9%) stddev-sans-first:8.72 09:14:42 INFO - PROCESS | 1656 | Values: 81.0 59.0 59.0 82.0 59.0 63.0 77.0 64.0 72.0 60.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:74.90 Median:76.50 stddev:7.52 (9.8%) stddev-sans-first:6.31 09:14:42 INFO - PROCESS | 1656 | Values: 88.0 69.0 83.0 72.0 70.0 68.0 81.0 68.0 69.0 81.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 09:14:42 INFO - PROCESS | 1656 | Values: 89.0 90.0 89.0 88.0 89.0 87.0 88.0 90.0 89.0 88.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:110.20 Median:95.50 stddev:19.50 (20.4%) stddev-sans-first:20.13 09:14:42 INFO - PROCESS | 1656 | Values: 123.0 146.0 140.0 97.0 95.0 97.0 96.0 100.0 96.0 112.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:103.40 Median:90.50 stddev:19.64 (21.7%) stddev-sans-first:20.22 09:14:42 INFO - PROCESS | 1656 | Values: 90.0 93.0 89.0 91.0 97.0 90.0 91.0 122.0 140.0 131.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.00 stddev:3.19 (4.8%) stddev-sans-first:3.07 09:14:42 INFO - PROCESS | 1656 | Values: 63.0 67.0 63.0 65.0 69.0 68.0 66.0 66.0 74.0 67.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:62.00 stddev:3.34 (5.4%) stddev-sans-first:3.30 09:14:42 INFO - PROCESS | 1656 | Values: 57.0 63.0 57.0 59.0 65.0 62.0 59.0 56.0 62.0 65.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:5.73 (6.7%) stddev-sans-first:6.04 09:14:42 INFO - PROCESS | 1656 | Values: 84.0 87.0 85.0 85.0 83.0 86.0 92.0 86.0 74.0 96.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:143.00 stddev:16.95 (11.9%) stddev-sans-first:17.86 09:14:42 INFO - PROCESS | 1656 | Values: 140.0 146.0 138.0 135.0 137.0 132.0 136.0 153.0 190.0 148.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:164.00 stddev:2.26 (1.4%) stddev-sans-first:2.39 09:14:42 INFO - PROCESS | 1656 | Values: 164.0 163.0 168.0 164.0 162.0 164.0 163.0 162.0 159.0 164.0 09:14:42 INFO - PROCESS | 1656 | 09:14:42 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.23 (1.9%) stddev-sans-first:1.12 09:14:42 INFO - PROCESS | 1656 | Values: 66.0 64.0 63.0 65.0 63.0 65.0 66.0 64.0 63.0 63.0 09:14:42 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:14:42 INFO - PROCESS | 1656 | 09:14:43 INFO - TEST-INFO | 1656: exit 0 09:14:43 INFO - TEST-OK | kraken | took 59254ms 09:14:43 INFO - SUITE-END | took 1175s 09:14:43 INFO - Completed test suite (00:19:35) 09:14:43 INFO - javascript benchmark 09:14:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7889.405955848595, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3596.40659291217, 3607.6572725591077, 3621.692156166726, 3629.4737263096845, 3640.5995340512554, 6107.297660769007, 6151.538634602412, 6153.50771938245, 6153.753849846012, 6181.969090154555, 6108.480779133768, 6138.263408390988, 6139.017757158856, 6170.660613666247, 6205.720742566584, 4699.271612900006, 5534.640248383852, 5584.776608935658, 5619.887602247953, 5691.9146212806845, 5653.971730141344, 5659.632123911953, 5689.203511508392, 5690.715464226792, 5703.372629010805, 5435.1847222916485, 5448.863778405532, 5644.209810626516, 5654.236678048473, 5655.0, 4613.400257966459, 5523.7790488380415, 5556.583256255756, 5568.610197286192, 5589.552835773128, 4697.765111744433, 5433.9675461662555, 5616.606837521375, 5624.943750562483, 5650.943490565124, 6177.66022868742, 6195.96902015487, 6214.378562143795, 6218.31598524162, 6231.6572588507615, 6301.495880329563, 6338.302786693506, 6434.227892652865, 6451.51613628975, 6624.6025238485845, 5720.370759216483, 6678.799636010927, 6685.565438246547, 6700.128983232197, 6726.260111387744, 6170.568060235785, 6220.471259942956, 6232.0963460298, 6233.438990490878, 6235.064740288947, 6536.215654121535, 6561.179852518435, 6705.497087718343, 6716.429103526158, 6743.2919543448215, 6448.290688024314, 6486.416222539994, 6508.219013718384, 6512.934870651327, 6524.314946930599, 6636.170478690164, 6668.033135195369, 6671.566348187353, 6686.933130668728, 6703.296153903867, 6215.720292586844, 6258.593191442542, 6268.404501572343, 6518.217813862367, 6538.215414150237, 6370.617762934146, 6426.678666066678, 6472.741090356333, 6501.804945851632, 6535.3464653534265, 5158.664686795346, 5163.4062082860955, 5188.299579556711, 5200.531952124327, 5263.789448422097, 4663.997240593289, 4667.32323813052, 4676.596084061083, 4684.367610372624, 4694.131585656664, 6117.908231376533, 6197.96901015492, 6205.720742566596, 6208.379162083846, 6215.533834962396, 6452.612843229421, 6510.0, 6516.674166291667, 6584.967075164594, 6703.363180497845, 6211.0, 6233.625982441068, 6235.781747638902, 6240.188775459162, 6252.843678907972, 5904.350521442638, 5909.468376586948, 5912.615679980788, 5922.407759224129, 5934.7922822701, 5744.913826292693, 6545.14913061299, 6604.867902641824, 6775.62734049632, 6844.623545705034, 6647.169103862017, 6677.599344039373, 6688.632125233159, 6692.665366731547, 6698.83252918681, 6721.22705888829, 6880.105586273752, 6886.586804791728, 6906.758263460755, 6917.204521479893, 5439.048166570755, 6082.087686846831, 6225.28409232944, 6555.770548030987, 6606.8348291293105, 6322.810315690536, 6384.808455746335, 6479.092926990319, 6562.475001999924, 6592.17597800275, 6207.348228435949, 6396.328385519453, 6396.872062558629, 6428.4857211423905, 6442.420182183626, 5582.692951887685, 6333.493320534254, 6371.522135839923, 6406.711697973696, 6411.198600174978, 6085.2393450818645, 6146.508279337731, 6239.844003899939, 6242.53181011435, 6276.246850377984, 6407.455366293917, 6407.647579383179, 6429.067785171031, 6446.258680251831, 6495.415412612699, 6151.477124444294, 6170.660613666291, 6189.876202476015, 6204.968975155095, 6259.968700156653], "value": 6027.111346534156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.3386355461412, 827.441477003023, 852.1648784191494, 986.955586998585, 990.6037584966017, 40473.79763101184, 41190.0, 42455.0, 42585.78707106464, 42611.14777704444, 38188.80905595475, 38372.61627383725, 38416.0, 38418.23163536727, 38440.61559384405, 8337.24964753172, 8436.28291595212, 11292.774144517105, 11543.595974140906, 11766.529338826436, 11449.084073274118, 11506.942465287662, 11511.194216404854, 11522.02062824661, 11560.0, 11481.827772583418, 11506.482208300604, 11519.0, 11522.769544609102, 11527.538898444052, 11427.942860285728, 11469.197156198987, 11469.713257168554, 11478.081753459745, 11531.884681153166, 11072.335659860435, 11084.113270938306, 11110.0, 11138.443077846156, 11169.776604467947, 35523.64476355255, 35689.572417103285, 35736.46395304072, 35827.82086089579, 35839.745608903824, 3947.0, 3965.960340396588, 3989.501312335958, 4005.0, 4047.150098479323, 26625.467490650273, 26627.73372266272, 26643.600345994826, 28378.0, 28549.28626784326, 3218.5011323244935, 3270.444024515838, 3310.3875782980226, 3311.93376132476, 3406.591209054929, 350620.0, 354907.0, 363175.0, 366754.1662291698, 381728.0913595442, 44816.0, 45006.54993450024, 45132.54867451349, 45185.0, 45186.54813451889, 46754.298685519745, 46811.82970425767, 46970.82572935704, 47033.76483117563, 47059.529404705514, 13079.215247085205, 13909.374078166507, 14469.710605787825, 14630.341634626468, 14664.926675366769, 12718.0, 12723.363831808372, 12727.93636031814, 12736.61790146297, 12739.980801535856, 10184.777826660847, 12138.817917731241, 12161.148719589544, 12211.755764884652, 12295.385230738428, 4549.658775591844, 4806.2310030394965, 4863.440704318978, 5701.173329867229, 5703.686297253608, 1102.8897110289067, 1145.7823013627385, 1153.3541216918552, 1181.3266438130183, 1213.6116442738416, 27349.86325068402, 27774.583381249297, 27776.0, 27795.85349204543, 27916.581251281248, 1079.9784004320027, 1296.2093123194843, 1298.4351806964, 1350.4868150103096, 1355.213975893979, 14847.0, 15372.846271537364, 16678.416255431002, 16862.566681831937, 17023.06373149464, 496057.0, 496854.0, 497734.0, 498517.0, 501003.0, 59761.0, 60111.39888601058, 60404.39595604075, 60561.394386055574, 60812.39187608156, 1477.3573495529265, 1519.5973067136997, 1520.6694142625204, 1527.4348491058379, 1528.0907859823387, 25906.70466476631, 28600.856995714887, 29128.12615621535, 29899.0, 29970.400591988473, 49450.0, 49612.51162465132, 49632.0, 49671.0, 49812.00375992387, 476327.618361906, 477210.0, 478472.82290766365, 479204.0, 480006.0, 35849.74525891685, 36871.26257474889, 36991.630083698816, 37011.259774804894, 37053.62946370609, 45229.773851130536, 45349.773251133534, 45641.543584563726, 46872.531274686815, 47092.529074708815, 28567.857160714062, 31599.684003159673, 31970.68029319677, 32587.18532036718, 32677.18307042343, 31650.366992660478, 31741.8412907934, 32287.35425291528, 32465.513017304285, 32572.348553029282, 30452.695473044987, 31660.683393165775, 37218.23974512168, 37420.0, 38177.236455270184, 31797.20506987344, 32211.838940805144, 32409.189770255933, 32701.672983269862, 33081.17297067496, 22360.881955902467, 23842.64236036426, 23950.401239968443, 25086.37284067913, 25729.742702572734], "value": 19465.810489350686, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.1387087117054, 428.293316028553, 442.7033887295511, 444.7528933375039, 448.3767563087307, 18295.634087318245, 19466.221351145938, 20545.691814622773, 21531.6770248446, 21726.565468690616, 21535.569288614217, 23253.418664533394, 23714.0, 23767.643485347733, 23830.0, 6822.863542729155, 6879.140107486564, 7166.784996450088, 7216.494845360827, 7235.710571577157, 5989.131575921489, 7109.289071092901, 7114.146302443714, 7122.074130363046, 7127.03784989025, 6713.194416669982, 7004.5096843221, 7062.717491300367, 7084.0436541067065, 7150.821229469253, 6724.3611856874, 7021.71913123477, 7077.964610176968, 7091.290870912919, 7102.502824802266, 6885.724571017154, 6887.139107611549, 6892.758753443655, 6897.965510172417, 6903.9989201565995, 20614.896925515426, 22961.885190574107, 23058.0, 23385.0, 27240.774165162617, 2613.8562379069144, 3033.8179709217297, 3050.679678633756, 3111.5021596544443, 3120.7503399728193, 15441.459548915735, 16223.026618402933, 17226.310947562084, 17293.962362258306, 17351.392701255394, 2115.3336698939784, 2131.5523740014614, 2131.989340053305, 2159.4601349662585, 2180.716506854115, 130534.0, 136034.0, 145443.0, 170389.14805425893, 189817.10182897994, 18215.72676409855, 18621.44135675932, 19990.649743365157, 20062.699059514118, 21552.568948621254, 28810.991615293366, 31241.843790780902, 32400.67599324024, 32877.671223287936, 32976.835115824266, 10346.430946297874, 10768.138548916068, 10915.781684366268, 10943.28868623537, 11101.056410205061, 7778.455508114378, 8618.0, 9054.366194366332, 9142.222911052502, 9215.63137474507, 7874.842503150019, 7967.960160199162, 9621.18219951298, 9680.370775899684, 9780.532920061905, 3994.7868032218234, 4043.838246470108, 4253.745145182179, 4338.240807858612, 4362.432883725159, 1029.5881647340973, 1031.664708969588, 1041.9664387571754, 1074.3714926767773, 1084.2514226436103, 17358.91320543389, 21245.78754212438, 21281.382878552333, 21283.89358053231, 21513.569728605653, 1052.5947510208625, 1056.1286938275953, 1058.1059005140644, 1086.098538213281, 1094.2176343914139, 10604.098651614699, 11065.280756750946, 11074.778504429867, 11131.998120169063, 11212.831807522894, 636093.0, 725281.1207831887, 761528.0, 787452.0, 789730.0, 109863.0, 117634.0, 131684.0, 147144.0, 156153.0, 1257.8239046533492, 1275.354792317918, 1292.5734507612465, 1296.7989961555975, 1300.5903140510898, 15502.844971550592, 17888.0, 18553.072346382898, 18737.531561711065, 18787.624247515247, 46708.832279193295, 49588.26441074545, 50410.49589504205, 61535.6923215381, 62345.37654623396, 61626.69186654038, 85396.0, 85968.57015715132, 86763.0, 87795.12204878125, 10857.13142948578, 11337.829932551183, 11427.60003399877, 11799.174057816213, 11799.469023894117, 12702.983761298889, 12711.55509557161, 12733.172343797623, 13012.763787440179, 13108.803367949298, 10489.580416783243, 10765.677029689123, 11254.21220514556, 11384.203105782515, 11446.198766086294, 9446.480443575669, 9531.189848862925, 9678.0, 9709.854352184864, 9821.901780982385, 9456.196223321094, 11282.153838461983, 11537.653870383901, 11674.824877626672, 11692.94153529227, 11425.771484570096, 11435.485403156712, 11542.653720388402, 11724.824127637921, 11776.352300623548, 9374.484403357881, 11280.21038527328, 11336.0, 11440.256383335056, 11444.02725768319], "value": 11523.073735627304, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1582.5727053695502, 1612.9516114516568, 1653.1816750708401, 1656.6024154202992, 1674.179651970534, 9835.557399917003, 10108.74728131797, 11189.49647265873, 11678.883211167886, 11837.7632447351, 10060.0, 11010.119190464764, 11354.943225283883, 11395.259308144965, 11523.942380288086, 2456.361346050022, 2491.165459571046, 2491.816387216076, 2494.3639371960126, 2500.712418071918, 2398.268528098929, 2419.443527988564, 2441.1455990403306, 2441.951160976779, 2446.804255659552, 2449.5713250181234, 2456.299954512964, 2468.358226861011, 2472.5178590174924, 2485.1798906360946, 2072.336852207294, 2417.3231495181376, 2428.3929017745563, 2429.817763667732, 2435.0990133650484, 2417.721961974378, 2424.660547523348, 2424.7454017328278, 2441.3286346254745, 2467.062516243816, 11303.886961130365, 11435.0, 11444.313341199555, 11454.885451145465, 11506.137039722054, 5882.147088672162, 6050.9092363614145, 6056.091586262052, 6057.3336932937345, 6065.848353791146, 3590.0845284452557, 3653.5433070866143, 3661.286049220403, 3810.161764411825, 3879.650831425185, 1239.3493415956605, 1241.8633950265464, 1250.2623452163177, 1287.8003909394058, 1290.4580076367947, 15818.0, 15835.920820395824, 15844.603884902854, 15868.603284917854, 15937.840621593752, 5020.397552293675, 5150.896982060338, 5275.4724527547705, 5278.102722537178, 5301.628885977944, 12038.518459261706, 12157.270563766202, 12163.2702037878, 12181.269123852597, 12221.572244971383, 6002.279726432856, 6055.6063855849225, 6099.695015249308, 6101.633901965896, 6114.57197996136, 4818.469968303484, 5040.76501257187, 5506.64206826555, 5526.640768350045, 5529.253550770594, 3985.580304229975, 4056.371262454324, 4084.7957602119777, 4127.834886604563, 4134.255833949918, 957.3155194036298, 1108.9889101109047, 1110.4281295133012, 1112.193659596786, 1126.1385040444068, 867.9522626255616, 981.3326937682302, 1000.9199264058858, 1004.6634377483479, 1044.8380501022355, 6567.835804104936, 6595.142631457976, 6639.701213445406, 6825.419839313714, 6827.146606674166, 986.4032260482448, 1030.845373194012, 1040.6045702633103, 1041.734357738787, 1091.1489038549944, 3456.7753096048677, 3498.4927185557954, 3820.9235815283537, 4280.464941882265, 4435.090806384684, 11895.286282823059, 13934.512292069729, 14110.0, 14129.858701412853, 14187.64530886736, 13853.16880987144, 14049.086809357359, 14188.716225675429, 14211.0, 14310.28448577567, 9361.672341468016, 10457.790844183073, 10476.161907047417, 10575.894241057646, 10581.682549523526, 6038.75844966214, 6547.476201903932, 6581.177352830896, 6639.734410623521, 6791.185057793096, 12292.446839892405, 12648.873511265137, 12698.746025079261, 12704.618861434172, 12744.61766147017, 12687.429065691882, 12872.742545149233, 12909.35453227316, 12957.481700732242, 13089.738205236032, 8518.190771876662, 8574.742757717278, 8651.13488651155, 8661.52361620117, 8665.523396213268, 8905.821883562423, 10012.799744004933, 10394.58421663125, 10434.269601127848, 10535.63125290611, 8932.598033088656, 9041.23149532297, 9061.0, 9183.127602877716, 9205.953970230106, 8718.738437846874, 8817.867731984154, 8849.734507964771, 8871.822563548822, 8993.190612844613, 6991.44068474531, 8192.30365418946, 8259.545724985184, 8267.793305167419, 8319.708810191614, 5735.168400581976, 5756.94243057564, 5787.971060144672, 5855.443732845373, 5939.287285525765, 6029.125776762257, 6044.848878778066, 6153.200083989232, 6176.598521096114, 6331.746730130743], "value": 5254.43621844999, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.703710735502, 398.72805749658585, 399.8960270329717, 401.7448919935841, 407.17343792102025, 4975.651704380691, 5068.1130802109665, 5315.574754019669, 5330.0, 5417.295751552298, 4576.496585375606, 4833.371661683985, 4897.755112244391, 5137.152369858969, 5246.816361427351, 3778.1310298631333, 3779.81100944953, 4155.39746736723, 4170.416141740159, 4373.147236288917, 3954.9802250988705, 4329.956700433003, 4371.7922449163125, 4378.40891479651, 4395.318725597537, 4124.876253712393, 4198.958010419888, 4323.308270676693, 4370.344448332759, 4379.408779814716, 4284.0, 4298.376735373385, 4320.802606071516, 4333.393324934512, 4357.629601483878, 3961.861334853295, 3996.2606917720314, 4002.9777517973744, 4013.819378127991, 4019.4975628046495, 4374.190774706658, 4572.451305843287, 4780.6973723649, 4923.458419573844, 5160.0, 4252.893677658083, 4549.545045495457, 4876.366072410599, 4938.209886418192, 5068.822591209327, 5049.6212784041345, 5632.197696737046, 5641.774329026834, 5644.661320320753, 5657.943420565783, 4098.897527561805, 4262.87211383659, 4495.280755079204, 4500.437445319335, 4546.908741901952, 4769.976150119262, 5145.30538377318, 5413.593980451482, 5667.979763642543, 5722.54219662426, 4398.582134697199, 4475.395821564047, 4587.564181402761, 4606.792694328763, 4632.841789552612, 4614.838480653161, 4636.9536304637195, 4675.18184317743, 4685.484596694361, 4718.617098365304, 4335.544767799335, 4503.662225333048, 4601.631869450436, 4672.892526469927, 4990.431841233535, 4576.0, 5100.872478188001, 5518.558515318765, 5530.834074977757, 5560.527355174774, 1380.2615600653803, 1410.6825964158188, 1544.3436539470904, 1571.677806049757, 1586.341668207704, 1040.0379648824808, 1168.4391492083848, 1200.963971080869, 1211.6001719432566, 1214.4656351205442, 4790.538885639913, 5117.769700363493, 5211.166213405837, 5253.557095950325, 5338.172583249602, 5435.7825686972865, 5647.37878833328, 5763.0, 5797.391273916262, 5808.622439541501, 4484.282514797616, 4897.897972956056, 4962.950370496321, 5288.312519372457, 5379.327584051994, 2789.804713670024, 2849.6580410350475, 2863.2126165304708, 3043.5195315817537, 3064.7394971427234, 5422.53908417781, 5534.564791491731, 5679.819301191346, 5770.6249093808765, 5997.070454079625, 4425.800838962255, 5301.522862942276, 5522.806701765499, 5637.0, 6076.635401875901, 5516.393196748354, 6037.698115094316, 6056.727447264796, 6129.295131059896, 6135.9079613805825, 5670.291213598301, 5873.56247157442, 5887.1757953885635, 5920.585559010828, 5956.5532585055425, 5437.293151890229, 5753.338366087786, 5826.5724897400405, 5849.678267695147, 5866.67733274674, 5752.61266574367, 5752.884942301047, 5942.702864856654, 6067.240500255053, 6072.726727297194, 3737.5701794294005, 4417.425734654603, 4576.336431217522, 4641.953580464152, 4674.485806561208, 4942.357493525818, 5625.662460252398, 5873.911891321719, 5904.852378690567, 5931.970340148272, 5088.168259426529, 5295.8878635485435, 5486.341639003345, 5547.223388725501, 5652.491275785199, 5696.373398926032, 5698.914516282176, 5702.0, 5705.486506214461, 5792.768289268382, 5339.718467567678, 5540.529055030368, 5574.498295153294, 5596.804111856229, 5608.887822243614, 5431.09911530961, 5604.271444712162, 5621.943780562306, 5627.18405831144, 5649.903819730663, 4804.879878002938, 5371.221172929825, 5513.696746678971, 5537.2900156894175, 5580.414056524007], "value": 4310.727175176697, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.82684982454, 756.4553521464544, 759.6277823866303, 874.1171416868963, 880.5729221327657, 16360.427385041527, 16942.3223071077, 17173.7423938641, 17182.14089295535, 17196.82803171968, 15890.443834465796, 15924.920375398136, 16061.83938160618, 16067.678646427064, 16071.0, 972.333951243397, 988.906053924876, 1007.969760907174, 1027.342500799489, 1033.3489901362132, 1026.9332493387906, 1028.9073983341498, 1029.0943969307, 1029.7580068683853, 1031.0823367203195, 981.8429051351785, 1074.7796701676139, 1090.8091084060297, 1091.476091476092, 1093.5297821936565, 1076.8330908709163, 1077.439731339699, 1078.2667785905621, 1082.832161015044, 1083.2958576924982, 788.562347896917, 795.9204079592052, 805.7099444200142, 807.4186585658316, 809.0291650019999, 16437.93153445022, 17022.404215852384, 17416.825831741648, 17637.294508219657, 17863.642727145514, 14642.0, 14714.484993025193, 14803.333849976776, 14870.70258594833, 14991.32539035746, 11508.482118304697, 11846.466908989116, 11899.167058305922, 12005.339706316148, 12347.876521234763, 3801.2967600993907, 3842.7886466244345, 3857.614238576148, 3948.9012774680864, 4034.536028356747, 122220.0, 125022.37488812498, 126203.36898315449, 127243.36378318141, 127570.0, 10468.581256749798, 11139.944300278446, 11307.547698092148, 11356.48895799691, 11793.0, 23421.53156936852, 23914.282571522883, 24265.636015459782, 24328.0, 24343.269701908972, 7877.645505952246, 8118.570183086312, 8149.470284431493, 8167.305779008732, 8403.537805420761, 4160.791960401968, 4553.901956583528, 4681.485036645966, 4731.455882573479, 4789.137955168103, 3671.7980511071614, 3718.2377612589353, 3728.382121733944, 3772.264408440383, 3804.31522325984, 663.9800805975829, 671.1342368344781, 678.1489231015045, 686.087503620184, 687.8658661560947, 705.2312978853075, 725.9056322678018, 726.7565365602583, 743.9665215065335, 777.5684495105271, 10489.028927564714, 10700.518476668569, 11134.552508174047, 11151.163662725328, 11153.219274650697, 620.4230066038627, 712.4407340237992, 736.9447291453163, 739.3696898339149, 742.6138408027904, 4938.43208031081, 5237.711925844039, 5253.816116435906, 5278.630495865253, 5315.973420132952, 100644.0, 101550.0, 102516.48741756393, 104313.0, 105165.47417262864, 62724.372756271856, 74138.0, 78184.0, 79783.0, 80116.59941700254, 78605.60697196478, 79314.81027784589, 79318.20681793224, 80587.7911831323, 81612.77580836292, 9549.665761698307, 10179.7455063624, 10205.84691229647, 10281.588736450758, 10292.845607315747, 51919.74040129775, 52183.43449696515, 52276.47723522716, 54168.45831541792, 54695.72652136714, 66817.66591167013, 67013.0, 67127.32872671405, 67220.6638966802, 67552.0, 11599.594014209462, 11840.171188016757, 11843.170978031456, 12040.0, 12297.508099675913, 13457.596272111852, 13548.661283467993, 13654.795178072138, 14286.571402857931, 14431.711365772415, 12431.440585173508, 12566.246025238515, 12763.80854287205, 12907.290099044267, 13344.466221351036, 12451.626451206479, 12468.314242716737, 12536.74926501483, 12616.873831261937, 13056.673583160496, 11974.846266180713, 12360.0, 12448.128630995743, 12639.684007899876, 13052.54316098932, 9220.631174752934, 9766.974467681075, 9768.951155244178, 9774.360922551014, 9967.202623790225, 10558.736031599272, 10660.388204889852, 10742.516586753458, 10755.193360497837, 10808.783824323627], "value": 7874.061421198586, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1477.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 120.0, 120.0, 119.0, 120.0, 119.0, 119.0, 120.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 136.0, 134.0, 143.0, 110.0, 112.0, 113.0, 139.0, 154.0, 110.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 194.0, 193.0, 195.0, 197.0, 198.0, 200.0, 200.0, 195.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 82.0, 59.0, 63.0, 77.0, 64.0, 72.0, 60.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 83.0, 72.0, 70.0, 68.0, 81.0, 68.0, 69.0, 81.0], "value": 74.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 88.0, 89.0, 87.0, 88.0, 90.0, 89.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 146.0, 140.0, 97.0, 95.0, 97.0, 96.0, 100.0, 96.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 89.0, 91.0, 97.0, 90.0, 91.0, 122.0, 140.0, 131.0], "value": 103.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 63.0, 65.0, 69.0, 68.0, 66.0, 66.0, 74.0, 67.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 57.0, 59.0, 65.0, 62.0, 59.0, 56.0, 62.0, 65.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 85.0, 83.0, 86.0, 92.0, 86.0, 74.0, 96.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 138.0, 135.0, 137.0, 132.0, 136.0, 153.0, 190.0, 148.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 168.0, 164.0, 162.0, 164.0, 163.0, 162.0, 159.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 63.0, 65.0, 63.0, 65.0, 66.0, 64.0, 63.0, 63.0], "value": 64.2, "unit": "score"}]}]} 09:14:43 INFO - Return code: 0 09:14:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:43 ERROR - # TBPL SUCCESS # 09:14:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:43 INFO - 2017-05-04 09:14:43.613 screenresolution[1661:11895] starting screenresolution argv=screenresolution get 09:14:43 INFO - 2017-05-04 09:14:43.616 screenresolution[1661:11895] Display 0: 1600x1200x32@60 09:14:43 INFO - 2017-05-04 09:14:43.622 screenresolution[1662:11898] starting screenresolution argv=screenresolution list 09:14:43 INFO - Available Modes on Display 0 09:14:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:43 INFO - Intel Iris: 09:14:43 INFO - Chipset Model: Intel Iris 09:14:43 INFO - Type: GPU 09:14:43 INFO - Bus: Built-In 09:14:43 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:43 INFO - Vendor: Intel (0x8086) 09:14:43 INFO - Device ID: 0x0a2e 09:14:43 INFO - Revision ID: 0x0009 09:14:43 INFO - Displays: 09:14:43 INFO - Display: 09:14:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:43 INFO - Display Serial Number: 1600x1200 60 09:14:43 INFO - Main Display: Yes 09:14:43 INFO - Mirror: Off 09:14:43 INFO - Online: Yes 09:14:43 INFO - Rotation: Supported 09:14:43 INFO - Return code: 0 09:14:43 INFO - Running post-action listener: _resource_record_post_action 09:14:43 INFO - [mozharness: 2017-05-04 16:14:43.857571Z] Finished run-tests step (success) 09:14:43 INFO - Running post-run listener: _resource_record_post_run 09:14:43 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 34680832; Write bytes: 431060992; Read time: 213; Write time: 1446 09:14:43 INFO - TinderboxPrint: CPU usage
54.6% 09:14:43 INFO - TinderboxPrint: I/O read bytes / time
34,680,832 / 213 09:14:43 INFO - TinderboxPrint: I/O write bytes / time
431,060,992 / 1,446 09:14:43 INFO - TinderboxPrint: CPU idle
2,165.2 (45.4%) 09:14:43 INFO - TinderboxPrint: CPU system
958.3 (20.1%) 09:14:43 INFO - TinderboxPrint: CPU user
1,642.2 (34.5%) 09:14:43 INFO - TinderboxPrint: Swap in / out
153,305,088 / 0 09:14:43 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123101696; Write bytes: 127744000; Read time: 11143; Write time: 296 09:14:43 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 32485376; Write bytes: 302100480; Read time: 191; Write time: 1145 09:14:44 INFO - Running post-run listener: _upload_blobber_files 09:14:44 INFO - Blob upload gear active. 09:14:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:45 INFO - (blobuploader) - INFO - Done attempting. 09:14:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:46 INFO - (blobuploader) - INFO - Done attempting. 09:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:14:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:47 INFO - (blobuploader) - INFO - Done attempting. 09:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:50 INFO - (blobuploader) - INFO - Done attempting. 09:14:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:50 INFO - Return code: 0 09:14:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311f89bd2f945f9fe3400a985440308d70672896c9163872a5bbaeeea4547dfe57fcd6c20afe9969d93d3a709be42ca732e22dfa3fa7235732cd5c453844da3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/502f23b8a72b204bd88aa0febafdd7ddbe734b0ccd6f8d7962560926b12c69a6b4e6c34264cfeb5bd0df1834befdfc23d70571c36e5823c27a84cbe250238dd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db4e97b667009d28e96c70ebbeb506de5a072de88987152ee243d8623d51308a1328fd64eeff464ba39479afc21b944d8eeab74d247af46fa3aa6a44a0ee47a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542ed8d19041572b00fc4f1b392149f12bc5f39e0258a22946f797a33aaecbdfb44be0ee96e059c3443c342136f332a58471558eeeb1ec48d5bfc96c14dc3638"} 09:14:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:50 INFO - Contents: 09:14:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311f89bd2f945f9fe3400a985440308d70672896c9163872a5bbaeeea4547dfe57fcd6c20afe9969d93d3a709be42ca732e22dfa3fa7235732cd5c453844da3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/502f23b8a72b204bd88aa0febafdd7ddbe734b0ccd6f8d7962560926b12c69a6b4e6c34264cfeb5bd0df1834befdfc23d70571c36e5823c27a84cbe250238dd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db4e97b667009d28e96c70ebbeb506de5a072de88987152ee243d8623d51308a1328fd64eeff464ba39479afc21b944d8eeab74d247af46fa3aa6a44a0ee47a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542ed8d19041572b00fc4f1b392149f12bc5f39e0258a22946f797a33aaecbdfb44be0ee96e059c3443c342136f332a58471558eeeb1ec48d5bfc96c14dc3638"} 09:14:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:50 INFO - Copying logs to upload dir... 09:14:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.202527 ========= master_lag: 1.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-04 09:14:51.394117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:14:51.397472) ========= 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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311f89bd2f945f9fe3400a985440308d70672896c9163872a5bbaeeea4547dfe57fcd6c20afe9969d93d3a709be42ca732e22dfa3fa7235732cd5c453844da3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/502f23b8a72b204bd88aa0febafdd7ddbe734b0ccd6f8d7962560926b12c69a6b4e6c34264cfeb5bd0df1834befdfc23d70571c36e5823c27a84cbe250238dd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db4e97b667009d28e96c70ebbeb506de5a072de88987152ee243d8623d51308a1328fd64eeff464ba39479afc21b944d8eeab74d247af46fa3aa6a44a0ee47a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542ed8d19041572b00fc4f1b392149f12bc5f39e0258a22946f797a33aaecbdfb44be0ee96e059c3443c342136f332a58471558eeeb1ec48d5bfc96c14dc3638"} build_url:https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/INXekcxKQAyq1gbOIJQw0g/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311f89bd2f945f9fe3400a985440308d70672896c9163872a5bbaeeea4547dfe57fcd6c20afe9969d93d3a709be42ca732e22dfa3fa7235732cd5c453844da3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/502f23b8a72b204bd88aa0febafdd7ddbe734b0ccd6f8d7962560926b12c69a6b4e6c34264cfeb5bd0df1834befdfc23d70571c36e5823c27a84cbe250238dd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db4e97b667009d28e96c70ebbeb506de5a072de88987152ee243d8623d51308a1328fd64eeff464ba39479afc21b944d8eeab74d247af46fa3aa6a44a0ee47a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542ed8d19041572b00fc4f1b392149f12bc5f39e0258a22946f797a33aaecbdfb44be0ee96e059c3443c342136f332a58471558eeeb1ec48d5bfc96c14dc3638"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 09:14:51.438252) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:14:51.438575) ========= 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.bDuYYUHXtu/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.hKi8vvSQTM/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 09:14:51.487054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:14:51.487365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 09:14:51.487736) ========= ========= Total master_lag: 1.30 =========