builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0337 starttime: 1479185140.2 results: success (0) buildid: 20161114183828 builduid: 96659040565047c48c3d56e5b26f7c82 revision: 5e76768327660437bf3486554ad318e4b70276e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.203082) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.203559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.203847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.225648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.225909) ========= 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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-14 20:45:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-11-14 20:45:40 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.070115 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.317846) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.321167) ========= 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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.025606 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:40.369501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 20:45:40.369809) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5e76768327660437bf3486554ad318e4b70276e1 --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 5e76768327660437bf3486554ad318e4b70276e1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-14 20:45:40,474 truncating revision to first 12 chars 2016-11-14 20:45:40,474 Setting DEBUG logging. 2016-11-14 20:45:40,475 attempt 1/10 2016-11-14 20:45:40,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5e7676832766?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 20:45:41,881 unpacking tar archive at: mozilla-central-5e7676832766/testing/mozharness/ program finished with exit code 0 elapsedTime=1.701992 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 20:45:42.094738) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:42.095062) ========= script_repo_revision: 5e76768327660437bf3486554ad318e4b70276e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:42.098478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:42.098753) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 20:45:42.116680) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-11-14 20:45:42.116950) ========= /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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:45:42 INFO - MultiFileLogger online at 20161114 20:45:42 in /builds/slave/test 20:45:42 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 20:45:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:45:42 INFO - {'append_to_log': False, 20:45:42 INFO - 'base_work_dir': '/builds/slave/test', 20:45:42 INFO - 'blob_upload_branch': 'Firefox', 20:45:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:45:42 INFO - 'branch': 'Firefox', 20:45:42 INFO - 'buildbot_json_path': 'buildprops.json', 20:45:42 INFO - 'config_files': ('talos/mac_config.py',), 20:45:42 INFO - 'default_actions': ('clobber', 20:45:42 INFO - 'read-buildbot-config', 20:45:42 INFO - 'download-and-extract', 20:45:42 INFO - 'populate-webroot', 20:45:42 INFO - 'create-virtualenv', 20:45:42 INFO - 'install', 20:45:42 INFO - 'run-tests'), 20:45:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:45:42 INFO - 'download_minidump_stackwalk': True, 20:45:42 INFO - 'download_symbols': 'ondemand', 20:45:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:45:42 INFO - 'tooltool.py': '/tools/tooltool.py', 20:45:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:45:42 INFO - '/tools/misc-python/virtualenv.py')}, 20:45:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:45:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:45:42 INFO - 'installer_path': 'installer.exe', 20:45:42 INFO - 'log_level': 'info', 20:45:42 INFO - 'log_name': 'talos', 20:45:42 INFO - 'log_to_console': True, 20:45:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:45:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:45:42 INFO - 'opt_config_files': (), 20:45:42 INFO - 'pip_index': False, 20:45:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:45:42 INFO - 'cmd': ('bash', 20:45:42 INFO - '-c', 20:45:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:45:42 INFO - 'enabled': True, 20:45:42 INFO - 'halt_on_failure': False, 20:45:42 INFO - 'name': 'check_screen_resolution'},), 20:45:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:45:42 INFO - 'cmd': ('bash', 20:45:42 INFO - '-c', 20:45:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:45:42 INFO - 'enabled': True, 20:45:42 INFO - 'halt_on_failure': False, 20:45:42 INFO - 'name': 'check_screen_resolution'},), 20:45:42 INFO - 'run_cmd_checks_enabled': True, 20:45:42 INFO - 'sps_profile': False, 20:45:42 INFO - 'sps_profile_interval': 0, 20:45:42 INFO - 'suite': 'dromaeojs-e10s', 20:45:42 INFO - 'system_bits': '32', 20:45:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:45:42 INFO - 'title': 't-yosemite-r7-0337', 20:45:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:45:42 INFO - 'use_talos_json': True, 20:45:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:45:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:45:42 INFO - 'work_dir': 'build'} 20:45:42 INFO - [mozharness: 2016-11-15 04:45:42.327147Z] Running clobber step. 20:45:42 INFO - Running pre-action listener: _resource_record_pre_action 20:45:42 INFO - Running main action method: clobber 20:45:42 INFO - rmtree: /builds/slave/test/build 20:45:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:45:44 INFO - Running post-action listener: _resource_record_post_action 20:45:44 INFO - [mozharness: 2016-11-15 04:45:44.710027Z] Finished clobber step (success) 20:45:44 INFO - [mozharness: 2016-11-15 04:45:44.710160Z] Running read-buildbot-config step. 20:45:44 INFO - Running pre-action listener: _resource_record_pre_action 20:45:44 INFO - Running main action method: read_buildbot_config 20:45:44 INFO - Using buildbot properties: 20:45:44 INFO - { 20:45:44 INFO - "project": "", 20:45:44 INFO - "product": "firefox", 20:45:44 INFO - "script_repo_revision": "production", 20:45:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:45:44 INFO - "repository": "", 20:45:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:45:44 INFO - "buildid": "20161114183828", 20:45:44 INFO - "pgo_build": "False", 20:45:44 INFO - "basedir": "/builds/slave/test", 20:45:44 INFO - "buildnumber": 167, 20:45:44 INFO - "slavename": "t-yosemite-r7-0337", 20:45:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:45:44 INFO - "platform": "yosemite_r7", 20:45:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:45:44 INFO - "branch": "mozilla-central", 20:45:44 INFO - "repo_path": "mozilla-central", 20:45:44 INFO - "revision": "5e76768327660437bf3486554ad318e4b70276e1", 20:45:44 INFO - "stage_platform": "macosx64", 20:45:44 INFO - "builduid": "96659040565047c48c3d56e5b26f7c82", 20:45:44 INFO - "slavebuilddir": "test" 20:45:44 INFO - } 20:45:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:45:44 INFO - Running post-action listener: _resource_record_post_action 20:45:44 INFO - [mozharness: 2016-11-15 04:45:44.713508Z] Finished read-buildbot-config step (success) 20:45:44 INFO - [mozharness: 2016-11-15 04:45:44.713618Z] Running download-and-extract step. 20:45:44 INFO - Running pre-action listener: _resource_record_pre_action 20:45:44 INFO - Running main action method: download_and_extract 20:45:44 INFO - mkdir: /builds/slave/test/build/tests 20:45: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')]} 20:45:44 INFO - trying https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:45:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/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 20:45:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/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 20:45:46 INFO - Downloaded 1199 bytes. 20:45:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:45:46 INFO - Using the following test package requirements: 20:45:46 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:45:46 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:45:46 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:45:46 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'jsshell-mac.zip'], 20:45:46 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:45:46 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:45:46 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:45:46 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:45:46 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:45:46 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:45:46 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:45:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:45:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:45:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:45:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:45:46 INFO - Http code: 200 20:45:46 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 20:45:46 INFO - x-amz-cf-id: 8VLNpuM6nWz_5l9GHmrjcX-aQxuUY8VWvpONA_v5A6yPHoaMIDff0w== 20:45:46 INFO - x-amz-version-id: 4hxBEHWaYrJiP7ZPkiuuOdrogBukRpst 20:45:47 INFO - Expected file size: 16328744 20:45:47 INFO - Obtained file size: 16328744 20:45:53 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 20:45:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:45:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:45:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:45:54 INFO - Http code: 200 20:45:54 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 20:45:54 INFO - x-amz-cf-id: zQ2SjSbIgzXpzj-J0lby3cprWmBdoLohwllxqDPkWpEIn9Ehbtb-Fg== 20:45:54 INFO - x-amz-version-id: 4hxBEHWaYrJiP7ZPkiuuOdrogBukRpst 20:45:54 INFO - Expected file size: 16328744 20:45:54 INFO - Obtained file size: 16328744 20:46:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:46:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:46:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:46:01 INFO - Http code: 200 20:46:01 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 20:46:01 INFO - x-amz-cf-id: aaWCLcr2xJjEBQJu43qJOaUnzRAnh4MB4CiQEgfRlKwTK6td2EsPFw== 20:46:01 INFO - x-amz-version-id: ApCwi9xqoEg7o2ftPPxxzKI6EoV9zIwO 20:46:01 INFO - Expected file size: 14409038 20:46:01 INFO - Obtained file size: 14409038 20:46: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')]} 20:46:02 INFO - trying https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:46:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:46:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/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 20:46:04 INFO - Downloaded 110942521 bytes. 20:46:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:46:04 INFO - mkdir: /builds/slave/test/properties 20:46:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:46:04 INFO - Writing to file /builds/slave/test/properties/build_url 20:46:04 INFO - Contents: 20:46:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:46:05 INFO - Running post-action listener: _resource_record_post_action 20:46:05 INFO - Running post-action listener: set_extra_try_arguments 20:46:05 INFO - [mozharness: 2016-11-15 04:46:05.653453Z] Finished download-and-extract step (success) 20:46:05 INFO - [mozharness: 2016-11-15 04:46:05.653592Z] Running populate-webroot step. 20:46:05 INFO - Running pre-action listener: _resource_record_pre_action 20:46:05 INFO - Running main action method: populate_webroot 20:46:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:46:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:46:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:46:05 INFO - '--tppagecycles', 20:46:05 INFO - '7'], 20:46:05 INFO - 'tests': ['tsvgm']}}, 20:46:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:46:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:46:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:46:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:46:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['damp', 'tps']}, 20:46:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'tests': ['damp', 'tps']}, 20:46:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['dromaeo_dom']}, 20:46:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:46:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['basic_compositor_video']}, 20:46:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:46:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'other-e10s': {'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['a11yr', 20:46:05 INFO - 'ts_paint', 20:46:05 INFO - 'tpaint', 20:46:05 INFO - 'sessionrestore', 20:46:05 INFO - 'sessionrestore_no_auto_restore', 20:46:05 INFO - 'tabpaint']}, 20:46:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['tsvgx', 20:46:05 INFO - 'tsvgr_opacity', 20:46:05 INFO - 'tart', 20:46:05 INFO - 'tscrollx', 20:46:05 INFO - 'cart']}, 20:46:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:46:05 INFO - 'tsvgr_opacity', 20:46:05 INFO - 'tart', 20:46:05 INFO - 'tscrollx', 20:46:05 INFO - 'cart']}, 20:46:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'talos_options': ['--disable-e10s'], 20:46:05 INFO - 'tests': ['tp5o']}, 20:46:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'tests': ['tp5o']}, 20:46:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'talos_options': ['--disable-e10s', 20:46:05 INFO - '--xperf_path', 20:46:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:05 INFO - 'tests': ['tp5n']}, 20:46:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:05 INFO - 'talos_options': ['--xperf_path', 20:46:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:05 INFO - 'tests': ['tp5n']}}, 20:46:05 INFO - 'talos.zip': {'path': '', 20:46:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:46:05 INFO - Running post-action listener: _resource_record_post_action 20:46:05 INFO - [mozharness: 2016-11-15 04:46:05.672355Z] Finished populate-webroot step (success) 20:46:05 INFO - [mozharness: 2016-11-15 04:46:05.672485Z] Running create-virtualenv step. 20:46:05 INFO - Running pre-action listener: _resource_record_pre_action 20:46:05 INFO - Running main action method: create_virtualenv 20:46:05 INFO - Creating virtualenv /builds/slave/test/build/venv 20:46:05 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 20:46:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:46:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:46:05 INFO - Using real prefix '/tools/python27' 20:46:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:46:06 INFO - Installing distribute.............................................................................................................................................................................................done. 20:46:09 INFO - Installing pip.................done. 20:46:09 INFO - Return code: 0 20:46:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:46:09 INFO - Reading from file tmpfile_stdout 20:46:09 INFO - Output received: 20:46:09 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) 20:46:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:46:09 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')]} 20:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:09 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')]} 20:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:09 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:09 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 20:46:09 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 20:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:09 INFO - 'HOME': '/Users/cltbld', 20:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:09 INFO - 'LOGNAME': 'cltbld', 20:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:09 INFO - 'MOZ_NO_REMOTE': '1', 20:46:09 INFO - 'NO_EM_RESTART': '1', 20:46:09 INFO - 'PAGER': '/bin/cat', 20:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:09 INFO - 'PWD': '/builds/slave/test', 20:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:09 INFO - 'SHELL': '/bin/bash', 20:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:09 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:09 INFO - 'USER': 'cltbld', 20:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:09 INFO - 'XPC_FLAGS': '0x0', 20:46:09 INFO - 'XPC_SERVICE_NAME': '0', 20:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:09 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 20:46:09 INFO - Cleaning up... 20:46:09 INFO - Return code: 0 20:46:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:46:09 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')]} 20:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:09 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')]} 20:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:09 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:09 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 20:46:09 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 20:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:09 INFO - 'HOME': '/Users/cltbld', 20:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:09 INFO - 'LOGNAME': 'cltbld', 20:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:09 INFO - 'MOZ_NO_REMOTE': '1', 20:46:09 INFO - 'NO_EM_RESTART': '1', 20:46:09 INFO - 'PAGER': '/bin/cat', 20:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:09 INFO - 'PWD': '/builds/slave/test', 20:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:09 INFO - 'SHELL': '/bin/bash', 20:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:09 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:09 INFO - 'USER': 'cltbld', 20:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:09 INFO - 'XPC_FLAGS': '0x0', 20:46:09 INFO - 'XPC_SERVICE_NAME': '0', 20:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:10 INFO - Downloading/unpacking psutil>=3.1.1 20:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:46:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:13 INFO - Installing collected packages: psutil 20:46:13 INFO - Running setup.py install for psutil 20:46:13 INFO - building 'psutil._psutil_osx' extension 20:46:13 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 20:46:14 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 20:46:14 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 20:46:14 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 20:46:14 INFO - building 'psutil._psutil_posix' extension 20:46:14 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 20:46:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:46:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:46:14 INFO - ^ 20:46:14 INFO - 1 warning generated. 20:46:14 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 20:46:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:14 INFO - Successfully installed psutil 20:46:14 INFO - Cleaning up... 20:46:14 INFO - Return code: 0 20:46:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:46:14 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')]} 20:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:14 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')]} 20:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:14 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:14 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 20:46:14 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 20:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:14 INFO - 'HOME': '/Users/cltbld', 20:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:14 INFO - 'LOGNAME': 'cltbld', 20:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:14 INFO - 'MOZ_NO_REMOTE': '1', 20:46:14 INFO - 'NO_EM_RESTART': '1', 20:46:14 INFO - 'PAGER': '/bin/cat', 20:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:14 INFO - 'PWD': '/builds/slave/test', 20:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:14 INFO - 'SHELL': '/bin/bash', 20:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:14 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:14 INFO - 'USER': 'cltbld', 20:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:14 INFO - 'XPC_FLAGS': '0x0', 20:46:14 INFO - 'XPC_SERVICE_NAME': '0', 20:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:46:17 INFO - Installing collected packages: mozsystemmonitor 20:46:17 INFO - Running setup.py install for mozsystemmonitor 20:46:17 INFO - Successfully installed mozsystemmonitor 20:46:17 INFO - Cleaning up... 20:46:17 INFO - Return code: 0 20:46:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:17 INFO - 'HOME': '/Users/cltbld', 20:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:17 INFO - 'LOGNAME': 'cltbld', 20:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:17 INFO - 'MOZ_NO_REMOTE': '1', 20:46:17 INFO - 'NO_EM_RESTART': '1', 20:46:17 INFO - 'PAGER': '/bin/cat', 20:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:17 INFO - 'PWD': '/builds/slave/test', 20:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:17 INFO - 'SHELL': '/bin/bash', 20:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:17 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:17 INFO - 'USER': 'cltbld', 20:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:17 INFO - 'XPC_FLAGS': '0x0', 20:46:17 INFO - 'XPC_SERVICE_NAME': '0', 20:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:18 INFO - Downloading/unpacking jsonschema==2.5.1 20:46:18 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 20:46:18 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 20:46:18 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 20:46:18 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 20:46:18 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 20:46:18 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 20:46:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:46:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:21 INFO - Downloading functools32-3.2.3-2.tar.gz 20:46:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:46:21 INFO - warning: no files found matching '*.txt' 20:46:21 INFO - no previously-included directories found matching 'build' 20:46:21 INFO - no previously-included directories found matching 'dist' 20:46:21 INFO - no previously-included directories found matching '.git*' 20:46:21 INFO - Installing collected packages: jsonschema, functools32 20:46:21 INFO - Running setup.py install for functools32 20:46:21 INFO - warning: no files found matching '*.txt' 20:46:21 INFO - no previously-included directories found matching 'build' 20:46:21 INFO - no previously-included directories found matching 'dist' 20:46:21 INFO - no previously-included directories found matching '.git*' 20:46:21 INFO - Successfully installed jsonschema functools32 20:46:21 INFO - Cleaning up... 20:46:21 INFO - Return code: 0 20:46:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:21 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:21 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 20:46:21 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 20:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:21 INFO - 'HOME': '/Users/cltbld', 20:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:21 INFO - 'LOGNAME': 'cltbld', 20:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:21 INFO - 'MOZ_NO_REMOTE': '1', 20:46:21 INFO - 'NO_EM_RESTART': '1', 20:46:21 INFO - 'PAGER': '/bin/cat', 20:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:21 INFO - 'PWD': '/builds/slave/test', 20:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:21 INFO - 'SHELL': '/bin/bash', 20:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:21 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:21 INFO - 'USER': 'cltbld', 20:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:21 INFO - 'XPC_FLAGS': '0x0', 20:46:21 INFO - 'XPC_SERVICE_NAME': '0', 20:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:46:21 INFO - Cleaning up... 20:46:21 INFO - Return code: 0 20:46:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:21 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:21 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 20:46:21 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 20:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:21 INFO - 'HOME': '/Users/cltbld', 20:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:21 INFO - 'LOGNAME': 'cltbld', 20:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:21 INFO - 'MOZ_NO_REMOTE': '1', 20:46:21 INFO - 'NO_EM_RESTART': '1', 20:46:21 INFO - 'PAGER': '/bin/cat', 20:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:21 INFO - 'PWD': '/builds/slave/test', 20:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:21 INFO - 'SHELL': '/bin/bash', 20:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:21 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:21 INFO - 'USER': 'cltbld', 20:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:21 INFO - 'XPC_FLAGS': '0x0', 20:46:21 INFO - 'XPC_SERVICE_NAME': '0', 20:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:21 INFO - Downloading/unpacking blobuploader==1.2.4 20:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:24 INFO - Downloading blobuploader-1.2.4.tar.gz 20:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:46:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:46:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:46:25 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 20:46:25 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 20:46:25 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 20:46:25 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 20:46:25 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 20:46:25 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 20:46:25 INFO - Downloading docopt-0.6.1.tar.gz 20:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:46:25 INFO - Installing collected packages: blobuploader, requests, docopt 20:46:25 INFO - Running setup.py install for blobuploader 20:46:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:46:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:46:25 INFO - Running setup.py install for requests 20:46:26 INFO - Running setup.py install for docopt 20:46:26 INFO - Successfully installed blobuploader requests docopt 20:46:26 INFO - Cleaning up... 20:46:26 INFO - Return code: 0 20:46:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:46:26 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')]} 20:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:26 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')]} 20:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:26 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:26 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 20:46:26 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 20:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:26 INFO - 'HOME': '/Users/cltbld', 20:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:26 INFO - 'LOGNAME': 'cltbld', 20:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:26 INFO - 'MOZ_NO_REMOTE': '1', 20:46:26 INFO - 'NO_EM_RESTART': '1', 20:46:26 INFO - 'PAGER': '/bin/cat', 20:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:26 INFO - 'PWD': '/builds/slave/test', 20:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:26 INFO - 'SHELL': '/bin/bash', 20:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:26 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:26 INFO - 'USER': 'cltbld', 20:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:26 INFO - 'XPC_FLAGS': '0x0', 20:46:26 INFO - 'XPC_SERVICE_NAME': '0', 20:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:46:26 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-52I9c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-2ZsJX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-HT0f43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-PIMj64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-VCr4o1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-XELS1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-qMjMC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-9cvlAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-kA1pa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mz2eG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:46:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xqc7fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-6CMaQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-SjBLJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-MV1caw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-XsJYor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-SeWCU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:46:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-UO_ba0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:46:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:46:28 INFO - Running setup.py install for manifestparser 20:46:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:46:28 INFO - Running setup.py install for mozcrash 20:46:28 INFO - Running setup.py install for mozdebug 20:46:29 INFO - Running setup.py install for mozdevice 20:46:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Running setup.py install for mozfile 20:46:29 INFO - Running setup.py install for mozhttpd 20:46:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Running setup.py install for mozinfo 20:46:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Running setup.py install for mozInstall 20:46:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:46:29 INFO - Running setup.py install for mozleak 20:46:29 INFO - Running setup.py install for mozlog 20:46:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Running setup.py install for moznetwork 20:46:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Running setup.py install for mozprocess 20:46:30 INFO - Running setup.py install for mozprofile 20:46:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Running setup.py install for mozrunner 20:46:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:46:30 INFO - Running setup.py install for mozscreenshot 20:46:30 INFO - Running setup.py install for moztest 20:46:31 INFO - Running setup.py install for mozversion 20:46:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:46:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:46:31 INFO - Cleaning up... 20:46:31 INFO - Return code: 0 20:46:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:46:31 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')]} 20:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:31 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')]} 20:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:31 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:31 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 20:46:31 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 20:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:31 INFO - 'HOME': '/Users/cltbld', 20:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:31 INFO - 'LOGNAME': 'cltbld', 20:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:31 INFO - 'MOZ_NO_REMOTE': '1', 20:46:31 INFO - 'NO_EM_RESTART': '1', 20:46:31 INFO - 'PAGER': '/bin/cat', 20:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:31 INFO - 'PWD': '/builds/slave/test', 20:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:31 INFO - 'SHELL': '/bin/bash', 20:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:31 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:31 INFO - 'USER': 'cltbld', 20:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:31 INFO - 'XPC_FLAGS': '0x0', 20:46:31 INFO - 'XPC_SERVICE_NAME': '0', 20:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:46:31 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-hi0ydb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:46:31 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)) 20:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:46:31 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-vKknNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:46:31 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)) 20:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:46:31 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-lP0MzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:46:31 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)) 20:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:46:31 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-AGqOiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 20:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:46:31 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IlWiyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-oir0hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-GyJvjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-EoeCqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-4dMC03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-K_ObLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-WJ_yaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-tUDTSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ORsfeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:46:32 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)) 20:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:46:32 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-8AEiVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:46:33 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)) 20:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:46:33 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-lP8D2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:46:33 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)) 20:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:46:33 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-msja9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:46:33 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)) 20:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:46:33 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-u2Jgw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:46:33 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)) 20:46:33 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)) 20:46:33 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)) 20:46:33 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)) 20:46:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:46:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:46:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:46:33 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 20:46:33 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 20:46:33 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 20:46:33 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 20:46:33 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 20:46:33 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 20:46:35 INFO - Downloading blessings-1.6.tar.gz 20:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:46:35 INFO - Installing collected packages: blessings 20:46:35 INFO - Running setup.py install for blessings 20:46:36 INFO - Successfully installed blessings 20:46:36 INFO - Cleaning up... 20:46:36 INFO - Return code: 0 20:46:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:46:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:46:36 INFO - Reading from file tmpfile_stdout 20:46:36 INFO - Current package versions: 20:46:36 INFO - blessings == 1.6 20:46:36 INFO - blobuploader == 1.2.4 20:46:36 INFO - docopt == 0.6.1 20:46:36 INFO - functools32 == 3.2.3-2 20:46:36 INFO - jsonschema == 2.5.1 20:46:36 INFO - manifestparser == 1.1 20:46:36 INFO - mozInstall == 1.12 20:46:36 INFO - mozcrash == 1.0 20:46:36 INFO - mozdebug == 0.1 20:46:36 INFO - mozdevice == 0.48 20:46:36 INFO - mozfile == 1.2 20:46:36 INFO - mozhttpd == 0.7 20:46:36 INFO - mozinfo == 0.9 20:46:36 INFO - mozleak == 0.1 20:46:36 INFO - mozlog == 3.4 20:46:36 INFO - moznetwork == 0.27 20:46:36 INFO - mozprocess == 0.23 20:46:36 INFO - mozprofile == 0.28 20:46:36 INFO - mozrunner == 6.13 20:46:36 INFO - mozscreenshot == 0.1 20:46:36 INFO - mozsystemmonitor == 0.3 20:46:36 INFO - moztest == 0.7 20:46:36 INFO - mozversion == 1.4 20:46:36 INFO - psutil == 3.1.1 20:46:36 INFO - requests == 1.2.3 20:46:36 INFO - wsgiref == 0.1.2 20:46:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:37 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:37 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 20:46:37 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 20:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:37 INFO - 'HOME': '/Users/cltbld', 20:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:37 INFO - 'LOGNAME': 'cltbld', 20:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:37 INFO - 'MOZ_NO_REMOTE': '1', 20:46:37 INFO - 'NO_EM_RESTART': '1', 20:46:37 INFO - 'PAGER': '/bin/cat', 20:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:37 INFO - 'PWD': '/builds/slave/test', 20:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:37 INFO - 'SHELL': '/bin/bash', 20:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:37 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:37 INFO - 'USER': 'cltbld', 20:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:37 INFO - 'XPC_FLAGS': '0x0', 20:46:37 INFO - 'XPC_SERVICE_NAME': '0', 20:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 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)) 20:46:37 INFO - Cleaning up... 20:46:37 INFO - Return code: 0 20:46:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:46:37 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')]} 20:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:37 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')]} 20:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:37 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 0x10f04a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d54f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, '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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 20:46:37 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 20:46:37 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 20:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:37 INFO - 'HOME': '/Users/cltbld', 20:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:37 INFO - 'LOGNAME': 'cltbld', 20:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:37 INFO - 'MOZ_NO_REMOTE': '1', 20:46:37 INFO - 'NO_EM_RESTART': '1', 20:46:37 INFO - 'PAGER': '/bin/cat', 20:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:37 INFO - 'PWD': '/builds/slave/test', 20:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:37 INFO - 'SHELL': '/bin/bash', 20:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:46:37 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:37 INFO - 'USER': 'cltbld', 20:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:37 INFO - 'XPC_FLAGS': '0x0', 20:46:37 INFO - 'XPC_SERVICE_NAME': '0', 20:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:46:38 INFO - Cleaning up... 20:46:38 INFO - Return code: 0 20:46:38 INFO - Running post-action listener: _resource_record_post_action 20:46:38 INFO - Running post-action listener: _start_resource_monitoring 20:46:38 INFO - Starting resource monitoring. 20:46:38 INFO - [mozharness: 2016-11-15 04:46:38.232915Z] Finished create-virtualenv step (success) 20:46:38 INFO - [mozharness: 2016-11-15 04:46:38.233929Z] Running install step. 20:46:38 INFO - Running pre-action listener: _resource_record_pre_action 20:46:38 INFO - Running main action method: install 20:46:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:46:38 INFO - Reading from file tmpfile_stdout 20:46:38 INFO - Detecting whether we're running mozinstall >=1.0... 20:46:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:46:38 INFO - Reading from file tmpfile_stdout 20:46:38 INFO - Output received: 20:46:38 INFO - Usage: mozinstall [options] installer 20:46:38 INFO - Options: 20:46:38 INFO - -h, --help show this help message and exit 20:46:38 INFO - -d DEST, --destination=DEST 20:46:38 INFO - Directory to install application into. [default: 20:46:38 INFO - "/builds/slave/test"] 20:46:38 INFO - --app=APP Application being installed. [default: firefox] 20:46:38 INFO - mkdir: /builds/slave/test/build/application 20:46:38 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'] 20:46:38 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 20:47:12 INFO - Reading from file tmpfile_stdout 20:47:12 INFO - Output received: 20:47:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:47:12 INFO - Running post-action listener: _resource_record_post_action 20:47:12 INFO - [mozharness: 2016-11-15 04:47:12.822898Z] Finished install step (success) 20:47:12 INFO - [mozharness: 2016-11-15 04:47:12.823058Z] Running run-tests step. 20:47:12 INFO - Running pre-action listener: _resource_record_pre_action 20:47:12 INFO - Running main action method: run_tests 20:47:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:47:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:47:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:47:12 INFO - 2016-11-14 20:47:12.837 screenresolution[1625:7363] starting screenresolution argv=screenresolution get 20:47:12 INFO - 2016-11-14 20:47:12.840 screenresolution[1625:7363] Display 0: 1600x1200x32@60 20:47:12 INFO - 2016-11-14 20:47:12.846 screenresolution[1626:7365] starting screenresolution argv=screenresolution list 20:47:12 INFO - Available Modes on Display 0 20:47:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:47:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:47:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:47:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:47:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:47:13 INFO - Intel Iris: 20:47:13 INFO - Chipset Model: Intel Iris 20:47:13 INFO - Type: GPU 20:47:13 INFO - Bus: Built-In 20:47:13 INFO - VRAM (Dynamic, Max): 1536 MB 20:47:13 INFO - Vendor: Intel (0x8086) 20:47:13 INFO - Device ID: 0x0a2e 20:47:13 INFO - Revision ID: 0x0009 20:47:13 INFO - Displays: 20:47:13 INFO - Display: 20:47:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:47:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:47:13 INFO - Display Serial Number: 1600x1200 60 20:47:13 INFO - Main Display: Yes 20:47:13 INFO - Mirror: Off 20:47:13 INFO - Online: Yes 20:47:13 INFO - Rotation: Supported 20:47:13 INFO - Return code: 0 20:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:47:13 INFO - Python 2.7.3 20:47:13 INFO - Return code: 0 20:47:13 INFO - grabbing minidump binary from tooltool 20:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:13 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 0x10ee5ed30>, '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 0x10ec84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee56b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:47:13 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 20:47:13 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 20:47:13 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 20:47:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:47:13 INFO - Return code: 0 20:47:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:47:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:47:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:47:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:47:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--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 20:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0337 --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 20:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U5WTvZVdEw/Render', 20:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:13 INFO - 'HOME': '/Users/cltbld', 20:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:13 INFO - 'LOGNAME': 'cltbld', 20:47:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:47:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:13 INFO - 'MOZ_NO_REMOTE': '1', 20:47:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:47:13 INFO - 'NO_EM_RESTART': '1', 20:47:13 INFO - 'PAGER': '/bin/cat', 20:47:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:13 INFO - 'PWD': '/builds/slave/test', 20:47:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:13 INFO - 'SHELL': '/bin/bash', 20:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJpV2Cql2E/Listeners', 20:47:13 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 20:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:13 INFO - 'USER': 'cltbld', 20:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:13 INFO - 'XPC_FLAGS': '0x0', 20:47:13 INFO - 'XPC_SERVICE_NAME': '0', 20:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--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 20:47:13 INFO - mozversion application_buildid: 20161114183828 20:47:13 INFO - mozversion application_changeset: 5e76768327660437bf3486554ad318e4b70276e1 20:47:13 INFO - mozversion application_display_name: Nightly 20:47:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:47:13 INFO - mozversion application_name: Firefox 20:47:13 INFO - mozversion application_remotingname: firefox 20:47:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:47:13 INFO - mozversion application_vendor: Mozilla 20:47:13 INFO - mozversion application_version: 53.0a1 20:47:13 INFO - mozversion platform_buildid: 20161114183828 20:47:13 INFO - mozversion platform_changeset: 5e76768327660437bf3486554ad318e4b70276e1 20:47:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:47:13 INFO - mozversion platform_version: 53.0a1 20:47:13 INFO - using testdate: 1479185233 20:47:13 INFO - actual date: 1479185233 20:47:13 INFO - starting webserver on 'localhost:49250' 20:47:13 INFO - SUITE-START | Running 2 tests 20:47:13 INFO - TEST-START | dromaeo_css 20:47:13 INFO - operating with platform_type : mac_ 20:47:13 INFO - Initialising browser for dromaeo_css test... 20:47:13 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpg74hN_/profile http://localhost:49250/getInfo.html) 20:47:16 INFO - PROCESS | 1636 | 2016-11-14 20:47:16.227 plugin-container[1638:7534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b3f, name = 'com.apple.tsm.portname' 20:47:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:47:16 INFO - PROCESS | 1636 | 2016-11-14 20:47:16.230 plugin-container[1638:7534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.CFPasteboardClient' 20:47:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:47:16 INFO - PROCESS | 1636 | 2016-11-14 20:47:16.230 plugin-container[1638:7534] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:47:16 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 20:47:16 INFO - PROCESS | 1636 | colorDepth:24 20:47:16 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 20:47:16 INFO - PROCESS | 1636 | __metrics 20:47:16 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 20:47:17 INFO - TEST-INFO | 1636: exit 0 20:47:17 INFO - Browser initialized. 20:47:17 INFO - Running cycle 1/1 for dromaeo_css test... 20:47:17 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpg74hN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:47:19 INFO - PROCESS | 1639 | 2016-11-14 20:47:19.743 plugin-container[1640:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 20:47:19 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:47:19 INFO - PROCESS | 1639 | 2016-11-14 20:47:19.743 plugin-container[1640:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 20:47:19 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:47:19 INFO - PROCESS | 1639 | 2016-11-14 20:47:19.743 plugin-container[1640:7655] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:47:29 INFO - PROCESS | 1639 | RSS: Main: 237244416 20:47:29 INFO - PROCESS | 1639 | 20:48:18 INFO - PROCESS | 1639 | 1479185298983 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] 20:50:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 20:50:34 INFO - PROCESS | 1639 | RSS: Main: 255455232 20:50:34 INFO - PROCESS | 1639 | 20:53:36 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 20:53:36 INFO - PROCESS | 1639 | RSS: Main: 264380416 20:53:36 INFO - PROCESS | 1639 | 20:56:38 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 20:56:39 INFO - PROCESS | 1639 | RSS: Main: 270094336 20:56:39 INFO - PROCESS | 1639 | 20:59:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 20:59:42 INFO - PROCESS | 1639 | RSS: Main: 268627968 20:59:42 INFO - PROCESS | 1639 | 21:02:45 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 21:02:45 INFO - PROCESS | 1639 | RSS: Main: 274051072 21:02:45 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 21:05:48 INFO - PROCESS | 1639 | RSS: Main: 276291584 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | __start_tp_report 21:05:48 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 21:05:48 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 21:05:48 INFO - PROCESS | 1639 | |i|pagename|runs| 21:05:48 INFO - PROCESS | 1639 | |0;dojo.html;2883.596296518487;3350.112220261631;3388.728901687865;3393.032985599103;3415.077928959183;5616.382197958222;5654.858628534278;5676.403977582351;5694.003549378863;5798.217240672508;5636.408177141403;5751.884962300752;5755.021646320125;5772.797952071678;5773.364929857723;5083.923741143886;5172.534471897528;5180.766865491043;5241.8951620967555;5301.098813201765;5219.034478621448;5250.396204436482;5260.158374660055;5289.233061206123;5305;4271.444712187411;4885.462599114095;5164.877657205232;5272.710000949946;5274.709890956015;4290.485141782975;5125.794968201268;5250.553702935255;5279.102552566073;5291.550218231436;5061.493850614946;5189.948100518985;5201.985612805504;5210.76551555181;5228.816991405282;5466.426025267329;5706.857328566777;5743.8564035899435;5754.482096611325;5758.452946970031;5002.399712034543;5158.561522270612;6033.245844269466;6119.632822030692;6157.0456579230295;5169.689818610896;5869.325027621835;5916.26046744157;5958.13607026983;6113.445900579388;5489.615726899079;5500.367457742411;5501.889962200774;5533.280673512418;5538.972305138489;6037.939620603738;6039.4262545058145;6043.969780151071;6059.333473317932;6071.3928607139815;5753.597248192586;5983.37174596661;6028.457438830526;6037.6679282639;6070.180525628984;6107.786227482017;6132.4166970161305;6145.723442445101;6148.077788331786;6166.506679465632;6051.0923361496125;6082.087686847009;6139.969300153471;6155.1075094112;6170.382961703794;5980.910286345708;6077.483413909778;6084.421979911901;6140.078988151724;6155.938440615537;4016.49793775778;4649.95350046502;4893.290472881412;4935.210366341368;4935.950640493621;4403.097365040159;4428.4243048403505;4438.134563760101;4463.977680111579;4465.24090904547;4850.83022094225;5687.317521897398;5776.480116789509;5808.651480911159;5868.471837534642;6143.726821076141;6161.199044124235;6181.690915454209;6187.041008643668;6207.96896015517;5811.796587119515;5824.417558244227;5839.270091238595;5856.297244330623;5880.176775251468;5410.242566040757;5417.645588602849;5418.593605479526;5446.346438427414;5455.672659640434;6294.496440284766;6307.5269354798575;6310.842228944313;6325.620462772248;6407.487401007908;6124.655575320095;6128.387161283836;6193.040078787795;6244.937550624436;6246.750129994749;6222.222222222223;6277.18396608438;6309.05364195356;6323.747050117944;6327.145835312174;6154.723037463235;6202.782902598387;6218.9427797273665;6221.4089661482085;6223.626582404889;6127.189528148541;6228;6229.314775374614;6234.688265586612;6249.218847644044;5138.434772174983;5689.971550142388;6014.669193194416;6050.667213303311;6061.3029501606115;5989.101634754822;6090.786822461193;6099.329073801967;6107.6640784757265;6122.540809439399;5568.91646625309;5854;5928.911066334094;5937;5951.137085122719;5953.553483488669;6020.066889632114;6060.848478788065;6080.908786368296;6116;5876.823695289148;5889.234399528034;5900.793472228452;5903.970480147572;5907.940920590739 21:05:48 INFO - PROCESS | 1639 | |1;ext.html;471.9079779443009;471.9126961512123;487.66838549786155;520.8333333333335;531.4499493024717;38811.41782873259;40439.1912161757;40579.797101014454;40613.78158655242;40624.39063414051;36098.458523122106;36961.63038369615;39264.01839953996;40865.365385384546;41980.580194198046;9369.203617692505;9529.237660987104;9549.522523873813;9564.856527152098;9566.37818541796;8377.45546539476;9324.440533568008;9356.29827762917;9440.858387124199;9464.195543378786;9333;9508.619655213783;9511.714648560519;9533.47565883876;9552.37909535878;9437.76405589859;9499.097585729345;9526.047395260419;9537.713868583953;9549.379290346105;9295.256379489625;9362;9404.435733855991;9443.291753118543;9464.52677366129;39190.60809391898;39289.41065884014;39428.81713548598;39521.60478395237;39574.60425395738;3371.3257348530146;3635.4365073413665;3901.1807520420743;3903.2974064668347;3913.0804260998925;28973.985910493033;29415.85292073526;29457;29536;29892.85053574718;3146.465100932847;3163.4780261256865;3176.872925082994;3191.6010498687665;3273.0344548358294;491648;492083;495953;497575;497979;59570;59646;59772;59794.10308845371;59882.90995265364;51951;61843;61970.760584788055;62453.687731561055;62467.375326246154;15516.612084697746;15625.921870390575;15766.842331576767;15779.68440631181;15789.21053947298;12095.274283543016;12190.329531875832;12201.023918086532;12214.511419543296;12245.69385765363;16442.671146577002;17025.829741702673;17034;17035.318587256617;17049.40327088571;5925.585209035412;5928.614640048383;5930.762769489172;6002.519798416116;6053.485453736482;842.4566154830101;917.9495127768037;919.5746592781162;1033.6485594897772;1100.3342977498658;25391.87304063468;29197.562036569467;29212.809039322136;29792.851035744683;29816.254593635334;999.1856636840952;1009.2531526670211;1013.9391636501833;1029.8558201851747;1061.936283822973;14202.786958195635;15070.849291507164;15080.622984425478;15428.845711542965;15515.767263491056;651214;653231;655273;655335;655523;50966.745166274675;51314.48685513172;51370;51557.48442515602;51590.74204629028;1249.7812882745484;1297.7274772297735;1323.4309247023873;1326.6219127548795;1334.6796768775423;24753.009879604615;28143.296417589725;28910.566341504473;29597.704022959493;29680.851595742748;47164;50434.24348634847;50891;50903.98192036212;51024.489755101975;487273;496430.5178474084;575204;582091;583129;25437.61843572385;26068.087616933317;26256.949722010904;26940.191794246202;39982.800085999384;45628.771856140505;46035.53964460312;46287.07425851397;46781.59655210349;47098.058038838346;29240.41519169562;30651.54022689617;33929;37632;37808.054798630255;34827.129321767155;37556.30996605105;37883.81058094692;38803.41794873136;38852.80573597114;36510.45234321434;36872.63127368692;37657.623423766505;37709.622903770614;37755.62244377631;27537;28463.573046403908;29176.7082329174;32030.263558462833;32185.839070804497;22414.887925560266;24506.142285019938;24701.629475558242;24770.50458990846;24849.254522364357 21:05:48 INFO - PROCESS | 1639 | |2;jquery.html;354.6843309454586;371.58753783300534;375.5981100222761;382.6536984029451;402.9214303210873;18692.626147477044;19071.237150513964;19273.903630481862;19842.30551930683;20004.299849505234;13043.543475978342;19234.13446394912;19308.227670893182;20062.59874802503;20141.798582014177;4585.335126406669;5338.092524270874;5512.33851937768;5519.089350257203;5519.39286678465;4578.931316030262;5971.581989260753;6052.394760523956;6059.575829691923;6081.1790408294755;5770.451807078321;5983.311919129291;6035.818925432244;6037.728302226388;6052;5973.88052238955;6024.879502409927;6051.848703782397;6053.788117415869;6055.485283750886;5057.418396884332;5211.609129315279;5236.266922630834;5284.920726189111;5321.494458026481;17400.73898891518;22443.663345049838;23821.88089059561;24596.754032459627;24598.877005615035;1739.2347367158409;1909.6467153576632;1932.2174519319558;2057.341650671786;2238.9552208955893;9444.197243234334;9601.279904007157;9700.660476883346;10146.391216527032;10177.236707246986;1537.8615924566732;1859.7024476083761;1864.4220291709614;1871.1766822598013;1887.9528011799678;112573;125715;126108.36945815304;127025;129282.41435171338;11044.33733975964;11201.775964480665;11364.772704545863;11494.367809770543;11583.6524904253;14374.496892608708;14467.421303147967;14484;14491.92754036223;14702.63243418923;8025.678972841021;8243;8317.168283171635;8426.452280601741;8449.53527555978;8040.2361775631225;8081;8085.434019618688;8232.958835205785;8256.504609723435;3761.4169803680475;3917.2763983847017;4478.910421791546;4479.865604031885;4514.442517331516;1870.4575673054933;1922.9423117306503;1924.5284905198316;1951.9024048797503;1957.5595491014692;853.3343991686495;854.8628428927681;954.1508057828538;970.5098925042809;991.8165139449225;19644.151598018492;19899.403017909484;20389.89805050965;20829.375118746462;20848;868.7828042989253;889.390767324372;893.1827377949104;971.4268581536786;1031.7162780235344;10676.128129528728;11018.724531886766;11061.391623460788;11061.778764424667;11067.50196241171;227007;227490.86254568622;227753;229026;560171;112050;116735.83264167419;116764;117650.52922089367;122020.38989804994;960.558143254097;979.314479864098;1025.7743296474764;1027.3476342522551;1028.2082796246848;18570.164342604345;18850;19086.427407177805;19166.945817980144;19309.710354344414;37589;45532;54363.72818136042;54734.631634209465;54972.72513637566;77994;79641;79798;80966;83240;7239.782806515813;7965.641546130322;8025.638846252049;8035.517868927883;8081.595920203848;10501.422421766876;11201.767805541222;11720.179587429138;12182.147249692436;12340.074494413135;10543.841842372218;10558.78573964004;10691.251612387057;10745.677629671123;10997.285176463503;9523.952380238332;9955.655986441738;10451.738706532398;10698.946505267424;10997.230193886351;9937.304388693012;10162.441065741159;11158.218924675195;11212.887871121184;11416.543338266376;7326.4138868889295;7951.483153594998;8004;8076.676932922853;8147.551884646401;10501.107405870282;10784.460776960963;10994.890051099386;11147.665570032912;11179.937905898927 21:05:48 INFO - PROCESS | 1639 | |3;mootools.html;1451.9637009074772;1515.454436402895;1624.3989723802194;1658.179201295359;1691.3741915491262;6190.792795404898;7531.09626844778;7821;8256.504609723414;8501.532415717142;6765.864682706343;8270.834583308346;8299.958500207491;8366.28886544643;8391.874121888177;1976.6244413561449;2256.9210077647244;2295.380247333219;2303.7581053989343;2305.5734689082487;2288.6338185890263;2319.9536009279805;2363.763623637631;2364.6689463475127;2369.95260094798;2257.0971611355426;2270.2281224383705;2285.0631241191113;2297.885105744706;2309.919152829643;2096.716943212662;2101.0860275779933;2116.968245476319;2123.3205374280237;2154.91380344786;2293.9885300573555;2311.04091801902;2311.3297143828268;2319.0839618350656;2319.721633404002;8368.58157092143;8375;8436.493810371336;8509.702160424416;8536.74389768308;5070.8225212117395;5232.895342093175;5241.0828105081455;5256.421793602701;5257.86855328616;2737.86310684465;3095.938081238385;3430.3020005097387;3431.0736101252774;3450.4996775467666;988.649029594498;1002.098111699469;1040.3705758016442;1043.7129789307926;1046.832506798916;10318;10786.73033174169;11019.008289253925;11033.393163376011;11049.28179668319;4324.027093903909;4402.295632698753;4439.755813430228;4446.377507149002;4447.132809102194;7499.550026998398;8644.135586441305;8712.825743485222;8735.694250701195;8750.64997400097;5051.242313652908;5125.846224613268;5130.256112863613;5138.640295179376;5208.713520756395;4084.509858816961;4645.001324715203;4762.714237145782;4790.53174803444;4813.711177329302;2989.37223183134;3414.6243913169533;3498.8775392861126;3513.244652399695;3581.3016461789703;1099.2689861242184;1109.3177695717077;1114.2645853736658;1115.2639258089785;1123.4775829239443;721.469719755979;820.5404973215019;828.4159667434444;843.1821133500495;849.1805407781397;5092;5102.540771330598;5104.3109180260935;5324.92012619811;5352.197170424468;378.8068085276474;405.8133258700972;413.02526038549;434.2249085382577;438.82008376565454;2875.5399136138117;3064.7241748242764;3390.0846771371594;3442.6891460077227;3518.876839310612;9731.756706082262;10222.539985700661;10255.128314093236;10257.640982565574;10293.7426564335;8121.878171827428;10086.798264034678;10214.489275536194;10219.489025548693;10230.48847557634;6838.555493892881;7627.237276272439;7738.148803631596;7880;7898.368130549542;4404.55954404557;4620.376249206314;5195.5843532518065;5265.763040663102;5474.8905021898545;9343.626254949726;9410.57652405657;9423.811523769624;9482.762930926781;9509.904900950902;8486.490810551386;8780.121987801169;8906.153915378029;9313.953430232805;9355.345125841126;6909.205441374306;7124.501284910007;7150.606716630427;7170.677319520739;7191.316824901835;7794.727184548514;7988.680452781824;8013.439059265796;8026.197380262161;8151.755447336589;5745.6552606843725;7155.677994490156;7285.453590980804;7318.231585683639;7335.7432489862595;7081.539699919489;7091.680874360769;7096.077509923884;7184.173820010766;7187.209406965125;6455.935440645533;6456;6472.935270647234;6518.413342799171;6549.934500654933;4615.26155815068;4622.121796858721;4651.139539185261;4674.509176536415;4773.403324584427;4494.865154045384;4533.591976722111;4639.814407423665;4653.418322709661;4741.383620129361 21:05:48 INFO - PROCESS | 1639 | |4;prototype.html;268.72589958242594;307.6876969875577;310.7250083675946;314.19722608734673;314.38223890056037;5083.745812709363;5377.2203030560595;5378.315853640259;5433.945660543393;5438.2114593383985;4600.930986035204;4825.276208568716;4947.975260123694;5271.789128434858;5343.438938911407;3174.5397117326056;3878.720022392618;4006.5793091725386;4018.718689691723;4049.7975101244965;3342.3094409000746;4065.7357271777387;4086.4891888513935;4087.591240875918;4112.547619761824;4053.675705943532;4057.2291264659766;4096.733712308705;4097.180563887235;4115.938260926088;3712.795796231206;3975.5626881043063;4022.5374081980362;4073.9388909166532;4109.856155034575;2863.3414314707525;3441.554547090228;3449.3273811606746;3469.9826500867584;3510.947335789965;4312.978435107836;4485.9551404485865;4622.375979242793;4891.021795640886;5152.330197074356;4370.2570563004365;4597.747123908183;4608.262677971508;4629.907401851978;4633.7683115844075;4737.289406589005;4806.495317991596;5196.974015129938;5549.500544950974;5553.944460555384;2978.4043191361816;3012.548117782328;3501.77238479498;3722.8831350594714;3842.7886466244345;5098.725318670333;5848.356680765112;5865.912011319833;5960.433758792908;5965.194698715704;3258.6904244096772;3571.8035508047305;3854.5952674969285;3872.0513474199;3909.631628929852;3429.1427143214196;3876.2247550490015;3931.3709806431407;3975.940360894589;3985.880423587297;3900.9077458311717;4610.561996610317;4621.2837010263465;4673.948361618609;4676.532346765364;5460.726963651801;5706.201131841545;5780.918869207167;5801;5820.108464748889;1184.6150001249457;1271.9491220351083;1276.7701813673627;1281.7372438650054;1319.99020749127;802.9718959836495;841.0286119531951;864.3042350907455;880.8988764044943;898.6764764684708;4522.660800439914;4562.931556026662;5090.363704536933;5355.009323275207;5395.271638328775;5429.4027656957705;5885.381520082012;5910.143029260733;5915.408459154136;5952.285725712942;4987.426445958688;5301.893962120736;5326.893462130735;5328;5383.327084114486;2866.7099805087796;2885.3796433766843;2933.07883336415;2951.081796832076;2985.6413433387816;4818.951810481921;5663.745131469093;5770.4603580563125;5809.518572763918;5834.737436815353;4662.417197850269;5176.637635365489;5284.365876094893;5408.702521361363;5649.432534678372;5099.464556221617;5164.45066480062;5385.569154467633;5794.652320860761;5799.2750906136735;4790.209615413418;4809.110314591811;4834.419869615669;5411.810586629449;5566.942280966603;4410.09593033421;5237.250443517184;5285.947140528546;5391.353037635509;5595.468430499096;5803.332616749128;5837.453074935231;5907.645541267538;5907.970460147843;5908.3205431375945;3171.1754943715264;3377.257003459235;3540.9291814163057;3647.598764135996;3687.8893633191046;5152.9742351288005;5308.309919710413;5370.597205209547;5402.135658294654;5755.453231942959;4805.543473370024;4973.756560859785;4984.825531106394;5315.973420132875;5400.83797486076;4558.544145585415;4741.620670346295;4910.729909854992;5323.779113636349;5416.377116631637;4240.87616781547;4523.90952180948;4841;4884.609231261562;5153.783872934674;4639.396878405786;5113.10814998438;5198.6880787152895;5647.745851436777;5669.376368599534;4649.349091127177;5099.821506247264;5510.393856675682;5558.861028474321;5566.415526369673 21:05:48 INFO - PROCESS | 1639 | |5;yui.html;683.7299266789616;691.4226620771656;738.6675995801891;756.3835474088618;779.1468342165327;13686.52097176599;14456.036512514935;14503.709925801477;14537.345819438124;14577.708445831091;13031.413586388611;13172.551019387884;13175;13223.603291901234;13232.867671323284;636.9076483909841;660.28689015863;785.1167436633787;810.5906517208801;811.7645882694042;822.6257053040882;825.3809642767924;826.4008593769499;826.5371392020458;828.2628460670009;848.3722045686179;871.2507243770384;871.2594294849359;874.3398733955886;875.6672464463527;858.5792961448908;863.7624653220352;864.5288317866778;865.5931712095305;869.7173418638968;647.9988417894365;648.3354561574376;648.3905129178557;649.4804156674642;651.6122906870404;13609.795853062213;13919.512817051456;13924.025318227728;13953.720925581532;14449.205293708841;13503.459861605525;13514.459421623222;13537.187768733907;13616.863831361758;13938.094023888516;11084.168687348481;11098;11137.164712646583;11179.183726328143;11399.544018239261;3564.8752293669595;3668;3673.5959044505084;3684.797336146511;3787.16682329887;84170.57914710448;86592.7011094834;87278;87449;89860.99999999935;10321.690349289533;10358.94222208957;10518.894811051945;10800.567977280822;10833.620823271147;20511.897440513;20838.27066052681;20896.582068358854;20910.581788364452;20972.580548388945;7320.487565870444;7352.375048120863;7381.594012329373;7386.96306518464;7470.70117195317;3583.8028908409765;3677.2093999790172;4034.5561988181275;4105.486814148231;4141.627253547195;3256.2836176041233;3264.4613638749342;3308.2887179256095;3317.3531161423775;3330.733541316689;517.510952150222;531.415443012683;607.2743072028971;618.1006635345561;624.0296339192508;389.8966773804944;491.52322247419943;501.2755672917704;621.142822904398;627.419636835925;8467;8764.03595604483;8791.469898125812;8845.159709827556;8970.41692289999;565.9179418984224;586.5542187937114;595.1935127901631;628.7893555658906;641.5092454272485;4249.320108782642;4259.42497762798;4313.439252897103;4328.805203765838;4387.100644367961;81808.59095704602;117299;118712;119827.40086299513;123052.76947230592;100429;104135;105152.47423762832;106457;106732;75372;89849;93533.0646693538;93984.0601593989;94046.05953940374;7403.51877127985;7936.365090792608;8220.3834712398;8388.24505794457;8524.957375213084;43707.781461093764;43726.81365931627;44184;45994.540054600366;56219.43780562142;64519.677401612695;71787;77001.22998770165;80116;80152.59923700344;9212.078792120734;10018.64934727281;11363.772724545628;11446.313221206754;11819.290842549475;11881.524739010689;12106.334151621746;12106.576269830513;12111.91759540011;12259.141860069709;11570.884291157317;11621.593244236412;11635.185537012328;11950.701232469259;11955.402229888645;11288.830667540158;11593.304401735923;11597;11598.18812683138;11641.359725215194;11265.561712595849;11614.30314181118;11615.6515304541;11882.346470943836;11916.225445346025;9199.632014719604;9448.905510944802;9716.951415242878;9767.367591141714;9792.951035244778;9732.902670973199;10055.69832905014;10061.949690251502;10086.495675216063;10123.392596444237 21:05:48 INFO - PROCESS | 1639 | __end_tp_report 21:05:48 INFO - PROCESS | 1639 | __start_cc_report 21:05:48 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,383 21:05:48 INFO - PROCESS | 1639 | __end_cc_report 21:05:48 INFO - PROCESS | 1639 | __startTimestamp1479186348867__endTimestamp 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | ------- Summary: start ------- 21:05:48 INFO - PROCESS | 1639 | Number of tests: 6 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5651.70 Median:5869.33 stddev:656.54 (11.2%) stddev-sans-first:621.61 21:05:48 INFO - PROCESS | 1639 | Values: 2883.6 3350.1 3388.7 3393.0 3415.1 5616.4 5654.9 5676.4 5694.0 5798.2 5636.4 5751.9 5755.0 5772.8 5773.4 5083.9 5172.5 5180.8 5241.9 5301.1 5219.0 5250.4 5260.2 5289.2 5305.0 4271.4 4885.5 5164.9 5272.7 5274.7 4290.5 5125.8 5250.6 5279.1 5291.6 5061.5 5189.9 5202.0 5210.8 5228.8 5466.4 5706.9 5743.9 5754.5 5758.5 5002.4 5158.6 6033.2 6119.6 6157.0 5169.7 5869.3 5916.3 5958.1 6113.4 5489.6 5500.4 5501.9 5533.3 5539.0 6037.9 6039.4 6044.0 6059.3 6071.4 5753.6 5983.4 6028.5 6037.7 6070.2 6107.8 6132.4 6145.7 6148.1 6166.5 6051.1 6082.1 6140.0 6155.1 6170.4 5980.9 6077.5 6084.4 6140.1 6155.9 4016.5 4650.0 4893.3 4935.2 4936.0 4403.1 4428.4 4438.1 4464.0 4465.2 4850.8 5687.3 5776.5 5808.7 5868.5 6143.7 6161.2 6181.7 6187.0 6208.0 5811.8 5824.4 5839.3 5856.3 5880.2 5410.2 5417.6 5418.6 5446.3 5455.7 6294.5 6307.5 6310.8 6325.6 6407.5 6124.7 6128.4 6193.0 6244.9 6246.8 6222.2 6277.2 6309.1 6323.7 6327.1 6154.7 6202.8 6218.9 6221.4 6223.6 6127.2 6228.0 6229.3 6234.7 6249.2 5138.4 5690.0 6014.7 6050.7 6061.3 5989.1 6090.8 6099.3 6107.7 6122.5 5568.9 5854.0 5928.9 5937.0 5951.1 5953.6 6020.1 6060.8 6080.9 6116.0 5876.8 5889.2 5900.8 5904.0 5907.9 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:68948.01 Median:39359.11 stddev:152260.22 (386.8%) stddev-sans-first:152600.55 21:05:48 INFO - PROCESS | 1639 | Values: 471.9 471.9 487.7 520.8 531.4 38811.4 40439.2 40579.8 40613.8 40624.4 36098.5 36961.6 39264.0 40865.4 41980.6 9369.2 9529.2 9549.5 9564.9 9566.4 8377.5 9324.4 9356.3 9440.9 9464.2 9333.0 9508.6 9511.7 9533.5 9552.4 9437.8 9499.1 9526.0 9537.7 9549.4 9295.3 9362.0 9404.4 9443.3 9464.5 39190.6 39289.4 39428.8 39521.6 39574.6 3371.3 3635.4 3901.2 3903.3 3913.1 28974.0 29415.9 29457.0 29536.0 29892.9 3146.5 3163.5 3176.9 3191.6 3273.0 491648.0 492083.0 495953.0 497575.0 497979.0 59570.0 59646.0 59772.0 59794.1 59882.9 51951.0 61843.0 61970.8 62453.7 62467.4 15516.6 15625.9 15766.8 15779.7 15789.2 12095.3 12190.3 12201.0 12214.5 12245.7 16442.7 17025.8 17034.0 17035.3 17049.4 5925.6 5928.6 5930.8 6002.5 6053.5 842.5 917.9 919.6 1033.6 1100.3 25391.9 29197.6 29212.8 29792.9 29816.3 999.2 1009.3 1013.9 1029.9 1061.9 14202.8 15070.8 15080.6 15428.8 15515.8 651214.0 653231.0 655273.0 655335.0 655523.0 50966.7 51314.5 51370.0 51557.5 51590.7 1249.8 1297.7 1323.4 1326.6 1334.7 24753.0 28143.3 28910.6 29597.7 29680.9 47164.0 50434.2 50891.0 50904.0 51024.5 487273.0 496430.5 575204.0 582091.0 583129.0 25437.6 26068.1 26256.9 26940.2 39982.8 45628.8 46035.5 46287.1 46781.6 47098.1 29240.4 30651.5 33929.0 37632.0 37808.1 34827.1 37556.3 37883.8 38803.4 38852.8 36510.5 36872.6 37657.6 37709.6 37755.6 27537.0 28463.6 29176.7 32030.3 32185.8 22414.9 24506.1 24701.6 24770.5 24849.3 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:26096.39 Median:227248.93 stddev:58251.36 (25.6%) stddev-sans-first:58382.71 21:05:48 INFO - PROCESS | 1639 | Values: 354.7 371.6 375.6 382.7 402.9 18692.6 19071.2 19273.9 19842.3 20004.3 13043.5 19234.1 19308.2 20062.6 20141.8 4585.3 5338.1 5512.3 5519.1 5519.4 4578.9 5971.6 6052.4 6059.6 6081.2 5770.5 5983.3 6035.8 6037.7 6052.0 5973.9 6024.9 6051.8 6053.8 6055.5 5057.4 5211.6 5236.3 5284.9 5321.5 17400.7 22443.7 23821.9 24596.8 24598.9 1739.2 1909.6 1932.2 2057.3 2239.0 9444.2 9601.3 9700.7 10146.4 10177.2 1537.9 1859.7 1864.4 1871.2 1888.0 112573.0 125715.0 126108.4 127025.0 129282.4 11044.3 11201.8 11364.8 11494.4 11583.7 14374.5 14467.4 14484.0 14491.9 14702.6 8025.7 8243.0 8317.2 8426.5 8449.5 8040.2 8081.0 8085.4 8233.0 8256.5 3761.4 3917.3 4478.9 4479.9 4514.4 1870.5 1922.9 1924.5 1951.9 1957.6 853.3 854.9 954.2 970.5 991.8 19644.2 19899.4 20389.9 20829.4 20848.0 868.8 889.4 893.2 971.4 1031.7 10676.1 11018.7 11061.4 11061.8 11067.5 227007.0 227490.9 227753.0 229026.0 560171.0 112050.0 116735.8 116764.0 117650.5 122020.4 960.6 979.3 1025.8 1027.3 1028.2 18570.2 18850.0 19086.4 19166.9 19309.7 37589.0 45532.0 54363.7 54734.6 54972.7 77994.0 79641.0 79798.0 80966.0 83240.0 7239.8 7965.6 8025.6 8035.5 8081.6 10501.4 11201.8 11720.2 12182.1 12340.1 10543.8 10558.8 10691.3 10745.7 10997.3 9524.0 9955.7 10451.7 10698.9 10997.2 9937.3 10162.4 11158.2 11212.9 11416.5 7326.4 7951.5 8004.0 8076.7 8147.6 10501.1 10784.5 10994.9 11147.7 11179.9 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5278.16 Median:4768.06 stddev:2983.14 (62.6%) stddev-sans-first:2977.66 21:05:48 INFO - PROCESS | 1639 | Values: 1452.0 1515.5 1624.4 1658.2 1691.4 6190.8 7531.1 7821.0 8256.5 8501.5 6765.9 8270.8 8300.0 8366.3 8391.9 1976.6 2256.9 2295.4 2303.8 2305.6 2288.6 2320.0 2363.8 2364.7 2370.0 2257.1 2270.2 2285.1 2297.9 2309.9 2096.7 2101.1 2117.0 2123.3 2154.9 2294.0 2311.0 2311.3 2319.1 2319.7 8368.6 8375.0 8436.5 8509.7 8536.7 5070.8 5232.9 5241.1 5256.4 5257.9 2737.9 3095.9 3430.3 3431.1 3450.5 988.6 1002.1 1040.4 1043.7 1046.8 10318.0 10786.7 11019.0 11033.4 11049.3 4324.0 4402.3 4439.8 4446.4 4447.1 7499.6 8644.1 8712.8 8735.7 8750.6 5051.2 5125.8 5130.3 5138.6 5208.7 4084.5 4645.0 4762.7 4790.5 4813.7 2989.4 3414.6 3498.9 3513.2 3581.3 1099.3 1109.3 1114.3 1115.3 1123.5 721.5 820.5 828.4 843.2 849.2 5092.0 5102.5 5104.3 5324.9 5352.2 378.8 405.8 413.0 434.2 438.8 2875.5 3064.7 3390.1 3442.7 3518.9 9731.8 10222.5 10255.1 10257.6 10293.7 8121.9 10086.8 10214.5 10219.5 10230.5 6838.6 7627.2 7738.1 7880.0 7898.4 4404.6 4620.4 5195.6 5265.8 5474.9 9343.6 9410.6 9423.8 9482.8 9509.9 8486.5 8780.1 8906.2 9314.0 9355.3 6909.2 7124.5 7150.6 7170.7 7191.3 7794.7 7988.7 8013.4 8026.2 8151.8 5745.7 7155.7 7285.5 7318.2 7335.7 7081.5 7091.7 7096.1 7184.2 7187.2 6455.9 6456.0 6472.9 6518.4 6549.9 4615.3 4622.1 4651.1 4674.5 4773.4 4494.9 4533.6 4639.8 4653.4 4741.4 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4413.93 Median:4829.85 stddev:1366.35 (28.3%) stddev-sans-first:1334.29 21:05:48 INFO - PROCESS | 1639 | Values: 268.7 307.7 310.7 314.2 314.4 5083.7 5377.2 5378.3 5433.9 5438.2 4600.9 4825.3 4948.0 5271.8 5343.4 3174.5 3878.7 4006.6 4018.7 4049.8 3342.3 4065.7 4086.5 4087.6 4112.5 4053.7 4057.2 4096.7 4097.2 4115.9 3712.8 3975.6 4022.5 4073.9 4109.9 2863.3 3441.6 3449.3 3470.0 3510.9 4313.0 4486.0 4622.4 4891.0 5152.3 4370.3 4597.7 4608.3 4629.9 4633.8 4737.3 4806.5 5197.0 5549.5 5553.9 2978.4 3012.5 3501.8 3722.9 3842.8 5098.7 5848.4 5865.9 5960.4 5965.2 3258.7 3571.8 3854.6 3872.1 3909.6 3429.1 3876.2 3931.4 3975.9 3985.9 3900.9 4610.6 4621.3 4673.9 4676.5 5460.7 5706.2 5780.9 5801.0 5820.1 1184.6 1271.9 1276.8 1281.7 1320.0 803.0 841.0 864.3 880.9 898.7 4522.7 4562.9 5090.4 5355.0 5395.3 5429.4 5885.4 5910.1 5915.4 5952.3 4987.4 5301.9 5326.9 5328.0 5383.3 2866.7 2885.4 2933.1 2951.1 2985.6 4819.0 5663.7 5770.5 5809.5 5834.7 4662.4 5176.6 5284.4 5408.7 5649.4 5099.5 5164.5 5385.6 5794.7 5799.3 4790.2 4809.1 4834.4 5411.8 5566.9 4410.1 5237.3 5285.9 5391.4 5595.5 5803.3 5837.5 5907.6 5908.0 5908.3 3171.2 3377.3 3540.9 3647.6 3687.9 5153.0 5308.3 5370.6 5402.1 5755.5 4805.5 4973.8 4984.8 5316.0 5400.8 4558.5 4741.6 4910.7 5323.8 5416.4 4240.9 4523.9 4841.0 4884.6 5153.8 4639.4 5113.1 5198.7 5647.7 5669.4 4649.3 5099.8 5510.4 5558.9 5566.4 21:05:48 INFO - PROCESS | 1639 | 21:05:48 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20431.85 Median:4321.12 stddev:31144.23 (720.7%) stddev-sans-first:31196.30 21:05:48 INFO - PROCESS | 1639 | Values: 683.7 691.4 738.7 756.4 779.1 13686.5 14456.0 14503.7 14537.3 14577.7 13031.4 13172.6 13175.0 13223.6 13232.9 636.9 660.3 785.1 810.6 811.8 822.6 825.4 826.4 826.5 828.3 848.4 871.3 871.3 874.3 875.7 858.6 863.8 864.5 865.6 869.7 648.0 648.3 648.4 649.5 651.6 13609.8 13919.5 13924.0 13953.7 14449.2 13503.5 13514.5 13537.2 13616.9 13938.1 11084.2 11098.0 11137.2 11179.2 11399.5 3564.9 3668.0 3673.6 3684.8 3787.2 84170.6 86592.7 87278.0 87449.0 89861.0 10321.7 10358.9 10518.9 10800.6 10833.6 20511.9 20838.3 20896.6 20910.6 20972.6 7320.5 7352.4 7381.6 7387.0 7470.7 3583.8 3677.2 4034.6 4105.5 4141.6 3256.3 3264.5 3308.3 3317.4 3330.7 517.5 531.4 607.3 618.1 624.0 389.9 491.5 501.3 621.1 627.4 8467.0 8764.0 8791.5 8845.2 8970.4 565.9 586.6 595.2 628.8 641.5 4249.3 4259.4 4313.4 4328.8 4387.1 81808.6 117299.0 118712.0 119827.4 123052.8 100429.0 104135.0 105152.5 106457.0 106732.0 75372.0 89849.0 93533.1 93984.1 94046.1 7403.5 7936.4 8220.4 8388.2 8525.0 43707.8 43726.8 44184.0 45994.5 56219.4 64519.7 71787.0 77001.2 80116.0 80152.6 9212.1 10018.6 11363.8 11446.3 11819.3 11881.5 12106.3 12106.6 12111.9 12259.1 11570.9 11621.6 11635.2 11950.7 11955.4 11288.8 11593.3 11597.0 11598.2 11641.4 11265.6 11614.3 11615.7 11882.3 11916.2 9199.6 9448.9 9717.0 9767.4 9793.0 9732.9 10055.7 10061.9 10086.5 10123.4 21:05:48 INFO - PROCESS | 1639 | -------- Summary: end -------- 21:05:48 INFO - PROCESS | 1639 | 21:05:49 INFO - TEST-INFO | 1639: exit 0 21:05:49 INFO - TEST-OK | dromaeo_css | took 1116133ms 21:05:49 INFO - TEST-START | kraken 21:05:49 INFO - operating with platform_type : mac_ 21:05:49 INFO - Initialising browser for kraken test... 21:05:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmp10KzNG/profile http://localhost:49250/getInfo.html) 21:05:52 INFO - PROCESS | 1651 | 2016-11-14 21:05:52.395 plugin-container[1652:9965] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 21:05:52 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:52 INFO - PROCESS | 1651 | 2016-11-14 21:05:52.398 plugin-container[1652:9965] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 21:05:52 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:52 INFO - PROCESS | 1651 | 2016-11-14 21:05:52.398 plugin-container[1652:9965] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:05:52 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 21:05:52 INFO - PROCESS | 1651 | colorDepth:24 21:05:52 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 21:05:52 INFO - PROCESS | 1651 | __metrics 21:05:52 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 21:05:53 INFO - TEST-INFO | 1651: exit 0 21:05:53 INFO - Browser initialized. 21:05:53 INFO - Running cycle 1/1 for kraken test... 21:05:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmp10KzNG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:05:55 INFO - PROCESS | 1653 | 2016-11-14 21:05:55.705 plugin-container[1654:10082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 21:05:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:55 INFO - PROCESS | 1653 | 2016-11-14 21:05:55.708 plugin-container[1654:10082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 21:05:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:05:55 INFO - PROCESS | 1653 | 2016-11-14 21:05:55.708 plugin-container[1654:10082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:06:05 INFO - PROCESS | 1653 | RSS: Main: 237944832 21:06:05 INFO - PROCESS | 1653 | 21:06:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 21:06:09 INFO - PROCESS | 1653 | RSS: Main: 250355712 21:06:09 INFO - PROCESS | 1653 | 21:06:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 21:06:12 INFO - PROCESS | 1653 | RSS: Main: 257880064 21:06:12 INFO - PROCESS | 1653 | 21:06:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 21:06:17 INFO - PROCESS | 1653 | RSS: Main: 260653056 21:06:17 INFO - PROCESS | 1653 | 21:06:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 21:06:19 INFO - PROCESS | 1653 | RSS: Main: 262770688 21:06:19 INFO - PROCESS | 1653 | 21:06:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:06:22 INFO - PROCESS | 1653 | RSS: Main: 263335936 21:06:22 INFO - PROCESS | 1653 | 21:06:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 21:06:25 INFO - PROCESS | 1653 | RSS: Main: 268623872 21:06:25 INFO - PROCESS | 1653 | 21:06:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 21:06:29 INFO - PROCESS | 1653 | RSS: Main: 267870208 21:06:29 INFO - PROCESS | 1653 | 21:06:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 21:06:33 INFO - PROCESS | 1653 | RSS: Main: 267993088 21:06:33 INFO - PROCESS | 1653 | 21:06:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:06:35 INFO - PROCESS | 1653 | RSS: Main: 267300864 21:06:35 INFO - PROCESS | 1653 | 21:06:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:06:38 INFO - PROCESS | 1653 | RSS: Main: 267329536 21:06:38 INFO - PROCESS | 1653 | 21:06:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:06:41 INFO - PROCESS | 1653 | RSS: Main: 267378688 21:06:41 INFO - PROCESS | 1653 | 21:06:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:06:45 INFO - PROCESS | 1653 | RSS: Main: 267661312 21:06:45 INFO - PROCESS | 1653 | 21:06:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:06:48 INFO - PROCESS | 1653 | RSS: Main: 267390976 21:06:48 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 21:06:50 INFO - PROCESS | 1653 | RSS: Main: 270516224 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | __start_tp_report 21:06:50 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 21:06:50 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 21:06:50 INFO - PROCESS | 1653 | |i|pagename|runs| 21:06:50 INFO - PROCESS | 1653 | |0;ai-astar;106;118;120;142;172;162;118;117;119;119 21:06:50 INFO - PROCESS | 1653 | |1;audio-beat-detection;147;117;115;153;119;126;108;149;132;130 21:06:50 INFO - PROCESS | 1653 | |2;audio-dft;198;224;251;209;229;261;277;207;202;252 21:06:50 INFO - PROCESS | 1653 | |3;audio-fft;79;58;58;87;59;59;60;65;86;75 21:06:50 INFO - PROCESS | 1653 | |4;audio-oscillator;89;70;87;73;75;70;77;73;107;122 21:06:50 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;79;127;130;135;88;91;87;88;130 21:06:50 INFO - PROCESS | 1653 | |6;imaging-darkroom;105;101;105;122;111;141;111;105;171;170 21:06:50 INFO - PROCESS | 1653 | |7;imaging-desaturate;139;109;136;141;117;87;87;144;119;92 21:06:50 INFO - PROCESS | 1653 | |8;json-parse-financial;57;56;57;57;57;58;57;57;56;58 21:06:50 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;85;108;86;77;73;70;71;71;67;69 21:06:50 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;90;99;112;111;105;85;93;91;89 21:06:50 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;148;148;149;148;152;158;165;166;207;165 21:06:50 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;176;229;191;179;180;207;241;182;176;176 21:06:50 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;71;82;93;95;92;89;77;67;72 21:06:50 INFO - PROCESS | 1653 | __end_tp_report 21:06:50 INFO - PROCESS | 1653 | __start_cc_report 21:06:50 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,983 21:06:50 INFO - PROCESS | 1653 | __end_cc_report 21:06:50 INFO - PROCESS | 1653 | __startTimestamp1479186410988__endTimestamp 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | ------- Summary: start ------- 21:06:50 INFO - PROCESS | 1653 | Number of tests: 14 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:129.30 Median:119.50 stddev:21.86 (18.3%) stddev-sans-first:21.50 21:06:50 INFO - PROCESS | 1653 | Values: 106.0 118.0 120.0 142.0 172.0 162.0 118.0 117.0 119.0 119.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:131.00 stddev:15.62 (11.9%) stddev-sans-first:15.25 21:06:50 INFO - PROCESS | 1653 | Values: 147.0 117.0 115.0 153.0 119.0 126.0 108.0 149.0 132.0 130.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:231.00 Median:240.00 stddev:27.69 (11.5%) stddev-sans-first:26.67 21:06:50 INFO - PROCESS | 1653 | Values: 198.0 224.0 251.0 209.0 229.0 261.0 277.0 207.0 202.0 252.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:68.60 Median:70.00 stddev:11.96 (17.1%) stddev-sans-first:12.07 21:06:50 INFO - PROCESS | 1653 | Values: 79.0 58.0 58.0 87.0 59.0 59.0 60.0 65.0 86.0 75.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:74.00 stddev:17.54 (23.7%) stddev-sans-first:18.53 21:06:50 INFO - PROCESS | 1653 | Values: 89.0 70.0 87.0 73.0 75.0 70.0 77.0 73.0 107.0 122.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:87.50 stddev:22.68 (25.9%) stddev-sans-first:23.44 21:06:50 INFO - PROCESS | 1653 | Values: 90.0 79.0 127.0 130.0 135.0 88.0 91.0 87.0 88.0 130.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:116.50 stddev:27.01 (23.2%) stddev-sans-first:27.74 21:06:50 INFO - PROCESS | 1653 | Values: 105.0 101.0 105.0 122.0 111.0 141.0 111.0 105.0 171.0 170.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:117.10 Median:142.50 stddev:22.71 (15.9%) stddev-sans-first:22.67 21:06:50 INFO - PROCESS | 1653 | Values: 139.0 109.0 136.0 141.0 117.0 87.0 87.0 144.0 119.0 92.0 21:06:50 INFO - PROCESS | 1653 | 21:06:50 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 21:06:50 INFO - PROCESS | 1653 | Values: 57.0 56.0 57.0 57.0 57.0 58.0 57.0 57.0 56.0 58.0 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:72.00 stddev:12.48 (17.3%) stddev-sans-first:12.96 21:06:51 INFO - PROCESS | 1653 | Values: 85.0 108.0 86.0 77.0 73.0 70.0 71.0 71.0 67.0 69.0 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:89.50 stddev:10.06 (11.2%) stddev-sans-first:9.98 21:06:51 INFO - PROCESS | 1653 | Values: 86.0 90.0 99.0 112.0 111.0 105.0 85.0 93.0 91.0 89.0 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:161.50 stddev:17.99 (11.1%) stddev-sans-first:18.49 21:06:51 INFO - PROCESS | 1653 | Values: 148.0 148.0 149.0 148.0 152.0 158.0 165.0 166.0 207.0 165.0 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.70 Median:186.50 stddev:23.92 (12.8%) stddev-sans-first:24.49 21:06:51 INFO - PROCESS | 1653 | Values: 176.0 229.0 191.0 179.0 180.0 207.0 241.0 182.0 176.0 176.0 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.60 Median:85.50 stddev:10.99 (12.9%) stddev-sans-first:10.67 21:06:51 INFO - PROCESS | 1653 | Values: 68.0 71.0 82.0 93.0 95.0 92.0 89.0 77.0 67.0 72.0 21:06:51 INFO - PROCESS | 1653 | -------- Summary: end -------- 21:06:51 INFO - PROCESS | 1653 | 21:06:51 INFO - TEST-INFO | 1653: exit 0 21:06:51 INFO - TEST-OK | kraken | took 62349ms 21:06:51 INFO - SUITE-END | took 1178s 21:06:52 INFO - Completed test suite (00:19:38) 21:06:52 INFO - javascript benchmark 21:06:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6963.506604552846, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2883.596296518487, 3350.112220261631, 3388.728901687865, 3393.032985599103, 3415.077928959183, 5616.382197958222, 5654.858628534278, 5676.403977582351, 5694.003549378863, 5798.217240672508, 5636.408177141403, 5751.884962300752, 5755.021646320125, 5772.797952071678, 5773.364929857723, 5083.923741143886, 5172.534471897528, 5180.766865491043, 5241.8951620967555, 5301.098813201765, 5219.034478621448, 5250.396204436482, 5260.158374660055, 5289.233061206123, 5305.0, 4271.444712187411, 4885.462599114095, 5164.877657205232, 5272.710000949946, 5274.709890956015, 4290.485141782975, 5125.794968201268, 5250.553702935255, 5279.102552566073, 5291.550218231436, 5061.493850614946, 5189.948100518985, 5201.985612805504, 5210.76551555181, 5228.816991405282, 5466.426025267329, 5706.857328566777, 5743.8564035899435, 5754.482096611325, 5758.452946970031, 5002.399712034543, 5158.561522270612, 6033.245844269466, 6119.632822030692, 6157.0456579230295, 5169.689818610896, 5869.325027621835, 5916.26046744157, 5958.13607026983, 6113.445900579388, 5489.615726899079, 5500.367457742411, 5501.889962200774, 5533.280673512418, 5538.972305138489, 6037.939620603738, 6039.4262545058145, 6043.969780151071, 6059.333473317932, 6071.3928607139815, 5753.597248192586, 5983.37174596661, 6028.457438830526, 6037.6679282639, 6070.180525628984, 6107.786227482017, 6132.4166970161305, 6145.723442445101, 6148.077788331786, 6166.506679465632, 6051.0923361496125, 6082.087686847009, 6139.969300153471, 6155.1075094112, 6170.382961703794, 5980.910286345708, 6077.483413909778, 6084.421979911901, 6140.078988151724, 6155.938440615537, 4016.49793775778, 4649.95350046502, 4893.290472881412, 4935.210366341368, 4935.950640493621, 4403.097365040159, 4428.4243048403505, 4438.134563760101, 4463.977680111579, 4465.24090904547, 4850.83022094225, 5687.317521897398, 5776.480116789509, 5808.651480911159, 5868.471837534642, 6143.726821076141, 6161.199044124235, 6181.690915454209, 6187.041008643668, 6207.96896015517, 5811.796587119515, 5824.417558244227, 5839.270091238595, 5856.297244330623, 5880.176775251468, 5410.242566040757, 5417.645588602849, 5418.593605479526, 5446.346438427414, 5455.672659640434, 6294.496440284766, 6307.5269354798575, 6310.842228944313, 6325.620462772248, 6407.487401007908, 6124.655575320095, 6128.387161283836, 6193.040078787795, 6244.937550624436, 6246.750129994749, 6222.222222222223, 6277.18396608438, 6309.05364195356, 6323.747050117944, 6327.145835312174, 6154.723037463235, 6202.782902598387, 6218.9427797273665, 6221.4089661482085, 6223.626582404889, 6127.189528148541, 6228.0, 6229.314775374614, 6234.688265586612, 6249.218847644044, 5138.434772174983, 5689.971550142388, 6014.669193194416, 6050.667213303311, 6061.3029501606115, 5989.101634754822, 6090.786822461193, 6099.329073801967, 6107.6640784757265, 6122.540809439399, 5568.91646625309, 5854.0, 5928.911066334094, 5937.0, 5951.137085122719, 5953.553483488669, 6020.066889632114, 6060.848478788065, 6080.908786368296, 6116.0, 5876.823695289148, 5889.234399528034, 5900.793472228452, 5903.970480147572, 5907.940920590739], "value": 5610.169120347887, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.9079779443009, 471.9126961512123, 487.66838549786155, 520.8333333333335, 531.4499493024717, 38811.41782873259, 40439.1912161757, 40579.797101014454, 40613.78158655242, 40624.39063414051, 36098.458523122106, 36961.63038369615, 39264.01839953996, 40865.365385384546, 41980.580194198046, 9369.203617692505, 9529.237660987104, 9549.522523873813, 9564.856527152098, 9566.37818541796, 8377.45546539476, 9324.440533568008, 9356.29827762917, 9440.858387124199, 9464.195543378786, 9333.0, 9508.619655213783, 9511.714648560519, 9533.47565883876, 9552.37909535878, 9437.76405589859, 9499.097585729345, 9526.047395260419, 9537.713868583953, 9549.379290346105, 9295.256379489625, 9362.0, 9404.435733855991, 9443.291753118543, 9464.52677366129, 39190.60809391898, 39289.41065884014, 39428.81713548598, 39521.60478395237, 39574.60425395738, 3371.3257348530146, 3635.4365073413665, 3901.1807520420743, 3903.2974064668347, 3913.0804260998925, 28973.985910493033, 29415.85292073526, 29457.0, 29536.0, 29892.85053574718, 3146.465100932847, 3163.4780261256865, 3176.872925082994, 3191.6010498687665, 3273.0344548358294, 491648.0, 492083.0, 495953.0, 497575.0, 497979.0, 59570.0, 59646.0, 59772.0, 59794.10308845371, 59882.90995265364, 51951.0, 61843.0, 61970.760584788055, 62453.687731561055, 62467.375326246154, 15516.612084697746, 15625.921870390575, 15766.842331576767, 15779.68440631181, 15789.21053947298, 12095.274283543016, 12190.329531875832, 12201.023918086532, 12214.511419543296, 12245.69385765363, 16442.671146577002, 17025.829741702673, 17034.0, 17035.318587256617, 17049.40327088571, 5925.585209035412, 5928.614640048383, 5930.762769489172, 6002.519798416116, 6053.485453736482, 842.4566154830101, 917.9495127768037, 919.5746592781162, 1033.6485594897772, 1100.3342977498658, 25391.87304063468, 29197.562036569467, 29212.809039322136, 29792.851035744683, 29816.254593635334, 999.1856636840952, 1009.2531526670211, 1013.9391636501833, 1029.8558201851747, 1061.936283822973, 14202.786958195635, 15070.849291507164, 15080.622984425478, 15428.845711542965, 15515.767263491056, 651214.0, 653231.0, 655273.0, 655335.0, 655523.0, 50966.745166274675, 51314.48685513172, 51370.0, 51557.48442515602, 51590.74204629028, 1249.7812882745484, 1297.7274772297735, 1323.4309247023873, 1326.6219127548795, 1334.6796768775423, 24753.009879604615, 28143.296417589725, 28910.566341504473, 29597.704022959493, 29680.851595742748, 47164.0, 50434.24348634847, 50891.0, 50903.98192036212, 51024.489755101975, 487273.0, 496430.5178474084, 575204.0, 582091.0, 583129.0, 25437.61843572385, 26068.087616933317, 26256.949722010904, 26940.191794246202, 39982.800085999384, 45628.771856140505, 46035.53964460312, 46287.07425851397, 46781.59655210349, 47098.058038838346, 29240.41519169562, 30651.54022689617, 33929.0, 37632.0, 37808.054798630255, 34827.129321767155, 37556.30996605105, 37883.81058094692, 38803.41794873136, 38852.80573597114, 36510.45234321434, 36872.63127368692, 37657.623423766505, 37709.622903770614, 37755.62244377631, 27537.0, 28463.573046403908, 29176.7082329174, 32030.263558462833, 32185.839070804497, 22414.887925560266, 24506.142285019938, 24701.629475558242, 24770.50458990846, 24849.254522364357], "value": 19279.804989837165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6843309454586, 371.58753783300534, 375.5981100222761, 382.6536984029451, 402.9214303210873, 18692.626147477044, 19071.237150513964, 19273.903630481862, 19842.30551930683, 20004.299849505234, 13043.543475978342, 19234.13446394912, 19308.227670893182, 20062.59874802503, 20141.798582014177, 4585.335126406669, 5338.092524270874, 5512.33851937768, 5519.089350257203, 5519.39286678465, 4578.931316030262, 5971.581989260753, 6052.394760523956, 6059.575829691923, 6081.1790408294755, 5770.451807078321, 5983.311919129291, 6035.818925432244, 6037.728302226388, 6052.0, 5973.88052238955, 6024.879502409927, 6051.848703782397, 6053.788117415869, 6055.485283750886, 5057.418396884332, 5211.609129315279, 5236.266922630834, 5284.920726189111, 5321.494458026481, 17400.73898891518, 22443.663345049838, 23821.88089059561, 24596.754032459627, 24598.877005615035, 1739.2347367158409, 1909.6467153576632, 1932.2174519319558, 2057.341650671786, 2238.9552208955893, 9444.197243234334, 9601.279904007157, 9700.660476883346, 10146.391216527032, 10177.236707246986, 1537.8615924566732, 1859.7024476083761, 1864.4220291709614, 1871.1766822598013, 1887.9528011799678, 112573.0, 125715.0, 126108.36945815304, 127025.0, 129282.41435171338, 11044.33733975964, 11201.775964480665, 11364.772704545863, 11494.367809770543, 11583.6524904253, 14374.496892608708, 14467.421303147967, 14484.0, 14491.92754036223, 14702.63243418923, 8025.678972841021, 8243.0, 8317.168283171635, 8426.452280601741, 8449.53527555978, 8040.2361775631225, 8081.0, 8085.434019618688, 8232.958835205785, 8256.504609723435, 3761.4169803680475, 3917.2763983847017, 4478.910421791546, 4479.865604031885, 4514.442517331516, 1870.4575673054933, 1922.9423117306503, 1924.5284905198316, 1951.9024048797503, 1957.5595491014692, 853.3343991686495, 854.8628428927681, 954.1508057828538, 970.5098925042809, 991.8165139449225, 19644.151598018492, 19899.403017909484, 20389.89805050965, 20829.375118746462, 20848.0, 868.7828042989253, 889.390767324372, 893.1827377949104, 971.4268581536786, 1031.7162780235344, 10676.128129528728, 11018.724531886766, 11061.391623460788, 11061.778764424667, 11067.50196241171, 227007.0, 227490.86254568622, 227753.0, 229026.0, 560171.0, 112050.0, 116735.83264167419, 116764.0, 117650.52922089367, 122020.38989804994, 960.558143254097, 979.314479864098, 1025.7743296474764, 1027.3476342522551, 1028.2082796246848, 18570.164342604345, 18850.0, 19086.427407177805, 19166.945817980144, 19309.710354344414, 37589.0, 45532.0, 54363.72818136042, 54734.631634209465, 54972.72513637566, 77994.0, 79641.0, 79798.0, 80966.0, 83240.0, 7239.782806515813, 7965.641546130322, 8025.638846252049, 8035.517868927883, 8081.595920203848, 10501.422421766876, 11201.767805541222, 11720.179587429138, 12182.147249692436, 12340.074494413135, 10543.841842372218, 10558.78573964004, 10691.251612387057, 10745.677629671123, 10997.285176463503, 9523.952380238332, 9955.655986441738, 10451.738706532398, 10698.946505267424, 10997.230193886351, 9937.304388693012, 10162.441065741159, 11158.218924675195, 11212.887871121184, 11416.543338266376, 7326.4138868889295, 7951.483153594998, 8004.0, 8076.676932922853, 8147.551884646401, 10501.107405870282, 10784.460776960963, 10994.890051099386, 11147.665570032912, 11179.937905898927], "value": 9090.101089865979, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.9637009074772, 1515.454436402895, 1624.3989723802194, 1658.179201295359, 1691.3741915491262, 6190.792795404898, 7531.09626844778, 7821.0, 8256.504609723414, 8501.532415717142, 6765.864682706343, 8270.834583308346, 8299.958500207491, 8366.28886544643, 8391.874121888177, 1976.6244413561449, 2256.9210077647244, 2295.380247333219, 2303.7581053989343, 2305.5734689082487, 2288.6338185890263, 2319.9536009279805, 2363.763623637631, 2364.6689463475127, 2369.95260094798, 2257.0971611355426, 2270.2281224383705, 2285.0631241191113, 2297.885105744706, 2309.919152829643, 2096.716943212662, 2101.0860275779933, 2116.968245476319, 2123.3205374280237, 2154.91380344786, 2293.9885300573555, 2311.04091801902, 2311.3297143828268, 2319.0839618350656, 2319.721633404002, 8368.58157092143, 8375.0, 8436.493810371336, 8509.702160424416, 8536.74389768308, 5070.8225212117395, 5232.895342093175, 5241.0828105081455, 5256.421793602701, 5257.86855328616, 2737.86310684465, 3095.938081238385, 3430.3020005097387, 3431.0736101252774, 3450.4996775467666, 988.649029594498, 1002.098111699469, 1040.3705758016442, 1043.7129789307926, 1046.832506798916, 10318.0, 10786.73033174169, 11019.008289253925, 11033.393163376011, 11049.28179668319, 4324.027093903909, 4402.295632698753, 4439.755813430228, 4446.377507149002, 4447.132809102194, 7499.550026998398, 8644.135586441305, 8712.825743485222, 8735.694250701195, 8750.64997400097, 5051.242313652908, 5125.846224613268, 5130.256112863613, 5138.640295179376, 5208.713520756395, 4084.509858816961, 4645.001324715203, 4762.714237145782, 4790.53174803444, 4813.711177329302, 2989.37223183134, 3414.6243913169533, 3498.8775392861126, 3513.244652399695, 3581.3016461789703, 1099.2689861242184, 1109.3177695717077, 1114.2645853736658, 1115.2639258089785, 1123.4775829239443, 721.469719755979, 820.5404973215019, 828.4159667434444, 843.1821133500495, 849.1805407781397, 5092.0, 5102.540771330598, 5104.3109180260935, 5324.92012619811, 5352.197170424468, 378.8068085276474, 405.8133258700972, 413.02526038549, 434.2249085382577, 438.82008376565454, 2875.5399136138117, 3064.7241748242764, 3390.0846771371594, 3442.6891460077227, 3518.876839310612, 9731.756706082262, 10222.539985700661, 10255.128314093236, 10257.640982565574, 10293.7426564335, 8121.878171827428, 10086.798264034678, 10214.489275536194, 10219.489025548693, 10230.48847557634, 6838.555493892881, 7627.237276272439, 7738.148803631596, 7880.0, 7898.368130549542, 4404.55954404557, 4620.376249206314, 5195.5843532518065, 5265.763040663102, 5474.8905021898545, 9343.626254949726, 9410.57652405657, 9423.811523769624, 9482.762930926781, 9509.904900950902, 8486.490810551386, 8780.121987801169, 8906.153915378029, 9313.953430232805, 9355.345125841126, 6909.205441374306, 7124.501284910007, 7150.606716630427, 7170.677319520739, 7191.316824901835, 7794.727184548514, 7988.680452781824, 8013.439059265796, 8026.197380262161, 8151.755447336589, 5745.6552606843725, 7155.677994490156, 7285.453590980804, 7318.231585683639, 7335.7432489862595, 7081.539699919489, 7091.680874360769, 7096.077509923884, 7184.173820010766, 7187.209406965125, 6455.935440645533, 6456.0, 6472.935270647234, 6518.413342799171, 6549.934500654933, 4615.26155815068, 4622.121796858721, 4651.139539185261, 4674.509176536415, 4773.403324584427, 4494.865154045384, 4533.591976722111, 4639.814407423665, 4653.418322709661, 4741.383620129361], "value": 4172.600682663193, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.72589958242594, 307.6876969875577, 310.7250083675946, 314.19722608734673, 314.38223890056037, 5083.745812709363, 5377.2203030560595, 5378.315853640259, 5433.945660543393, 5438.2114593383985, 4600.930986035204, 4825.276208568716, 4947.975260123694, 5271.789128434858, 5343.438938911407, 3174.5397117326056, 3878.720022392618, 4006.5793091725386, 4018.718689691723, 4049.7975101244965, 3342.3094409000746, 4065.7357271777387, 4086.4891888513935, 4087.591240875918, 4112.547619761824, 4053.675705943532, 4057.2291264659766, 4096.733712308705, 4097.180563887235, 4115.938260926088, 3712.795796231206, 3975.5626881043063, 4022.5374081980362, 4073.9388909166532, 4109.856155034575, 2863.3414314707525, 3441.554547090228, 3449.3273811606746, 3469.9826500867584, 3510.947335789965, 4312.978435107836, 4485.9551404485865, 4622.375979242793, 4891.021795640886, 5152.330197074356, 4370.2570563004365, 4597.747123908183, 4608.262677971508, 4629.907401851978, 4633.7683115844075, 4737.289406589005, 4806.495317991596, 5196.974015129938, 5549.500544950974, 5553.944460555384, 2978.4043191361816, 3012.548117782328, 3501.77238479498, 3722.8831350594714, 3842.7886466244345, 5098.725318670333, 5848.356680765112, 5865.912011319833, 5960.433758792908, 5965.194698715704, 3258.6904244096772, 3571.8035508047305, 3854.5952674969285, 3872.0513474199, 3909.631628929852, 3429.1427143214196, 3876.2247550490015, 3931.3709806431407, 3975.940360894589, 3985.880423587297, 3900.9077458311717, 4610.561996610317, 4621.2837010263465, 4673.948361618609, 4676.532346765364, 5460.726963651801, 5706.201131841545, 5780.918869207167, 5801.0, 5820.108464748889, 1184.6150001249457, 1271.9491220351083, 1276.7701813673627, 1281.7372438650054, 1319.99020749127, 802.9718959836495, 841.0286119531951, 864.3042350907455, 880.8988764044943, 898.6764764684708, 4522.660800439914, 4562.931556026662, 5090.363704536933, 5355.009323275207, 5395.271638328775, 5429.4027656957705, 5885.381520082012, 5910.143029260733, 5915.408459154136, 5952.285725712942, 4987.426445958688, 5301.893962120736, 5326.893462130735, 5328.0, 5383.327084114486, 2866.7099805087796, 2885.3796433766843, 2933.07883336415, 2951.081796832076, 2985.6413433387816, 4818.951810481921, 5663.745131469093, 5770.4603580563125, 5809.518572763918, 5834.737436815353, 4662.417197850269, 5176.637635365489, 5284.365876094893, 5408.702521361363, 5649.432534678372, 5099.464556221617, 5164.45066480062, 5385.569154467633, 5794.652320860761, 5799.2750906136735, 4790.209615413418, 4809.110314591811, 4834.419869615669, 5411.810586629449, 5566.942280966603, 4410.09593033421, 5237.250443517184, 5285.947140528546, 5391.353037635509, 5595.468430499096, 5803.332616749128, 5837.453074935231, 5907.645541267538, 5907.970460147843, 5908.3205431375945, 3171.1754943715264, 3377.257003459235, 3540.9291814163057, 3647.598764135996, 3687.8893633191046, 5152.9742351288005, 5308.309919710413, 5370.597205209547, 5402.135658294654, 5755.453231942959, 4805.543473370024, 4973.756560859785, 4984.825531106394, 5315.973420132875, 5400.83797486076, 4558.544145585415, 4741.620670346295, 4910.729909854992, 5323.779113636349, 5416.377116631637, 4240.87616781547, 4523.90952180948, 4841.0, 4884.609231261562, 5153.783872934674, 4639.396878405786, 5113.10814998438, 5198.6880787152895, 5647.745851436777, 5669.376368599534, 4649.349091127177, 5099.821506247264, 5510.393856675682, 5558.861028474321, 5566.415526369673], "value": 3980.2414897863823, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.7299266789616, 691.4226620771656, 738.6675995801891, 756.3835474088618, 779.1468342165327, 13686.52097176599, 14456.036512514935, 14503.709925801477, 14537.345819438124, 14577.708445831091, 13031.413586388611, 13172.551019387884, 13175.0, 13223.603291901234, 13232.867671323284, 636.9076483909841, 660.28689015863, 785.1167436633787, 810.5906517208801, 811.7645882694042, 822.6257053040882, 825.3809642767924, 826.4008593769499, 826.5371392020458, 828.2628460670009, 848.3722045686179, 871.2507243770384, 871.2594294849359, 874.3398733955886, 875.6672464463527, 858.5792961448908, 863.7624653220352, 864.5288317866778, 865.5931712095305, 869.7173418638968, 647.9988417894365, 648.3354561574376, 648.3905129178557, 649.4804156674642, 651.6122906870404, 13609.795853062213, 13919.512817051456, 13924.025318227728, 13953.720925581532, 14449.205293708841, 13503.459861605525, 13514.459421623222, 13537.187768733907, 13616.863831361758, 13938.094023888516, 11084.168687348481, 11098.0, 11137.164712646583, 11179.183726328143, 11399.544018239261, 3564.8752293669595, 3668.0, 3673.5959044505084, 3684.797336146511, 3787.16682329887, 84170.57914710448, 86592.7011094834, 87278.0, 87449.0, 89860.99999999935, 10321.690349289533, 10358.94222208957, 10518.894811051945, 10800.567977280822, 10833.620823271147, 20511.897440513, 20838.27066052681, 20896.582068358854, 20910.581788364452, 20972.580548388945, 7320.487565870444, 7352.375048120863, 7381.594012329373, 7386.96306518464, 7470.70117195317, 3583.8028908409765, 3677.2093999790172, 4034.5561988181275, 4105.486814148231, 4141.627253547195, 3256.2836176041233, 3264.4613638749342, 3308.2887179256095, 3317.3531161423775, 3330.733541316689, 517.510952150222, 531.415443012683, 607.2743072028971, 618.1006635345561, 624.0296339192508, 389.8966773804944, 491.52322247419943, 501.2755672917704, 621.142822904398, 627.419636835925, 8467.0, 8764.03595604483, 8791.469898125812, 8845.159709827556, 8970.41692289999, 565.9179418984224, 586.5542187937114, 595.1935127901631, 628.7893555658906, 641.5092454272485, 4249.320108782642, 4259.42497762798, 4313.439252897103, 4328.805203765838, 4387.100644367961, 81808.59095704602, 117299.0, 118712.0, 119827.40086299513, 123052.76947230592, 100429.0, 104135.0, 105152.47423762832, 106457.0, 106732.0, 75372.0, 89849.0, 93533.0646693538, 93984.0601593989, 94046.05953940374, 7403.51877127985, 7936.365090792608, 8220.3834712398, 8388.24505794457, 8524.957375213084, 43707.781461093764, 43726.81365931627, 44184.0, 45994.540054600366, 56219.43780562142, 64519.677401612695, 71787.0, 77001.22998770165, 80116.0, 80152.59923700344, 9212.078792120734, 10018.64934727281, 11363.772724545628, 11446.313221206754, 11819.290842549475, 11881.524739010689, 12106.334151621746, 12106.576269830513, 12111.91759540011, 12259.141860069709, 11570.884291157317, 11621.593244236412, 11635.185537012328, 11950.701232469259, 11955.402229888645, 11288.830667540158, 11593.304401735923, 11597.0, 11598.18812683138, 11641.359725215194, 11265.561712595849, 11614.30314181118, 11615.6515304541, 11882.346470943836, 11916.225445346025, 9199.632014719604, 9448.905510944802, 9716.951415242878, 9767.367591141714, 9792.951035244778, 9732.902670973199, 10055.69832905014, 10061.949690251502, 10086.495675216063, 10123.392596444237], "value": 6981.608676211077, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1654.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 120.0, 142.0, 172.0, 162.0, 118.0, 117.0, 119.0, 119.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 115.0, 153.0, 119.0, 126.0, 108.0, 149.0, 132.0, 130.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 224.0, 251.0, 209.0, 229.0, 261.0, 277.0, 207.0, 202.0, 252.0], "value": 231.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 87.0, 59.0, 59.0, 60.0, 65.0, 86.0, 75.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 87.0, 73.0, 75.0, 70.0, 77.0, 73.0, 107.0, 122.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 79.0, 127.0, 130.0, 135.0, 88.0, 91.0, 87.0, 88.0, 130.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 101.0, 105.0, 122.0, 111.0, 141.0, 111.0, 105.0, 171.0, 170.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 136.0, 141.0, 117.0, 87.0, 87.0, 144.0, 119.0, 92.0], "value": 117.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 57.0, 57.0, 58.0, 57.0, 57.0, 56.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 108.0, 86.0, 77.0, 73.0, 70.0, 71.0, 71.0, 67.0, 69.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 99.0, 112.0, 111.0, 105.0, 85.0, 93.0, 91.0, 89.0], "value": 96.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 148.0, 152.0, 158.0, 165.0, 166.0, 207.0, 165.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 229.0, 191.0, 179.0, 180.0, 207.0, 241.0, 182.0, 176.0, 176.0], "value": 193.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 82.0, 93.0, 95.0, 92.0, 89.0, 77.0, 67.0, 72.0], "value": 80.6, "unit": "score"}]}]} 21:06:52 INFO - Return code: 0 21:06:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:06:52 ERROR - # TBPL SUCCESS # 21:06:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:06:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:06:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:06:52 INFO - 2016-11-14 21:06:52.295 screenresolution[1658:10473] starting screenresolution argv=screenresolution get 21:06:52 INFO - 2016-11-14 21:06:52.298 screenresolution[1658:10473] Display 0: 1600x1200x32@60 21:06:52 INFO - 2016-11-14 21:06:52.304 screenresolution[1659:10476] starting screenresolution argv=screenresolution list 21:06:52 INFO - Available Modes on Display 0 21:06:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:06:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:06:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:06:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:06:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:06:52 INFO - Intel Iris: 21:06:52 INFO - Chipset Model: Intel Iris 21:06:52 INFO - Type: GPU 21:06:52 INFO - Bus: Built-In 21:06:52 INFO - VRAM (Dynamic, Max): 1536 MB 21:06:52 INFO - Vendor: Intel (0x8086) 21:06:52 INFO - Device ID: 0x0a2e 21:06:52 INFO - Revision ID: 0x0009 21:06:52 INFO - Displays: 21:06:52 INFO - Display: 21:06:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:06:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:06:52 INFO - Display Serial Number: 1600x1200 60 21:06:52 INFO - Main Display: Yes 21:06:52 INFO - Mirror: Off 21:06:52 INFO - Online: Yes 21:06:52 INFO - Rotation: Supported 21:06:52 INFO - Return code: 0 21:06:52 INFO - Running post-action listener: _resource_record_post_action 21:06:52 INFO - [mozharness: 2016-11-15 05:06:52.542906Z] Finished run-tests step (success) 21:06:52 INFO - Running post-run listener: _resource_record_post_run 21:06:52 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 43756032; Write bytes: 700000768; Read time: 356; Write time: 1928 21:06:52 INFO - TinderboxPrint: CPU usage
54.7% 21:06:52 INFO - TinderboxPrint: I/O read bytes / time
43,756,032 / 356 21:06:52 INFO - TinderboxPrint: I/O write bytes / time
700,000,768 / 1,928 21:06:52 INFO - TinderboxPrint: CPU idle
2,198.7 (45.3%) 21:06:52 INFO - TinderboxPrint: CPU system
986.8 (20.3%) 21:06:52 INFO - TinderboxPrint: CPU user
1,668.2 (34.4%) 21:06:52 INFO - TinderboxPrint: Swap in / out
442,580,992 / 0 21:06:52 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387693056; Write bytes: 381593088; Read time: 28741; Write time: 753 21:06:52 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 41941504; Write bytes: 298079232; Read time: 341; Write time: 1122 21:06:53 INFO - Running post-run listener: _upload_blobber_files 21:06:53 INFO - Blob upload gear active. 21:06:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:06:53 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 21:06:53 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'] 21:06:53 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 21:06:53 INFO - (blobuploader) - INFO - Open directory for files ... 21:06:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:06:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:54 INFO - (blobuploader) - INFO - Done attempting. 21:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:55 INFO - (blobuploader) - INFO - Done attempting. 21:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:56 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 21:07:56 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 21:07:56 INFO - (blobuploader) - INFO - Done attempting. 21:07:56 INFO - (blobuploader) - INFO - Iteration through files over. 21:07:56 INFO - Return code: 0 21:07:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:07:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:07:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f80bac9ad5fbc854258c1288ef239058c1e4e26edfcff14896821a2cfc6f85f886006c3660786efb14ba2292435698cb18707fa699f4cc7f98b024ca1710f371", "resource-usage.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f81e31f0e7c5537793ffc005a837ea71491c4a02734aacf0783f0e32a1e4f42a87c11ecb6c48953cf894a9238a56882e948c9bd21511fea6979ae13589c4353"} 21:07:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:07:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:07:56 INFO - Contents: 21:07:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f80bac9ad5fbc854258c1288ef239058c1e4e26edfcff14896821a2cfc6f85f886006c3660786efb14ba2292435698cb18707fa699f4cc7f98b024ca1710f371", "resource-usage.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f81e31f0e7c5537793ffc005a837ea71491c4a02734aacf0783f0e32a1e4f42a87c11ecb6c48953cf894a9238a56882e948c9bd21511fea6979ae13589c4353"} 21:07:56 INFO - Running post-run listener: copy_logs_to_upload_dir 21:07:56 INFO - Copying logs to upload dir... 21:07:56 INFO - mkdir: /builds/slave/test/build/upload/logs 21:07:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1334.026333 ========= master_lag: 1.70 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-11-14 21:07:57.844924) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-11-14 21:07:57.852491) ========= 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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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/f80bac9ad5fbc854258c1288ef239058c1e4e26edfcff14896821a2cfc6f85f886006c3660786efb14ba2292435698cb18707fa699f4cc7f98b024ca1710f371", "resource-usage.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f81e31f0e7c5537793ffc005a837ea71491c4a02734aacf0783f0e32a1e4f42a87c11ecb6c48953cf894a9238a56882e948c9bd21511fea6979ae13589c4353"} build_url:https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010732 build_url: 'https://queue.taskcluster.net/v1/task/Ao1PIlP8ROeGX0_ib1Cm9g/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/f80bac9ad5fbc854258c1288ef239058c1e4e26edfcff14896821a2cfc6f85f886006c3660786efb14ba2292435698cb18707fa699f4cc7f98b024ca1710f371", "resource-usage.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f81e31f0e7c5537793ffc005a837ea71491c4a02734aacf0783f0e32a1e4f42a87c11ecb6c48953cf894a9238a56882e948c9bd21511fea6979ae13589c4353"}' ========= master_lag: 5.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-11-14 21:08:02.924523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 21:08:02.924869) ========= 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.U5WTvZVdEw/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.RJpV2Cql2E/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.005030 ========= master_lag: 0.91 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 21:08:03.834946) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 21:08:03.838281) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 21:08:04.159413) ========= ========= Total master_lag: 7.74 =========