builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0071 starttime: 1479821517.25 results: success (0) buildid: 20161122030216 builduid: 7004ea875ed3469d84fb2a5cc65733d2 revision: 0534254e9a40b4bade2577c631fe4cfa0b5db41d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.255070) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.255490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.255764) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.282633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.283034) ========= 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.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 --2016-11-22 05:31:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-11-22 05:31:57 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.443981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.444291) ========= 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.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.026561 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:57.492479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 05:31:57.492800) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0534254e9a40b4bade2577c631fe4cfa0b5db41d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0534254e9a40b4bade2577c631fe4cfa0b5db41d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 2016-11-22 05:31:57,586 truncating revision to first 12 chars 2016-11-22 05:31:57,586 Setting DEBUG logging. 2016-11-22 05:31:57,586 attempt 1/10 2016-11-22 05:31:57,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0534254e9a40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 05:31:58,635 unpacking tar archive at: mozilla-central-0534254e9a40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.324045 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 05:31:59.201363) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:59.201704) ========= script_repo_revision: 0534254e9a40b4bade2577c631fe4cfa0b5db41d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:59.202126) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 05:31:59.202397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 05:32:00.055594) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 05:32:00.056094) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:00 INFO - MultiFileLogger online at 20161122 05:32:00 in /builds/slave/test 05:32:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:32:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:00 INFO - {'append_to_log': False, 05:32:00 INFO - 'base_work_dir': '/builds/slave/test', 05:32:00 INFO - 'blob_upload_branch': 'Firefox', 05:32:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:00 INFO - 'branch': 'Firefox', 05:32:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:00 INFO - 'config_files': ('talos/mac_config.py',), 05:32:00 INFO - 'default_actions': ('clobber', 05:32:00 INFO - 'read-buildbot-config', 05:32:00 INFO - 'download-and-extract', 05:32:00 INFO - 'populate-webroot', 05:32:00 INFO - 'create-virtualenv', 05:32:00 INFO - 'install', 05:32:00 INFO - 'run-tests'), 05:32:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:00 INFO - 'download_minidump_stackwalk': True, 05:32:00 INFO - 'download_symbols': 'ondemand', 05:32:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:32:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:32:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:32:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:32:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:00 INFO - 'installer_path': 'installer.exe', 05:32:00 INFO - 'log_level': 'info', 05:32:00 INFO - 'log_name': 'talos', 05:32:00 INFO - 'log_to_console': True, 05:32:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:32:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:32:00 INFO - 'opt_config_files': (), 05:32:00 INFO - 'pip_index': False, 05:32:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:00 INFO - 'cmd': ('bash', 05:32:00 INFO - '-c', 05:32:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:00 INFO - 'enabled': True, 05:32:00 INFO - 'halt_on_failure': False, 05:32:00 INFO - 'name': 'check_screen_resolution'},), 05:32:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:00 INFO - 'cmd': ('bash', 05:32:00 INFO - '-c', 05:32:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:00 INFO - 'enabled': True, 05:32:00 INFO - 'halt_on_failure': False, 05:32:00 INFO - 'name': 'check_screen_resolution'},), 05:32:00 INFO - 'run_cmd_checks_enabled': True, 05:32:00 INFO - 'sps_profile': False, 05:32:00 INFO - 'sps_profile_interval': 0, 05:32:00 INFO - 'suite': 'dromaeojs-e10s', 05:32:00 INFO - 'system_bits': '32', 05:32:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:00 INFO - 'title': 't-yosemite-r7-0071', 05:32:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:00 INFO - 'use_talos_json': True, 05:32:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:00 INFO - 'work_dir': 'build'} 05:32:00 INFO - [mozharness: 2016-11-22 13:32:00.261402Z] Running clobber step. 05:32:00 INFO - Running pre-action listener: _resource_record_pre_action 05:32:00 INFO - Running main action method: clobber 05:32:00 INFO - rmtree: /builds/slave/test/build 05:32:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:02 INFO - Running post-action listener: _resource_record_post_action 05:32:02 INFO - [mozharness: 2016-11-22 13:32:02.669686Z] Finished clobber step (success) 05:32:02 INFO - [mozharness: 2016-11-22 13:32:02.669838Z] Running read-buildbot-config step. 05:32:02 INFO - Running pre-action listener: _resource_record_pre_action 05:32:02 INFO - Running main action method: read_buildbot_config 05:32:02 INFO - Using buildbot properties: 05:32:02 INFO - { 05:32:02 INFO - "project": "", 05:32:02 INFO - "product": "firefox", 05:32:02 INFO - "script_repo_revision": "production", 05:32:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:32:02 INFO - "repository": "", 05:32:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:32:02 INFO - "buildid": "20161122030216", 05:32:02 INFO - "stage_platform": "macosx64", 05:32:02 INFO - "basedir": "/builds/slave/test", 05:32:02 INFO - "nightly_build": "True", 05:32:02 INFO - "buildnumber": 69, 05:32:02 INFO - "slavename": "t-yosemite-r7-0071", 05:32:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:32:02 INFO - "platform": "yosemite_r7", 05:32:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:32:02 INFO - "branch": "mozilla-central", 05:32:02 INFO - "repo_path": "mozilla-central", 05:32:02 INFO - "revision": "0534254e9a40b4bade2577c631fe4cfa0b5db41d", 05:32:02 INFO - "pgo_build": "False", 05:32:02 INFO - "builduid": "7004ea875ed3469d84fb2a5cc65733d2", 05:32:02 INFO - "slavebuilddir": "test" 05:32:02 INFO - } 05:32:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:32:02 INFO - Running post-action listener: _resource_record_post_action 05:32:02 INFO - [mozharness: 2016-11-22 13:32:02.673226Z] Finished read-buildbot-config step (success) 05:32:02 INFO - [mozharness: 2016-11-22 13:32:02.673332Z] Running download-and-extract step. 05:32:02 INFO - Running pre-action listener: _resource_record_pre_action 05:32:02 INFO - Running main action method: download_and_extract 05:32:02 INFO - mkdir: /builds/slave/test/build/tests 05:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:02 INFO - trying https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:32:02 INFO - Downloading https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:32:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:32:03 INFO - Downloaded 1199 bytes. 05:32:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:32:03 INFO - Using the following test package requirements: 05:32:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:32:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:32:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:32:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'jsshell-mac.zip'], 05:32:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:32:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:32:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:32:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:32:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:32:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:32:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:32:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:32:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:32:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:32:03 INFO - Fetch https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:32:04 INFO - Http code: 200 05:32:04 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 05:32:04 INFO - x-amz-cf-id: 3gXCPYgmQps4mVdjRNo7adHMsd6cyDkpP6HbAUueHHkmqYkowpWtrA== 05:32:04 INFO - x-amz-version-id: NUallmRGdQASbFeuJliG.FUUfNoHjYX1 05:32:04 INFO - Expected file size: 16282237 05:32:04 INFO - Obtained file size: 16282237 05:32:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:32:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:32:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:32:11 INFO - Fetch https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:32:11 INFO - Http code: 200 05:32:11 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 05:32:11 INFO - x-amz-cf-id: T-0lGXEYj6A6d8BQHeiCeT6OXd1WfaJ-yBTbCrdxdnXG4nulcV2tWA== 05:32:11 INFO - x-amz-version-id: NUallmRGdQASbFeuJliG.FUUfNoHjYX1 05:32:11 INFO - Expected file size: 16282237 05:32:11 INFO - Obtained file size: 16282237 05:32:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:32:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:32:18 INFO - Fetch https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:32:19 INFO - Http code: 200 05:32:19 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 05:32:19 INFO - x-amz-cf-id: Z-iEbe7z2zm9oIemD2O_GVyviqPFJnygsF0waV0NeAU47J-NtdaFXw== 05:32:19 INFO - x-amz-version-id: cZXsRVUsEB19vmQr1CeA2xJDe1tVKgqD 05:32:19 INFO - Expected file size: 14409392 05:32:19 INFO - Obtained file size: 14409392 05:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:19 INFO - trying https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:32:19 INFO - Downloading https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:32:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:32:22 INFO - Downloaded 107601983 bytes. 05:32:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:32:22 INFO - mkdir: /builds/slave/test/properties 05:32:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:22 INFO - Contents: 05:32:22 INFO - build_url:https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:32:23 INFO - Running post-action listener: _resource_record_post_action 05:32:23 INFO - Running post-action listener: set_extra_try_arguments 05:32:23 INFO - [mozharness: 2016-11-22 13:32:23.121585Z] Finished download-and-extract step (success) 05:32:23 INFO - [mozharness: 2016-11-22 13:32:23.121718Z] Running populate-webroot step. 05:32:23 INFO - Running pre-action listener: _resource_record_pre_action 05:32:23 INFO - Running main action method: populate_webroot 05:32:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:23 INFO - '--tppagecycles', 05:32:23 INFO - '7'], 05:32:23 INFO - 'tests': ['tsvgm']}}, 05:32:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:32:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['damp', 'tps']}, 05:32:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'tests': ['damp', 'tps']}, 05:32:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['dromaeo_dom']}, 05:32:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['basic_compositor_video']}, 05:32:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:32:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['a11yr', 05:32:23 INFO - 'ts_paint', 05:32:23 INFO - 'tpaint', 05:32:23 INFO - 'sessionrestore', 05:32:23 INFO - 'sessionrestore_no_auto_restore', 05:32:23 INFO - 'tabpaint']}, 05:32:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['tsvgx', 05:32:23 INFO - 'tsvgr_opacity', 05:32:23 INFO - 'tart', 05:32:23 INFO - 'tscrollx', 05:32:23 INFO - 'cart']}, 05:32:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:23 INFO - 'tsvgr_opacity', 05:32:23 INFO - 'tart', 05:32:23 INFO - 'tscrollx', 05:32:23 INFO - 'cart']}, 05:32:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'talos_options': ['--disable-e10s'], 05:32:23 INFO - 'tests': ['tp5o']}, 05:32:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:32:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'talos_options': ['--disable-e10s', 05:32:23 INFO - '--xperf_path', 05:32:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:23 INFO - 'tests': ['tp5n']}, 05:32:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:32:23 INFO - 'talos_options': ['--xperf_path', 05:32:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:23 INFO - 'tests': ['tp5n']}}, 05:32:23 INFO - 'talos.zip': {'path': '', 05:32:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:23 INFO - Running post-action listener: _resource_record_post_action 05:32:23 INFO - [mozharness: 2016-11-22 13:32:23.139500Z] Finished populate-webroot step (success) 05:32:23 INFO - [mozharness: 2016-11-22 13:32:23.139610Z] Running create-virtualenv step. 05:32:23 INFO - Running pre-action listener: _resource_record_pre_action 05:32:23 INFO - Running main action method: create_virtualenv 05:32:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:23 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 05:32:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:32:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:23 INFO - Using real prefix '/tools/python27' 05:32:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:26 INFO - Installing pip.................done. 05:32:26 INFO - Return code: 0 05:32:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:27 INFO - Reading from file tmpfile_stdout 05:32:27 INFO - Output received: 05:32:27 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) 05:32:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:32:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:27 INFO - 'HOME': '/Users/cltbld', 05:32:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:27 INFO - 'LOGNAME': 'cltbld', 05:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:27 INFO - 'MOZ_NO_REMOTE': '1', 05:32:27 INFO - 'NO_EM_RESTART': '1', 05:32:27 INFO - 'PAGER': '/bin/cat', 05:32:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:27 INFO - 'PWD': '/builds/slave/test', 05:32:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:27 INFO - 'SHELL': '/bin/bash', 05:32:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:27 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:27 INFO - 'USER': 'cltbld', 05:32:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:27 INFO - 'XPC_FLAGS': '0x0', 05:32:27 INFO - 'XPC_SERVICE_NAME': '0', 05:32:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:27 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 05:32:27 INFO - Cleaning up... 05:32:27 INFO - Return code: 0 05:32:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:32:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:27 INFO - 'HOME': '/Users/cltbld', 05:32:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:27 INFO - 'LOGNAME': 'cltbld', 05:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:27 INFO - 'MOZ_NO_REMOTE': '1', 05:32:27 INFO - 'NO_EM_RESTART': '1', 05:32:27 INFO - 'PAGER': '/bin/cat', 05:32:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:27 INFO - 'PWD': '/builds/slave/test', 05:32:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:27 INFO - 'SHELL': '/bin/bash', 05:32:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:27 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:27 INFO - 'USER': 'cltbld', 05:32:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:27 INFO - 'XPC_FLAGS': '0x0', 05:32:27 INFO - 'XPC_SERVICE_NAME': '0', 05:32:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:27 INFO - Downloading/unpacking psutil>=3.1.1 05:32:27 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 05:32:27 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 05:32:27 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 05:32:27 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 05:32:27 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 05:32:27 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 05:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:31 INFO - Installing collected packages: psutil 05:32:31 INFO - Running setup.py install for psutil 05:32:31 INFO - building 'psutil._psutil_osx' extension 05:32:31 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 05:32:31 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 05:32:31 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 05:32:32 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 05:32:32 INFO - building 'psutil._psutil_posix' extension 05:32:32 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 05:32:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:32:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:32:32 INFO - ^ 05:32:32 INFO - 1 warning generated. 05:32:32 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 05:32:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:32 INFO - Successfully installed psutil 05:32:32 INFO - Cleaning up... 05:32:32 INFO - Return code: 0 05:32:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:32 INFO - 'HOME': '/Users/cltbld', 05:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:32 INFO - 'LOGNAME': 'cltbld', 05:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:32 INFO - 'MOZ_NO_REMOTE': '1', 05:32:32 INFO - 'NO_EM_RESTART': '1', 05:32:32 INFO - 'PAGER': '/bin/cat', 05:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:32 INFO - 'PWD': '/builds/slave/test', 05:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:32 INFO - 'SHELL': '/bin/bash', 05:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:32 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:32 INFO - 'USER': 'cltbld', 05:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:32 INFO - 'XPC_FLAGS': '0x0', 05:32:32 INFO - 'XPC_SERVICE_NAME': '0', 05:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:32 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 05:32:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:35 INFO - Installing collected packages: mozsystemmonitor 05:32:35 INFO - Running setup.py install for mozsystemmonitor 05:32:35 INFO - Successfully installed mozsystemmonitor 05:32:35 INFO - Cleaning up... 05:32:35 INFO - Return code: 0 05:32:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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', '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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:32: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 jsonschema==2.5.1 05:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:35 INFO - 'HOME': '/Users/cltbld', 05:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:35 INFO - 'LOGNAME': 'cltbld', 05:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:35 INFO - 'MOZ_NO_REMOTE': '1', 05:32:35 INFO - 'NO_EM_RESTART': '1', 05:32:35 INFO - 'PAGER': '/bin/cat', 05:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:35 INFO - 'PWD': '/builds/slave/test', 05:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:35 INFO - 'SHELL': '/bin/bash', 05:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:35 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:35 INFO - 'USER': 'cltbld', 05:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:35 INFO - 'XPC_FLAGS': '0x0', 05:32:35 INFO - 'XPC_SERVICE_NAME': '0', 05:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:38 INFO - warning: no files found matching '*.txt' 05:32:38 INFO - no previously-included directories found matching 'build' 05:32:38 INFO - no previously-included directories found matching 'dist' 05:32:38 INFO - no previously-included directories found matching '.git*' 05:32:38 INFO - Installing collected packages: jsonschema, functools32 05:32:38 INFO - Running setup.py install for functools32 05:32:38 INFO - warning: no files found matching '*.txt' 05:32:38 INFO - no previously-included directories found matching 'build' 05:32:38 INFO - no previously-included directories found matching 'dist' 05:32:38 INFO - no previously-included directories found matching '.git*' 05:32:38 INFO - Successfully installed jsonschema functools32 05:32:38 INFO - Cleaning up... 05:32:39 INFO - Return code: 0 05:32:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32: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 05:32: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 05:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:39 INFO - 'HOME': '/Users/cltbld', 05:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:39 INFO - 'LOGNAME': 'cltbld', 05:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:39 INFO - 'MOZ_NO_REMOTE': '1', 05:32:39 INFO - 'NO_EM_RESTART': '1', 05:32:39 INFO - 'PAGER': '/bin/cat', 05:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:39 INFO - 'PWD': '/builds/slave/test', 05:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:39 INFO - 'SHELL': '/bin/bash', 05:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:39 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:39 INFO - 'USER': 'cltbld', 05:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:39 INFO - 'XPC_FLAGS': '0x0', 05:32:39 INFO - 'XPC_SERVICE_NAME': '0', 05:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:32:39 INFO - Cleaning up... 05:32:39 INFO - Return code: 0 05:32:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32: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 05:32: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 05:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:39 INFO - 'HOME': '/Users/cltbld', 05:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:39 INFO - 'LOGNAME': 'cltbld', 05:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:39 INFO - 'MOZ_NO_REMOTE': '1', 05:32:39 INFO - 'NO_EM_RESTART': '1', 05:32:39 INFO - 'PAGER': '/bin/cat', 05:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:39 INFO - 'PWD': '/builds/slave/test', 05:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:39 INFO - 'SHELL': '/bin/bash', 05:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:39 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:39 INFO - 'USER': 'cltbld', 05:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:39 INFO - 'XPC_FLAGS': '0x0', 05:32:39 INFO - 'XPC_SERVICE_NAME': '0', 05:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:32:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:43 INFO - Downloading docopt-0.6.1.tar.gz 05:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:43 INFO - Running setup.py install for blobuploader 05:32:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:32:43 INFO - Running setup.py install for requests 05:32:44 INFO - Running setup.py install for docopt 05:32:44 INFO - Successfully installed blobuploader requests docopt 05:32:44 INFO - Cleaning up... 05:32:44 INFO - Return code: 0 05:32:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:44 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')]} 05:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:44 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')]} 05:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:44 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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:44 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 05:32:44 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 05:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:44 INFO - 'HOME': '/Users/cltbld', 05:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:44 INFO - 'LOGNAME': 'cltbld', 05:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:44 INFO - 'MOZ_NO_REMOTE': '1', 05:32:44 INFO - 'NO_EM_RESTART': '1', 05:32:44 INFO - 'PAGER': '/bin/cat', 05:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:44 INFO - 'PWD': '/builds/slave/test', 05:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:44 INFO - 'SHELL': '/bin/bash', 05:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:44 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:44 INFO - 'USER': 'cltbld', 05:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:44 INFO - 'XPC_FLAGS': '0x0', 05:32:44 INFO - 'XPC_SERVICE_NAME': '0', 05:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:44 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-hz5clA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:44 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-koXqD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:44 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-CQ1Zb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:44 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-zSPoCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:44 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ANpoTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sP05nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-uJJTdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-pOTqL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-4odjvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fNnPMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ERHXXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Z1TWmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-hGuefW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:45 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-6HIPcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:46 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0NSwww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:46 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-oilVNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:46 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8FPX7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:46 INFO - Running setup.py install for manifestparser 05:32:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:46 INFO - Running setup.py install for mozcrash 05:32:46 INFO - Running setup.py install for mozdebug 05:32:46 INFO - Running setup.py install for mozdevice 05:32:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:32:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:46 INFO - Running setup.py install for mozfile 05:32:47 INFO - Running setup.py install for mozhttpd 05:32:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozinfo 05:32:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozInstall 05:32:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozleak 05:32:47 INFO - Running setup.py install for mozlog 05:32:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for moznetwork 05:32:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozprocess 05:32:48 INFO - Running setup.py install for mozprofile 05:32:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozrunner 05:32:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozscreenshot 05:32:48 INFO - Running setup.py install for moztest 05:32:48 INFO - Running setup.py install for mozversion 05:32:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:48 INFO - Cleaning up... 05:32:48 INFO - Return code: 0 05:32:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:48 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')]} 05:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:48 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')]} 05:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:49 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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:49 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 05:32:49 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 05:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:49 INFO - 'HOME': '/Users/cltbld', 05:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:49 INFO - 'LOGNAME': 'cltbld', 05:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:49 INFO - 'MOZ_NO_REMOTE': '1', 05:32:49 INFO - 'NO_EM_RESTART': '1', 05:32:49 INFO - 'PAGER': '/bin/cat', 05:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:49 INFO - 'PWD': '/builds/slave/test', 05:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:49 INFO - 'SHELL': '/bin/bash', 05:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:49 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:49 INFO - 'USER': 'cltbld', 05:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:49 INFO - 'XPC_FLAGS': '0x0', 05:32:49 INFO - 'XPC_SERVICE_NAME': '0', 05:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-2qCHkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-GC6QOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Qc47b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-P2gV5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-HczluY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-wk4Fls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-PJkb6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-T7WtxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Qdshhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xIq6Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sYJJnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-itjU4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:50 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Qhfj3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:51 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)) 05:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-JtxKBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:51 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)) 05:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Trv9kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:51 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)) 05:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-O2YQfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 05:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-3Y2Szv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:51 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)) 05:32:51 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)) 05:32:51 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)) 05:32:51 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)) 05:32:51 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)) 05:32:51 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)) 05:32:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:51 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 05:32:51 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 05:32:51 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 05:32:51 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 05:32:51 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 05:32:51 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 05:32:54 INFO - Downloading blessings-1.6.tar.gz 05:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:54 INFO - Installing collected packages: blessings 05:32:54 INFO - Running setup.py install for blessings 05:32:54 INFO - Successfully installed blessings 05:32:54 INFO - Cleaning up... 05:32:54 INFO - Return code: 0 05:32:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:54 INFO - Reading from file tmpfile_stdout 05:32:54 INFO - Current package versions: 05:32:54 INFO - blessings == 1.6 05:32:54 INFO - blobuploader == 1.2.4 05:32:54 INFO - docopt == 0.6.1 05:32:54 INFO - functools32 == 3.2.3-2 05:32:54 INFO - jsonschema == 2.5.1 05:32:54 INFO - manifestparser == 1.1 05:32:54 INFO - mozInstall == 1.12 05:32:54 INFO - mozcrash == 1.0 05:32:54 INFO - mozdebug == 0.1 05:32:54 INFO - mozdevice == 0.49 05:32:54 INFO - mozfile == 1.2 05:32:54 INFO - mozhttpd == 0.7 05:32:54 INFO - mozinfo == 0.9 05:32:54 INFO - mozleak == 0.1 05:32:54 INFO - mozlog == 3.4 05:32:54 INFO - moznetwork == 0.27 05:32:54 INFO - mozprocess == 0.23 05:32:54 INFO - mozprofile == 0.28 05:32:54 INFO - mozrunner == 6.13 05:32:54 INFO - mozscreenshot == 0.1 05:32:54 INFO - mozsystemmonitor == 0.3 05:32:54 INFO - moztest == 0.7 05:32:54 INFO - mozversion == 1.4 05:32:54 INFO - psutil == 3.1.1 05:32:54 INFO - requests == 1.2.3 05:32:54 INFO - wsgiref == 0.1.2 05:32:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 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 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:55 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 05:32:55 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 05:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:55 INFO - 'HOME': '/Users/cltbld', 05:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:55 INFO - 'LOGNAME': 'cltbld', 05:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:55 INFO - 'MOZ_NO_REMOTE': '1', 05:32:55 INFO - 'NO_EM_RESTART': '1', 05:32:55 INFO - 'PAGER': '/bin/cat', 05:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:55 INFO - 'PWD': '/builds/slave/test', 05:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:55 INFO - 'SHELL': '/bin/bash', 05:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:55 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:55 INFO - 'USER': 'cltbld', 05:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:55 INFO - 'XPC_FLAGS': '0x0', 05:32:55 INFO - 'XPC_SERVICE_NAME': '0', 05:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 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)) 05:32:55 INFO - Cleaning up... 05:32:55 INFO - Return code: 0 05:32:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028643f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda01d731b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, '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.LKANGffWxz/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.kDUeekjnBx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 05:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:55 INFO - 'HOME': '/Users/cltbld', 05:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:55 INFO - 'LOGNAME': 'cltbld', 05:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:55 INFO - 'MOZ_NO_REMOTE': '1', 05:32:55 INFO - 'NO_EM_RESTART': '1', 05:32:55 INFO - 'PAGER': '/bin/cat', 05:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:55 INFO - 'PWD': '/builds/slave/test', 05:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:55 INFO - 'SHELL': '/bin/bash', 05:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:32:55 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:55 INFO - 'USER': 'cltbld', 05:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:55 INFO - 'XPC_FLAGS': '0x0', 05:32:55 INFO - 'XPC_SERVICE_NAME': '0', 05:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:55 INFO - Cleaning up... 05:32:55 INFO - Return code: 0 05:32:55 INFO - Running post-action listener: _resource_record_post_action 05:32:55 INFO - Running post-action listener: _start_resource_monitoring 05:32:55 INFO - Starting resource monitoring. 05:32:55 INFO - [mozharness: 2016-11-22 13:32:55.886913Z] Finished create-virtualenv step (success) 05:32:55 INFO - [mozharness: 2016-11-22 13:32:55.887919Z] Running install step. 05:32:55 INFO - Running pre-action listener: _resource_record_pre_action 05:32:55 INFO - Running main action method: install 05:32:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:56 INFO - Reading from file tmpfile_stdout 05:32:56 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:56 INFO - Reading from file tmpfile_stdout 05:32:56 INFO - Output received: 05:32:56 INFO - Usage: mozinstall [options] installer 05:32:56 INFO - Options: 05:32:56 INFO - -h, --help show this help message and exit 05:32:56 INFO - -d DEST, --destination=DEST 05:32:56 INFO - Directory to install application into. [default: 05:32:56 INFO - "/builds/slave/test"] 05:32:56 INFO - --app=APP Application being installed. [default: firefox] 05:32:56 INFO - mkdir: /builds/slave/test/build/application 05:32:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:33:29 INFO - Reading from file tmpfile_stdout 05:33:29 INFO - Output received: 05:33:29 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:33:29 INFO - Running post-action listener: _resource_record_post_action 05:33:29 INFO - [mozharness: 2016-11-22 13:33:29.303434Z] Finished install step (success) 05:33:29 INFO - [mozharness: 2016-11-22 13:33:29.303600Z] Running run-tests step. 05:33:29 INFO - Running pre-action listener: _resource_record_pre_action 05:33:29 INFO - Running main action method: run_tests 05:33:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:29 INFO - 2016-11-22 05:33:29.317 screenresolution[1624:7410] starting screenresolution argv=screenresolution get 05:33:29 INFO - 2016-11-22 05:33:29.320 screenresolution[1624:7410] Display 0: 1600x1200x32@60 05:33:29 INFO - 2016-11-22 05:33:29.326 screenresolution[1625:7412] starting screenresolution argv=screenresolution list 05:33:29 INFO - Available Modes on Display 0 05:33:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:29 INFO - Intel Iris: 05:33:29 INFO - Chipset Model: Intel Iris 05:33:29 INFO - Type: GPU 05:33:29 INFO - Bus: Built-In 05:33:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:29 INFO - Vendor: Intel (0x8086) 05:33:29 INFO - Device ID: 0x0a2e 05:33:29 INFO - Revision ID: 0x0009 05:33:29 INFO - Displays: 05:33:29 INFO - Display: 05:33:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:29 INFO - Display Serial Number: 1600x1200 60 05:33:29 INFO - Main Display: Yes 05:33:29 INFO - Mirror: Off 05:33:29 INFO - Online: Yes 05:33:29 INFO - Rotation: Supported 05:33:29 INFO - Return code: 0 05:33:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:29 INFO - Python 2.7.3 05:33:29 INFO - Return code: 0 05:33:29 INFO - grabbing minidump binary from tooltool 05:33:29 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')]} 05:33:29 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 0x10267ad30>, '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 0x1024e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102672b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:29 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 05:33:29 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 05:33:29 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 05:33:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:29 INFO - Return code: 0 05:33:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:33:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:33:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 05:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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 05:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKANGffWxz/Render', 05:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:29 INFO - 'HOME': '/Users/cltbld', 05:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:29 INFO - 'LOGNAME': 'cltbld', 05:33:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:29 INFO - 'MOZ_NO_REMOTE': '1', 05:33:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:29 INFO - 'NO_EM_RESTART': '1', 05:33:29 INFO - 'PAGER': '/bin/cat', 05:33:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:29 INFO - 'PWD': '/builds/slave/test', 05:33:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:29 INFO - 'SHELL': '/bin/bash', 05:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kDUeekjnBx/Listeners', 05:33:29 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 05:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:29 INFO - 'USER': 'cltbld', 05:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:29 INFO - 'XPC_FLAGS': '0x0', 05:33:29 INFO - 'XPC_SERVICE_NAME': '0', 05:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 05:33:29 INFO - mozversion application_buildid: 20161122030216 05:33:29 INFO - mozversion application_changeset: 0534254e9a40b4bade2577c631fe4cfa0b5db41d 05:33:29 INFO - mozversion application_display_name: Nightly 05:33:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:29 INFO - mozversion application_name: Firefox 05:33:29 INFO - mozversion application_remotingname: firefox 05:33:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:29 INFO - mozversion application_vendor: Mozilla 05:33:29 INFO - mozversion application_version: 53.0a1 05:33:29 INFO - mozversion platform_buildid: 20161122030216 05:33:29 INFO - mozversion platform_changeset: 0534254e9a40b4bade2577c631fe4cfa0b5db41d 05:33:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:29 INFO - mozversion platform_version: 53.0a1 05:33:29 INFO - using testdate: 1479821609 05:33:29 INFO - actual date: 1479821609 05:33:29 INFO - starting webserver on 'localhost:49249' 05:33:29 INFO - SUITE-START | Running 2 tests 05:33:29 INFO - TEST-START | dromaeo_css 05:33:29 INFO - operating with platform_type : mac_ 05:33:29 INFO - Initialising browser for dromaeo_css test... 05:33:29 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpPEL13b/profile http://localhost:49249/getInfo.html) 05:33:33 INFO - PROCESS | 1635 | 2016-11-22 05:33:33.421 plugin-container[1638:7592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d4b, name = 'com.apple.tsm.portname' 05:33:33 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:33:33 INFO - PROCESS | 1635 | 2016-11-22 05:33:33.421 plugin-container[1638:7592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 05:33:33 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:33:33 INFO - PROCESS | 1635 | 2016-11-22 05:33:33.422 plugin-container[1638:7592] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:33:33 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 05:33:33 INFO - PROCESS | 1635 | colorDepth:24 05:33:33 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 05:33:33 INFO - PROCESS | 1635 | __metrics 05:33:33 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:33:34 INFO - TEST-INFO | 1635: exit 0 05:33:34 INFO - Browser initialized. 05:33:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:34 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpPEL13b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:37 INFO - PROCESS | 1639 | 2016-11-22 05:33:37.315 plugin-container[1640:7710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 05:33:37 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:33:37 INFO - PROCESS | 1639 | 2016-11-22 05:33:37.316 plugin-container[1640:7710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:33:37 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:33:37 INFO - PROCESS | 1639 | 2016-11-22 05:33:37.316 plugin-container[1640:7710] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:33:46 INFO - PROCESS | 1639 | RSS: Main: 243281920 05:33:46 INFO - PROCESS | 1639 | 05:34:36 INFO - PROCESS | 1639 | 1479821676520 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:36:49 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 05:36:52 INFO - PROCESS | 1639 | RSS: Main: 250798080 05:36:52 INFO - PROCESS | 1639 | 05:39:54 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 05:39:55 INFO - PROCESS | 1639 | RSS: Main: 260378624 05:39:55 INFO - PROCESS | 1639 | 05:42:57 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 05:42:57 INFO - PROCESS | 1639 | RSS: Main: 264843264 05:42:57 INFO - PROCESS | 1639 | 05:45:59 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 05:46:00 INFO - PROCESS | 1639 | RSS: Main: 263409664 05:46:00 INFO - PROCESS | 1639 | 05:49:03 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 05:49:03 INFO - PROCESS | 1639 | RSS: Main: 267833344 05:49:03 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 05:52:06 INFO - PROCESS | 1639 | RSS: Main: 270503936 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | __start_tp_report 05:52:06 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 05:52:06 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 05:52:06 INFO - PROCESS | 1639 | |i|pagename|runs| 05:52:06 INFO - PROCESS | 1639 | |0;dojo.html;3349;3356.228067544465;3409.846556904939;3414.265932824442;3420.196253880339;5625.943740562593;5650.3219613646315;5682.374938756739;5689.687067211302;5757.366689664133;5640.153976903456;5675.51758100562;5716.114002329646;5726.3414707308675;5738.626989245696;5097.898042039157;5175.301334319875;5236.057509648262;5278.498542638443;5305.920411193835;5155.381354237478;5242.397124330712;5293.258943747878;5310.468953104678;5340.973295133519;5082.949170508285;5134.383873935151;5140.640155189139;5198.856251624656;5263.236830659533;4352.869413917556;5189.247559103909;5202.713850738208;5208.635395522315;5209.739513024334;5112.36095488064;5125.897482050375;5149.99575082859;5153.252778347156;5168.793248270103;4886.60907127429;5170.146925757245;5632;5655.208270842086;5675.5743319250805;6118.588722523077;6152.046432802923;6168.44483996438;6171.413715697002;6174.506039516873;6212.378762123797;6270.498360131179;6293.056041593752;6296.851574212894;6326.240851097852;5776.711164441803;5845.976954033027;5860.677662728505;5863.237779088734;5906.936751384749;5125.821061155955;5254.6847189168775;5988.9101663475085;6214.658193799294;6307.747690092437;4936.605071594264;6053.969730151321;6153.569250152446;6155.415235552616;6203.7828675997025;5250.65870718402;6106.877862442726;6270.905936410957;6282.403171698681;6295.213098362705;5785.640374511986;6076.5442591805795;6080.908786368208;6125.41859352575;6149.41580549847;5514.869451762379;5677.602567820296;6223.066540019034;6242.375762423812;6247.500199983991;3848.5574158972076;4917.852464426073;4927.753612319441;4928.48250933654;4949.208126699711;4349.412829268072;4385.627221686129;4392.077663690597;4423.668224883147;4454.1537107950035;5671.943280567224;5709.800156994485;5712.971435142797;5727.37001916429;5812.709364531756;5344;6288.434040936338;6290.968545157245;6291.087792270096;6297.874042519124;4439.822407103744;5804;5846.123081537803;5865.648061116347;5895.174675545427;5285.497877701612;5325.0148722486465;5356.223347614574;5374.946250537445;5408.080626293539;6232.345603711545;6238.844028899223;6248.4688801452385;6256.343083976208;6311.431971122621;6267.2479302484;6278.8430289241305;6284.811455656338;6292.465140463112;6313.431791138636;6174.567780255518;6228.750849965951;6307.274663413767;6314.242290924934;6320.083587879823;5813.854653633693;5941.792037278675;6096.664683442454;6113.57204995646;6117.096718007379;5496.560275178056;6163.260408750979;6170.290416601969;6217.09852071438;6218.844528886814;6092.969535152296;6108.786192483241;6117.204763380732;6133.365267548864;6156.230471191101;6135.233095863017;6140.600860944024;6144.38556144382;6155.599886007395;6166.691665416801;5676.659400435987;5851.9173952818865;5859.9121013183985;5863.560232982458;5950.583459157818;6038.275406951194;6064.969675151596;6086.604370716066;6131.988406491147;6140.964409184605;5961.225040744675;6007.90988135187;6011.338752737108;6011.6693581853415;6025 05:52:06 INFO - PROCESS | 1639 | |1;ext.html;476.42590678232733;515.6854318865493;522.9671398986999;526.670830730793;539.9082156033473;33954.83022584887;37870.4319435208;37956.430653540185;37971;37987.86036418908;37736.05659858505;38264.617353826456;38292;38309.61690383095;38399.80800095996;8984.595693193822;9207.769805754842;9222.769430764218;9224.123708247706;9239.353245272834;7530.510516816416;7665.655045522964;8875.890513685788;9152.039035901234;9168.495732734697;9122.908770912272;9156.8626470603;9157.496337701425;9179.541022948826;9182.449053056804;9069.863952040725;9136.360454768135;9140.177384035402;9144.314176436794;9145.451272923647;7815;9149.634014639407;9154.49650269235;9178.4034037788;9187.219086377667;37183.81408092969;37423.4386484203;37511.437328439824;37607.24785504302;37672.62327376719;3203.4393981053454;3247;3301.6863397977395;3423.2608229587036;3674.5590529136407;28741.85629071862;29286.707132928823;29483.410331793242;29589.260268493246;30202.73479489047;3151.196444906534;3182.8859899035383;3218.5494030835466;3242.4487837067754;3317.552130462405;352753;354036;354446;355639;356709;31915.52126718101;32811.83594082062;51748;52039.95920081577;52119.73940130351;32411.027669169962;32933.3413331732;33172;33215.83392083024;33258.501122482696;13699.246541440316;13832.446702132003;13841.100328478818;13857.722845543032;13918.09532380392;11469.655910322703;11566.710832229093;11570;11574.074074074053;11578.884211157949;7557.4331925105835;7604.2395760423515;7622.047244094489;7625.504342217738;7667.616619169019;2796.1891051595217;2937.8090424122365;3267.2975310308398;3272.640009598942;3290.5393244945726;854.6752234150982;864.8832407625016;871.9782005449914;871.9912800871911;941.7975135345933;24348.756512434647;25695.87152064228;28275.43449131047;29249.415011699646;29498.165238518854;913.4291068082448;970.2092794372563;988.5255077562811;988.6292640259902;1042.2443728296923;14248.857511424752;14342.928285358716;14526.787231702689;14913.70172596542;15196;628658;629121;631142;633755;636596;53345;54175;54190.645233869465;54195.45804541904;54276.72861635745;1304.2174695182814;1323.781576039962;1325.5360623781598;1348.2382453913508;1349.9865001350058;23079.88460057689;23496.76503235014;28097.859510703132;28123.7344319502;30020.549691755077;46282;46457.30314045224;46522;46647.76676116598;46693.299600506696;565092;570093;570934;571085;571488;24980.250592482254;25135.371615709755;25423.491530169664;25443.491130176924;25604.359891002874;42321.788391057846;43263;43866.56133438625;44147.585241475594;44226.33660495031;36630.08424789402;36674.44988325124;37434.689785857365;37738;37958.62041379551;36449;36551.817240913624;36725.81637091797;37594.624053760206;37704;35995.10012249715;36038.639613603526;37011;37236.441453378764;38898.416523752734;28333.00834470866;29887.402251955275;31903.202419939687;32000.35999280048;33166;22200.666989995487;23661.29016129519;24467.38831529226;24550.50898982046;24681.506369872142 05:52:06 INFO - PROCESS | 1639 | |2;jquery.html;325.3980136747018;359.97480176387654;360.83762306961876;378.2001067741727;395.11100024943875;18628.627427451444;19724.506887327825;19970.70043949342;20502.89748551259;20753.273635422764;18195.727064094048;19160;20087.598248035032;20424.285150019754;20515.871627060544;5574.470425309589;5659.405762394951;5919.2009078774245;5940;5945.643261404308;3814.065553939275;4008.158286759784;5749.770009199627;5869.442402971732;6050.2739671239515;6019.819405417845;6075.665838378887;6080.361562035989;6092.177556029946;6131.110988906606;6287.276963149227;6483.481321494269;6623.07276981223;6662.633555154464;6673.799786006428;5003.699778013294;5205.219217117425;5851.32709738377;5880.382559831241;5880.70596470179;12648.49406023758;12894.935525322408;12924.676883077904;12931.224126552343;17719.202635881415;2080.760712518065;2090.592334494774;2092.612866619665;2106.2838634864065;2219.6892435059103;9071.228945539637;9675.564599593035;10109.949450252776;10231.693049208536;10285.331453455501;1677.211710496077;1752.5618595351161;1778.6264884374168;1790.239148361954;1851.278001579385;115394;124423;124771;126403;127484;11386.975172234539;11524.135689823297;11640.476178571984;11744.58893938708;12007.099467539969;14279.857201427852;14313.499027533986;14319.35562899672;14329.35517901697;14333.283335833166;8037.477563958441;8319.459235149818;8338.499690018618;8455.746327610059;8464.830703385898;7967.3626109911065;8130.065042520067;8199.631016604268;8211.466254693425;8354.164583541597;4303.677224208197;4437.5935614874825;4456.866294011184;4507.166174257773;4553.066621342617;1320.42561485754;1639.6474757927103;1642.884998050125;1738.3828740797087;1877.1552801239498;793.9880901786478;808.0747544062016;870.9085546017703;874.1957399192758;876.6581033396981;19472.805271947098;20316.28892988768;20997.475063123544;21246.25638102659;21418.03618837156;739.5156172706886;797.0236460336158;797.8284668796236;830.8504469195482;910.6949172027445;9528.618855245852;10481.947590262153;11131.833022504668;11154.996050355507;11200.831987520194;567097;569017.1549142228;574120;580176;584417;97780;101017.484737729;113651;115643.7960291772;117499;944.7732544189482;954.7565370830393;961.9326647134633;967.8403063494444;973.1679414100984;15426.22868856575;17989.55026124305;18187.909060455142;18550.628987420445;18969.715454268473;47659.04681906273;50191.24713129233;50463.74768126136;50995.745021274655;51599.74200128976;75447;81195;82386;82387.58806205931;83697.58151209205;7788.415868809975;7820.921790782247;7947.682092716226;7970.083440404428;7982.560959147303;10678.679639610824;12475.438605262603;12684.175528590613;12720.74558508843;12753.362331883183;9327.626894924128;10549.261551691616;10927.672169834917;10961.287516311415;11029.062529685067;8890.199882010651;10581.947090264499;10699.839502407314;10983.835242471529;11323.716907077389;9163.450192988443;10669.91936572361;10888.891111088788;11139.997400234019;11253.437328133725;7802.882956755539;7868.527888326718;7913.089994650459;7991.880121798062;8024.157463466252;8869.423487473294;9950.751231219278;10890.78218435611;11189.496472658913;11203.582907213378 05:52:06 INFO - PROCESS | 1639 | |3;mootools.html;1435.8779503742182;1456.0390142505944;1618.3364820423628;1630.3397124164717;1658.5604814724093;7619.466637335375;7890.8816367754525;8099.473534220279;8566.914330856698;8573.14268573143;8019.799505012377;8356.456830306026;8377.287930525912;8470.491770493774;8476.449030812993;2313.8727369994645;2339.5671800716837;2341.75411581784;2349.5770761262966;2353.070537137838;2325.5813953488364;2369.1707902234252;2372.92881213563;2373.8100951923807;2385.510970251103;2324.9651255231183;2331.2423462374713;2346.214018303862;2353.646952957053;2354.2348736660656;2300.8504447210876;2304.5045315257216;2327.9883600582057;2330.5571941331264;2332.1837356925025;2333.2067097187037;2355.5171189906027;2356.26955643751;2361.1263832381956;2361.716594008713;8499.192576705203;8537.317014638815;8539.786505337353;8540.871886921701;8543.231109200202;5019.899602007976;5041.369828771403;5226.745581060556;5237.764300606482;5257.658252213632;2889.4798936191687;3393.779404338735;3573.1424458130123;3625.7099432045375;3629.6370362963758;1028.6348346337018;1032.0097107567922;1071.6784964510616;1071.9678409647722;1088.4829705889717;10864.565417383295;10923.39921304328;10985.176111791647;11041.558337666564;11179.944100279447;4481.4174157359985;4564.475085365226;4670.813167473331;4685.086408150378;4721.527847215251;7602.543847369176;9391.060893910691;9586;9590.90409095914;9608.615655373847;4768.642351823558;5071.732066983254;5197.454267301954;5269.104252277122;5278.788848446019;4322.891927701832;4329.090890912944;4767.904641907143;4782.689125206851;4843.0798148352405;3389.4576867701544;3707.8516859325864;3819.9809000955374;3847.1920896611464;3855.7493762905883;1236.4806781151938;1245.296407529761;1245.7944439167436;1251.1054595963928;1259.508791571288;850.5024560632099;857.4041041476114;876.8903887014123;886.4592598514901;887.2325438495654;5468.562514998791;5473.698946557977;5602.439756024446;5676.914846277309;5722.37053924068;412.99793501032303;420.38833497261413;430.3329838749925;432.88744926318753;435.6427729262038;3671.0822294426393;3751.756135851161;3830.9808450957566;3842.6733727633464;3873.5545412277384;8444.831103377897;10045.447500387549;10238.53926573291;10245.385276883262;10284.794304113875;9816;9941.502924853728;10199.18406527476;10202;10232.181425485944;7861.882071768928;7955.880661789962;7969.3226075784205;7971.322437592869;7971.681132754741;5100.183970564692;5463.562914966873;5511.366192887869;5556;5557.861053473695;8061.87907181381;9238;9288.907110929074;9311.813763724553;9423.38747981379;9512.336590371737;9533.094356036165;9612.75968100775;9614.9038509614;9615.423074615546;6567.507436942344;6764.188297404343;6936;7073.610951397723;7211.026511421101;7851.2148785121035;8029.076656184614;8057.838843222986;8059.919400805917;8060.83878322442;7054.329838665319;7134.250903655249;7159.534630249017;7174.426045916419;7263.963680181565;6207.534434917489;6236.719347629277;6962.0632478973;7070.7525236616475;7133.251008644018;6597.901031484627;6710.228323742832;6750.662466876538;6773.830654233487;6778.254392016973;4811.78346974394;4861.246506791453;4878.146324393288;4906.681065730716;4948.777305021355;4303.698741088219;4765.166095933268;4783.756223381876;4797.832075877328;4988.376452943382 05:52:06 INFO - PROCESS | 1639 | |4;prototype.html;296.09395250533385;296.4131020579253;301.12523120335436;307.5264093296323;311.32442599558954;4652.767361631918;5106.744662766865;5333.973330133354;5476.7535460904255;5543.667379957195;4520.3897473841025;5184.444666600024;5307.469253074691;5372.979133964544;5397.6250449802055;3329.833508324586;3512.7365447591533;3839.616038396152;4141.7929103544775;4188.769617671027;3422.161570415252;3754.6057663945303;3836.489612005012;4052.959470405288;4201.45811783871;3387.627360990289;3390.406678831207;4154.459920210368;4160.479940007499;4181.665466762652;3711.6102809204913;4047.826130422159;4122.2579935611575;4136.855210067633;4183.142455796585;3121.0480803354967;3472.3402553515;3634.309481198564;3637.4177232903508;3655.94516082259;4396.692231543793;4406.823727050914;4803.3035209894715;5095.197641297272;5248.71132087735;4026.979865100656;4286.892827679302;4705.764711764432;4712.222483290253;4740.549647783455;4775.254228818443;5661.46216109469;5689.487946084831;5708.286464191976;5725.914111288334;3100.7751937984594;3332.933341333184;3768.7939859245053;3923.411018537497;3934.9016274593077;5126.5642420394315;5948.434898684618;6004.674297357217;6007.468095635629;6029.547783916224;3311.983440082832;3549.5208146900354;3974.18529201513;3983.780892050965;4023.9195216095513;3941.1526521797377;4061.7766022869027;4079.286124928126;4095.8531611148924;4123.525794533607;3917.9608203918165;4132.359484279941;4352.542982986741;4655.51117132703;4706.223473126965;4671.252599584051;4889.633277504201;5353.678779273256;5673.659580425187;5804.854878628068;1158.8841115888345;1208.6917835951776;1219.2928101700993;1254.5358217459598;1256.6418570707306;778.8130848596296;791.9406044546566;868.8740132680853;900.599233341157;901.4450073622947;4811.927821082687;5115.928049140104;5129.820456284012;5140.938658459775;5151.0851752667495;4789.544993225638;5212.322398088224;5604.3835178130375;5639.43605639441;5640.266765320564;4372.453443319585;4448.399466072039;5048.879470622347;5056.241563765465;5156.329677141947;2577.523158215736;2746.5742809864505;2908.9127326180246;2969.4209629122547;2974.691135900197;5231.555317797954;5321.840344789663;5431.456854314537;5499.955008548363;5794.739236734357;4762.238041913277;4964.914735810968;5481.4511252267885;5714.2571465710025;5732.942670573324;5521.944780552143;5729.713514324267;5818.738156782955;5846.1815345852465;5865.824025279248;5209.817656382009;5330.307060082165;5393.352797664148;5558.6937069787755;5595.272614560081;4760.690555113906;5059.369128053465;5484.61607687458;5498.3127109111365;5543.584231182596;5170.914108037355;5462.804259508073;5553.722973580812;5605.887882242414;5621.353544342453;3205.8397080145432;3452.085197422635;3782.981085094557;3799.506064211635;3844.2311537692012;4799.66402351832;5084.669496482717;5221.660592061504;5588.6646801192055;5605.002647167492;4717.0754132438105;5275.498827611371;5383.16560933056;5450.972745136249;5457.454254574511;5735.3977832327;5777.855553611025;5799.188113664006;5810.912836307374;5819.592628515963;5244.711540865289;5367.4364191759305;5428.891422171613;5647.604667673388;5649.30520843737;5801.448862358069;5806.622569532967;5807.651540907559;5808.36108028125;5811.593188476766;4672.696274742131;4910.484399138039;5157.793688252428;5508.944910551004;5626.2967129108865 05:52:06 INFO - PROCESS | 1639 | |5;yui.html;691.9481038922082;699.2832346844484;711.3846522757816;751.3983254835201;793.968241270349;11015.889841101587;14696.926515367408;14740.041897276684;14751.557453276391;14795.186264755448;12831.48674053039;13475.865241347583;13596.93201533991;13600.387982540784;13620;805.1867613710151;821.5686764448683;822.7819627798638;824.6206744897324;824.9587520623945;650.7624716978293;673.3973094080794;804.7384600004992;816.3265306122443;821.1008945204991;854.239726643288;860.3203469259278;862.4609618988133;862.6204470033197;864.5331520978664;850.9361797865154;854.9230569248798;856.3149480415642;856.8329175810688;857.4255248983196;627.3381582430579;634.1027446163673;634.3276127305026;634.6287421858218;635.9936400636027;13745.518906838313;14346.56960291193;14360.497382591559;14480.637984050378;14480.855191448056;12955.67610809728;13273.203607783564;13282.667933301647;13328.200307981551;13451.192928424325;10714.357138571711;10895.673129806119;11400.942995285053;11502.769944601145;11696.298222106701;3600.459931010343;3651.6713495785243;3724.1806802503406;3762.623737626243;3765.6987441004653;88265.67601485983;88414.23171536533;89744;90027.99999999935;90446.99999999935;7957.925680033237;8751.956240218758;9953.402795832273;10084.344517606332;10648.893511064945;19811.702824457643;20014.99925003744;20111.893845838327;20206.292779752934;20312.79687203109;6397.616143031433;6421.197350331208;7474.327310542078;7509.612983770287;7605.239476052461;4170.311898536768;4175.728577642443;4227.365895115758;4240.109576988805;4244.257254980428;2988.5367767995995;3428.777129486575;3468.1156305142026;3471.7396195285455;3476.860925562949;596.9076589840581;609.4819403507072;634.5494698763837;640.9487241020707;645.0614356111846;515.7936825269923;523.8035736598775;623.9469645080128;628.3151365012159;652.6084349390328;8793.689933304595;8899.199072083415;8956.955215223883;8987.865182022275;9126.81746365069;608.57703895792;613.4264462727364;639.7217210513442;642.7075680565425;658.2331583705026;4203.180379825966;4305.9569404306185;4350.108227813291;4474.015716542355;4535.523770004102;107397.463012686;108974;112264;113660.22417085725;116082;78058.2194178051;94514;94594;98255.50872245737;99045;88685.55657221672;88739.55630221807;89566.55216723874;90356;90553.99999999868;8566.901109228278;8592.163421335654;8636.913630863612;8800.867986980073;9174.128457796498;48664.99999999858;50260;50523.989520210125;51164;54201.45798541964;53480.73259633677;67830;72697;72914;73688.26311737028;12125.27248365101;12243.75512489763;12277.20198187115;12392;12587.685307867376;13743.175409475463;13803.17180969145;13976.510822121178;14056.226907519775;14250.572482825532;12342.568010119603;12679.93660031694;12844.486220551073;13148.934255328662;13414.865851341752;12828;12986.610401687965;13249.735005299648;13298.800517992044;13548;12361.93819030899;12752.362381881054;12857.03572232105;13262.137961032888;13339.932805375352;10651.680449586525;10725.785484290427;10786.568537258421;10879.456027198767;10958.397288149228;10004.449755263238;10201.234907381828;10886.183536234972;10919.071878890383;11037.72405689832 05:52:06 INFO - PROCESS | 1639 | __end_tp_report 05:52:06 INFO - PROCESS | 1639 | __start_cc_report 05:52:06 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,370 05:52:06 INFO - PROCESS | 1639 | __end_cc_report 05:52:06 INFO - PROCESS | 1639 | __startTimestamp1479822726852__endTimestamp 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | ------- Summary: start ------- 05:52:06 INFO - PROCESS | 1639 | Number of tests: 6 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5666.30 Median:5859.91 stddev:661.40 (11.3%) stddev-sans-first:637.96 05:52:06 INFO - PROCESS | 1639 | Values: 3349.0 3356.2 3409.8 3414.3 3420.2 5625.9 5650.3 5682.4 5689.7 5757.4 5640.2 5675.5 5716.1 5726.3 5738.6 5097.9 5175.3 5236.1 5278.5 5305.9 5155.4 5242.4 5293.3 5310.5 5341.0 5082.9 5134.4 5140.6 5198.9 5263.2 4352.9 5189.2 5202.7 5208.6 5209.7 5112.4 5125.9 5150.0 5153.3 5168.8 4886.6 5170.1 5632.0 5655.2 5675.6 6118.6 6152.0 6168.4 6171.4 6174.5 6212.4 6270.5 6293.1 6296.9 6326.2 5776.7 5846.0 5860.7 5863.2 5906.9 5125.8 5254.7 5988.9 6214.7 6307.7 4936.6 6054.0 6153.6 6155.4 6203.8 5250.7 6106.9 6270.9 6282.4 6295.2 5785.6 6076.5 6080.9 6125.4 6149.4 5514.9 5677.6 6223.1 6242.4 6247.5 3848.6 4917.9 4927.8 4928.5 4949.2 4349.4 4385.6 4392.1 4423.7 4454.2 5671.9 5709.8 5713.0 5727.4 5812.7 5344.0 6288.4 6291.0 6291.1 6297.9 4439.8 5804.0 5846.1 5865.6 5895.2 5285.5 5325.0 5356.2 5374.9 5408.1 6232.3 6238.8 6248.5 6256.3 6311.4 6267.2 6278.8 6284.8 6292.5 6313.4 6174.6 6228.8 6307.3 6314.2 6320.1 5813.9 5941.8 6096.7 6113.6 6117.1 5496.6 6163.3 6170.3 6217.1 6218.8 6093.0 6108.8 6117.2 6133.4 6156.2 6135.2 6140.6 6144.4 6155.6 6166.7 5676.7 5851.9 5859.9 5863.6 5950.6 6038.3 6065.0 6086.6 6132.0 6141.0 5961.2 6007.9 6011.3 6011.7 6025.0 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:63092.60 Median:37473.06 stddev:142838.62 (381.2%) stddev-sans-first:143161.95 05:52:06 INFO - PROCESS | 1639 | Values: 476.4 515.7 523.0 526.7 539.9 33954.8 37870.4 37956.4 37971.0 37987.9 37736.1 38264.6 38292.0 38309.6 38399.8 8984.6 9207.8 9222.8 9224.1 9239.4 7530.5 7665.7 8875.9 9152.0 9168.5 9122.9 9156.9 9157.5 9179.5 9182.4 9069.9 9136.4 9140.2 9144.3 9145.5 7815.0 9149.6 9154.5 9178.4 9187.2 37183.8 37423.4 37511.4 37607.2 37672.6 3203.4 3247.0 3301.7 3423.3 3674.6 28741.9 29286.7 29483.4 29589.3 30202.7 3151.2 3182.9 3218.5 3242.4 3317.6 352753.0 354036.0 354446.0 355639.0 356709.0 31915.5 32811.8 51748.0 52040.0 52119.7 32411.0 32933.3 33172.0 33215.8 33258.5 13699.2 13832.4 13841.1 13857.7 13918.1 11469.7 11566.7 11570.0 11574.1 11578.9 7557.4 7604.2 7622.0 7625.5 7667.6 2796.2 2937.8 3267.3 3272.6 3290.5 854.7 864.9 872.0 872.0 941.8 24348.8 25695.9 28275.4 29249.4 29498.2 913.4 970.2 988.5 988.6 1042.2 14248.9 14342.9 14526.8 14913.7 15196.0 628658.0 629121.0 631142.0 633755.0 636596.0 53345.0 54175.0 54190.6 54195.5 54276.7 1304.2 1323.8 1325.5 1348.2 1350.0 23079.9 23496.8 28097.9 28123.7 30020.5 46282.0 46457.3 46522.0 46647.8 46693.3 565092.0 570093.0 570934.0 571085.0 571488.0 24980.3 25135.4 25423.5 25443.5 25604.4 42321.8 43263.0 43866.6 44147.6 44226.3 36630.1 36674.4 37434.7 37738.0 37958.6 36449.0 36551.8 36725.8 37594.6 37704.0 35995.1 36038.6 37011.0 37236.4 38898.4 28333.0 29887.4 31903.2 32000.4 33166.0 22200.7 23661.3 24467.4 24550.5 24681.5 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:33505.77 Median:2104.59 stddev:96001.22 (4561.5%) stddev-sans-first:96238.20 05:52:06 INFO - PROCESS | 1639 | Values: 325.4 360.0 360.8 378.2 395.1 18628.6 19724.5 19970.7 20502.9 20753.3 18195.7 19160.0 20087.6 20424.3 20515.9 5574.5 5659.4 5919.2 5940.0 5945.6 3814.1 4008.2 5749.8 5869.4 6050.3 6019.8 6075.7 6080.4 6092.2 6131.1 6287.3 6483.5 6623.1 6662.6 6673.8 5003.7 5205.2 5851.3 5880.4 5880.7 12648.5 12894.9 12924.7 12931.2 17719.2 2080.8 2090.6 2092.6 2106.3 2219.7 9071.2 9675.6 10109.9 10231.7 10285.3 1677.2 1752.6 1778.6 1790.2 1851.3 115394.0 124423.0 124771.0 126403.0 127484.0 11387.0 11524.1 11640.5 11744.6 12007.1 14279.9 14313.5 14319.4 14329.4 14333.3 8037.5 8319.5 8338.5 8455.7 8464.8 7967.4 8130.1 8199.6 8211.5 8354.2 4303.7 4437.6 4456.9 4507.2 4553.1 1320.4 1639.6 1642.9 1738.4 1877.2 794.0 808.1 870.9 874.2 876.7 19472.8 20316.3 20997.5 21246.3 21418.0 739.5 797.0 797.8 830.9 910.7 9528.6 10481.9 11131.8 11155.0 11200.8 567097.0 569017.2 574120.0 580176.0 584417.0 97780.0 101017.5 113651.0 115643.8 117499.0 944.8 954.8 961.9 967.8 973.2 15426.2 17989.6 18187.9 18550.6 18969.7 47659.0 50191.2 50463.7 50995.7 51599.7 75447.0 81195.0 82386.0 82387.6 83697.6 7788.4 7820.9 7947.7 7970.1 7982.6 10678.7 12475.4 12684.2 12720.7 12753.4 9327.6 10549.3 10927.7 10961.3 11029.1 8890.2 10581.9 10699.8 10983.8 11323.7 9163.5 10669.9 10888.9 11140.0 11253.4 7802.9 7868.5 7913.1 7991.9 8024.2 8869.4 9950.8 10890.8 11189.5 11203.6 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5421.86 Median:4927.73 stddev:3001.65 (60.9%) stddev-sans-first:2995.12 05:52:06 INFO - PROCESS | 1639 | Values: 1435.9 1456.0 1618.3 1630.3 1658.6 7619.5 7890.9 8099.5 8566.9 8573.1 8019.8 8356.5 8377.3 8470.5 8476.4 2313.9 2339.6 2341.8 2349.6 2353.1 2325.6 2369.2 2372.9 2373.8 2385.5 2325.0 2331.2 2346.2 2353.6 2354.2 2300.9 2304.5 2328.0 2330.6 2332.2 2333.2 2355.5 2356.3 2361.1 2361.7 8499.2 8537.3 8539.8 8540.9 8543.2 5019.9 5041.4 5226.7 5237.8 5257.7 2889.5 3393.8 3573.1 3625.7 3629.6 1028.6 1032.0 1071.7 1072.0 1088.5 10864.6 10923.4 10985.2 11041.6 11179.9 4481.4 4564.5 4670.8 4685.1 4721.5 7602.5 9391.1 9586.0 9590.9 9608.6 4768.6 5071.7 5197.5 5269.1 5278.8 4322.9 4329.1 4767.9 4782.7 4843.1 3389.5 3707.9 3820.0 3847.2 3855.7 1236.5 1245.3 1245.8 1251.1 1259.5 850.5 857.4 876.9 886.5 887.2 5468.6 5473.7 5602.4 5676.9 5722.4 413.0 420.4 430.3 432.9 435.6 3671.1 3751.8 3831.0 3842.7 3873.6 8444.8 10045.4 10238.5 10245.4 10284.8 9816.0 9941.5 10199.2 10202.0 10232.2 7861.9 7955.9 7969.3 7971.3 7971.7 5100.2 5463.6 5511.4 5556.0 5557.9 8061.9 9238.0 9288.9 9311.8 9423.4 9512.3 9533.1 9612.8 9614.9 9615.4 6567.5 6764.2 6936.0 7073.6 7211.0 7851.2 8029.1 8057.8 8059.9 8060.8 7054.3 7134.3 7159.5 7174.4 7264.0 6207.5 6236.7 6962.1 7070.8 7133.3 6597.9 6710.2 6750.7 6773.8 6778.3 4811.8 4861.2 4878.1 4906.7 4948.8 4303.7 4765.2 4783.8 4797.8 4988.4 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4463.41 Median:5057.81 stddev:1387.81 (27.4%) stddev-sans-first:1356.00 05:52:06 INFO - PROCESS | 1639 | Values: 296.1 296.4 301.1 307.5 311.3 4652.8 5106.7 5334.0 5476.8 5543.7 4520.4 5184.4 5307.5 5373.0 5397.6 3329.8 3512.7 3839.6 4141.8 4188.8 3422.2 3754.6 3836.5 4053.0 4201.5 3387.6 3390.4 4154.5 4160.5 4181.7 3711.6 4047.8 4122.3 4136.9 4183.1 3121.0 3472.3 3634.3 3637.4 3655.9 4396.7 4406.8 4803.3 5095.2 5248.7 4027.0 4286.9 4705.8 4712.2 4740.5 4775.3 5661.5 5689.5 5708.3 5725.9 3100.8 3332.9 3768.8 3923.4 3934.9 5126.6 5948.4 6004.7 6007.5 6029.5 3312.0 3549.5 3974.2 3983.8 4023.9 3941.2 4061.8 4079.3 4095.9 4123.5 3918.0 4132.4 4352.5 4655.5 4706.2 4671.3 4889.6 5353.7 5673.7 5804.9 1158.9 1208.7 1219.3 1254.5 1256.6 778.8 791.9 868.9 900.6 901.4 4811.9 5115.9 5129.8 5140.9 5151.1 4789.5 5212.3 5604.4 5639.4 5640.3 4372.5 4448.4 5048.9 5056.2 5156.3 2577.5 2746.6 2908.9 2969.4 2974.7 5231.6 5321.8 5431.5 5500.0 5794.7 4762.2 4964.9 5481.5 5714.3 5732.9 5521.9 5729.7 5818.7 5846.2 5865.8 5209.8 5330.3 5393.4 5558.7 5595.3 4760.7 5059.4 5484.6 5498.3 5543.6 5170.9 5462.8 5553.7 5605.9 5621.4 3205.8 3452.1 3783.0 3799.5 3844.2 4799.7 5084.7 5221.7 5588.7 5605.0 4717.1 5275.5 5383.2 5451.0 5457.5 5735.4 5777.9 5799.2 5810.9 5819.6 5244.7 5367.4 5428.9 5647.6 5649.3 5801.4 5806.6 5807.7 5808.4 5811.6 4672.7 4910.5 5157.8 5508.9 5626.3 05:52:06 INFO - PROCESS | 1639 | 05:52:06 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20333.82 Median:4504.77 stddev:30053.41 (667.1%) stddev-sans-first:30101.53 05:52:06 INFO - PROCESS | 1639 | Values: 691.9 699.3 711.4 751.4 794.0 11015.9 14696.9 14740.0 14751.6 14795.2 12831.5 13475.9 13596.9 13600.4 13620.0 805.2 821.6 822.8 824.6 825.0 650.8 673.4 804.7 816.3 821.1 854.2 860.3 862.5 862.6 864.5 850.9 854.9 856.3 856.8 857.4 627.3 634.1 634.3 634.6 636.0 13745.5 14346.6 14360.5 14480.6 14480.9 12955.7 13273.2 13282.7 13328.2 13451.2 10714.4 10895.7 11400.9 11502.8 11696.3 3600.5 3651.7 3724.2 3762.6 3765.7 88265.7 88414.2 89744.0 90028.0 90447.0 7957.9 8752.0 9953.4 10084.3 10648.9 19811.7 20015.0 20111.9 20206.3 20312.8 6397.6 6421.2 7474.3 7509.6 7605.2 4170.3 4175.7 4227.4 4240.1 4244.3 2988.5 3428.8 3468.1 3471.7 3476.9 596.9 609.5 634.5 640.9 645.1 515.8 523.8 623.9 628.3 652.6 8793.7 8899.2 8957.0 8987.9 9126.8 608.6 613.4 639.7 642.7 658.2 4203.2 4306.0 4350.1 4474.0 4535.5 107397.5 108974.0 112264.0 113660.2 116082.0 78058.2 94514.0 94594.0 98255.5 99045.0 88685.6 88739.6 89566.6 90356.0 90554.0 8566.9 8592.2 8636.9 8800.9 9174.1 48665.0 50260.0 50524.0 51164.0 54201.5 53480.7 67830.0 72697.0 72914.0 73688.3 12125.3 12243.8 12277.2 12392.0 12587.7 13743.2 13803.2 13976.5 14056.2 14250.6 12342.6 12679.9 12844.5 13148.9 13414.9 12828.0 12986.6 13249.7 13298.8 13548.0 12361.9 12752.4 12857.0 13262.1 13339.9 10651.7 10725.8 10786.6 10879.5 10958.4 10004.4 10201.2 10886.2 10919.1 11037.7 05:52:06 INFO - PROCESS | 1639 | -------- Summary: end -------- 05:52:06 INFO - PROCESS | 1639 | 05:52:07 INFO - TEST-INFO | 1639: exit 0 05:52:07 INFO - TEST-OK | dromaeo_css | took 1117599ms 05:52:07 INFO - TEST-START | kraken 05:52:07 INFO - operating with platform_type : mac_ 05:52:07 INFO - Initialising browser for kraken test... 05:52:07 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpMZaFF3/profile http://localhost:49249/getInfo.html) 05:52:10 INFO - PROCESS | 1650 | 2016-11-22 05:52:10.624 plugin-container[1652:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 05:52:10 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:10 INFO - PROCESS | 1650 | 2016-11-22 05:52:10.625 plugin-container[1652:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:52:10 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:10 INFO - PROCESS | 1650 | 2016-11-22 05:52:10.625 plugin-container[1652:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:52:10 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:52:10 INFO - PROCESS | 1650 | colorDepth:24 05:52:10 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:52:10 INFO - PROCESS | 1650 | __metrics 05:52:10 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:52:11 INFO - TEST-INFO | 1650: exit 0 05:52:11 INFO - Browser initialized. 05:52:11 INFO - Running cycle 1/1 for kraken test... 05:52:11 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpMZaFF3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:14 INFO - PROCESS | 1653 | 2016-11-22 05:52:14.368 plugin-container[1654:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:52:14 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:14 INFO - PROCESS | 1653 | 2016-11-22 05:52:14.368 plugin-container[1654:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:52:14 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:14 INFO - PROCESS | 1653 | 2016-11-22 05:52:14.368 plugin-container[1654:10162] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:52:24 INFO - PROCESS | 1653 | RSS: Main: 243253248 05:52:24 INFO - PROCESS | 1653 | 05:52:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:27 INFO - PROCESS | 1653 | RSS: Main: 255262720 05:52:27 INFO - PROCESS | 1653 | 05:52:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 05:52:30 INFO - PROCESS | 1653 | RSS: Main: 262811648 05:52:30 INFO - PROCESS | 1653 | 05:52:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 05:52:35 INFO - PROCESS | 1653 | RSS: Main: 265785344 05:52:35 INFO - PROCESS | 1653 | 05:52:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 05:52:37 INFO - PROCESS | 1653 | RSS: Main: 266874880 05:52:37 INFO - PROCESS | 1653 | 05:52:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:40 INFO - PROCESS | 1653 | RSS: Main: 268484608 05:52:40 INFO - PROCESS | 1653 | 05:52:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:43 INFO - PROCESS | 1653 | RSS: Main: 274026496 05:52:43 INFO - PROCESS | 1653 | 05:52:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:47 INFO - PROCESS | 1653 | RSS: Main: 273711104 05:52:47 INFO - PROCESS | 1653 | 05:52:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 05:52:51 INFO - PROCESS | 1653 | RSS: Main: 274087936 05:52:51 INFO - PROCESS | 1653 | 05:52:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:53 INFO - PROCESS | 1653 | RSS: Main: 273231872 05:52:53 INFO - PROCESS | 1653 | 05:52:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:56 INFO - PROCESS | 1653 | RSS: Main: 273182720 05:52:56 INFO - PROCESS | 1653 | 05:52:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:59 INFO - PROCESS | 1653 | RSS: Main: 273170432 05:52:59 INFO - PROCESS | 1653 | 05:53:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:03 INFO - PROCESS | 1653 | RSS: Main: 273440768 05:53:03 INFO - PROCESS | 1653 | 05:53:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:06 INFO - PROCESS | 1653 | RSS: Main: 273371136 05:53:06 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 05:53:08 INFO - PROCESS | 1653 | RSS: Main: 275161088 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | __start_tp_report 05:53:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 05:53:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 05:53:08 INFO - PROCESS | 1653 | |i|pagename|runs| 05:53:08 INFO - PROCESS | 1653 | |0;ai-astar;115;117;118;118;119;118;117;118;118;118 05:53:08 INFO - PROCESS | 1653 | |1;audio-beat-detection;143;105;113;155;139;141;140;118;143;116 05:53:08 INFO - PROCESS | 1653 | |2;audio-dft;194;214;207;206;202;277;275;207;208;276 05:53:08 INFO - PROCESS | 1653 | |3;audio-fft;82;60;61;87;61;62;60;62;82;60 05:53:08 INFO - PROCESS | 1653 | |4;audio-oscillator;91;73;88;72;74;92;86;70;70;89 05:53:08 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;105;106;90;87;89;91;91;88;92;87 05:53:08 INFO - PROCESS | 1653 | |6;imaging-darkroom;127;143;132;144;140;97;102;143;97;96 05:53:08 INFO - PROCESS | 1653 | |7;imaging-desaturate;95;137;148;84;123;149;126;91;141;97 05:53:08 INFO - PROCESS | 1653 | |8;json-parse-financial;57;58;57;56;58;60;58;57;60;59 05:53:08 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;73;69;69;70;74;97;96;69;71;71 05:53:08 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;89;92;93;89;90;87;89;91;106;107 05:53:08 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;152;148;166;158;161;149;148;169;214;190 05:53:08 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;180;201;225;223;171;179;174;177;175;176 05:53:08 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;74;89;85;89;90;87;77;70;68;70 05:53:08 INFO - PROCESS | 1653 | __end_tp_report 05:53:08 INFO - PROCESS | 1653 | __start_cc_report 05:53:08 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,959 05:53:08 INFO - PROCESS | 1653 | __end_cc_report 05:53:08 INFO - PROCESS | 1653 | __startTimestamp1479822788945__endTimestamp 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | ------- Summary: start ------- 05:53:08 INFO - PROCESS | 1653 | Number of tests: 14 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:117.60 Median:118.00 stddev:1.07 (0.9%) stddev-sans-first:0.60 05:53:08 INFO - PROCESS | 1653 | Values: 115.0 117.0 118.0 118.0 119.0 118.0 117.0 118.0 118.0 118.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:140.50 stddev:16.67 (11.9%) stddev-sans-first:17.14 05:53:08 INFO - PROCESS | 1653 | Values: 143.0 105.0 113.0 155.0 139.0 141.0 140.0 118.0 143.0 116.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:226.60 Median:211.00 stddev:34.46 (16.3%) stddev-sans-first:34.47 05:53:08 INFO - PROCESS | 1653 | Values: 194.0 214.0 207.0 206.0 202.0 277.0 275.0 207.0 208.0 276.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:67.70 Median:62.00 stddev:11.13 (17.9%) stddev-sans-first:10.53 05:53:08 INFO - PROCESS | 1653 | Values: 82.0 60.0 61.0 87.0 61.0 62.0 60.0 62.0 82.0 60.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:87.00 stddev:9.38 (10.8%) stddev-sans-first:9.15 05:53:08 INFO - PROCESS | 1653 | Values: 91.0 73.0 88.0 72.0 74.0 92.0 86.0 70.0 70.0 89.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:89.50 stddev:7.01 (7.8%) stddev-sans-first:5.83 05:53:08 INFO - PROCESS | 1653 | Values: 105.0 106.0 90.0 87.0 89.0 91.0 91.0 88.0 92.0 87.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:122.10 Median:143.50 stddev:21.45 (14.9%) stddev-sans-first:22.68 05:53:08 INFO - PROCESS | 1653 | Values: 127.0 143.0 132.0 144.0 140.0 97.0 102.0 143.0 97.0 96.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:119.10 Median:116.50 stddev:25.13 (21.6%) stddev-sans-first:25.09 05:53:08 INFO - PROCESS | 1653 | Values: 95.0 137.0 148.0 84.0 123.0 149.0 126.0 91.0 141.0 97.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:1.33 (2.3%) stddev-sans-first:1.36 05:53:08 INFO - PROCESS | 1653 | Values: 57.0 58.0 57.0 56.0 58.0 60.0 58.0 57.0 60.0 59.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:72.00 stddev:10.99 (15.3%) stddev-sans-first:11.61 05:53:08 INFO - PROCESS | 1653 | Values: 73.0 69.0 69.0 70.0 74.0 97.0 96.0 69.0 71.0 71.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:89.50 stddev:7.17 (8.0%) stddev-sans-first:7.43 05:53:08 INFO - PROCESS | 1653 | Values: 89.0 92.0 93.0 89.0 90.0 87.0 89.0 91.0 106.0 107.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:163.50 stddev:21.37 (13.1%) stddev-sans-first:22.10 05:53:08 INFO - PROCESS | 1653 | Values: 152.0 148.0 166.0 158.0 161.0 149.0 148.0 169.0 214.0 190.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.10 Median:179.50 stddev:20.62 (11.5%) stddev-sans-first:21.66 05:53:08 INFO - PROCESS | 1653 | Values: 180.0 201.0 225.0 223.0 171.0 179.0 174.0 177.0 175.0 176.0 05:53:08 INFO - PROCESS | 1653 | 05:53:08 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:86.00 stddev:8.97 (10.4%) stddev-sans-first:9.26 05:53:08 INFO - PROCESS | 1653 | Values: 74.0 89.0 85.0 89.0 90.0 87.0 77.0 70.0 68.0 70.0 05:53:08 INFO - PROCESS | 1653 | -------- Summary: end -------- 05:53:08 INFO - PROCESS | 1653 | 05:53:10 INFO - TEST-INFO | 1653: exit 0 05:53:10 INFO - TEST-OK | kraken | took 62605ms 05:53:10 INFO - SUITE-END | took 1180s 05:53:10 INFO - Completed test suite (00:19:40) 05:53:10 INFO - javascript benchmark 05:53:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6927.054556139949, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3349.0, 3356.228067544465, 3409.846556904939, 3414.265932824442, 3420.196253880339, 5625.943740562593, 5650.3219613646315, 5682.374938756739, 5689.687067211302, 5757.366689664133, 5640.153976903456, 5675.51758100562, 5716.114002329646, 5726.3414707308675, 5738.626989245696, 5097.898042039157, 5175.301334319875, 5236.057509648262, 5278.498542638443, 5305.920411193835, 5155.381354237478, 5242.397124330712, 5293.258943747878, 5310.468953104678, 5340.973295133519, 5082.949170508285, 5134.383873935151, 5140.640155189139, 5198.856251624656, 5263.236830659533, 4352.869413917556, 5189.247559103909, 5202.713850738208, 5208.635395522315, 5209.739513024334, 5112.36095488064, 5125.897482050375, 5149.99575082859, 5153.252778347156, 5168.793248270103, 4886.60907127429, 5170.146925757245, 5632.0, 5655.208270842086, 5675.5743319250805, 6118.588722523077, 6152.046432802923, 6168.44483996438, 6171.413715697002, 6174.506039516873, 6212.378762123797, 6270.498360131179, 6293.056041593752, 6296.851574212894, 6326.240851097852, 5776.711164441803, 5845.976954033027, 5860.677662728505, 5863.237779088734, 5906.936751384749, 5125.821061155955, 5254.6847189168775, 5988.9101663475085, 6214.658193799294, 6307.747690092437, 4936.605071594264, 6053.969730151321, 6153.569250152446, 6155.415235552616, 6203.7828675997025, 5250.65870718402, 6106.877862442726, 6270.905936410957, 6282.403171698681, 6295.213098362705, 5785.640374511986, 6076.5442591805795, 6080.908786368208, 6125.41859352575, 6149.41580549847, 5514.869451762379, 5677.602567820296, 6223.066540019034, 6242.375762423812, 6247.500199983991, 3848.5574158972076, 4917.852464426073, 4927.753612319441, 4928.48250933654, 4949.208126699711, 4349.412829268072, 4385.627221686129, 4392.077663690597, 4423.668224883147, 4454.1537107950035, 5671.943280567224, 5709.800156994485, 5712.971435142797, 5727.37001916429, 5812.709364531756, 5344.0, 6288.434040936338, 6290.968545157245, 6291.087792270096, 6297.874042519124, 4439.822407103744, 5804.0, 5846.123081537803, 5865.648061116347, 5895.174675545427, 5285.497877701612, 5325.0148722486465, 5356.223347614574, 5374.946250537445, 5408.080626293539, 6232.345603711545, 6238.844028899223, 6248.4688801452385, 6256.343083976208, 6311.431971122621, 6267.2479302484, 6278.8430289241305, 6284.811455656338, 6292.465140463112, 6313.431791138636, 6174.567780255518, 6228.750849965951, 6307.274663413767, 6314.242290924934, 6320.083587879823, 5813.854653633693, 5941.792037278675, 6096.664683442454, 6113.57204995646, 6117.096718007379, 5496.560275178056, 6163.260408750979, 6170.290416601969, 6217.09852071438, 6218.844528886814, 6092.969535152296, 6108.786192483241, 6117.204763380732, 6133.365267548864, 6156.230471191101, 6135.233095863017, 6140.600860944024, 6144.38556144382, 6155.599886007395, 6166.691665416801, 5676.659400435987, 5851.9173952818865, 5859.9121013183985, 5863.560232982458, 5950.583459157818, 6038.275406951194, 6064.969675151596, 6086.604370716066, 6131.988406491147, 6140.964409184605, 5961.225040744675, 6007.90988135187, 6011.338752737108, 6011.6693581853415, 6025.0], "value": 5626.5906026530365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.42590678232733, 515.6854318865493, 522.9671398986999, 526.670830730793, 539.9082156033473, 33954.83022584887, 37870.4319435208, 37956.430653540185, 37971.0, 37987.86036418908, 37736.05659858505, 38264.617353826456, 38292.0, 38309.61690383095, 38399.80800095996, 8984.595693193822, 9207.769805754842, 9222.769430764218, 9224.123708247706, 9239.353245272834, 7530.510516816416, 7665.655045522964, 8875.890513685788, 9152.039035901234, 9168.495732734697, 9122.908770912272, 9156.8626470603, 9157.496337701425, 9179.541022948826, 9182.449053056804, 9069.863952040725, 9136.360454768135, 9140.177384035402, 9144.314176436794, 9145.451272923647, 7815.0, 9149.634014639407, 9154.49650269235, 9178.4034037788, 9187.219086377667, 37183.81408092969, 37423.4386484203, 37511.437328439824, 37607.24785504302, 37672.62327376719, 3203.4393981053454, 3247.0, 3301.6863397977395, 3423.2608229587036, 3674.5590529136407, 28741.85629071862, 29286.707132928823, 29483.410331793242, 29589.260268493246, 30202.73479489047, 3151.196444906534, 3182.8859899035383, 3218.5494030835466, 3242.4487837067754, 3317.552130462405, 352753.0, 354036.0, 354446.0, 355639.0, 356709.0, 31915.52126718101, 32811.83594082062, 51748.0, 52039.95920081577, 52119.73940130351, 32411.027669169962, 32933.3413331732, 33172.0, 33215.83392083024, 33258.501122482696, 13699.246541440316, 13832.446702132003, 13841.100328478818, 13857.722845543032, 13918.09532380392, 11469.655910322703, 11566.710832229093, 11570.0, 11574.074074074053, 11578.884211157949, 7557.4331925105835, 7604.2395760423515, 7622.047244094489, 7625.504342217738, 7667.616619169019, 2796.1891051595217, 2937.8090424122365, 3267.2975310308398, 3272.640009598942, 3290.5393244945726, 854.6752234150982, 864.8832407625016, 871.9782005449914, 871.9912800871911, 941.7975135345933, 24348.756512434647, 25695.87152064228, 28275.43449131047, 29249.415011699646, 29498.165238518854, 913.4291068082448, 970.2092794372563, 988.5255077562811, 988.6292640259902, 1042.2443728296923, 14248.857511424752, 14342.928285358716, 14526.787231702689, 14913.70172596542, 15196.0, 628658.0, 629121.0, 631142.0, 633755.0, 636596.0, 53345.0, 54175.0, 54190.645233869465, 54195.45804541904, 54276.72861635745, 1304.2174695182814, 1323.781576039962, 1325.5360623781598, 1348.2382453913508, 1349.9865001350058, 23079.88460057689, 23496.76503235014, 28097.859510703132, 28123.7344319502, 30020.549691755077, 46282.0, 46457.30314045224, 46522.0, 46647.76676116598, 46693.299600506696, 565092.0, 570093.0, 570934.0, 571085.0, 571488.0, 24980.250592482254, 25135.371615709755, 25423.491530169664, 25443.491130176924, 25604.359891002874, 42321.788391057846, 43263.0, 43866.56133438625, 44147.585241475594, 44226.33660495031, 36630.08424789402, 36674.44988325124, 37434.689785857365, 37738.0, 37958.62041379551, 36449.0, 36551.817240913624, 36725.81637091797, 37594.624053760206, 37704.0, 35995.10012249715, 36038.639613603526, 37011.0, 37236.441453378764, 38898.416523752734, 28333.00834470866, 29887.402251955275, 31903.202419939687, 32000.35999280048, 33166.0, 22200.666989995487, 23661.29016129519, 24467.38831529226, 24550.50898982046, 24681.506369872142], "value": 17407.712074471245, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.3980136747018, 359.97480176387654, 360.83762306961876, 378.2001067741727, 395.11100024943875, 18628.627427451444, 19724.506887327825, 19970.70043949342, 20502.89748551259, 20753.273635422764, 18195.727064094048, 19160.0, 20087.598248035032, 20424.285150019754, 20515.871627060544, 5574.470425309589, 5659.405762394951, 5919.2009078774245, 5940.0, 5945.643261404308, 3814.065553939275, 4008.158286759784, 5749.770009199627, 5869.442402971732, 6050.2739671239515, 6019.819405417845, 6075.665838378887, 6080.361562035989, 6092.177556029946, 6131.110988906606, 6287.276963149227, 6483.481321494269, 6623.07276981223, 6662.633555154464, 6673.799786006428, 5003.699778013294, 5205.219217117425, 5851.32709738377, 5880.382559831241, 5880.70596470179, 12648.49406023758, 12894.935525322408, 12924.676883077904, 12931.224126552343, 17719.202635881415, 2080.760712518065, 2090.592334494774, 2092.612866619665, 2106.2838634864065, 2219.6892435059103, 9071.228945539637, 9675.564599593035, 10109.949450252776, 10231.693049208536, 10285.331453455501, 1677.211710496077, 1752.5618595351161, 1778.6264884374168, 1790.239148361954, 1851.278001579385, 115394.0, 124423.0, 124771.0, 126403.0, 127484.0, 11386.975172234539, 11524.135689823297, 11640.476178571984, 11744.58893938708, 12007.099467539969, 14279.857201427852, 14313.499027533986, 14319.35562899672, 14329.35517901697, 14333.283335833166, 8037.477563958441, 8319.459235149818, 8338.499690018618, 8455.746327610059, 8464.830703385898, 7967.3626109911065, 8130.065042520067, 8199.631016604268, 8211.466254693425, 8354.164583541597, 4303.677224208197, 4437.5935614874825, 4456.866294011184, 4507.166174257773, 4553.066621342617, 1320.42561485754, 1639.6474757927103, 1642.884998050125, 1738.3828740797087, 1877.1552801239498, 793.9880901786478, 808.0747544062016, 870.9085546017703, 874.1957399192758, 876.6581033396981, 19472.805271947098, 20316.28892988768, 20997.475063123544, 21246.25638102659, 21418.03618837156, 739.5156172706886, 797.0236460336158, 797.8284668796236, 830.8504469195482, 910.6949172027445, 9528.618855245852, 10481.947590262153, 11131.833022504668, 11154.996050355507, 11200.831987520194, 567097.0, 569017.1549142228, 574120.0, 580176.0, 584417.0, 97780.0, 101017.484737729, 113651.0, 115643.7960291772, 117499.0, 944.7732544189482, 954.7565370830393, 961.9326647134633, 967.8403063494444, 973.1679414100984, 15426.22868856575, 17989.55026124305, 18187.909060455142, 18550.628987420445, 18969.715454268473, 47659.04681906273, 50191.24713129233, 50463.74768126136, 50995.745021274655, 51599.74200128976, 75447.0, 81195.0, 82386.0, 82387.58806205931, 83697.58151209205, 7788.415868809975, 7820.921790782247, 7947.682092716226, 7970.083440404428, 7982.560959147303, 10678.679639610824, 12475.438605262603, 12684.175528590613, 12720.74558508843, 12753.362331883183, 9327.626894924128, 10549.261551691616, 10927.672169834917, 10961.287516311415, 11029.062529685067, 8890.199882010651, 10581.947090264499, 10699.839502407314, 10983.835242471529, 11323.716907077389, 9163.450192988443, 10669.91936572361, 10888.891111088788, 11139.997400234019, 11253.437328133725, 7802.882956755539, 7868.527888326718, 7913.089994650459, 7991.880121798062, 8024.157463466252, 8869.423487473294, 9950.751231219278, 10890.78218435611, 11189.496472658913, 11203.582907213378], "value": 9080.414342124493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.8779503742182, 1456.0390142505944, 1618.3364820423628, 1630.3397124164717, 1658.5604814724093, 7619.466637335375, 7890.8816367754525, 8099.473534220279, 8566.914330856698, 8573.14268573143, 8019.799505012377, 8356.456830306026, 8377.287930525912, 8470.491770493774, 8476.449030812993, 2313.8727369994645, 2339.5671800716837, 2341.75411581784, 2349.5770761262966, 2353.070537137838, 2325.5813953488364, 2369.1707902234252, 2372.92881213563, 2373.8100951923807, 2385.510970251103, 2324.9651255231183, 2331.2423462374713, 2346.214018303862, 2353.646952957053, 2354.2348736660656, 2300.8504447210876, 2304.5045315257216, 2327.9883600582057, 2330.5571941331264, 2332.1837356925025, 2333.2067097187037, 2355.5171189906027, 2356.26955643751, 2361.1263832381956, 2361.716594008713, 8499.192576705203, 8537.317014638815, 8539.786505337353, 8540.871886921701, 8543.231109200202, 5019.899602007976, 5041.369828771403, 5226.745581060556, 5237.764300606482, 5257.658252213632, 2889.4798936191687, 3393.779404338735, 3573.1424458130123, 3625.7099432045375, 3629.6370362963758, 1028.6348346337018, 1032.0097107567922, 1071.6784964510616, 1071.9678409647722, 1088.4829705889717, 10864.565417383295, 10923.39921304328, 10985.176111791647, 11041.558337666564, 11179.944100279447, 4481.4174157359985, 4564.475085365226, 4670.813167473331, 4685.086408150378, 4721.527847215251, 7602.543847369176, 9391.060893910691, 9586.0, 9590.90409095914, 9608.615655373847, 4768.642351823558, 5071.732066983254, 5197.454267301954, 5269.104252277122, 5278.788848446019, 4322.891927701832, 4329.090890912944, 4767.904641907143, 4782.689125206851, 4843.0798148352405, 3389.4576867701544, 3707.8516859325864, 3819.9809000955374, 3847.1920896611464, 3855.7493762905883, 1236.4806781151938, 1245.296407529761, 1245.7944439167436, 1251.1054595963928, 1259.508791571288, 850.5024560632099, 857.4041041476114, 876.8903887014123, 886.4592598514901, 887.2325438495654, 5468.562514998791, 5473.698946557977, 5602.439756024446, 5676.914846277309, 5722.37053924068, 412.99793501032303, 420.38833497261413, 430.3329838749925, 432.88744926318753, 435.6427729262038, 3671.0822294426393, 3751.756135851161, 3830.9808450957566, 3842.6733727633464, 3873.5545412277384, 8444.831103377897, 10045.447500387549, 10238.53926573291, 10245.385276883262, 10284.794304113875, 9816.0, 9941.502924853728, 10199.18406527476, 10202.0, 10232.181425485944, 7861.882071768928, 7955.880661789962, 7969.3226075784205, 7971.322437592869, 7971.681132754741, 5100.183970564692, 5463.562914966873, 5511.366192887869, 5556.0, 5557.861053473695, 8061.87907181381, 9238.0, 9288.907110929074, 9311.813763724553, 9423.38747981379, 9512.336590371737, 9533.094356036165, 9612.75968100775, 9614.9038509614, 9615.423074615546, 6567.507436942344, 6764.188297404343, 6936.0, 7073.610951397723, 7211.026511421101, 7851.2148785121035, 8029.076656184614, 8057.838843222986, 8059.919400805917, 8060.83878322442, 7054.329838665319, 7134.250903655249, 7159.534630249017, 7174.426045916419, 7263.963680181565, 6207.534434917489, 6236.719347629277, 6962.0632478973, 7070.7525236616475, 7133.251008644018, 6597.901031484627, 6710.228323742832, 6750.662466876538, 6773.830654233487, 6778.254392016973, 4811.78346974394, 4861.246506791453, 4878.146324393288, 4906.681065730716, 4948.777305021355, 4303.698741088219, 4765.166095933268, 4783.756223381876, 4797.832075877328, 4988.376452943382], "value": 4318.250270683069, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.09395250533385, 296.4131020579253, 301.12523120335436, 307.5264093296323, 311.32442599558954, 4652.767361631918, 5106.744662766865, 5333.973330133354, 5476.7535460904255, 5543.667379957195, 4520.3897473841025, 5184.444666600024, 5307.469253074691, 5372.979133964544, 5397.6250449802055, 3329.833508324586, 3512.7365447591533, 3839.616038396152, 4141.7929103544775, 4188.769617671027, 3422.161570415252, 3754.6057663945303, 3836.489612005012, 4052.959470405288, 4201.45811783871, 3387.627360990289, 3390.406678831207, 4154.459920210368, 4160.479940007499, 4181.665466762652, 3711.6102809204913, 4047.826130422159, 4122.2579935611575, 4136.855210067633, 4183.142455796585, 3121.0480803354967, 3472.3402553515, 3634.309481198564, 3637.4177232903508, 3655.94516082259, 4396.692231543793, 4406.823727050914, 4803.3035209894715, 5095.197641297272, 5248.71132087735, 4026.979865100656, 4286.892827679302, 4705.764711764432, 4712.222483290253, 4740.549647783455, 4775.254228818443, 5661.46216109469, 5689.487946084831, 5708.286464191976, 5725.914111288334, 3100.7751937984594, 3332.933341333184, 3768.7939859245053, 3923.411018537497, 3934.9016274593077, 5126.5642420394315, 5948.434898684618, 6004.674297357217, 6007.468095635629, 6029.547783916224, 3311.983440082832, 3549.5208146900354, 3974.18529201513, 3983.780892050965, 4023.9195216095513, 3941.1526521797377, 4061.7766022869027, 4079.286124928126, 4095.8531611148924, 4123.525794533607, 3917.9608203918165, 4132.359484279941, 4352.542982986741, 4655.51117132703, 4706.223473126965, 4671.252599584051, 4889.633277504201, 5353.678779273256, 5673.659580425187, 5804.854878628068, 1158.8841115888345, 1208.6917835951776, 1219.2928101700993, 1254.5358217459598, 1256.6418570707306, 778.8130848596296, 791.9406044546566, 868.8740132680853, 900.599233341157, 901.4450073622947, 4811.927821082687, 5115.928049140104, 5129.820456284012, 5140.938658459775, 5151.0851752667495, 4789.544993225638, 5212.322398088224, 5604.3835178130375, 5639.43605639441, 5640.266765320564, 4372.453443319585, 4448.399466072039, 5048.879470622347, 5056.241563765465, 5156.329677141947, 2577.523158215736, 2746.5742809864505, 2908.9127326180246, 2969.4209629122547, 2974.691135900197, 5231.555317797954, 5321.840344789663, 5431.456854314537, 5499.955008548363, 5794.739236734357, 4762.238041913277, 4964.914735810968, 5481.4511252267885, 5714.2571465710025, 5732.942670573324, 5521.944780552143, 5729.713514324267, 5818.738156782955, 5846.1815345852465, 5865.824025279248, 5209.817656382009, 5330.307060082165, 5393.352797664148, 5558.6937069787755, 5595.272614560081, 4760.690555113906, 5059.369128053465, 5484.61607687458, 5498.3127109111365, 5543.584231182596, 5170.914108037355, 5462.804259508073, 5553.722973580812, 5605.887882242414, 5621.353544342453, 3205.8397080145432, 3452.085197422635, 3782.981085094557, 3799.506064211635, 3844.2311537692012, 4799.66402351832, 5084.669496482717, 5221.660592061504, 5588.6646801192055, 5605.002647167492, 4717.0754132438105, 5275.498827611371, 5383.16560933056, 5450.972745136249, 5457.454254574511, 5735.3977832327, 5777.855553611025, 5799.188113664006, 5810.912836307374, 5819.592628515963, 5244.711540865289, 5367.4364191759305, 5428.891422171613, 5647.604667673388, 5649.30520843737, 5801.448862358069, 5806.622569532967, 5807.651540907559, 5808.36108028125, 5811.593188476766, 4672.696274742131, 4910.484399138039, 5157.793688252428, 5508.944910551004, 5626.2967129108865], "value": 4017.1845685426283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.9481038922082, 699.2832346844484, 711.3846522757816, 751.3983254835201, 793.968241270349, 11015.889841101587, 14696.926515367408, 14740.041897276684, 14751.557453276391, 14795.186264755448, 12831.48674053039, 13475.865241347583, 13596.93201533991, 13600.387982540784, 13620.0, 805.1867613710151, 821.5686764448683, 822.7819627798638, 824.6206744897324, 824.9587520623945, 650.7624716978293, 673.3973094080794, 804.7384600004992, 816.3265306122443, 821.1008945204991, 854.239726643288, 860.3203469259278, 862.4609618988133, 862.6204470033197, 864.5331520978664, 850.9361797865154, 854.9230569248798, 856.3149480415642, 856.8329175810688, 857.4255248983196, 627.3381582430579, 634.1027446163673, 634.3276127305026, 634.6287421858218, 635.9936400636027, 13745.518906838313, 14346.56960291193, 14360.497382591559, 14480.637984050378, 14480.855191448056, 12955.67610809728, 13273.203607783564, 13282.667933301647, 13328.200307981551, 13451.192928424325, 10714.357138571711, 10895.673129806119, 11400.942995285053, 11502.769944601145, 11696.298222106701, 3600.459931010343, 3651.6713495785243, 3724.1806802503406, 3762.623737626243, 3765.6987441004653, 88265.67601485983, 88414.23171536533, 89744.0, 90027.99999999935, 90446.99999999935, 7957.925680033237, 8751.956240218758, 9953.402795832273, 10084.344517606332, 10648.893511064945, 19811.702824457643, 20014.99925003744, 20111.893845838327, 20206.292779752934, 20312.79687203109, 6397.616143031433, 6421.197350331208, 7474.327310542078, 7509.612983770287, 7605.239476052461, 4170.311898536768, 4175.728577642443, 4227.365895115758, 4240.109576988805, 4244.257254980428, 2988.5367767995995, 3428.777129486575, 3468.1156305142026, 3471.7396195285455, 3476.860925562949, 596.9076589840581, 609.4819403507072, 634.5494698763837, 640.9487241020707, 645.0614356111846, 515.7936825269923, 523.8035736598775, 623.9469645080128, 628.3151365012159, 652.6084349390328, 8793.689933304595, 8899.199072083415, 8956.955215223883, 8987.865182022275, 9126.81746365069, 608.57703895792, 613.4264462727364, 639.7217210513442, 642.7075680565425, 658.2331583705026, 4203.180379825966, 4305.9569404306185, 4350.108227813291, 4474.015716542355, 4535.523770004102, 107397.463012686, 108974.0, 112264.0, 113660.22417085725, 116082.0, 78058.2194178051, 94514.0, 94594.0, 98255.50872245737, 99045.0, 88685.55657221672, 88739.55630221807, 89566.55216723874, 90356.0, 90553.99999999868, 8566.901109228278, 8592.163421335654, 8636.913630863612, 8800.867986980073, 9174.128457796498, 48664.99999999858, 50260.0, 50523.989520210125, 51164.0, 54201.45798541964, 53480.73259633677, 67830.0, 72697.0, 72914.0, 73688.26311737028, 12125.27248365101, 12243.75512489763, 12277.20198187115, 12392.0, 12587.685307867376, 13743.175409475463, 13803.17180969145, 13976.510822121178, 14056.226907519775, 14250.572482825532, 12342.568010119603, 12679.93660031694, 12844.486220551073, 13148.934255328662, 13414.865851341752, 12828.0, 12986.610401687965, 13249.735005299648, 13298.800517992044, 13548.0, 12361.93819030899, 12752.362381881054, 12857.03572232105, 13262.137961032888, 13339.932805375352, 10651.680449586525, 10725.785484290427, 10786.568537258421, 10879.456027198767, 10958.397288149228, 10004.449755263238, 10201.234907381828, 10886.183536234972, 10919.071878890383, 11037.72405689832], "value": 7160.218318564232, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 118.0, 118.0, 119.0, 118.0, 117.0, 118.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 105.0, 113.0, 155.0, 139.0, 141.0, 140.0, 118.0, 143.0, 116.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 214.0, 207.0, 206.0, 202.0, 277.0, 275.0, 207.0, 208.0, 276.0], "value": 226.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 87.0, 61.0, 62.0, 60.0, 62.0, 82.0, 60.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 88.0, 72.0, 74.0, 92.0, 86.0, 70.0, 70.0, 89.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 90.0, 87.0, 89.0, 91.0, 91.0, 88.0, 92.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 143.0, 132.0, 144.0, 140.0, 97.0, 102.0, 143.0, 97.0, 96.0], "value": 122.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 137.0, 148.0, 84.0, 123.0, 149.0, 126.0, 91.0, 141.0, 97.0], "value": 119.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 56.0, 58.0, 60.0, 58.0, 57.0, 60.0, 59.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 69.0, 70.0, 74.0, 97.0, 96.0, 69.0, 71.0, 71.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 93.0, 89.0, 90.0, 87.0, 89.0, 91.0, 106.0, 107.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 148.0, 166.0, 158.0, 161.0, 149.0, 148.0, 169.0, 214.0, 190.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 201.0, 225.0, 223.0, 171.0, 179.0, 174.0, 177.0, 175.0, 176.0], "value": 188.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 89.0, 85.0, 89.0, 90.0, 87.0, 77.0, 70.0, 68.0, 70.0], "value": 79.9, "unit": "score"}]}]} 05:53:10 INFO - Return code: 0 05:53:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:10 ERROR - # TBPL SUCCESS # 05:53:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:10 INFO - 2016-11-22 05:53:10.741 screenresolution[1658:10552] starting screenresolution argv=screenresolution get 05:53:10 INFO - 2016-11-22 05:53:10.743 screenresolution[1658:10552] Display 0: 1600x1200x32@60 05:53:10 INFO - 2016-11-22 05:53:10.749 screenresolution[1659:10555] starting screenresolution argv=screenresolution list 05:53:10 INFO - Available Modes on Display 0 05:53:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:10 INFO - Intel Iris: 05:53:10 INFO - Chipset Model: Intel Iris 05:53:10 INFO - Type: GPU 05:53:10 INFO - Bus: Built-In 05:53:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:10 INFO - Vendor: Intel (0x8086) 05:53:10 INFO - Device ID: 0x0a2e 05:53:10 INFO - Revision ID: 0x0009 05:53:10 INFO - Displays: 05:53:10 INFO - Display: 05:53:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:10 INFO - Display Serial Number: 1600x1200 60 05:53:10 INFO - Main Display: Yes 05:53:10 INFO - Mirror: Off 05:53:10 INFO - Online: Yes 05:53:10 INFO - Rotation: Supported 05:53:10 INFO - Return code: 0 05:53:10 INFO - Running post-action listener: _resource_record_post_action 05:53:10 INFO - [mozharness: 2016-11-22 13:53:10.986364Z] Finished run-tests step (success) 05:53:10 INFO - Running post-run listener: _resource_record_post_run 05:53:11 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 40052736; Write bytes: 695451648; Read time: 394; Write time: 1912 05:53:11 INFO - TinderboxPrint: CPU usage
54.6% 05:53:11 INFO - TinderboxPrint: I/O read bytes / time
40,052,736 / 394 05:53:11 INFO - TinderboxPrint: I/O write bytes / time
695,451,648 / 1,912 05:53:11 INFO - TinderboxPrint: CPU idle
2,204.7 (45.4%) 05:53:11 INFO - TinderboxPrint: CPU system
978.6 (20.1%) 05:53:11 INFO - TinderboxPrint: CPU user
1,674.4 (34.5%) 05:53:11 INFO - TinderboxPrint: Swap in / out
422,666,240 / 0 05:53:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 375917056; Write bytes: 369755648; Read time: 28844; Write time: 692 05:53:11 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 37976064; Write bytes: 309201408; Read time: 369; Write time: 1176 05:53:11 INFO - Running post-run listener: _upload_blobber_files 05:53:11 INFO - Blob upload gear active. 05:53:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:53:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:53:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:53:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:13 INFO - (blobuploader) - INFO - Done attempting. 05:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:14 INFO - (blobuploader) - INFO - Done attempting. 05:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:16 INFO - (blobuploader) - INFO - Done attempting. 05:53:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:16 INFO - Return code: 0 05:53:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d322f6a854f2911c4e6e257764796ecf25a53ae428793942dd22d02d76712b9e7996136f2045bca8f20a64b0796027f538e7b8e519978ef702f96c284ee425e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec487ec502e6087689620ef162c089710decfbc6b51eacbaf6b9189c837b743b5a5671cd870710ad9ff2b56fab49290ce6ee558f1429b201de6ad3f42fc0743d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15b03c469f8f827096e456f9b8c1c77e4c40871485e518038ad97e919c5e494d88fc882db5ee91dfe63680bd8741c41f87d94d58910490fb8dce1037a0649371"} 05:53:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:16 INFO - Contents: 05:53:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d322f6a854f2911c4e6e257764796ecf25a53ae428793942dd22d02d76712b9e7996136f2045bca8f20a64b0796027f538e7b8e519978ef702f96c284ee425e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec487ec502e6087689620ef162c089710decfbc6b51eacbaf6b9189c837b743b5a5671cd870710ad9ff2b56fab49290ce6ee558f1429b201de6ad3f42fc0743d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15b03c469f8f827096e456f9b8c1c77e4c40871485e518038ad97e919c5e494d88fc882db5ee91dfe63680bd8741c41f87d94d58910490fb8dce1037a0649371"} 05:53:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:16 INFO - Copying logs to upload dir... 05:53:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.689420 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 05:53:16.788486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 05:53:16.792496) ========= 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.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d322f6a854f2911c4e6e257764796ecf25a53ae428793942dd22d02d76712b9e7996136f2045bca8f20a64b0796027f538e7b8e519978ef702f96c284ee425e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec487ec502e6087689620ef162c089710decfbc6b51eacbaf6b9189c837b743b5a5671cd870710ad9ff2b56fab49290ce6ee558f1429b201de6ad3f42fc0743d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15b03c469f8f827096e456f9b8c1c77e4c40871485e518038ad97e919c5e494d88fc882db5ee91dfe63680bd8741c41f87d94d58910490fb8dce1037a0649371"} build_url:https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010980 build_url: 'https://queue.taskcluster.net/v1/task/D1VaquXdRqyXO6zvp11ufw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d322f6a854f2911c4e6e257764796ecf25a53ae428793942dd22d02d76712b9e7996136f2045bca8f20a64b0796027f538e7b8e519978ef702f96c284ee425e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec487ec502e6087689620ef162c089710decfbc6b51eacbaf6b9189c837b743b5a5671cd870710ad9ff2b56fab49290ce6ee558f1429b201de6ad3f42fc0743d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15b03c469f8f827096e456f9b8c1c77e4c40871485e518038ad97e919c5e494d88fc882db5ee91dfe63680bd8741c41f87d94d58910490fb8dce1037a0649371"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 05:53:16.828817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:53:16.829333) ========= 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.LKANGffWxz/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.kDUeekjnBx/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 05:53:16.878990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 05:53:16.879307) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 05:53:16.879669) ========= ========= Total master_lag: 0.54 =========