builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0160 starttime: 1477223973.26 results: success (0) buildid: 20161023030206 builduid: 9b8d70f72fef4e6fb21f6da08ae705ff revision: 60dd82380d43a2b681f50842238f829204486290 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.258502) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.258924) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.259215) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.283773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.284064) ========= 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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-10-23 04:59:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-10-23 04:59:33 (14.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.604308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.910155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.910440) ========= 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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.025899 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:33.959643) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 04:59:33.959953) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 60dd82380d43a2b681f50842238f829204486290 --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 60dd82380d43a2b681f50842238f829204486290 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-10-23 04:59:34,035 truncating revision to first 12 chars 2016-10-23 04:59:34,035 Setting DEBUG logging. 2016-10-23 04:59:34,035 attempt 1/10 2016-10-23 04:59:34,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/60dd82380d43?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-23 04:59:35,224 unpacking tar archive at: mozilla-central-60dd82380d43/testing/mozharness/ program finished with exit code 0 elapsedTime=1.439517 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-23 04:59:35.420818) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:35.421089) ========= script_repo_revision: 60dd82380d43a2b681f50842238f829204486290 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:35.421461) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:35.421741) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 04:59:35.439410) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-23 04:59:35.439729) ========= /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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 04:59:35 INFO - MultiFileLogger online at 20161023 04:59:35 in /builds/slave/test 04:59:35 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 04:59:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:35 INFO - {'append_to_log': False, 04:59:35 INFO - 'base_work_dir': '/builds/slave/test', 04:59:35 INFO - 'blob_upload_branch': 'Firefox', 04:59:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:35 INFO - 'branch': 'Firefox', 04:59:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:35 INFO - 'config_files': ('talos/mac_config.py',), 04:59:35 INFO - 'default_actions': ('clobber', 04:59:35 INFO - 'read-buildbot-config', 04:59:35 INFO - 'download-and-extract', 04:59:35 INFO - 'populate-webroot', 04:59:35 INFO - 'create-virtualenv', 04:59:35 INFO - 'install', 04:59:35 INFO - 'run-tests'), 04:59:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:35 INFO - 'download_minidump_stackwalk': True, 04:59:35 INFO - 'download_symbols': 'ondemand', 04:59:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:35 INFO - 'installer_path': 'installer.exe', 04:59:35 INFO - 'log_level': 'info', 04:59:35 INFO - 'log_name': 'talos', 04:59:35 INFO - 'log_to_console': True, 04:59:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:35 INFO - 'opt_config_files': (), 04:59:35 INFO - 'pip_index': False, 04:59:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:35 INFO - 'cmd': ('bash', 04:59:35 INFO - '-c', 04:59:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:35 INFO - 'enabled': True, 04:59:35 INFO - 'halt_on_failure': False, 04:59:35 INFO - 'name': 'check_screen_resolution'},), 04:59:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:35 INFO - 'cmd': ('bash', 04:59:35 INFO - '-c', 04:59:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:35 INFO - 'enabled': True, 04:59:35 INFO - 'halt_on_failure': False, 04:59:35 INFO - 'name': 'check_screen_resolution'},), 04:59:35 INFO - 'run_cmd_checks_enabled': True, 04:59:35 INFO - 'sps_profile': False, 04:59:35 INFO - 'sps_profile_interval': 0, 04:59:35 INFO - 'suite': 'dromaeojs-e10s', 04:59:35 INFO - 'system_bits': '32', 04:59:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:35 INFO - 'title': 't-yosemite-r7-0160', 04:59:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:35 INFO - 'use_talos_json': True, 04:59:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:35 INFO - 'work_dir': 'build'} 04:59:35 INFO - [mozharness: 2016-10-23 11:59:35.644521Z] Running clobber step. 04:59:35 INFO - Running pre-action listener: _resource_record_pre_action 04:59:35 INFO - Running main action method: clobber 04:59:35 INFO - rmtree: /builds/slave/test/build 04:59:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:37 INFO - Running post-action listener: _resource_record_post_action 04:59:37 INFO - [mozharness: 2016-10-23 11:59:37.863306Z] Finished clobber step (success) 04:59:37 INFO - [mozharness: 2016-10-23 11:59:37.863428Z] Running read-buildbot-config step. 04:59:37 INFO - Running pre-action listener: _resource_record_pre_action 04:59:37 INFO - Running main action method: read_buildbot_config 04:59:37 INFO - Using buildbot properties: 04:59:37 INFO - { 04:59:37 INFO - "project": "", 04:59:37 INFO - "product": "firefox", 04:59:37 INFO - "script_repo_revision": "production", 04:59:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:59:37 INFO - "repository": "", 04:59:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:59:37 INFO - "buildid": "20161023030206", 04:59:37 INFO - "stage_platform": "macosx64", 04:59:37 INFO - "basedir": "/builds/slave/test", 04:59:37 INFO - "nightly_build": "True", 04:59:37 INFO - "buildnumber": 32, 04:59:37 INFO - "slavename": "t-yosemite-r7-0160", 04:59:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:59:37 INFO - "platform": "yosemite_r7", 04:59:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:59:37 INFO - "branch": "mozilla-central", 04:59:37 INFO - "repo_path": "mozilla-central", 04:59:37 INFO - "revision": "60dd82380d43a2b681f50842238f829204486290", 04:59:37 INFO - "pgo_build": "False", 04:59:37 INFO - "builduid": "9b8d70f72fef4e6fb21f6da08ae705ff", 04:59:37 INFO - "slavebuilddir": "test" 04:59:37 INFO - } 04:59:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:59:37 INFO - Running post-action listener: _resource_record_post_action 04:59:37 INFO - [mozharness: 2016-10-23 11:59:37.866758Z] Finished read-buildbot-config step (success) 04:59:37 INFO - [mozharness: 2016-10-23 11:59:37.866913Z] Running download-and-extract step. 04:59:37 INFO - Running pre-action listener: _resource_record_pre_action 04:59:37 INFO - Running main action method: download_and_extract 04:59:37 INFO - mkdir: /builds/slave/test/build/tests 04:59: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')]} 04:59:37 INFO - trying https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:59:37 INFO - Downloading https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:59:38 INFO - Downloaded 1199 bytes. 04:59:38 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:59:38 INFO - Using the following test package requirements: 04:59:38 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:59:38 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:38 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:59:38 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'jsshell-mac.zip'], 04:59:38 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:59:38 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:59:38 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:59:38 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:59:38 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:59:38 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:59:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:38 INFO - Fetch https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:59:39 INFO - Http code: 200 04:59:39 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 04:59:39 INFO - x-amz-cf-id: fk3n1WZizDL2JMqO5RRJTizCKBI0SyGfdV4eYdvYCdMIxLzGDYWeLQ== 04:59:39 INFO - x-amz-version-id: sRbrjUpXABNuR54QAuDASgL57DWVL2B4 04:59:40 INFO - Expected file size: 16420835 04:59:40 INFO - Obtained file size: 16420835 04:59:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:59:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:59:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:46 INFO - Fetch https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:59:47 INFO - Http code: 200 04:59:47 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 04:59:47 INFO - x-amz-cf-id: t_BlAPmMH1pdkhAyDZf6emDKAoh2dPzPg4mDGct5X7ma95gsXoQfJA== 04:59:47 INFO - x-amz-version-id: sRbrjUpXABNuR54QAuDASgL57DWVL2B4 04:59:47 INFO - Expected file size: 16420835 04:59:47 INFO - Obtained file size: 16420835 04:59:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:59:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:59:53 INFO - Fetch https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:59:54 INFO - Http code: 200 04:59:54 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 04:59:54 INFO - x-amz-cf-id: xRHqDqpjV-n21IlqAtO32C47bRe5QvVauFfodYuQqmRwriu88z1wBg== 04:59:54 INFO - x-amz-version-id: hGNyWSlvoWwI2pWYId7YZs0ROxh50JDR 04:59:54 INFO - Expected file size: 14409028 04:59:54 INFO - Obtained file size: 14409028 04:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:55 INFO - trying https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:59:55 INFO - Downloading https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:59:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:59:58 INFO - Downloaded 111472897 bytes. 04:59:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:59:58 INFO - mkdir: /builds/slave/test/properties 04:59:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:59:58 INFO - Writing to file /builds/slave/test/properties/build_url 04:59:58 INFO - Contents: 04:59:58 INFO - build_url:https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:59:58 INFO - Running post-action listener: _resource_record_post_action 04:59:58 INFO - Running post-action listener: set_extra_try_arguments 04:59:58 INFO - [mozharness: 2016-10-23 11:59:58.806015Z] Finished download-and-extract step (success) 04:59:58 INFO - [mozharness: 2016-10-23 11:59:58.806155Z] Running populate-webroot step. 04:59:58 INFO - Running pre-action listener: _resource_record_pre_action 04:59:58 INFO - Running main action method: populate_webroot 04:59:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:59:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:59:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:59:58 INFO - '--tppagecycles', 04:59:58 INFO - '7'], 04:59:58 INFO - 'tests': ['tsvgm']}}, 04:59:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:59:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:59:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:59:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:59:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['damp', 'tps']}, 04:59:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'tests': ['damp', 'tps']}, 04:59:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['dromaeo_dom']}, 04:59:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:59:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['basic_compositor_video']}, 04:59:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:59:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'other-e10s': {'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['a11yr', 04:59:58 INFO - 'ts_paint', 04:59:58 INFO - 'tpaint', 04:59:58 INFO - 'sessionrestore', 04:59:58 INFO - 'sessionrestore_no_auto_restore', 04:59:58 INFO - 'tabpaint']}, 04:59:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['tsvgx', 04:59:58 INFO - 'tsvgr_opacity', 04:59:58 INFO - 'tart', 04:59:58 INFO - 'tscrollx', 04:59:58 INFO - 'cart']}, 04:59:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:59:58 INFO - 'tsvgr_opacity', 04:59:58 INFO - 'tart', 04:59:58 INFO - 'tscrollx', 04:59:58 INFO - 'cart']}, 04:59:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'talos_options': ['--disable-e10s'], 04:59:58 INFO - 'tests': ['tp5o']}, 04:59:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'tests': ['tp5o']}, 04:59:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'talos_options': ['--disable-e10s', 04:59:58 INFO - '--xperf_path', 04:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:58 INFO - 'tests': ['tp5n']}, 04:59:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:58 INFO - 'talos_options': ['--xperf_path', 04:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:58 INFO - 'tests': ['tp5n']}}, 04:59:58 INFO - 'talos.zip': {'path': '', 04:59:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:59:58 INFO - Running post-action listener: _resource_record_post_action 04:59:58 INFO - [mozharness: 2016-10-23 11:59:58.824835Z] Finished populate-webroot step (success) 04:59:58 INFO - [mozharness: 2016-10-23 11:59:58.824977Z] Running create-virtualenv step. 04:59:58 INFO - Running pre-action listener: _resource_record_pre_action 04:59:58 INFO - Running main action method: create_virtualenv 04:59:58 INFO - Creating virtualenv /builds/slave/test/build/venv 04:59:58 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 04:59:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:59:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:59:58 INFO - Using real prefix '/tools/python27' 04:59:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:02 INFO - Installing pip.................done. 05:00:02 INFO - Return code: 0 05:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:00:02 INFO - Reading from file tmpfile_stdout 05:00:02 INFO - Output received: 05:00:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:00:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:02 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:02 INFO - 'HOME': '/Users/cltbld', 05:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:02 INFO - 'LOGNAME': 'cltbld', 05:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:02 INFO - 'MOZ_NO_REMOTE': '1', 05:00:02 INFO - 'NO_EM_RESTART': '1', 05:00:02 INFO - 'PAGER': '/bin/cat', 05:00:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:02 INFO - 'PWD': '/builds/slave/test', 05:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:02 INFO - 'SHELL': '/bin/bash', 05:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:02 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:02 INFO - 'USER': 'cltbld', 05:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:02 INFO - 'XPC_FLAGS': '0x0', 05:00:02 INFO - 'XPC_SERVICE_NAME': '0', 05:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:00:03 INFO - Cleaning up... 05:00:03 INFO - Return code: 0 05:00:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:03 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:03 INFO - 'HOME': '/Users/cltbld', 05:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:03 INFO - 'LOGNAME': 'cltbld', 05:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:03 INFO - 'MOZ_NO_REMOTE': '1', 05:00:03 INFO - 'NO_EM_RESTART': '1', 05:00:03 INFO - 'PAGER': '/bin/cat', 05:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:03 INFO - 'PWD': '/builds/slave/test', 05:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:03 INFO - 'SHELL': '/bin/bash', 05:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:03 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:03 INFO - 'USER': 'cltbld', 05:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:03 INFO - 'XPC_FLAGS': '0x0', 05:00:03 INFO - 'XPC_SERVICE_NAME': '0', 05:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:03 INFO - Downloading/unpacking psutil>=3.1.1 05:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:07 INFO - Installing collected packages: psutil 05:00:07 INFO - Running setup.py install for psutil 05:00:07 INFO - building 'psutil._psutil_osx' extension 05:00:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:00:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:00:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:00:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:00:07 INFO - building 'psutil._psutil_posix' extension 05:00:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:00:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:00:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:00:08 INFO - ^ 05:00:08 INFO - 1 warning generated. 05:00:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:00:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:08 INFO - Successfully installed psutil 05:00:08 INFO - Cleaning up... 05:00:08 INFO - Return code: 0 05:00:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:08 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:08 INFO - 'HOME': '/Users/cltbld', 05:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:08 INFO - 'LOGNAME': 'cltbld', 05:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:08 INFO - 'MOZ_NO_REMOTE': '1', 05:00:08 INFO - 'NO_EM_RESTART': '1', 05:00:08 INFO - 'PAGER': '/bin/cat', 05:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:08 INFO - 'PWD': '/builds/slave/test', 05:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:08 INFO - 'SHELL': '/bin/bash', 05:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:08 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:08 INFO - 'USER': 'cltbld', 05:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:08 INFO - 'XPC_FLAGS': '0x0', 05:00:08 INFO - 'XPC_SERVICE_NAME': '0', 05:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:11 INFO - Installing collected packages: mozsystemmonitor 05:00:11 INFO - Running setup.py install for mozsystemmonitor 05:00:11 INFO - Successfully installed mozsystemmonitor 05:00:11 INFO - Cleaning up... 05:00:11 INFO - Return code: 0 05:00:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:11 INFO - 'HOME': '/Users/cltbld', 05:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:11 INFO - 'LOGNAME': 'cltbld', 05:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:11 INFO - 'MOZ_NO_REMOTE': '1', 05:00:11 INFO - 'NO_EM_RESTART': '1', 05:00:11 INFO - 'PAGER': '/bin/cat', 05:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:11 INFO - 'PWD': '/builds/slave/test', 05:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:11 INFO - 'SHELL': '/bin/bash', 05:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:11 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:11 INFO - 'USER': 'cltbld', 05:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:11 INFO - 'XPC_FLAGS': '0x0', 05:00:11 INFO - 'XPC_SERVICE_NAME': '0', 05:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:11 INFO - Downloading/unpacking jsonschema==2.5.1 05:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:14 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:14 INFO - warning: no files found matching '*.txt' 05:00:14 INFO - no previously-included directories found matching 'build' 05:00:14 INFO - no previously-included directories found matching 'dist' 05:00:14 INFO - no previously-included directories found matching '.git*' 05:00:14 INFO - Installing collected packages: jsonschema, functools32 05:00:14 INFO - Running setup.py install for functools32 05:00:14 INFO - warning: no files found matching '*.txt' 05:00:14 INFO - no previously-included directories found matching 'build' 05:00:14 INFO - no previously-included directories found matching 'dist' 05:00:14 INFO - no previously-included directories found matching '.git*' 05:00:14 INFO - Successfully installed jsonschema functools32 05:00:14 INFO - Cleaning up... 05:00:14 INFO - Return code: 0 05:00:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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', '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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:00: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 functools32==3.2.3-2 05:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:14 INFO - 'HOME': '/Users/cltbld', 05:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:14 INFO - 'LOGNAME': 'cltbld', 05:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:14 INFO - 'MOZ_NO_REMOTE': '1', 05:00:14 INFO - 'NO_EM_RESTART': '1', 05:00:14 INFO - 'PAGER': '/bin/cat', 05:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:14 INFO - 'PWD': '/builds/slave/test', 05:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:14 INFO - 'SHELL': '/bin/bash', 05:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:14 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:14 INFO - 'USER': 'cltbld', 05:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:14 INFO - 'XPC_FLAGS': '0x0', 05:00:14 INFO - 'XPC_SERVICE_NAME': '0', 05:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:00:15 INFO - Cleaning up... 05:00:15 INFO - Return code: 0 05:00:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:15 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:15 INFO - 'HOME': '/Users/cltbld', 05:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:15 INFO - 'LOGNAME': 'cltbld', 05:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:15 INFO - 'MOZ_NO_REMOTE': '1', 05:00:15 INFO - 'NO_EM_RESTART': '1', 05:00:15 INFO - 'PAGER': '/bin/cat', 05:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:15 INFO - 'PWD': '/builds/slave/test', 05:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:15 INFO - 'SHELL': '/bin/bash', 05:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:15 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:15 INFO - 'USER': 'cltbld', 05:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:15 INFO - 'XPC_FLAGS': '0x0', 05:00:15 INFO - 'XPC_SERVICE_NAME': '0', 05:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:15 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:17 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:19 INFO - Downloading docopt-0.6.1.tar.gz 05:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:19 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:19 INFO - Running setup.py install for blobuploader 05:00:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:19 INFO - Running setup.py install for requests 05:00:19 INFO - Running setup.py install for docopt 05:00:20 INFO - Successfully installed blobuploader requests docopt 05:00:20 INFO - Cleaning up... 05:00:20 INFO - Return code: 0 05:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:20 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:20 INFO - 'HOME': '/Users/cltbld', 05:00:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:20 INFO - 'LOGNAME': 'cltbld', 05:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:20 INFO - 'MOZ_NO_REMOTE': '1', 05:00:20 INFO - 'NO_EM_RESTART': '1', 05:00:20 INFO - 'PAGER': '/bin/cat', 05:00:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:20 INFO - 'PWD': '/builds/slave/test', 05:00:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:20 INFO - 'SHELL': '/bin/bash', 05:00:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:20 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:20 INFO - 'USER': 'cltbld', 05:00:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:20 INFO - 'XPC_FLAGS': '0x0', 05:00:20 INFO - 'XPC_SERVICE_NAME': '0', 05:00:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ui38DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Etr43J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Xn72k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Pt5vVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WtPHqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-kLNIiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-PffV98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-U6SL7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-hcG4Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ogf4Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-bahoE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-poP1jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-PBP6IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-XbVQTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-KYiUbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-gFxxsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-I0mk4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:22 INFO - Running setup.py install for manifestparser 05:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:22 INFO - Running setup.py install for mozcrash 05:00:22 INFO - Running setup.py install for mozdebug 05:00:22 INFO - Running setup.py install for mozdevice 05:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:22 INFO - Running setup.py install for mozfile 05:00:22 INFO - Running setup.py install for mozhttpd 05:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Running setup.py install for mozinfo 05:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Running setup.py install for mozInstall 05:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Running setup.py install for mozleak 05:00:23 INFO - Running setup.py install for mozlog 05:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Running setup.py install for moznetwork 05:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:23 INFO - Running setup.py install for mozprocess 05:00:23 INFO - Running setup.py install for mozprofile 05:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Running setup.py install for mozrunner 05:00:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Running setup.py install for mozscreenshot 05:00:24 INFO - Running setup.py install for moztest 05:00:24 INFO - Running setup.py install for mozversion 05:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:24 INFO - Cleaning up... 05:00:24 INFO - Return code: 0 05:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:24 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:24 INFO - 'HOME': '/Users/cltbld', 05:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:24 INFO - 'LOGNAME': 'cltbld', 05:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:24 INFO - 'MOZ_NO_REMOTE': '1', 05:00:24 INFO - 'NO_EM_RESTART': '1', 05:00:24 INFO - 'PAGER': '/bin/cat', 05:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:24 INFO - 'PWD': '/builds/slave/test', 05:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:24 INFO - 'SHELL': '/bin/bash', 05:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:24 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:24 INFO - 'USER': 'cltbld', 05:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:24 INFO - 'XPC_FLAGS': '0x0', 05:00:24 INFO - 'XPC_SERVICE_NAME': '0', 05:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:24 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-F31ln4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-e9HWZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-lcMSII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-JVGuth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:25 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)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-0ICTI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-3bpFE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-AQuO9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-l3nGbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Zai0hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:25 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-tip9UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-EqyREH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-6pyONX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-q1IMEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-btsvck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-qHnVTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ohgYET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:26 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IHQQSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:26 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)) 05:00:26 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)) 05:00:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:29 INFO - Downloading blessings-1.6.tar.gz 05:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:29 INFO - Installing collected packages: blessings 05:00:29 INFO - Running setup.py install for blessings 05:00:29 INFO - Successfully installed blessings 05:00:29 INFO - Cleaning up... 05:00:29 INFO - Return code: 0 05:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:29 INFO - Reading from file tmpfile_stdout 05:00:29 INFO - Current package versions: 05:00:29 INFO - blessings == 1.6 05:00:29 INFO - blobuploader == 1.2.4 05:00:29 INFO - docopt == 0.6.1 05:00:29 INFO - functools32 == 3.2.3-2 05:00:29 INFO - jsonschema == 2.5.1 05:00:29 INFO - manifestparser == 1.1 05:00:29 INFO - mozInstall == 1.12 05:00:29 INFO - mozcrash == 0.17 05:00:29 INFO - mozdebug == 0.1 05:00:29 INFO - mozdevice == 0.48 05:00:29 INFO - mozfile == 1.2 05:00:29 INFO - mozhttpd == 0.7 05:00:29 INFO - mozinfo == 0.9 05:00:29 INFO - mozleak == 0.1 05:00:29 INFO - mozlog == 3.4 05:00:29 INFO - moznetwork == 0.27 05:00:29 INFO - mozprocess == 0.23 05:00:29 INFO - mozprofile == 0.28 05:00:29 INFO - mozrunner == 6.12 05:00:29 INFO - mozscreenshot == 0.1 05:00:29 INFO - mozsystemmonitor == 0.3 05:00:29 INFO - moztest == 0.7 05:00:29 INFO - mozversion == 1.4 05:00:29 INFO - psutil == 3.1.1 05:00:29 INFO - requests == 1.2.3 05:00:29 INFO - wsgiref == 0.1.2 05:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:29 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 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:29 INFO - 'HOME': '/Users/cltbld', 05:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:29 INFO - 'LOGNAME': 'cltbld', 05:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:29 INFO - 'MOZ_NO_REMOTE': '1', 05:00:29 INFO - 'NO_EM_RESTART': '1', 05:00:29 INFO - 'PAGER': '/bin/cat', 05:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:29 INFO - 'PWD': '/builds/slave/test', 05:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:29 INFO - 'SHELL': '/bin/bash', 05:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:29 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:29 INFO - 'USER': 'cltbld', 05:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:29 INFO - 'XPC_FLAGS': '0x0', 05:00:29 INFO - 'XPC_SERVICE_NAME': '0', 05:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:00:30 INFO - Cleaning up... 05:00:30 INFO - Return code: 0 05:00:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e51490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff03dc3bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, '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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:30 INFO - 'HOME': '/Users/cltbld', 05:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:30 INFO - 'LOGNAME': 'cltbld', 05:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:30 INFO - 'MOZ_NO_REMOTE': '1', 05:00:30 INFO - 'NO_EM_RESTART': '1', 05:00:30 INFO - 'PAGER': '/bin/cat', 05:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:30 INFO - 'PWD': '/builds/slave/test', 05:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:30 INFO - 'SHELL': '/bin/bash', 05:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:00:30 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:30 INFO - 'USER': 'cltbld', 05:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:30 INFO - 'XPC_FLAGS': '0x0', 05:00:30 INFO - 'XPC_SERVICE_NAME': '0', 05:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:30 INFO - Cleaning up... 05:00:30 INFO - Return code: 0 05:00:30 INFO - Running post-action listener: _resource_record_post_action 05:00:30 INFO - Running post-action listener: _start_resource_monitoring 05:00:30 INFO - Starting resource monitoring. 05:00:30 INFO - [mozharness: 2016-10-23 12:00:30.455377Z] Finished create-virtualenv step (success) 05:00:30 INFO - [mozharness: 2016-10-23 12:00:30.456328Z] Running install step. 05:00:30 INFO - Running pre-action listener: _resource_record_pre_action 05:00:30 INFO - Running main action method: install 05:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:30 INFO - Reading from file tmpfile_stdout 05:00:30 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:30 INFO - Reading from file tmpfile_stdout 05:00:30 INFO - Output received: 05:00:30 INFO - Usage: mozinstall [options] installer 05:00:30 INFO - Options: 05:00:30 INFO - -h, --help show this help message and exit 05:00:30 INFO - -d DEST, --destination=DEST 05:00:30 INFO - Directory to install application into. [default: 05:00:30 INFO - "/builds/slave/test"] 05:00:30 INFO - --app=APP Application being installed. [default: firefox] 05:00:30 INFO - mkdir: /builds/slave/test/build/application 05:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:01:04 INFO - Reading from file tmpfile_stdout 05:01:04 INFO - Output received: 05:01:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:01:04 INFO - Running post-action listener: _resource_record_post_action 05:01:04 INFO - [mozharness: 2016-10-23 12:01:04.506095Z] Finished install step (success) 05:01:04 INFO - [mozharness: 2016-10-23 12:01:04.506228Z] Running run-tests step. 05:01:04 INFO - Running pre-action listener: _resource_record_pre_action 05:01:04 INFO - Running main action method: run_tests 05:01:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:04 INFO - 2016-10-23 05:01:04.520 screenresolution[1653:9003] starting screenresolution argv=screenresolution get 05:01:04 INFO - 2016-10-23 05:01:04.523 screenresolution[1653:9003] Display 0: 1600x1200x32@60 05:01:04 INFO - 2016-10-23 05:01:04.529 screenresolution[1654:9009] starting screenresolution argv=screenresolution list 05:01:04 INFO - Available Modes on Display 0 05:01:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:04 INFO - Intel Iris: 05:01:04 INFO - Chipset Model: Intel Iris 05:01:04 INFO - Type: GPU 05:01:04 INFO - Bus: Built-In 05:01:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:04 INFO - Vendor: Intel (0x8086) 05:01:04 INFO - Device ID: 0x0a2e 05:01:04 INFO - Revision ID: 0x0009 05:01:04 INFO - Displays: 05:01:04 INFO - Display: 05:01:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:04 INFO - Display Serial Number: 1600x1200 60 05:01:04 INFO - Main Display: Yes 05:01:04 INFO - Mirror: Off 05:01:04 INFO - Online: Yes 05:01:04 INFO - Rotation: Supported 05:01:04 INFO - Return code: 0 05:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:04 INFO - Python 2.7.3 05:01:04 INFO - Return code: 0 05:01:04 INFO - grabbing minidump binary from tooltool 05:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:04 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 0x103c65d30>, '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 0x103a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c5db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:01:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:01:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:01:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:04 INFO - Return code: 0 05:01:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:01:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:01:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhXNCu3i5t/Render', 05:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:04 INFO - 'HOME': '/Users/cltbld', 05:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:04 INFO - 'LOGNAME': 'cltbld', 05:01:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:04 INFO - 'MOZ_NO_REMOTE': '1', 05:01:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:04 INFO - 'NO_EM_RESTART': '1', 05:01:04 INFO - 'PAGER': '/bin/cat', 05:01:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:04 INFO - 'PWD': '/builds/slave/test', 05:01:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:04 INFO - 'SHELL': '/bin/bash', 05:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcSmOrUBb7/Listeners', 05:01:04 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:04 INFO - 'USER': 'cltbld', 05:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:04 INFO - 'XPC_FLAGS': '0x0', 05:01:04 INFO - 'XPC_SERVICE_NAME': '0', 05:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:01:05 INFO - mozversion application_buildid: 20161023030206 05:01:05 INFO - mozversion application_changeset: 60dd82380d43a2b681f50842238f829204486290 05:01:05 INFO - mozversion application_display_name: Nightly 05:01:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:05 INFO - mozversion application_name: Firefox 05:01:05 INFO - mozversion application_remotingname: firefox 05:01:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:01:05 INFO - mozversion application_vendor: Mozilla 05:01:05 INFO - mozversion application_version: 52.0a1 05:01:05 INFO - mozversion platform_buildid: 20161023030206 05:01:05 INFO - mozversion platform_changeset: 60dd82380d43a2b681f50842238f829204486290 05:01:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:01:05 INFO - mozversion platform_version: 52.0a1 05:01:05 INFO - using testdate: 1477224065 05:01:05 INFO - actual date: 1477224065 05:01:05 INFO - starting webserver on 'localhost:49280' 05:01:05 INFO - SUITE-START | Running 2 tests 05:01:05 INFO - TEST-START | dromaeo_css 05:01:05 INFO - operating with platform_type : mac_ 05:01:05 INFO - Initialising browser for dromaeo_css test... 05:01:05 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpjWpUro/profile http://localhost:49280/getInfo.html) 05:01:08 INFO - PROCESS | 1664 | 2016-10-23 05:01:08.192 plugin-container[1667:9179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x934b, name = 'com.apple.tsm.portname' 05:01:08 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:08 INFO - PROCESS | 1664 | 2016-10-23 05:01:08.192 plugin-container[1667:9179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d23, name = 'com.apple.CFPasteboardClient' 05:01:08 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:08 INFO - PROCESS | 1664 | 2016-10-23 05:01:08.192 plugin-container[1667:9179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:08 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:01:08 INFO - PROCESS | 1664 | colorDepth:24 05:01:08 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:01:08 INFO - PROCESS | 1664 | __metrics 05:01:08 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 05:01:08 INFO - TEST-INFO | 1664: exit 0 05:01:08 INFO - Browser initialized. 05:01:08 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:08 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpjWpUro/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:11 INFO - PROCESS | 1669 | 2016-10-23 05:01:11.355 plugin-container[1670:9301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 05:01:11 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:11 INFO - PROCESS | 1669 | 2016-10-23 05:01:11.356 plugin-container[1670:9301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 05:01:11 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:11 INFO - PROCESS | 1669 | 2016-10-23 05:01:11.356 plugin-container[1670:9301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:20 INFO - PROCESS | 1669 | RSS: Main: 236781568 05:01:20 INFO - PROCESS | 1669 | 05:02:10 INFO - PROCESS | 1669 | 1477224130585 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:04:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 05:04:25 INFO - PROCESS | 1669 | RSS: Main: 251109376 05:04:25 INFO - PROCESS | 1669 | 05:07:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 05:07:28 INFO - PROCESS | 1669 | RSS: Main: 263499776 05:07:28 INFO - PROCESS | 1669 | 05:10:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 05:10:31 INFO - PROCESS | 1669 | RSS: Main: 268197888 05:10:31 INFO - PROCESS | 1669 | 05:13:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 05:13:33 INFO - PROCESS | 1669 | RSS: Main: 268070912 05:13:33 INFO - PROCESS | 1669 | 05:16:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 05:16:37 INFO - PROCESS | 1669 | RSS: Main: 273444864 05:16:37 INFO - PROCESS | 1669 | 05:19:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 05:19:40 INFO - PROCESS | 1669 | RSS: Main: 275943424 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | __start_tp_report 05:19:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:19:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:19:40 INFO - PROCESS | 1669 | |i|pagename|runs| 05:19:40 INFO - PROCESS | 1669 | |0;dojo.html;3294.8187849668266;3314.138324035753;3371.7471189660764;3382.2559037011847;3396.592408910931;5476.507114359717;5519.9172012419795;5523.447655234475;5553.500184983356;5577.274954255951;5533.335999680044;5565.109582466806;5590.608657393984;5641.210230567724;5644.068728659766;5090.261912022755;5094.646885691667;5117.897642047157;5167.250748641445;5211.3746350438005;5163.19970404586;5215.895682086356;5218.634695571312;5233.031889100528;5267.65760225586;4601.769911504428;5013.874653133665;5093.79624815007;5180.818671346522;5208.479152084761;5034.974825125888;5061.670991385548;5103.464136265676;5154.819581314636;5169.276301317818;4597.448306203243;5074.213496907948;5097.847064588068;5155.355580552431;5213.635045546814;4228.978855105735;5011.824586139504;5442.727863606843;5563.221149039137;5590.944090559083;5884.6763428011445;5939.792107276267;6040.879182416327;6051.394860513958;6098.674287528765;6153.938460615382;6158.753649854045;6165.445109940082;6174.197354343906;6181.289151747562;5620.18507316445;5626.634268772516;5635.802746903921;5644.096944488862;5663.546916246691;5512.704514439103;5572.637778544382;6112.694365281718;6123.008104567321;6184.072389141575;5925.816023738872;6012.849678758066;6014.7594096236535;6033.879322413527;6035.849103772441;5970.014947533608;6090.634561926298;6147.846303842351;6168.383161683885;6170.629762214281;5958.433948774859;5985.102234664748;6032.125341825411;6063.787767428119;6136.662483563447;6060.848478787977;6097.085437184458;6097.481714054266;6100.786472473531;6110.175126357884;4842.249451335048;4852.1508735971065;4852.2479015752615;4865.1485989952325;4889;4270.658347332206;4331.155424692218;4333.436653235122;4345.283028300295;4367.606915377632;5543.334799824047;5656.236408084938;5677.489025987681;5716.885662286814;5724.227229324071;6082.300535438393;6104.725287362079;6184.536159788034;6188.133661287423;6202.4417802398;5521.089020311684;5685.715714214355;5697.145428185722;5722.484976352148;5723.742431590588;5229.843104706865;5257.579393648499;5272.55183309423;5285.286486324374;5350.438203988525;6236.906446403307;6238;6246.125542424064;6247.312795592481;6255.749770009149;6097.878042439215;6147.231596050494;6152.261728592597;6163.84590385244;6169.56813023102;6133.824358648169;6135.018317212141;6179.876402471836;6199.442050215503;6205.751769929152;6056.121862329849;6069.878602428015;6093.695315234309;6110.114033465212;6111.113888486234;6129.478994285536;6151.354107818794;6162.4397945519095;6169.784057558143;6185;4802.927956080592;5609.887802244014;5910.231669882888;5914;5929.584929054925;5924.911126333194;5950.2264705587995;6006.789762358297;6011.759529618941;6027.21646185993;5698.230738850202;5717.5997680163655;5726.341470730919;5747.19539264495;5754.367019627922;5250.684958902478;5886.323072846678;5892.292924849045;6001.009833377444;6293.3391993841815;5762.6830524319885;5799.159121927212;5810.360860305278;5819.5344372450945;5869.53043756507 05:19:40 INFO - PROCESS | 1669 | |1;ext.html;473.55485843307287;490.75217015407213;495.3238828998418;516.7829511605127;539.346901746148;30278.697213027863;36251.81874090629;39760.204795904065;39936.60063399365;42322.36516452248;40870.18259634806;41242.79378603103;41254;41318.96702582437;41394.58605413945;9364.578593963271;9527.618895244183;9546.61813527458;9570.952145239298;9593.376430531993;9389.483578403186;9415.717528474122;9450.85823712645;9472.763180920498;9502.57238424269;9418.5761640726;9446.763830904249;9455.149036586707;9466.479343636098;9476.28927830412;9327.720168394924;9378.624855005792;9391.154796068353;9419.058094190596;9442.96127425986;9261.16649501548;9268.90731092694;9353.719388418358;9363.063693630651;9384.765380865465;39121.41317880205;39268.41097383542;39378.80310598428;39461.40807887884;39641.00897477557;3350.8721961554343;3419.0999707557567;3897.3569361055393;4139.337705967031;4238.618524332794;26881;27559;30331.696683033108;30823.229419264473;30925;3147.1030756234363;3190.4257233697926;3295.4562497187935;3332.0337102240323;3333.3499967506345;460986;476281;498260;501217;502916;45149;56119;56391;56441.435585643616;56464.870702585715;58125;58315.708421457624;58397.4160258392;58746.41253587495;58866.7056664714;15150.848491515164;15399.07605543671;15421.537353879401;15422.614434639223;15525.534233973;11658.18392712502;11983.940080299542;12012.159148859671;12082.033437324993;12091.455884485043;13919.582412527438;14487.710245795026;14573.584755381029;14634.707305853823;14658.413663453555;5957.314908785545;6415.743370265137;6959.478039147084;7148.213696493382;7198.7840364789145;885.831691978522;972.7665360313615;989.1789814453986;1017.3946501831399;1021.7496713305139;24133.155339563033;24743.257702268962;29551.704482955327;29718.851405743266;29744.405111897642;998.5905778630727;1068.529846867376;1069.3637285814928;1092.6995076353908;1095.1950316517352;14584.124952502883;15103.32035058425;15194.240287985556;15280.847191527942;15346.48517918875;611602;636617;642201;651326;654875;55558.16662750062;55708.72145639327;55795.72102139463;55869.441305587236;55889;1341.4902337111835;1400.1179257068186;1409.3587417724912;1413.738458385202;1420.7300612883519;28564.42871142524;28639.854405823535;28746;29201.269968250115;29360.97236596709;38246.61753382542;45356.86607834698;46131;46735.831604210165;46753.53246467492;432039;574939;580885;584575;604308;24127.155549555682;24470.14354497584;25345.74654253434;32006.479287277914;38036.619633803304;40609.18781624292;45537.544624554655;46112;46993.76503117599;47234;37366.813165934;37390.8130459346;37444;38539.42190867083;38544;32856;37472.87581372563;38343.84968450951;38367.232655346175;38556.228875422894;36934.261314773015;37228.25543489061;37397;37453;38266.6173338263;33466.83266583652;33546.329073418885;33819.4927076099;33867.4919876207;33922.98231052973;20740.481487962923;24125.51748965046;24824.503509930062;25073.87463062673;25135.87432062828 05:19:40 INFO - PROCESS | 1669 | |2;jquery.html;377.11378261086446;379.1412450798939;385.708789863653;388.1015449235019;407.3035109961965;18953.05234738263;21079.894600526975;21434.57130857382;21530.784692153073;21754.782452175474;17392.82607173928;19852.801471985276;20554.38336849897;20828.895855520703;21239.787602123975;5725.599208055438;5864.149698293755;5912.231409916705;6023.608465449754;6043.153958445821;4111.157212771374;4347.08711170653;4909.950900490985;6018.217631707872;6334.651594162319;6614.371634694697;6695.832604184886;6710.496712746539;6760.42536384408;6768.255491895888;6442.194725659293;6633.867322653568;6694.765683201064;6710.865782684344;6717.664116794141;5585;5795.420457954213;5804.912926306109;5812.157237200625;5812.389699081577;25813.870930645415;26517;26984.59523107155;27062.32344191391;27330.726692733017;2095.559932414195;2304.919327823518;2787.9442411151663;2851.344190836119;3106.3631955449077;15104.956282713343;15538;15779.92110039454;15947.840521594751;16544.007359558345;1863.57137858292;1871.3917976657506;1874.7469091672722;1906.094605062598;1920.9722798302842;127795;131615.34192329072;131801;132537.6746232535;134520.54794520568;9821.459819709984;11115.166362522843;11197.048250898602;11240.606578769868;11366.602168924048;13951.720965580631;14003.43986240539;14032.508862189774;14060.296985150702;14068.577942661737;8344.249017588447;8435.746927592181;8436.28291595209;8459.238668519863;8576.399652024298;7910.79731527799;8067.757967260992;8399.454035487674;8527;8528.275096616857;6811.489138314646;6817.69320380584;6836.726530938806;6837.35045170708;6838.760643377557;1375.77987521996;1551.8448155184392;1657.6436066245815;1811.7644706188112;1874.5969616532238;826.9958650206711;850.37497439382;927.2581934452411;944.9338546301693;970.3110791338088;18043.865085870606;20672.58654826895;21482.677759833616;21495.89252053761;22081.33755987323;870.1559487297311;904.9411788233743;926.6108234541508;1006.864073350103;1015.8882522922472;10396.324238924446;10521.42132182722;10887;11290.661280161608;11295.943520282346;547620;570623;596152;600216;617335;132538;133014.33492832474;133845;134116;134348;826.9131741167212;940.2806852757645;983.5426526665135;1010.5200029985698;1018.3431686562126;16697.332106715596;19647.508812279808;20170.310747640222;20394.28619998293;20557.383278501668;39122.21755564816;49081.50918490912;50560.66870303859;55049.72475137599;57354;73956.52086958123;77349.45301093833;77448.61275693585;78117.60941195258;78331.60834195792;6919.37725604698;7721.498102623312;7941.523508589503;8028.6387112578905;8130.227628375294;9022.954885225532;10883.455827208449;11419;12315;12543.435545400662;10660.8933910663;10827.40449275266;10921.726956826144;11008.944955275172;11051.557937682725;8015.47899386538;10013.499325033865;10029.348092373972;10078.8488172676;10288.742781430525;10775.353478790985;11129.276597021168;11177.385243811668;11313.038391736794;11313.604023859125;6890.379865812101;7276.927230727625;7364.963175184304;7938.56337901421;8242.010958684756;9103.772405689646;9973.252006099427;10440.42577658236;11171.273867198606;11400.543978240778 05:19:40 INFO - PROCESS | 1669 | |3;mootools.html;1425.8806836633246;1502.0987407555467;1616.8463995920383;1875.0624687656173;1906.5805522784983;7678.308952194301;7936.76189714308;8063.233992770684;8617.784555386119;8667.826643467135;8051.758447246577;8412.663493460253;8447.493150410979;8454.57727113645;8511.233988940994;1698.9466530750951;1854.944351669452;1975.9110840012233;2216.091402524965;2278.943026424336;2196.604611169989;2271.9886400567975;2293.6674182243564;2313.595120853852;2324;1951.9069321180114;2213.1258153029544;2284.440312123532;2291.9656205156853;2305.72331320241;2055.825254853339;2141.314779270634;2268.512269861971;2276.203328834911;2282.7488976212603;2075.4603803011173;2086.800089948286;2238.6194346961056;2278.4759505313705;2285.4172186092605;8500.532470714108;8538.146185381474;8578.69974550894;8589.742307730778;8592.699255526026;4976.45259021511;5126.71803050832;5168.767405466725;5213.191955246943;5215.556677682402;3405.387030334539;3418.5812887651596;3481.704055155315;3499.895003149884;3565.5915913214135;1038.7091614347994;1046.9790604187874;1066.5627092891878;1070.9625163119254;1071.1109778883508;11027.062699670538;11188.992990630883;11193.664190074229;11223.831642525369;11233.550657973672;4310.676699247569;4618.237990731559;4641.094986477672;4642.257238841769;4658.627309815207;9575.425474471554;9707.223422126213;9707.417554946725;9717.12545870861;9726.854097188549;5160.7419629018395;5162.9483705162465;5165;5165.328507294028;5170.741462926839;4580.473245576802;4697.78859951303;4725.716457012591;4762.690425122356;4833.758312084382;3638.7816730996224;3720.1071742781537;3722.646348596879;3750.774953502799;3758.8684396046006;1138.260130914912;1139.3107170162098;1142.348861149137;1145.7765735681628;1156.9826452603218;724.974625888102;792.1919641965163;854.8418542569644;885.8272636835756;897.4794619120894;4847.224444088929;5474.233607294982;5562.9443705563235;5799.246098007232;5878.177055212273;402.62756949821886;412.8493100018472;420.66557087115814;427.7262551966744;430.4597729849082;3866.497355343787;3945.723799334019;3962;3990.06233535119;4055.797210139481;9857;10267.897321026843;10320.896791032144;10433.686989390331;10470.319429237228;10426.165906727443;10444.955504449494;10456.68629941103;10460;10467.476626168662;7823.295903368724;8013.318867896294;8043.235892590194;8048.5975701215875;8069.152738962442;5650.491455769001;5797.8550536236935;5914.733836977261;5948.196993405835;6016.368281330398;9485.33602647808;9709.514524273616;9822;9836.06557377048;9844.901550984685;8965.237954773656;9557.665481708107;9837.163841073589;9989.450580218157;10030.699079027641;6821.454283657395;7040.225575186828;7225.24134965821;7273.599952002479;7353.154387245535;7926.445148839756;8004.959975200087;8017.238362355567;8035.99550056243;8045.919540804517;7302.123745150616;7333.523320984119;7371.336579707852;7374.926250737424;7396.630168491662;6949.548279361826;7138.46461515378;7222.530535515175;7314.049173607397;7314.305141011595;6315.431611155018;6471.385218404244;6483.902741458787;6491.091247225298;6500.28496865335;4342.32693932441;4443.755748390456;4470.910581788281;4710.764461776829;4775.116603428377;4820.807167713252;4834.7429668285795;4966.155753521838;5046.4701206374275;5054.671446355975 05:19:40 INFO - PROCESS | 1669 | |4;prototype.html;302.8561433319175;306.96316442026955;312.58894553661946;315.136525918982;317.84107946026984;4605.157936825268;5224.764885580148;5414.512693857552;5558.694271815054;5589.972050139749;4810.15822231109;5181.740912954347;5193.5325820676135;5363.061464243761;5442.319710036246;3624.836882340301;3937.763734175944;4156.06488540079;4200.936985945198;4204.453421055258;3808.90477738056;3905.433712111742;4083.9591604084026;4206.032612499127;4212.389203565481;4021.6380525752675;4056.71602987791;4058.6753059755297;4070;4093.2427500912427;3593.9101522461888;3943.5859234780223;4057.4581658969696;4080.245154646385;4096.262672718909;3353.731701463877;3368.2758206985613;3486.477028445728;3517.7009954153928;3518.9472157917653;4674.434064588367;4701.200795864711;5184;5244.190754189798;5286.97356513215;4144.979275103636;4657.976710116462;4658.231391820345;4669.649776266793;4680.555347242041;5390.4879036491475;5706.714664266811;5721.7711291548285;5740.712964351766;5747.741351639186;3306.660802375042;3845.9615403845883;3855.8264878080554;3864.439656249856;3865.956191828219;5331.546818520431;6019.518438524908;6029.352241893679;6070.150178974947;6098.054801505729;3615.240799432149;3846.2307538492414;3873.4506197520654;3892.746971446847;3939.6060393960374;3837.4458344370573;3989.6608788253325;3996.2606917720314;4006.579309172553;4015.6988225883174;4660.813567457264;4678.134545109097;4678.2982552616695;4700.741459219776;4703.270992996091;5383.461653834585;5744.569157313218;5797.536197104221;5800.4199580042505;5818.130942922909;1211.4064108586906;1263.3304348695206;1302.5441095616459;1319.3930791835671;1349.9133197775884;776.2586729673149;865.8874346334936;912.5209265135857;914.9523795254396;916.4547094478775;5353.350091704631;5381.246625472437;5417.537264938524;5432.91850622241;5449.83650490478;4887.902241955141;5241.7419819243105;5780.88438231233;5885.205497257901;5895.587308888423;4198.4961804583645;4305.935410968838;4710.8351207707565;5406.135018397038;5437.592180586472;2096.308218287961;2139.4595890958485;2357.3163782503225;2595.636610874479;2610.425706344601;4256.893207765941;4942.986687729007;5102.438731739506;5114.314984633097;5159.25190847325;4606.673629816921;4687.7656117194;5016.595353301082;5058.696478211319;5068.41713202979;4531.909361812745;4745.95254047462;4870.03092479628;4900.774806298426;5135.768890399941;4249.681273904408;4716.905661886674;4778.761061946958;4990.176620857518;5005.34930459038;4563.680542362003;4694.413198350207;4881.414230292387;4889.3888263967;4924.212126059813;4436;4442.73343599385;4568.81724731007;5039.546440820343;5105.978804239093;3161.3361194148565;3306.3883181611477;3350.7486938480197;3369.578802649669;3385.136790118504;4187.5184353799705;4407.206702793528;4654.953450465452;4862.440819305683;5523.7790488380015;4770.831146369162;4978.775955082103;4989.927165659401;5039.974800125976;5698.601097923106;4529;5665.291838520185;5697.886042279214;5742.870135776096;5743.62666426681;4931.852044438672;5352.759125839424;5561.416051314553;5565.246947211615;5592.608517403743;4437.467503899553;5459.12653975362;5672.347680016851;5731.942680573141;5758.971205143947;4496.213162696449;5116.795328186831;5535.225068490334;5553.278073850373;5607.719614019365 05:19:40 INFO - PROCESS | 1669 | |5;yui.html;609.6890585801242;740.3336996702964;787.976360709179;798.744401791427;810.9538695083343;14876.776848347256;14889.329980150893;15611.531654050383;16528.91735541322;16636.91681541592;14757.11457312562;14912.701745965074;14928;14928.552143435687;14988.025778324429;665.8435267712084;687.52560733094;877.2850127146387;886.9689560865371;904.674317245791;901.6934242357597;902.7923577577161;903.453410686535;904.2449554621899;906.9546522673872;967.1682353176262;971.3346357744932;972.0716715536681;973.2019743810079;976.3507267666993;935.0789472369711;945.1304799584365;960.4141473701071;966.5215718219456;967.8983706710835;720.7513407874275;722.4401089155893;722.869883420984;723.4176487927216;726.9781906542812;15183.696326073527;15227.847721522754;15236;15240.771388429182;15258.38966441341;13384.126222328785;14501.274936253145;14691.77962330566;14710.190939498323;14711.55865324042;11473.024792892615;11530.365829879353;11778.293302401884;12042.939785301105;12168.330741809197;3805.4101614249835;3846.9807650961848;3851.768893866377;3982.1637456134245;3982.2433737589763;82628;108519.45740271248;109560;113907.43046284716;114421.85578144195;11231.326120432801;11231.943840280746;11290.379029153475;11446.313221206754;11544.653660390202;22988.88505557495;23584.292471225548;23672.7632723674;23706.7629323708;23747.931343089604;8064.8387032259025;8117.228863257981;8155.143709910492;8342.457740246864;8353.039400468902;4368.60682538573;4434.13534360803;4453.220686379936;4463.754493502824;4595.8969847237095;3582.7671201371827;3690.1881586051027;3694.224212915264;3694.3350196964802;3700.8149592520267;637.6588525139028;641.1056576076347;645.9128017717547;650.6421468192475;655.465795376776;627.3914303125961;667.2399988137925;667.4860357524776;686.5400181878153;690.5683947532792;8756.956215218883;9127.589258483384;9288.62845486173;9500.287478439144;9561.474118923526;625.6465097220056;657.0538424668462;660.1220376898713;666.7964324394515;667.4426853577318;4044.8674371176116;4262.722923010057;4443.888902777457;4670.346151538787;4740.597049250783;143691;145968;154941;157615;162446;111691;113613;114158;137229.6277037217;141493;65892;69466.305336947;72680.63659681668;97842.5107874456;99593;9571.282153838629;9680.273979451711;9912.454814985245;10077.899221007989;10169.135623472088;51934.961300773015;61296.693516532134;63858.40353991187;64404.84761218997;64541.67729161324;76617;93149.5342523283;94084;98917;99745;12924.224546526862;13069.869301306866;13131.277779721824;13136.540221092217;13354.332283385986;13091.970744534612;13441.2761475993;14455.78316325277;14645.926770366506;15291.923540382228;13356.866431335562;13418.530351437652;13495.055346125677;13556.153207826466;13596;11351.659450216506;13564.660883477993;13752.106113102616;13919.791203132163;14209.957675419098;13187.340632968506;13455.730885382432;13630.182189068688;13739.862601373858;13802.516911908437;11051.83422248683;11078.501467434146;11171.497282622138;11321.886781132083;11681.474333655176;8911.930568331842;9231.58457869384;9776.608935642495;11466.713332166762;11603.245789023686 05:19:40 INFO - PROCESS | 1669 | __end_tp_report 05:19:40 INFO - PROCESS | 1669 | __start_cc_report 05:19:40 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,352 05:19:40 INFO - PROCESS | 1669 | __end_cc_report 05:19:40 INFO - PROCESS | 1669 | __startTimestamp1477225180067__endTimestamp 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:19:40 INFO - PROCESS | 1669 | Number of tests: 6 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5602.79 Median:5726.34 stddev:634.28 (11.1%) stddev-sans-first:609.83 05:19:40 INFO - PROCESS | 1669 | Values: 3294.8 3314.1 3371.7 3382.3 3396.6 5476.5 5519.9 5523.4 5553.5 5577.3 5533.3 5565.1 5590.6 5641.2 5644.1 5090.3 5094.6 5117.9 5167.3 5211.4 5163.2 5215.9 5218.6 5233.0 5267.7 4601.8 5013.9 5093.8 5180.8 5208.5 5035.0 5061.7 5103.5 5154.8 5169.3 4597.4 5074.2 5097.8 5155.4 5213.6 4229.0 5011.8 5442.7 5563.2 5590.9 5884.7 5939.8 6040.9 6051.4 6098.7 6153.9 6158.8 6165.4 6174.2 6181.3 5620.2 5626.6 5635.8 5644.1 5663.5 5512.7 5572.6 6112.7 6123.0 6184.1 5925.8 6012.8 6014.8 6033.9 6035.8 5970.0 6090.6 6147.8 6168.4 6170.6 5958.4 5985.1 6032.1 6063.8 6136.7 6060.8 6097.1 6097.5 6100.8 6110.2 4842.2 4852.2 4852.2 4865.1 4889.0 4270.7 4331.2 4333.4 4345.3 4367.6 5543.3 5656.2 5677.5 5716.9 5724.2 6082.3 6104.7 6184.5 6188.1 6202.4 5521.1 5685.7 5697.1 5722.5 5723.7 5229.8 5257.6 5272.6 5285.3 5350.4 6236.9 6238.0 6246.1 6247.3 6255.7 6097.9 6147.2 6152.3 6163.8 6169.6 6133.8 6135.0 6179.9 6199.4 6205.8 6056.1 6069.9 6093.7 6110.1 6111.1 6129.5 6151.4 6162.4 6169.8 6185.0 4802.9 5609.9 5910.2 5914.0 5929.6 5924.9 5950.2 6006.8 6011.8 6027.2 5698.2 5717.6 5726.3 5747.2 5754.4 5250.7 5886.3 5892.3 6001.0 6293.3 5762.7 5799.2 5810.4 5819.5 5869.5 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:68492.36 Median:39551.21 stddev:151304.60 (382.6%) stddev-sans-first:151642.86 05:19:40 INFO - PROCESS | 1669 | Values: 473.6 490.8 495.3 516.8 539.3 30278.7 36251.8 39760.2 39936.6 42322.4 40870.2 41242.8 41254.0 41319.0 41394.6 9364.6 9527.6 9546.6 9571.0 9593.4 9389.5 9415.7 9450.9 9472.8 9502.6 9418.6 9446.8 9455.1 9466.5 9476.3 9327.7 9378.6 9391.2 9419.1 9443.0 9261.2 9268.9 9353.7 9363.1 9384.8 39121.4 39268.4 39378.8 39461.4 39641.0 3350.9 3419.1 3897.4 4139.3 4238.6 26881.0 27559.0 30331.7 30823.2 30925.0 3147.1 3190.4 3295.5 3332.0 3333.3 460986.0 476281.0 498260.0 501217.0 502916.0 45149.0 56119.0 56391.0 56441.4 56464.9 58125.0 58315.7 58397.4 58746.4 58866.7 15150.8 15399.1 15421.5 15422.6 15525.5 11658.2 11983.9 12012.2 12082.0 12091.5 13919.6 14487.7 14573.6 14634.7 14658.4 5957.3 6415.7 6959.5 7148.2 7198.8 885.8 972.8 989.2 1017.4 1021.7 24133.2 24743.3 29551.7 29718.9 29744.4 998.6 1068.5 1069.4 1092.7 1095.2 14584.1 15103.3 15194.2 15280.8 15346.5 611602.0 636617.0 642201.0 651326.0 654875.0 55558.2 55708.7 55795.7 55869.4 55889.0 1341.5 1400.1 1409.4 1413.7 1420.7 28564.4 28639.9 28746.0 29201.3 29361.0 38246.6 45356.9 46131.0 46735.8 46753.5 432039.0 574939.0 580885.0 584575.0 604308.0 24127.2 24470.1 25345.7 32006.5 38036.6 40609.2 45537.5 46112.0 46993.8 47234.0 37366.8 37390.8 37444.0 38539.4 38544.0 32856.0 37472.9 38343.8 38367.2 38556.2 36934.3 37228.3 37397.0 37453.0 38266.6 33466.8 33546.3 33819.5 33867.5 33923.0 20740.5 24125.5 24824.5 25073.9 25135.9 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35283.39 Median:15059.34 stddev:98515.51 (654.2%) stddev-sans-first:98757.01 05:19:40 INFO - PROCESS | 1669 | Values: 377.1 379.1 385.7 388.1 407.3 18953.1 21079.9 21434.6 21530.8 21754.8 17392.8 19852.8 20554.4 20828.9 21239.8 5725.6 5864.1 5912.2 6023.6 6043.2 4111.2 4347.1 4910.0 6018.2 6334.7 6614.4 6695.8 6710.5 6760.4 6768.3 6442.2 6633.9 6694.8 6710.9 6717.7 5585.0 5795.4 5804.9 5812.2 5812.4 25813.9 26517.0 26984.6 27062.3 27330.7 2095.6 2304.9 2787.9 2851.3 3106.4 15105.0 15538.0 15779.9 15947.8 16544.0 1863.6 1871.4 1874.7 1906.1 1921.0 127795.0 131615.3 131801.0 132537.7 134520.5 9821.5 11115.2 11197.0 11240.6 11366.6 13951.7 14003.4 14032.5 14060.3 14068.6 8344.2 8435.7 8436.3 8459.2 8576.4 7910.8 8067.8 8399.5 8527.0 8528.3 6811.5 6817.7 6836.7 6837.4 6838.8 1375.8 1551.8 1657.6 1811.8 1874.6 827.0 850.4 927.3 944.9 970.3 18043.9 20672.6 21482.7 21495.9 22081.3 870.2 904.9 926.6 1006.9 1015.9 10396.3 10521.4 10887.0 11290.7 11295.9 547620.0 570623.0 596152.0 600216.0 617335.0 132538.0 133014.3 133845.0 134116.0 134348.0 826.9 940.3 983.5 1010.5 1018.3 16697.3 19647.5 20170.3 20394.3 20557.4 39122.2 49081.5 50560.7 55049.7 57354.0 73956.5 77349.5 77448.6 78117.6 78331.6 6919.4 7721.5 7941.5 8028.6 8130.2 9023.0 10883.5 11419.0 12315.0 12543.4 10660.9 10827.4 10921.7 11008.9 11051.6 8015.5 10013.5 10029.3 10078.8 10288.7 10775.4 11129.3 11177.4 11313.0 11313.6 6890.4 7276.9 7365.0 7938.6 8242.0 9103.8 9973.3 10440.4 11171.3 11400.5 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5497.42 Median:4834.25 stddev:3124.30 (64.6%) stddev-sans-first:3118.08 05:19:40 INFO - PROCESS | 1669 | Values: 1425.9 1502.1 1616.8 1875.1 1906.6 7678.3 7936.8 8063.2 8617.8 8667.8 8051.8 8412.7 8447.5 8454.6 8511.2 1698.9 1854.9 1975.9 2216.1 2278.9 2196.6 2272.0 2293.7 2313.6 2324.0 1951.9 2213.1 2284.4 2292.0 2305.7 2055.8 2141.3 2268.5 2276.2 2282.7 2075.5 2086.8 2238.6 2278.5 2285.4 8500.5 8538.1 8578.7 8589.7 8592.7 4976.5 5126.7 5168.8 5213.2 5215.6 3405.4 3418.6 3481.7 3499.9 3565.6 1038.7 1047.0 1066.6 1071.0 1071.1 11027.1 11189.0 11193.7 11223.8 11233.6 4310.7 4618.2 4641.1 4642.3 4658.6 9575.4 9707.2 9707.4 9717.1 9726.9 5160.7 5162.9 5165.0 5165.3 5170.7 4580.5 4697.8 4725.7 4762.7 4833.8 3638.8 3720.1 3722.6 3750.8 3758.9 1138.3 1139.3 1142.3 1145.8 1157.0 725.0 792.2 854.8 885.8 897.5 4847.2 5474.2 5562.9 5799.2 5878.2 402.6 412.8 420.7 427.7 430.5 3866.5 3945.7 3962.0 3990.1 4055.8 9857.0 10267.9 10320.9 10433.7 10470.3 10426.2 10445.0 10456.7 10460.0 10467.5 7823.3 8013.3 8043.2 8048.6 8069.2 5650.5 5797.9 5914.7 5948.2 6016.4 9485.3 9709.5 9822.0 9836.1 9844.9 8965.2 9557.7 9837.2 9989.5 10030.7 6821.5 7040.2 7225.2 7273.6 7353.2 7926.4 8005.0 8017.2 8036.0 8045.9 7302.1 7333.5 7371.3 7374.9 7396.6 6949.5 7138.5 7222.5 7314.0 7314.3 6315.4 6471.4 6483.9 6491.1 6500.3 4342.3 4443.8 4470.9 4710.8 4775.1 4820.8 4834.7 4966.2 5046.5 5054.7 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4368.97 Median:4707.05 stddev:1343.12 (28.5%) stddev-sans-first:1311.76 05:19:40 INFO - PROCESS | 1669 | Values: 302.9 307.0 312.6 315.1 317.8 4605.2 5224.8 5414.5 5558.7 5590.0 4810.2 5181.7 5193.5 5363.1 5442.3 3624.8 3937.8 4156.1 4200.9 4204.5 3808.9 3905.4 4084.0 4206.0 4212.4 4021.6 4056.7 4058.7 4070.0 4093.2 3593.9 3943.6 4057.5 4080.2 4096.3 3353.7 3368.3 3486.5 3517.7 3518.9 4674.4 4701.2 5184.0 5244.2 5287.0 4145.0 4658.0 4658.2 4669.6 4680.6 5390.5 5706.7 5721.8 5740.7 5747.7 3306.7 3846.0 3855.8 3864.4 3866.0 5331.5 6019.5 6029.4 6070.2 6098.1 3615.2 3846.2 3873.5 3892.7 3939.6 3837.4 3989.7 3996.3 4006.6 4015.7 4660.8 4678.1 4678.3 4700.7 4703.3 5383.5 5744.6 5797.5 5800.4 5818.1 1211.4 1263.3 1302.5 1319.4 1349.9 776.3 865.9 912.5 915.0 916.5 5353.4 5381.2 5417.5 5432.9 5449.8 4887.9 5241.7 5780.9 5885.2 5895.6 4198.5 4305.9 4710.8 5406.1 5437.6 2096.3 2139.5 2357.3 2595.6 2610.4 4256.9 4943.0 5102.4 5114.3 5159.3 4606.7 4687.8 5016.6 5058.7 5068.4 4531.9 4746.0 4870.0 4900.8 5135.8 4249.7 4716.9 4778.8 4990.2 5005.3 4563.7 4694.4 4881.4 4889.4 4924.2 4436.0 4442.7 4568.8 5039.5 5106.0 3161.3 3306.4 3350.7 3369.6 3385.1 4187.5 4407.2 4655.0 4862.4 5523.8 4770.8 4978.8 4989.9 5040.0 5698.6 4529.0 5665.3 5697.9 5742.9 5743.6 4931.9 5352.8 5561.4 5565.2 5592.6 4437.5 5459.1 5672.3 5731.9 5759.0 4496.2 5116.8 5535.2 5553.3 5607.7 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:24004.43 Median:3916.97 stddev:38065.89 (971.8%) stddev-sans-first:38132.15 05:19:40 INFO - PROCESS | 1669 | Values: 609.7 740.3 788.0 798.7 811.0 14876.8 14889.3 15611.5 16528.9 16636.9 14757.1 14912.7 14928.0 14928.6 14988.0 665.8 687.5 877.3 887.0 904.7 901.7 902.8 903.5 904.2 907.0 967.2 971.3 972.1 973.2 976.4 935.1 945.1 960.4 966.5 967.9 720.8 722.4 722.9 723.4 727.0 15183.7 15227.8 15236.0 15240.8 15258.4 13384.1 14501.3 14691.8 14710.2 14711.6 11473.0 11530.4 11778.3 12042.9 12168.3 3805.4 3847.0 3851.8 3982.2 3982.2 82628.0 108519.5 109560.0 113907.4 114421.9 11231.3 11231.9 11290.4 11446.3 11544.7 22988.9 23584.3 23672.8 23706.8 23747.9 8064.8 8117.2 8155.1 8342.5 8353.0 4368.6 4434.1 4453.2 4463.8 4595.9 3582.8 3690.2 3694.2 3694.3 3700.8 637.7 641.1 645.9 650.6 655.5 627.4 667.2 667.5 686.5 690.6 8757.0 9127.6 9288.6 9500.3 9561.5 625.6 657.1 660.1 666.8 667.4 4044.9 4262.7 4443.9 4670.3 4740.6 143691.0 145968.0 154941.0 157615.0 162446.0 111691.0 113613.0 114158.0 137229.6 141493.0 65892.0 69466.3 72680.6 97842.5 99593.0 9571.3 9680.3 9912.5 10077.9 10169.1 51935.0 61296.7 63858.4 64404.8 64541.7 76617.0 93149.5 94084.0 98917.0 99745.0 12924.2 13069.9 13131.3 13136.5 13354.3 13092.0 13441.3 14455.8 14645.9 15291.9 13356.9 13418.5 13495.1 13556.2 13596.0 11351.7 13564.7 13752.1 13919.8 14210.0 13187.3 13455.7 13630.2 13739.9 13802.5 11051.8 11078.5 11171.5 11321.9 11681.5 8911.9 9231.6 9776.6 11466.7 11603.2 05:19:40 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:19:40 INFO - PROCESS | 1669 | 05:19:40 INFO - TEST-INFO | 1669: exit 0 05:19:40 INFO - TEST-OK | dromaeo_css | took 1115613ms 05:19:40 INFO - TEST-START | kraken 05:19:40 INFO - operating with platform_type : mac_ 05:19:40 INFO - Initialising browser for kraken test... 05:19:40 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpG2VfU7/profile http://localhost:49280/getInfo.html) 05:19:43 INFO - PROCESS | 1677 | 2016-10-23 05:19:43.411 plugin-container[1679:11336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:19:43 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:43 INFO - PROCESS | 1677 | 2016-10-23 05:19:43.412 plugin-container[1679:11336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:19:43 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:43 INFO - PROCESS | 1677 | 2016-10-23 05:19:43.412 plugin-container[1679:11336] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:43 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 05:19:43 INFO - PROCESS | 1677 | colorDepth:24 05:19:43 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 05:19:43 INFO - PROCESS | 1677 | __metrics 05:19:43 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 05:19:44 INFO - TEST-INFO | 1677: exit 0 05:19:44 INFO - Browser initialized. 05:19:44 INFO - Running cycle 1/1 for kraken test... 05:19:44 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpG2VfU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:46 INFO - PROCESS | 1680 | 2016-10-23 05:19:46.782 plugin-container[1681:11456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 05:19:46 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:46 INFO - PROCESS | 1680 | 2016-10-23 05:19:46.783 plugin-container[1681:11456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 05:19:46 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:46 INFO - PROCESS | 1680 | 2016-10-23 05:19:46.783 plugin-container[1681:11456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:56 INFO - PROCESS | 1680 | RSS: Main: 236613632 05:19:56 INFO - PROCESS | 1680 | 05:19:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:00 INFO - PROCESS | 1680 | RSS: Main: 249061376 05:20:00 INFO - PROCESS | 1680 | 05:20:03 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 05:20:03 INFO - PROCESS | 1680 | RSS: Main: 256663552 05:20:03 INFO - PROCESS | 1680 | 05:20:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 05:20:08 INFO - PROCESS | 1680 | RSS: Main: 259657728 05:20:08 INFO - PROCESS | 1680 | 05:20:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 05:20:10 INFO - PROCESS | 1680 | RSS: Main: 260808704 05:20:10 INFO - PROCESS | 1680 | 05:20:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:13 INFO - PROCESS | 1680 | RSS: Main: 261140480 05:20:13 INFO - PROCESS | 1680 | 05:20:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:16 INFO - PROCESS | 1680 | RSS: Main: 261222400 05:20:16 INFO - PROCESS | 1680 | 05:20:19 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:20 INFO - PROCESS | 1680 | RSS: Main: 261464064 05:20:20 INFO - PROCESS | 1680 | 05:20:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 05:20:23 INFO - PROCESS | 1680 | RSS: Main: 261480448 05:20:23 INFO - PROCESS | 1680 | 05:20:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:25 INFO - PROCESS | 1680 | RSS: Main: 262848512 05:20:25 INFO - PROCESS | 1680 | 05:20:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:28 INFO - PROCESS | 1680 | RSS: Main: 263041024 05:20:28 INFO - PROCESS | 1680 | 05:20:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:31 INFO - PROCESS | 1680 | RSS: Main: 262893568 05:20:31 INFO - PROCESS | 1680 | 05:20:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:35 INFO - PROCESS | 1680 | RSS: Main: 263413760 05:20:35 INFO - PROCESS | 1680 | 05:20:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:38 INFO - PROCESS | 1680 | RSS: Main: 263065600 05:20:38 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 05:20:40 INFO - PROCESS | 1680 | RSS: Main: 264409088 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | __start_tp_report 05:20:40 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 05:20:40 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 05:20:40 INFO - PROCESS | 1680 | |i|pagename|runs| 05:20:40 INFO - PROCESS | 1680 | |0;ai-astar;104;116;134;171;159;117;118;133;156;116 05:20:40 INFO - PROCESS | 1680 | |1;audio-beat-detection;147;116;123;186;129;119;114;117;143;118 05:20:40 INFO - PROCESS | 1680 | |2;audio-dft;210;225;200;203;194;191;274;196;266;278 05:20:40 INFO - PROCESS | 1680 | |3;audio-fft;87;60;60;83;70;71;52;52;85;87 05:20:40 INFO - PROCESS | 1680 | |4;audio-oscillator;91;69;88;72;69;69;89;92;98;86 05:20:40 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;91;124;88;88;89;84;91;77;137;89 05:20:40 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;95;94;99;152;94;95;94;94 05:20:40 INFO - PROCESS | 1680 | |7;imaging-desaturate;88;98;86;86;91;89;89;123;87;139 05:20:40 INFO - PROCESS | 1680 | |8;json-parse-financial;57;56;56;57;57;56;59;57;57;57 05:20:40 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;68;70;74;77;74;68;70;72;71;71 05:20:40 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;86;87;90;90;100;110;102;90;91;88 05:20:40 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;150;186;149;161;159;150;150;155;177;158 05:20:40 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;175;176;178;203;241;202;238;190;173;174 05:20:40 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;70;74;89;92;94;76;70;73;91;70 05:20:40 INFO - PROCESS | 1680 | __end_tp_report 05:20:40 INFO - PROCESS | 1680 | __start_cc_report 05:20:40 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,940 05:20:40 INFO - PROCESS | 1680 | __end_cc_report 05:20:40 INFO - PROCESS | 1680 | __startTimestamp1477225240830__endTimestamp 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | ------- Summary: start ------- 05:20:40 INFO - PROCESS | 1680 | Number of tests: 14 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:132.40 Median:133.50 stddev:22.48 (16.8%) stddev-sans-first:21.36 05:20:40 INFO - PROCESS | 1680 | Values: 104.0 116.0 134.0 171.0 159.0 117.0 118.0 133.0 156.0 116.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:126.00 stddev:22.40 (17.8%) stddev-sans-first:23.02 05:20:40 INFO - PROCESS | 1680 | Values: 147.0 116.0 123.0 186.0 129.0 119.0 114.0 117.0 143.0 118.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:223.70 Median:217.50 stddev:35.22 (16.2%) stddev-sans-first:37.01 05:20:40 INFO - PROCESS | 1680 | Values: 210.0 225.0 200.0 203.0 194.0 191.0 274.0 196.0 266.0 278.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:70.70 Median:77.00 stddev:14.21 (18.4%) stddev-sans-first:13.79 05:20:40 INFO - PROCESS | 1680 | Values: 87.0 60.0 60.0 83.0 70.0 71.0 52.0 52.0 85.0 87.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:88.50 stddev:11.27 (12.7%) stddev-sans-first:11.51 05:20:40 INFO - PROCESS | 1680 | Values: 91.0 69.0 88.0 72.0 69.0 69.0 89.0 92.0 98.0 86.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:88.50 stddev:18.99 (21.5%) stddev-sans-first:20.06 05:20:40 INFO - PROCESS | 1680 | Values: 91.0 124.0 88.0 88.0 89.0 84.0 91.0 77.0 137.0 89.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:95.00 stddev:18.09 (19.0%) stddev-sans-first:19.07 05:20:40 INFO - PROCESS | 1680 | Values: 95.0 95.0 95.0 94.0 99.0 152.0 94.0 95.0 94.0 94.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:88.50 stddev:18.33 (20.7%) stddev-sans-first:19.11 05:20:40 INFO - PROCESS | 1680 | Values: 88.0 98.0 86.0 86.0 91.0 89.0 89.0 123.0 87.0 139.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.88 (1.5%) stddev-sans-first:0.93 05:20:40 INFO - PROCESS | 1680 | Values: 57.0 56.0 56.0 57.0 57.0 56.0 59.0 57.0 57.0 57.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:2.84 (4.0%) stddev-sans-first:2.71 05:20:40 INFO - PROCESS | 1680 | Values: 68.0 70.0 74.0 77.0 74.0 68.0 70.0 72.0 71.0 71.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:89.00 stddev:7.88 (8.9%) stddev-sans-first:7.89 05:20:40 INFO - PROCESS | 1680 | Values: 86.0 87.0 90.0 90.0 100.0 110.0 102.0 90.0 91.0 88.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:159.50 Median:158.50 stddev:12.54 (7.9%) stddev-sans-first:12.82 05:20:40 INFO - PROCESS | 1680 | Values: 150.0 186.0 149.0 161.0 159.0 150.0 150.0 155.0 177.0 158.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.00 Median:196.00 stddev:25.99 (13.3%) stddev-sans-first:26.54 05:20:40 INFO - PROCESS | 1680 | Values: 175.0 176.0 178.0 203.0 241.0 202.0 238.0 190.0 173.0 174.0 05:20:40 INFO - PROCESS | 1680 | 05:20:40 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:82.50 stddev:10.24 (12.4%) stddev-sans-first:10.21 05:20:40 INFO - PROCESS | 1680 | Values: 70.0 74.0 89.0 92.0 94.0 76.0 70.0 73.0 91.0 70.0 05:20:40 INFO - PROCESS | 1680 | -------- Summary: end -------- 05:20:40 INFO - PROCESS | 1680 | 05:20:41 INFO - PROCESS | 1680 | 05:20:41 INFO - PROCESS | 1680 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x1000015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 05:20:41 INFO - PROCESS | 1680 | 05:20:41 INFO - TEST-INFO | 1680: exit 0 05:20:41 INFO - TEST-OK | kraken | took 60931ms 05:20:41 INFO - SUITE-END | took 1176s 05:20:41 INFO - Completed test suite (00:19:36) 05:20:41 INFO - javascript benchmark 05:20:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7206.892331253222, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.8187849668266, 3314.138324035753, 3371.7471189660764, 3382.2559037011847, 3396.592408910931, 5476.507114359717, 5519.9172012419795, 5523.447655234475, 5553.500184983356, 5577.274954255951, 5533.335999680044, 5565.109582466806, 5590.608657393984, 5641.210230567724, 5644.068728659766, 5090.261912022755, 5094.646885691667, 5117.897642047157, 5167.250748641445, 5211.3746350438005, 5163.19970404586, 5215.895682086356, 5218.634695571312, 5233.031889100528, 5267.65760225586, 4601.769911504428, 5013.874653133665, 5093.79624815007, 5180.818671346522, 5208.479152084761, 5034.974825125888, 5061.670991385548, 5103.464136265676, 5154.819581314636, 5169.276301317818, 4597.448306203243, 5074.213496907948, 5097.847064588068, 5155.355580552431, 5213.635045546814, 4228.978855105735, 5011.824586139504, 5442.727863606843, 5563.221149039137, 5590.944090559083, 5884.6763428011445, 5939.792107276267, 6040.879182416327, 6051.394860513958, 6098.674287528765, 6153.938460615382, 6158.753649854045, 6165.445109940082, 6174.197354343906, 6181.289151747562, 5620.18507316445, 5626.634268772516, 5635.802746903921, 5644.096944488862, 5663.546916246691, 5512.704514439103, 5572.637778544382, 6112.694365281718, 6123.008104567321, 6184.072389141575, 5925.816023738872, 6012.849678758066, 6014.7594096236535, 6033.879322413527, 6035.849103772441, 5970.014947533608, 6090.634561926298, 6147.846303842351, 6168.383161683885, 6170.629762214281, 5958.433948774859, 5985.102234664748, 6032.125341825411, 6063.787767428119, 6136.662483563447, 6060.848478787977, 6097.085437184458, 6097.481714054266, 6100.786472473531, 6110.175126357884, 4842.249451335048, 4852.1508735971065, 4852.2479015752615, 4865.1485989952325, 4889.0, 4270.658347332206, 4331.155424692218, 4333.436653235122, 4345.283028300295, 4367.606915377632, 5543.334799824047, 5656.236408084938, 5677.489025987681, 5716.885662286814, 5724.227229324071, 6082.300535438393, 6104.725287362079, 6184.536159788034, 6188.133661287423, 6202.4417802398, 5521.089020311684, 5685.715714214355, 5697.145428185722, 5722.484976352148, 5723.742431590588, 5229.843104706865, 5257.579393648499, 5272.55183309423, 5285.286486324374, 5350.438203988525, 6236.906446403307, 6238.0, 6246.125542424064, 6247.312795592481, 6255.749770009149, 6097.878042439215, 6147.231596050494, 6152.261728592597, 6163.84590385244, 6169.56813023102, 6133.824358648169, 6135.018317212141, 6179.876402471836, 6199.442050215503, 6205.751769929152, 6056.121862329849, 6069.878602428015, 6093.695315234309, 6110.114033465212, 6111.113888486234, 6129.478994285536, 6151.354107818794, 6162.4397945519095, 6169.784057558143, 6185.0, 4802.927956080592, 5609.887802244014, 5910.231669882888, 5914.0, 5929.584929054925, 5924.911126333194, 5950.2264705587995, 6006.789762358297, 6011.759529618941, 6027.21646185993, 5698.230738850202, 5717.5997680163655, 5726.341470730919, 5747.19539264495, 5754.367019627922, 5250.684958902478, 5886.323072846678, 5892.292924849045, 6001.009833377444, 6293.3391993841815, 5762.6830524319885, 5799.159121927212, 5810.360860305278, 5819.5344372450945, 5869.53043756507], "value": 5563.423585178253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.55485843307287, 490.75217015407213, 495.3238828998418, 516.7829511605127, 539.346901746148, 30278.697213027863, 36251.81874090629, 39760.204795904065, 39936.60063399365, 42322.36516452248, 40870.18259634806, 41242.79378603103, 41254.0, 41318.96702582437, 41394.58605413945, 9364.578593963271, 9527.618895244183, 9546.61813527458, 9570.952145239298, 9593.376430531993, 9389.483578403186, 9415.717528474122, 9450.85823712645, 9472.763180920498, 9502.57238424269, 9418.5761640726, 9446.763830904249, 9455.149036586707, 9466.479343636098, 9476.28927830412, 9327.720168394924, 9378.624855005792, 9391.154796068353, 9419.058094190596, 9442.96127425986, 9261.16649501548, 9268.90731092694, 9353.719388418358, 9363.063693630651, 9384.765380865465, 39121.41317880205, 39268.41097383542, 39378.80310598428, 39461.40807887884, 39641.00897477557, 3350.8721961554343, 3419.0999707557567, 3897.3569361055393, 4139.337705967031, 4238.618524332794, 26881.0, 27559.0, 30331.696683033108, 30823.229419264473, 30925.0, 3147.1030756234363, 3190.4257233697926, 3295.4562497187935, 3332.0337102240323, 3333.3499967506345, 460986.0, 476281.0, 498260.0, 501217.0, 502916.0, 45149.0, 56119.0, 56391.0, 56441.435585643616, 56464.870702585715, 58125.0, 58315.708421457624, 58397.4160258392, 58746.41253587495, 58866.7056664714, 15150.848491515164, 15399.07605543671, 15421.537353879401, 15422.614434639223, 15525.534233973, 11658.18392712502, 11983.940080299542, 12012.159148859671, 12082.033437324993, 12091.455884485043, 13919.582412527438, 14487.710245795026, 14573.584755381029, 14634.707305853823, 14658.413663453555, 5957.314908785545, 6415.743370265137, 6959.478039147084, 7148.213696493382, 7198.7840364789145, 885.831691978522, 972.7665360313615, 989.1789814453986, 1017.3946501831399, 1021.7496713305139, 24133.155339563033, 24743.257702268962, 29551.704482955327, 29718.851405743266, 29744.405111897642, 998.5905778630727, 1068.529846867376, 1069.3637285814928, 1092.6995076353908, 1095.1950316517352, 14584.124952502883, 15103.32035058425, 15194.240287985556, 15280.847191527942, 15346.48517918875, 611602.0, 636617.0, 642201.0, 651326.0, 654875.0, 55558.16662750062, 55708.72145639327, 55795.72102139463, 55869.441305587236, 55889.0, 1341.4902337111835, 1400.1179257068186, 1409.3587417724912, 1413.738458385202, 1420.7300612883519, 28564.42871142524, 28639.854405823535, 28746.0, 29201.269968250115, 29360.97236596709, 38246.61753382542, 45356.86607834698, 46131.0, 46735.831604210165, 46753.53246467492, 432039.0, 574939.0, 580885.0, 584575.0, 604308.0, 24127.155549555682, 24470.14354497584, 25345.74654253434, 32006.479287277914, 38036.619633803304, 40609.18781624292, 45537.544624554655, 46112.0, 46993.76503117599, 47234.0, 37366.813165934, 37390.8130459346, 37444.0, 38539.42190867083, 38544.0, 32856.0, 37472.87581372563, 38343.84968450951, 38367.232655346175, 38556.228875422894, 36934.261314773015, 37228.25543489061, 37397.0, 37453.0, 38266.6173338263, 33466.83266583652, 33546.329073418885, 33819.4927076099, 33867.4919876207, 33922.98231052973, 20740.481487962923, 24125.51748965046, 24824.503509930062, 25073.87463062673, 25135.87432062828], "value": 19324.073439240543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.11378261086446, 379.1412450798939, 385.708789863653, 388.1015449235019, 407.3035109961965, 18953.05234738263, 21079.894600526975, 21434.57130857382, 21530.784692153073, 21754.782452175474, 17392.82607173928, 19852.801471985276, 20554.38336849897, 20828.895855520703, 21239.787602123975, 5725.599208055438, 5864.149698293755, 5912.231409916705, 6023.608465449754, 6043.153958445821, 4111.157212771374, 4347.08711170653, 4909.950900490985, 6018.217631707872, 6334.651594162319, 6614.371634694697, 6695.832604184886, 6710.496712746539, 6760.42536384408, 6768.255491895888, 6442.194725659293, 6633.867322653568, 6694.765683201064, 6710.865782684344, 6717.664116794141, 5585.0, 5795.420457954213, 5804.912926306109, 5812.157237200625, 5812.389699081577, 25813.870930645415, 26517.0, 26984.59523107155, 27062.32344191391, 27330.726692733017, 2095.559932414195, 2304.919327823518, 2787.9442411151663, 2851.344190836119, 3106.3631955449077, 15104.956282713343, 15538.0, 15779.92110039454, 15947.840521594751, 16544.007359558345, 1863.57137858292, 1871.3917976657506, 1874.7469091672722, 1906.094605062598, 1920.9722798302842, 127795.0, 131615.34192329072, 131801.0, 132537.6746232535, 134520.54794520568, 9821.459819709984, 11115.166362522843, 11197.048250898602, 11240.606578769868, 11366.602168924048, 13951.720965580631, 14003.43986240539, 14032.508862189774, 14060.296985150702, 14068.577942661737, 8344.249017588447, 8435.746927592181, 8436.28291595209, 8459.238668519863, 8576.399652024298, 7910.79731527799, 8067.757967260992, 8399.454035487674, 8527.0, 8528.275096616857, 6811.489138314646, 6817.69320380584, 6836.726530938806, 6837.35045170708, 6838.760643377557, 1375.77987521996, 1551.8448155184392, 1657.6436066245815, 1811.7644706188112, 1874.5969616532238, 826.9958650206711, 850.37497439382, 927.2581934452411, 944.9338546301693, 970.3110791338088, 18043.865085870606, 20672.58654826895, 21482.677759833616, 21495.89252053761, 22081.33755987323, 870.1559487297311, 904.9411788233743, 926.6108234541508, 1006.864073350103, 1015.8882522922472, 10396.324238924446, 10521.42132182722, 10887.0, 11290.661280161608, 11295.943520282346, 547620.0, 570623.0, 596152.0, 600216.0, 617335.0, 132538.0, 133014.33492832474, 133845.0, 134116.0, 134348.0, 826.9131741167212, 940.2806852757645, 983.5426526665135, 1010.5200029985698, 1018.3431686562126, 16697.332106715596, 19647.508812279808, 20170.310747640222, 20394.28619998293, 20557.383278501668, 39122.21755564816, 49081.50918490912, 50560.66870303859, 55049.72475137599, 57354.0, 73956.52086958123, 77349.45301093833, 77448.61275693585, 78117.60941195258, 78331.60834195792, 6919.37725604698, 7721.498102623312, 7941.523508589503, 8028.6387112578905, 8130.227628375294, 9022.954885225532, 10883.455827208449, 11419.0, 12315.0, 12543.435545400662, 10660.8933910663, 10827.40449275266, 10921.726956826144, 11008.944955275172, 11051.557937682725, 8015.47899386538, 10013.499325033865, 10029.348092373972, 10078.8488172676, 10288.742781430525, 10775.353478790985, 11129.276597021168, 11177.385243811668, 11313.038391736794, 11313.604023859125, 6890.379865812101, 7276.927230727625, 7364.963175184304, 7938.56337901421, 8242.010958684756, 9103.772405689646, 9973.252006099427, 10440.42577658236, 11171.273867198606, 11400.543978240778], "value": 9730.352114971822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.8806836633246, 1502.0987407555467, 1616.8463995920383, 1875.0624687656173, 1906.5805522784983, 7678.308952194301, 7936.76189714308, 8063.233992770684, 8617.784555386119, 8667.826643467135, 8051.758447246577, 8412.663493460253, 8447.493150410979, 8454.57727113645, 8511.233988940994, 1698.9466530750951, 1854.944351669452, 1975.9110840012233, 2216.091402524965, 2278.943026424336, 2196.604611169989, 2271.9886400567975, 2293.6674182243564, 2313.595120853852, 2324.0, 1951.9069321180114, 2213.1258153029544, 2284.440312123532, 2291.9656205156853, 2305.72331320241, 2055.825254853339, 2141.314779270634, 2268.512269861971, 2276.203328834911, 2282.7488976212603, 2075.4603803011173, 2086.800089948286, 2238.6194346961056, 2278.4759505313705, 2285.4172186092605, 8500.532470714108, 8538.146185381474, 8578.69974550894, 8589.742307730778, 8592.699255526026, 4976.45259021511, 5126.71803050832, 5168.767405466725, 5213.191955246943, 5215.556677682402, 3405.387030334539, 3418.5812887651596, 3481.704055155315, 3499.895003149884, 3565.5915913214135, 1038.7091614347994, 1046.9790604187874, 1066.5627092891878, 1070.9625163119254, 1071.1109778883508, 11027.062699670538, 11188.992990630883, 11193.664190074229, 11223.831642525369, 11233.550657973672, 4310.676699247569, 4618.237990731559, 4641.094986477672, 4642.257238841769, 4658.627309815207, 9575.425474471554, 9707.223422126213, 9707.417554946725, 9717.12545870861, 9726.854097188549, 5160.7419629018395, 5162.9483705162465, 5165.0, 5165.328507294028, 5170.741462926839, 4580.473245576802, 4697.78859951303, 4725.716457012591, 4762.690425122356, 4833.758312084382, 3638.7816730996224, 3720.1071742781537, 3722.646348596879, 3750.774953502799, 3758.8684396046006, 1138.260130914912, 1139.3107170162098, 1142.348861149137, 1145.7765735681628, 1156.9826452603218, 724.974625888102, 792.1919641965163, 854.8418542569644, 885.8272636835756, 897.4794619120894, 4847.224444088929, 5474.233607294982, 5562.9443705563235, 5799.246098007232, 5878.177055212273, 402.62756949821886, 412.8493100018472, 420.66557087115814, 427.7262551966744, 430.4597729849082, 3866.497355343787, 3945.723799334019, 3962.0, 3990.06233535119, 4055.797210139481, 9857.0, 10267.897321026843, 10320.896791032144, 10433.686989390331, 10470.319429237228, 10426.165906727443, 10444.955504449494, 10456.68629941103, 10460.0, 10467.476626168662, 7823.295903368724, 8013.318867896294, 8043.235892590194, 8048.5975701215875, 8069.152738962442, 5650.491455769001, 5797.8550536236935, 5914.733836977261, 5948.196993405835, 6016.368281330398, 9485.33602647808, 9709.514524273616, 9822.0, 9836.06557377048, 9844.901550984685, 8965.237954773656, 9557.665481708107, 9837.163841073589, 9989.450580218157, 10030.699079027641, 6821.454283657395, 7040.225575186828, 7225.24134965821, 7273.599952002479, 7353.154387245535, 7926.445148839756, 8004.959975200087, 8017.238362355567, 8035.99550056243, 8045.919540804517, 7302.123745150616, 7333.523320984119, 7371.336579707852, 7374.926250737424, 7396.630168491662, 6949.548279361826, 7138.46461515378, 7222.530535515175, 7314.049173607397, 7314.305141011595, 6315.431611155018, 6471.385218404244, 6483.902741458787, 6491.091247225298, 6500.28496865335, 4342.32693932441, 4443.755748390456, 4470.910581788281, 4710.764461776829, 4775.116603428377, 4820.807167713252, 4834.7429668285795, 4966.155753521838, 5046.4701206374275, 5054.671446355975], "value": 4317.408261142433, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.8561433319175, 306.96316442026955, 312.58894553661946, 315.136525918982, 317.84107946026984, 4605.157936825268, 5224.764885580148, 5414.512693857552, 5558.694271815054, 5589.972050139749, 4810.15822231109, 5181.740912954347, 5193.5325820676135, 5363.061464243761, 5442.319710036246, 3624.836882340301, 3937.763734175944, 4156.06488540079, 4200.936985945198, 4204.453421055258, 3808.90477738056, 3905.433712111742, 4083.9591604084026, 4206.032612499127, 4212.389203565481, 4021.6380525752675, 4056.71602987791, 4058.6753059755297, 4070.0, 4093.2427500912427, 3593.9101522461888, 3943.5859234780223, 4057.4581658969696, 4080.245154646385, 4096.262672718909, 3353.731701463877, 3368.2758206985613, 3486.477028445728, 3517.7009954153928, 3518.9472157917653, 4674.434064588367, 4701.200795864711, 5184.0, 5244.190754189798, 5286.97356513215, 4144.979275103636, 4657.976710116462, 4658.231391820345, 4669.649776266793, 4680.555347242041, 5390.4879036491475, 5706.714664266811, 5721.7711291548285, 5740.712964351766, 5747.741351639186, 3306.660802375042, 3845.9615403845883, 3855.8264878080554, 3864.439656249856, 3865.956191828219, 5331.546818520431, 6019.518438524908, 6029.352241893679, 6070.150178974947, 6098.054801505729, 3615.240799432149, 3846.2307538492414, 3873.4506197520654, 3892.746971446847, 3939.6060393960374, 3837.4458344370573, 3989.6608788253325, 3996.2606917720314, 4006.579309172553, 4015.6988225883174, 4660.813567457264, 4678.134545109097, 4678.2982552616695, 4700.741459219776, 4703.270992996091, 5383.461653834585, 5744.569157313218, 5797.536197104221, 5800.4199580042505, 5818.130942922909, 1211.4064108586906, 1263.3304348695206, 1302.5441095616459, 1319.3930791835671, 1349.9133197775884, 776.2586729673149, 865.8874346334936, 912.5209265135857, 914.9523795254396, 916.4547094478775, 5353.350091704631, 5381.246625472437, 5417.537264938524, 5432.91850622241, 5449.83650490478, 4887.902241955141, 5241.7419819243105, 5780.88438231233, 5885.205497257901, 5895.587308888423, 4198.4961804583645, 4305.935410968838, 4710.8351207707565, 5406.135018397038, 5437.592180586472, 2096.308218287961, 2139.4595890958485, 2357.3163782503225, 2595.636610874479, 2610.425706344601, 4256.893207765941, 4942.986687729007, 5102.438731739506, 5114.314984633097, 5159.25190847325, 4606.673629816921, 4687.7656117194, 5016.595353301082, 5058.696478211319, 5068.41713202979, 4531.909361812745, 4745.95254047462, 4870.03092479628, 4900.774806298426, 5135.768890399941, 4249.681273904408, 4716.905661886674, 4778.761061946958, 4990.176620857518, 5005.34930459038, 4563.680542362003, 4694.413198350207, 4881.414230292387, 4889.3888263967, 4924.212126059813, 4436.0, 4442.73343599385, 4568.81724731007, 5039.546440820343, 5105.978804239093, 3161.3361194148565, 3306.3883181611477, 3350.7486938480197, 3369.578802649669, 3385.136790118504, 4187.5184353799705, 4407.206702793528, 4654.953450465452, 4862.440819305683, 5523.7790488380015, 4770.831146369162, 4978.775955082103, 4989.927165659401, 5039.974800125976, 5698.601097923106, 4529.0, 5665.291838520185, 5697.886042279214, 5742.870135776096, 5743.62666426681, 4931.852044438672, 5352.759125839424, 5561.416051314553, 5565.246947211615, 5592.608517403743, 4437.467503899553, 5459.12653975362, 5672.347680016851, 5731.942680573141, 5758.971205143947, 4496.213162696449, 5116.795328186831, 5535.225068490334, 5553.278073850373, 5607.719614019365], "value": 3946.457918736589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.6890585801242, 740.3336996702964, 787.976360709179, 798.744401791427, 810.9538695083343, 14876.776848347256, 14889.329980150893, 15611.531654050383, 16528.91735541322, 16636.91681541592, 14757.11457312562, 14912.701745965074, 14928.0, 14928.552143435687, 14988.025778324429, 665.8435267712084, 687.52560733094, 877.2850127146387, 886.9689560865371, 904.674317245791, 901.6934242357597, 902.7923577577161, 903.453410686535, 904.2449554621899, 906.9546522673872, 967.1682353176262, 971.3346357744932, 972.0716715536681, 973.2019743810079, 976.3507267666993, 935.0789472369711, 945.1304799584365, 960.4141473701071, 966.5215718219456, 967.8983706710835, 720.7513407874275, 722.4401089155893, 722.869883420984, 723.4176487927216, 726.9781906542812, 15183.696326073527, 15227.847721522754, 15236.0, 15240.771388429182, 15258.38966441341, 13384.126222328785, 14501.274936253145, 14691.77962330566, 14710.190939498323, 14711.55865324042, 11473.024792892615, 11530.365829879353, 11778.293302401884, 12042.939785301105, 12168.330741809197, 3805.4101614249835, 3846.9807650961848, 3851.768893866377, 3982.1637456134245, 3982.2433737589763, 82628.0, 108519.45740271248, 109560.0, 113907.43046284716, 114421.85578144195, 11231.326120432801, 11231.943840280746, 11290.379029153475, 11446.313221206754, 11544.653660390202, 22988.88505557495, 23584.292471225548, 23672.7632723674, 23706.7629323708, 23747.931343089604, 8064.8387032259025, 8117.228863257981, 8155.143709910492, 8342.457740246864, 8353.039400468902, 4368.60682538573, 4434.13534360803, 4453.220686379936, 4463.754493502824, 4595.8969847237095, 3582.7671201371827, 3690.1881586051027, 3694.224212915264, 3694.3350196964802, 3700.8149592520267, 637.6588525139028, 641.1056576076347, 645.9128017717547, 650.6421468192475, 655.465795376776, 627.3914303125961, 667.2399988137925, 667.4860357524776, 686.5400181878153, 690.5683947532792, 8756.956215218883, 9127.589258483384, 9288.62845486173, 9500.287478439144, 9561.474118923526, 625.6465097220056, 657.0538424668462, 660.1220376898713, 666.7964324394515, 667.4426853577318, 4044.8674371176116, 4262.722923010057, 4443.888902777457, 4670.346151538787, 4740.597049250783, 143691.0, 145968.0, 154941.0, 157615.0, 162446.0, 111691.0, 113613.0, 114158.0, 137229.6277037217, 141493.0, 65892.0, 69466.305336947, 72680.63659681668, 97842.5107874456, 99593.0, 9571.282153838629, 9680.273979451711, 9912.454814985245, 10077.899221007989, 10169.135623472088, 51934.961300773015, 61296.693516532134, 63858.40353991187, 64404.84761218997, 64541.67729161324, 76617.0, 93149.5342523283, 94084.0, 98917.0, 99745.0, 12924.224546526862, 13069.869301306866, 13131.277779721824, 13136.540221092217, 13354.332283385986, 13091.970744534612, 13441.2761475993, 14455.78316325277, 14645.926770366506, 15291.923540382228, 13356.866431335562, 13418.530351437652, 13495.055346125677, 13556.153207826466, 13596.0, 11351.659450216506, 13564.660883477993, 13752.106113102616, 13919.791203132163, 14209.957675419098, 13187.340632968506, 13455.730885382432, 13630.182189068688, 13739.862601373858, 13802.516911908437, 11051.83422248683, 11078.501467434146, 11171.497282622138, 11321.886781132083, 11681.474333655176, 8911.930568331842, 9231.58457869384, 9776.608935642495, 11466.713332166762, 11603.245789023686], "value": 7860.29356126731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 134.0, 171.0, 159.0, 117.0, 118.0, 133.0, 156.0, 116.0], "value": 132.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 123.0, 186.0, 129.0, 119.0, 114.0, 117.0, 143.0, 118.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 225.0, 200.0, 203.0, 194.0, 191.0, 274.0, 196.0, 266.0, 278.0], "value": 223.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 60.0, 83.0, 70.0, 71.0, 52.0, 52.0, 85.0, 87.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 88.0, 72.0, 69.0, 69.0, 89.0, 92.0, 98.0, 86.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 124.0, 88.0, 88.0, 89.0, 84.0, 91.0, 77.0, 137.0, 89.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 99.0, 152.0, 94.0, 95.0, 94.0, 94.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 98.0, 86.0, 86.0, 91.0, 89.0, 89.0, 123.0, 87.0, 139.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 57.0, 57.0, 56.0, 59.0, 57.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 74.0, 77.0, 74.0, 68.0, 70.0, 72.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 90.0, 90.0, 100.0, 110.0, 102.0, 90.0, 91.0, 88.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 186.0, 149.0, 161.0, 159.0, 150.0, 150.0, 155.0, 177.0, 158.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 178.0, 203.0, 241.0, 202.0, 238.0, 190.0, 173.0, 174.0], "value": 195.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 89.0, 92.0, 94.0, 76.0, 70.0, 73.0, 91.0, 70.0], "value": 79.9, "unit": "score"}]}]} 05:20:42 INFO - Return code: 0 05:20:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:20:42 ERROR - # TBPL SUCCESS # 05:20:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:42 INFO - 2016-10-23 05:20:42.177 screenresolution[1686:11885] starting screenresolution argv=screenresolution get 05:20:42 INFO - 2016-10-23 05:20:42.179 screenresolution[1686:11885] Display 0: 1600x1200x32@60 05:20:42 INFO - 2016-10-23 05:20:42.185 screenresolution[1687:11887] starting screenresolution argv=screenresolution list 05:20:42 INFO - Available Modes on Display 0 05:20:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:42 INFO - Intel Iris: 05:20:42 INFO - Chipset Model: Intel Iris 05:20:42 INFO - Type: GPU 05:20:42 INFO - Bus: Built-In 05:20:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:42 INFO - Vendor: Intel (0x8086) 05:20:42 INFO - Device ID: 0x0a2e 05:20:42 INFO - Revision ID: 0x0009 05:20:42 INFO - Displays: 05:20:42 INFO - Display: 05:20:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:42 INFO - Display Serial Number: 1600x1200 60 05:20:42 INFO - Main Display: Yes 05:20:42 INFO - Mirror: Off 05:20:42 INFO - Online: Yes 05:20:42 INFO - Rotation: Supported 05:20:42 INFO - Return code: 0 05:20:42 INFO - Running post-action listener: _resource_record_post_action 05:20:42 INFO - [mozharness: 2016-10-23 12:20:42.422516Z] Finished run-tests step (success) 05:20:42 INFO - Running post-run listener: _resource_record_post_run 05:20:42 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 34164736; Write bytes: 710469120; Read time: 325; Write time: 1905 05:20:42 INFO - TinderboxPrint: CPU usage
54.7% 05:20:42 INFO - TinderboxPrint: I/O read bytes / time
34,164,736 / 325 05:20:42 INFO - TinderboxPrint: I/O write bytes / time
710,469,120 / 1,905 05:20:42 INFO - TinderboxPrint: CPU idle
2,194.0 (45.3%) 05:20:42 INFO - TinderboxPrint: CPU system
981.2 (20.2%) 05:20:42 INFO - TinderboxPrint: CPU user
1,670.5 (34.5%) 05:20:42 INFO - TinderboxPrint: Swap in / out
435,093,504 / 0 05:20:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405891584; Write bytes: 408339968; Read time: 29741; Write time: 823 05:20:42 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30494720; Write bytes: 300744704; Read time: 294; Write time: 1077 05:20:42 INFO - Running post-run listener: _upload_blobber_files 05:20:42 INFO - Blob upload gear active. 05:20:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:20:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:20:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:44 INFO - (blobuploader) - INFO - Done attempting. 05:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:45 INFO - (blobuploader) - INFO - Done attempting. 05:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:20:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:47 INFO - (blobuploader) - INFO - Done attempting. 05:20:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:47 INFO - Return code: 0 05:20:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce93769aadf5e16d2560908f6e1a96f4cb09f83716b7e79de6b86d8d3556f53d091762a889a65a5cd206f584adbe38ec4ccfebd6e61831ed3ee08964fb58b5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211ca1d8103fcaaf6291e583551c04c2e276d756cf43a46c4fe773be69835725da8929a47d1411cf68ca759e2ab4b17ca71b66fa1ad5a413a2b796790bce00f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/884816a449d79f81a4e0f3d67711627b1da7153a656efecaa571f04b3fae2cb7a87a4e29b4c1e6e6dc88165b489eb63a368e8d3344e36a74c60c28475dfcdbff"} 05:20:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:47 INFO - Contents: 05:20:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce93769aadf5e16d2560908f6e1a96f4cb09f83716b7e79de6b86d8d3556f53d091762a889a65a5cd206f584adbe38ec4ccfebd6e61831ed3ee08964fb58b5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211ca1d8103fcaaf6291e583551c04c2e276d756cf43a46c4fe773be69835725da8929a47d1411cf68ca759e2ab4b17ca71b66fa1ad5a413a2b796790bce00f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/884816a449d79f81a4e0f3d67711627b1da7153a656efecaa571f04b3fae2cb7a87a4e29b4c1e6e6dc88165b489eb63a368e8d3344e36a74c60c28475dfcdbff"} 05:20:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:47 INFO - Copying logs to upload dir... 05:20:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.980474 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-23 05:20:47.459812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 05:20:47.463666) ========= 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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/fce93769aadf5e16d2560908f6e1a96f4cb09f83716b7e79de6b86d8d3556f53d091762a889a65a5cd206f584adbe38ec4ccfebd6e61831ed3ee08964fb58b5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211ca1d8103fcaaf6291e583551c04c2e276d756cf43a46c4fe773be69835725da8929a47d1411cf68ca759e2ab4b17ca71b66fa1ad5a413a2b796790bce00f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/884816a449d79f81a4e0f3d67711627b1da7153a656efecaa571f04b3fae2cb7a87a4e29b4c1e6e6dc88165b489eb63a368e8d3344e36a74c60c28475dfcdbff"} build_url:https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/SSxHZwD4Q6OjmJe3yKG5Lg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce93769aadf5e16d2560908f6e1a96f4cb09f83716b7e79de6b86d8d3556f53d091762a889a65a5cd206f584adbe38ec4ccfebd6e61831ed3ee08964fb58b5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211ca1d8103fcaaf6291e583551c04c2e276d756cf43a46c4fe773be69835725da8929a47d1411cf68ca759e2ab4b17ca71b66fa1ad5a413a2b796790bce00f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/884816a449d79f81a4e0f3d67711627b1da7153a656efecaa571f04b3fae2cb7a87a4e29b4c1e6e6dc88165b489eb63a368e8d3344e36a74c60c28475dfcdbff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 05:20:47.496375) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 05:20:47.496705) ========= 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.UhXNCu3i5t/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.RcSmOrUBb7/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005052 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 05:20:47.545098) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 05:20:47.545422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 05:20:47.545764) ========= ========= Total master_lag: 0.17 =========