builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0253 starttime: 1479365587.55 results: success (0) buildid: 20161116203623 builduid: e4955c3fccea48c18b331d3046e18862 revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.545743) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.546201) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.546631) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.571460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.571796) ========= 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.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-16 22:53:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.7M=0s 2016-11-16 22:53:07 (71.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.718385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.718711) ========= 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.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.025922 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:07.767472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-16 22:53:07.767866) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13f49da109ea460665ad27c8497cb1489548450c --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 13f49da109ea460665ad27c8497cb1489548450c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-16 22:53:07,867 truncating revision to first 12 chars 2016-11-16 22:53:07,867 Setting DEBUG logging. 2016-11-16 22:53:07,867 attempt 1/10 2016-11-16 22:53:07,867 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13f49da109ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 22:53:08,489 unpacking tar archive at: mozilla-central-13f49da109ea/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910575 ========= master_lag: 2.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-16 22:53:11.118264) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:11.118647) ========= script_repo_revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:11.119060) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:11.119347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 22:53:11.139456) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-16 22:53:11.139819) ========= /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.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:11 INFO - MultiFileLogger online at 20161116 22:53:11 in /builds/slave/test 22:53:11 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 22:53:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:53:11 INFO - {'append_to_log': False, 22:53:11 INFO - 'base_work_dir': '/builds/slave/test', 22:53:11 INFO - 'blob_upload_branch': 'Firefox', 22:53:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:53:11 INFO - 'branch': 'Firefox', 22:53:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:53:11 INFO - 'config_files': ('talos/mac_config.py',), 22:53:11 INFO - 'default_actions': ('clobber', 22:53:11 INFO - 'read-buildbot-config', 22:53:11 INFO - 'download-and-extract', 22:53:11 INFO - 'populate-webroot', 22:53:11 INFO - 'create-virtualenv', 22:53:11 INFO - 'install', 22:53:11 INFO - 'run-tests'), 22:53:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:53:11 INFO - 'download_minidump_stackwalk': True, 22:53:11 INFO - 'download_symbols': 'ondemand', 22:53:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:53:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:53:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:53:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:53:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:53:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:53:11 INFO - 'installer_path': 'installer.exe', 22:53:11 INFO - 'log_level': 'info', 22:53:11 INFO - 'log_name': 'talos', 22:53:11 INFO - 'log_to_console': True, 22:53:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:53:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:53:11 INFO - 'opt_config_files': (), 22:53:11 INFO - 'pip_index': False, 22:53:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:11 INFO - 'cmd': ('bash', 22:53:11 INFO - '-c', 22:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:11 INFO - 'enabled': True, 22:53:11 INFO - 'halt_on_failure': False, 22:53:11 INFO - 'name': 'check_screen_resolution'},), 22:53:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:11 INFO - 'cmd': ('bash', 22:53:11 INFO - '-c', 22:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:11 INFO - 'enabled': True, 22:53:11 INFO - 'halt_on_failure': False, 22:53:11 INFO - 'name': 'check_screen_resolution'},), 22:53:11 INFO - 'run_cmd_checks_enabled': True, 22:53:11 INFO - 'sps_profile': False, 22:53:11 INFO - 'sps_profile_interval': 0, 22:53:11 INFO - 'suite': 'dromaeojs-e10s', 22:53:11 INFO - 'system_bits': '32', 22:53:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:53:11 INFO - 'title': 't-yosemite-r7-0253', 22:53:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:53:11 INFO - 'use_talos_json': True, 22:53:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:53:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:53:11 INFO - 'work_dir': 'build'} 22:53:11 INFO - [mozharness: 2016-11-17 06:53:11.344712Z] Running clobber step. 22:53:11 INFO - Running pre-action listener: _resource_record_pre_action 22:53:11 INFO - Running main action method: clobber 22:53:11 INFO - rmtree: /builds/slave/test/build 22:53:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:53:13 INFO - Running post-action listener: _resource_record_post_action 22:53:13 INFO - [mozharness: 2016-11-17 06:53:13.846217Z] Finished clobber step (success) 22:53:13 INFO - [mozharness: 2016-11-17 06:53:13.846386Z] Running read-buildbot-config step. 22:53:13 INFO - Running pre-action listener: _resource_record_pre_action 22:53:13 INFO - Running main action method: read_buildbot_config 22:53:13 INFO - Using buildbot properties: 22:53:13 INFO - { 22:53:13 INFO - "project": "", 22:53:13 INFO - "product": "firefox", 22:53:13 INFO - "script_repo_revision": "production", 22:53:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:53:13 INFO - "repository": "", 22:53:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:53:13 INFO - "buildid": "20161116203623", 22:53:13 INFO - "pgo_build": "False", 22:53:13 INFO - "basedir": "/builds/slave/test", 22:53:13 INFO - "buildnumber": 92, 22:53:13 INFO - "slavename": "t-yosemite-r7-0253", 22:53:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:53:13 INFO - "platform": "yosemite_r7", 22:53:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:53:13 INFO - "branch": "mozilla-central", 22:53:13 INFO - "repo_path": "mozilla-central", 22:53:13 INFO - "revision": "13f49da109ea460665ad27c8497cb1489548450c", 22:53:13 INFO - "stage_platform": "macosx64", 22:53:13 INFO - "builduid": "e4955c3fccea48c18b331d3046e18862", 22:53:13 INFO - "slavebuilddir": "test" 22:53:13 INFO - } 22:53:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:53:13 INFO - Running post-action listener: _resource_record_post_action 22:53:13 INFO - [mozharness: 2016-11-17 06:53:13.849655Z] Finished read-buildbot-config step (success) 22:53:13 INFO - [mozharness: 2016-11-17 06:53:13.849788Z] Running download-and-extract step. 22:53:13 INFO - Running pre-action listener: _resource_record_pre_action 22:53:13 INFO - Running main action method: download_and_extract 22:53:13 INFO - mkdir: /builds/slave/test/build/tests 22:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:53:13 INFO - trying https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:53:13 INFO - Downloading https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 22:53:14 INFO - Downloaded 1199 bytes. 22:53:14 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:53:14 INFO - Using the following test package requirements: 22:53:14 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:53:14 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:53:14 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:53:14 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'jsshell-mac.zip'], 22:53:14 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:53:14 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:53:14 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:53:14 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:53:14 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:53:14 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:53:14 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:53:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:53:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:53:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:53:14 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:53:15 INFO - Http code: 200 22:53:15 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 22:53:15 INFO - x-amz-cf-id: _UQq-wQ-2TDwGSSI65Gr92nMfsH5ovFKsmSsxXcdw2x9bdjddLix4Q== 22:53:15 INFO - x-amz-version-id: pWW1DWMkf7BkNxnAYDTOLL7lUrCr06_f 22:53:15 INFO - Expected file size: 16273480 22:53:15 INFO - Obtained file size: 16273480 22:53:22 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:53:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:53:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:53:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:53:22 INFO - Http code: 200 22:53:22 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 22:53:22 INFO - x-amz-cf-id: s_2ncgIHPaERZX16HQHY-jcjuyolkkeXQFOWUcn66rUYLDsxjNcGwg== 22:53:22 INFO - x-amz-version-id: pWW1DWMkf7BkNxnAYDTOLL7lUrCr06_f 22:53:23 INFO - Expected file size: 16273480 22:53:23 INFO - Obtained file size: 16273480 22:53:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:53:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:53:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:53:29 INFO - Http code: 200 22:53:29 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 22:53:29 INFO - x-amz-cf-id: Yd2AYlIxB8w-MjR0yYRuPSUgcOC_4E9WrdQEkVgh73yDOjpcmZi_zw== 22:53:29 INFO - x-amz-version-id: .xNxLe0wM63LnjA0mOlcw5iiY7hg4OUD 22:53:30 INFO - Expected file size: 14409389 22:53:30 INFO - Obtained file size: 14409389 22:53: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')]} 22:53:30 INFO - trying https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 22:53:33 INFO - Downloaded 110986238 bytes. 22:53:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:33 INFO - mkdir: /builds/slave/test/properties 22:53:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:53:33 INFO - Writing to file /builds/slave/test/properties/build_url 22:53:33 INFO - Contents: 22:53:33 INFO - build_url:https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:53:34 INFO - Running post-action listener: _resource_record_post_action 22:53:34 INFO - Running post-action listener: set_extra_try_arguments 22:53:34 INFO - [mozharness: 2016-11-17 06:53:34.122719Z] Finished download-and-extract step (success) 22:53:34 INFO - [mozharness: 2016-11-17 06:53:34.122841Z] Running populate-webroot step. 22:53:34 INFO - Running pre-action listener: _resource_record_pre_action 22:53:34 INFO - Running main action method: populate_webroot 22:53:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:53:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:53:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:53:34 INFO - '--tppagecycles', 22:53:34 INFO - '7'], 22:53:34 INFO - 'tests': ['tsvgm']}}, 22:53:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:53:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:53:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:53:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:53:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['damp', 'tps']}, 22:53:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'tests': ['damp', 'tps']}, 22:53:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['dromaeo_dom']}, 22:53:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:53:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['basic_compositor_video']}, 22:53:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:53:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['a11yr', 22:53:34 INFO - 'ts_paint', 22:53:34 INFO - 'tpaint', 22:53:34 INFO - 'sessionrestore', 22:53:34 INFO - 'sessionrestore_no_auto_restore', 22:53:34 INFO - 'tabpaint']}, 22:53:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['tsvgx', 22:53:34 INFO - 'tsvgr_opacity', 22:53:34 INFO - 'tart', 22:53:34 INFO - 'tscrollx', 22:53:34 INFO - 'cart']}, 22:53:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:53:34 INFO - 'tsvgr_opacity', 22:53:34 INFO - 'tart', 22:53:34 INFO - 'tscrollx', 22:53:34 INFO - 'cart']}, 22:53:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'talos_options': ['--disable-e10s'], 22:53:34 INFO - 'tests': ['tp5o']}, 22:53:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:53:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'talos_options': ['--disable-e10s', 22:53:34 INFO - '--xperf_path', 22:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:34 INFO - 'tests': ['tp5n']}, 22:53:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:53:34 INFO - 'talos_options': ['--xperf_path', 22:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:34 INFO - 'tests': ['tp5n']}}, 22:53:34 INFO - 'talos.zip': {'path': '', 22:53:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:53:34 INFO - Running post-action listener: _resource_record_post_action 22:53:34 INFO - [mozharness: 2016-11-17 06:53:34.140481Z] Finished populate-webroot step (success) 22:53:34 INFO - [mozharness: 2016-11-17 06:53:34.140603Z] Running create-virtualenv step. 22:53:34 INFO - Running pre-action listener: _resource_record_pre_action 22:53:34 INFO - Running main action method: create_virtualenv 22:53:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:53:34 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 22:53:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:53:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:53:34 INFO - Using real prefix '/tools/python27' 22:53:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:53:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:53:37 INFO - Installing pip.................done. 22:53:37 INFO - Return code: 0 22:53:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:53:38 INFO - Reading from file tmpfile_stdout 22:53:38 INFO - Output received: 22:53:38 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) 22:53:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:53:38 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')]} 22:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:38 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')]} 22:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:38 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:38 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 22:53:38 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 22:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:38 INFO - 'HOME': '/Users/cltbld', 22:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:38 INFO - 'LOGNAME': 'cltbld', 22:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:38 INFO - 'MOZ_NO_REMOTE': '1', 22:53:38 INFO - 'NO_EM_RESTART': '1', 22:53:38 INFO - 'PAGER': '/bin/cat', 22:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:38 INFO - 'PWD': '/builds/slave/test', 22:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:38 INFO - 'SHELL': '/bin/bash', 22:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:38 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:38 INFO - 'USER': 'cltbld', 22:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:38 INFO - 'XPC_FLAGS': '0x0', 22:53:38 INFO - 'XPC_SERVICE_NAME': '0', 22:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:38 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 22:53:38 INFO - Cleaning up... 22:53:38 INFO - Return code: 0 22:53:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:53:38 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')]} 22:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:38 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')]} 22:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:38 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:38 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 22:53:38 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 22:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:38 INFO - 'HOME': '/Users/cltbld', 22:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:38 INFO - 'LOGNAME': 'cltbld', 22:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:38 INFO - 'MOZ_NO_REMOTE': '1', 22:53:38 INFO - 'NO_EM_RESTART': '1', 22:53:38 INFO - 'PAGER': '/bin/cat', 22:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:38 INFO - 'PWD': '/builds/slave/test', 22:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:38 INFO - 'SHELL': '/bin/bash', 22:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:38 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:38 INFO - 'USER': 'cltbld', 22:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:38 INFO - 'XPC_FLAGS': '0x0', 22:53:38 INFO - 'XPC_SERVICE_NAME': '0', 22:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:38 INFO - Downloading/unpacking psutil>=3.1.1 22:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:53:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:42 INFO - Installing collected packages: psutil 22:53:42 INFO - Running setup.py install for psutil 22:53:42 INFO - building 'psutil._psutil_osx' extension 22:53:42 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 22:53:42 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 22:53:42 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 22:53:42 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 22:53:42 INFO - building 'psutil._psutil_posix' extension 22:53:42 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 22:53:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:53:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:53:43 INFO - ^ 22:53:43 INFO - 1 warning generated. 22:53:43 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 22:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:43 INFO - Successfully installed psutil 22:53:43 INFO - Cleaning up... 22:53:43 INFO - Return code: 0 22:53:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:53:43 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')]} 22:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:43 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')]} 22:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:43 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:43 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 22:53:43 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 22:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:43 INFO - 'HOME': '/Users/cltbld', 22:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:43 INFO - 'LOGNAME': 'cltbld', 22:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:43 INFO - 'MOZ_NO_REMOTE': '1', 22:53:43 INFO - 'NO_EM_RESTART': '1', 22:53:43 INFO - 'PAGER': '/bin/cat', 22:53:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:43 INFO - 'PWD': '/builds/slave/test', 22:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:43 INFO - 'SHELL': '/bin/bash', 22:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:43 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:43 INFO - 'USER': 'cltbld', 22:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:43 INFO - 'XPC_FLAGS': '0x0', 22:53:43 INFO - 'XPC_SERVICE_NAME': '0', 22:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:53:43 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 22:53:43 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 22:53:43 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 22:53:43 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 22:53:43 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 22:53:43 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 22:53:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:53:46 INFO - Installing collected packages: mozsystemmonitor 22:53:46 INFO - Running setup.py install for mozsystemmonitor 22:53:46 INFO - Successfully installed mozsystemmonitor 22:53:46 INFO - Cleaning up... 22:53:46 INFO - Return code: 0 22:53:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:53:46 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')]} 22:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:46 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')]} 22:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:46 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:46 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 22:53:46 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 22:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:46 INFO - 'HOME': '/Users/cltbld', 22:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:46 INFO - 'LOGNAME': 'cltbld', 22:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:46 INFO - 'MOZ_NO_REMOTE': '1', 22:53:46 INFO - 'NO_EM_RESTART': '1', 22:53:46 INFO - 'PAGER': '/bin/cat', 22:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:46 INFO - 'PWD': '/builds/slave/test', 22:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:46 INFO - 'SHELL': '/bin/bash', 22:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:46 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:46 INFO - 'USER': 'cltbld', 22:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:46 INFO - 'XPC_FLAGS': '0x0', 22:53:46 INFO - 'XPC_SERVICE_NAME': '0', 22:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:46 INFO - Downloading/unpacking jsonschema==2.5.1 22:53:46 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 22:53:46 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 22:53:46 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 22:53:46 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 22:53:46 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 22:53:46 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 22:53:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:53:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:49 INFO - Downloading functools32-3.2.3-2.tar.gz 22:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:53:49 INFO - warning: no files found matching '*.txt' 22:53:49 INFO - no previously-included directories found matching 'build' 22:53:49 INFO - no previously-included directories found matching 'dist' 22:53:49 INFO - no previously-included directories found matching '.git*' 22:53:49 INFO - Installing collected packages: jsonschema, functools32 22:53:49 INFO - Running setup.py install for functools32 22:53:49 INFO - warning: no files found matching '*.txt' 22:53:49 INFO - no previously-included directories found matching 'build' 22:53:49 INFO - no previously-included directories found matching 'dist' 22:53:49 INFO - no previously-included directories found matching '.git*' 22:53:49 INFO - Successfully installed jsonschema functools32 22:53:49 INFO - Cleaning up... 22:53:50 INFO - Return code: 0 22:53:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:53:50 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')]} 22:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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')]} 22:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:50 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 22:53:50 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 22:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:50 INFO - 'HOME': '/Users/cltbld', 22:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:50 INFO - 'LOGNAME': 'cltbld', 22:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:50 INFO - 'MOZ_NO_REMOTE': '1', 22:53:50 INFO - 'NO_EM_RESTART': '1', 22:53:50 INFO - 'PAGER': '/bin/cat', 22:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:50 INFO - 'PWD': '/builds/slave/test', 22:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:50 INFO - 'SHELL': '/bin/bash', 22:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:50 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:50 INFO - 'USER': 'cltbld', 22:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:50 INFO - 'XPC_FLAGS': '0x0', 22:53:50 INFO - 'XPC_SERVICE_NAME': '0', 22:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:53:50 INFO - Cleaning up... 22:53:50 INFO - Return code: 0 22:53:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53:50 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')]} 22:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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')]} 22:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:50 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 22:53:50 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 22:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:50 INFO - 'HOME': '/Users/cltbld', 22:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:50 INFO - 'LOGNAME': 'cltbld', 22:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:50 INFO - 'MOZ_NO_REMOTE': '1', 22:53:50 INFO - 'NO_EM_RESTART': '1', 22:53:50 INFO - 'PAGER': '/bin/cat', 22:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:50 INFO - 'PWD': '/builds/slave/test', 22:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:50 INFO - 'SHELL': '/bin/bash', 22:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:50 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:50 INFO - 'USER': 'cltbld', 22:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:50 INFO - 'XPC_FLAGS': '0x0', 22:53:50 INFO - 'XPC_SERVICE_NAME': '0', 22:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:53 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:54 INFO - Downloading docopt-0.6.1.tar.gz 22:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:54 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:54 INFO - Running setup.py install for blobuploader 22:53:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:54 INFO - Running setup.py install for requests 22:53:55 INFO - Running setup.py install for docopt 22:53:55 INFO - Successfully installed blobuploader requests docopt 22:53:55 INFO - Cleaning up... 22:53:55 INFO - Return code: 0 22:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53: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')]} 22:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53: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')]} 22:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:55 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:55 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 22:53:55 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 22:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:55 INFO - 'HOME': '/Users/cltbld', 22:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:55 INFO - 'LOGNAME': 'cltbld', 22:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:55 INFO - 'MOZ_NO_REMOTE': '1', 22:53:55 INFO - 'NO_EM_RESTART': '1', 22:53:55 INFO - 'PAGER': '/bin/cat', 22:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:55 INFO - 'PWD': '/builds/slave/test', 22:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:55 INFO - 'SHELL': '/bin/bash', 22:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:55 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:55 INFO - 'USER': 'cltbld', 22:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:55 INFO - 'XPC_FLAGS': '0x0', 22:53:55 INFO - 'XPC_SERVICE_NAME': '0', 22:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:55 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-K8hYy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:55 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-z1KffC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:55 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-pp8wFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:55 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-2mf9pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:55 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-E_u1mV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-eYRIVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-06MuFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-3oYDWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-l9twS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-uKrauI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-aY6Sur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-WGFceK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-JrAnAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-l00Xq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-GvKbKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-71tyCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-BxEMa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:57 INFO - Running setup.py install for manifestparser 22:53:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozcrash 22:53:57 INFO - Running setup.py install for mozdebug 22:53:57 INFO - Running setup.py install for mozdevice 22:53:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozfile 22:53:58 INFO - Running setup.py install for mozhttpd 22:53:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozinfo 22:53:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozInstall 22:53:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozleak 22:53:58 INFO - Running setup.py install for mozlog 22:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for moznetwork 22:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozprocess 22:53:59 INFO - Running setup.py install for mozprofile 22:53:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Running setup.py install for mozrunner 22:53:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Running setup.py install for mozscreenshot 22:53:59 INFO - Running setup.py install for moztest 22:53:59 INFO - Running setup.py install for mozversion 22:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:59 INFO - Cleaning up... 22:53:59 INFO - Return code: 0 22:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:59 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')]} 22:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 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')]} 22:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:53:59 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 22:53:59 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 22:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:59 INFO - 'HOME': '/Users/cltbld', 22:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:59 INFO - 'LOGNAME': 'cltbld', 22:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:59 INFO - 'MOZ_NO_REMOTE': '1', 22:53:59 INFO - 'NO_EM_RESTART': '1', 22:53:59 INFO - 'PAGER': '/bin/cat', 22:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:59 INFO - 'PWD': '/builds/slave/test', 22:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:59 INFO - 'SHELL': '/bin/bash', 22:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:53:59 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:59 INFO - 'USER': 'cltbld', 22:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:59 INFO - 'XPC_FLAGS': '0x0', 22:53:59 INFO - 'XPC_SERVICE_NAME': '0', 22:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-9L5o1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-W0q1T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-dEzURl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Td99tC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-2krV6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-YREKZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-LpQykF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-vjWYKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-MyHE6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-SRSWL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-X9qZl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Ny39Wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4kXOiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4bpbKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-hrhv4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-nqoNhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-iV8jtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:01 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)) 22:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:01 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)) 22:54:01 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)) 22:54:01 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)) 22:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:01 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 22:54:01 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 22:54:01 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 22:54:01 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 22:54:01 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 22:54:01 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 22:54:04 INFO - Downloading blessings-1.6.tar.gz 22:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:54:04 INFO - Installing collected packages: blessings 22:54:04 INFO - Running setup.py install for blessings 22:54:04 INFO - Successfully installed blessings 22:54:04 INFO - Cleaning up... 22:54:04 INFO - Return code: 0 22:54:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:05 INFO - Reading from file tmpfile_stdout 22:54:05 INFO - Current package versions: 22:54:05 INFO - blessings == 1.6 22:54:05 INFO - blobuploader == 1.2.4 22:54:05 INFO - docopt == 0.6.1 22:54:05 INFO - functools32 == 3.2.3-2 22:54:05 INFO - jsonschema == 2.5.1 22:54:05 INFO - manifestparser == 1.1 22:54:05 INFO - mozInstall == 1.12 22:54:05 INFO - mozcrash == 1.0 22:54:05 INFO - mozdebug == 0.1 22:54:05 INFO - mozdevice == 0.48 22:54:05 INFO - mozfile == 1.2 22:54:05 INFO - mozhttpd == 0.7 22:54:05 INFO - mozinfo == 0.9 22:54:05 INFO - mozleak == 0.1 22:54:05 INFO - mozlog == 3.4 22:54:05 INFO - moznetwork == 0.27 22:54:05 INFO - mozprocess == 0.23 22:54:05 INFO - mozprofile == 0.28 22:54:05 INFO - mozrunner == 6.13 22:54:05 INFO - mozscreenshot == 0.1 22:54:05 INFO - mozsystemmonitor == 0.3 22:54:05 INFO - moztest == 0.7 22:54:05 INFO - mozversion == 1.4 22:54:05 INFO - psutil == 3.1.1 22:54:05 INFO - requests == 1.2.3 22:54:05 INFO - wsgiref == 0.1.2 22:54:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:05 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')]} 22:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:05 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')]} 22:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:05 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:54:05 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 22:54:05 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 22:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:05 INFO - 'HOME': '/Users/cltbld', 22:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:05 INFO - 'LOGNAME': 'cltbld', 22:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:05 INFO - 'MOZ_NO_REMOTE': '1', 22:54:05 INFO - 'NO_EM_RESTART': '1', 22:54:05 INFO - 'PAGER': '/bin/cat', 22:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:05 INFO - 'PWD': '/builds/slave/test', 22:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:05 INFO - 'SHELL': '/bin/bash', 22:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:54:05 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:05 INFO - 'USER': 'cltbld', 22:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:05 INFO - 'XPC_FLAGS': '0x0', 22:54:05 INFO - 'XPC_SERVICE_NAME': '0', 22:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 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)) 22:54:06 INFO - Cleaning up... 22:54:06 INFO - Return code: 0 22:54:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:54:06 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')]} 22:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:06 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')]} 22:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:06 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 0x106a173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10698d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c985c4b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, '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.jXQM8UbmCv/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.fncxmDmD1M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 22:54:06 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 22:54:06 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 22:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:06 INFO - 'HOME': '/Users/cltbld', 22:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:06 INFO - 'LOGNAME': 'cltbld', 22:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:06 INFO - 'MOZ_NO_REMOTE': '1', 22:54:06 INFO - 'NO_EM_RESTART': '1', 22:54:06 INFO - 'PAGER': '/bin/cat', 22:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:06 INFO - 'PWD': '/builds/slave/test', 22:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:06 INFO - 'SHELL': '/bin/bash', 22:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:54:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:06 INFO - 'USER': 'cltbld', 22:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:06 INFO - 'XPC_FLAGS': '0x0', 22:54:06 INFO - 'XPC_SERVICE_NAME': '0', 22:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:54:06 INFO - Cleaning up... 22:54:06 INFO - Return code: 0 22:54:06 INFO - Running post-action listener: _resource_record_post_action 22:54:06 INFO - Running post-action listener: _start_resource_monitoring 22:54:06 INFO - Starting resource monitoring. 22:54:06 INFO - [mozharness: 2016-11-17 06:54:06.796506Z] Finished create-virtualenv step (success) 22:54:06 INFO - [mozharness: 2016-11-17 06:54:06.797497Z] Running install step. 22:54:06 INFO - Running pre-action listener: _resource_record_pre_action 22:54:06 INFO - Running main action method: install 22:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:07 INFO - Reading from file tmpfile_stdout 22:54:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:54:07 INFO - Reading from file tmpfile_stdout 22:54:07 INFO - Output received: 22:54:07 INFO - Usage: mozinstall [options] installer 22:54:07 INFO - Options: 22:54:07 INFO - -h, --help show this help message and exit 22:54:07 INFO - -d DEST, --destination=DEST 22:54:07 INFO - Directory to install application into. [default: 22:54:07 INFO - "/builds/slave/test"] 22:54:07 INFO - --app=APP Application being installed. [default: firefox] 22:54:07 INFO - mkdir: /builds/slave/test/build/application 22:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:54:40 INFO - Reading from file tmpfile_stdout 22:54:40 INFO - Output received: 22:54:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:54:40 INFO - Running post-action listener: _resource_record_post_action 22:54:40 INFO - [mozharness: 2016-11-17 06:54:40.862833Z] Finished install step (success) 22:54:40 INFO - [mozharness: 2016-11-17 06:54:40.862957Z] Running run-tests step. 22:54:40 INFO - Running pre-action listener: _resource_record_pre_action 22:54:40 INFO - Running main action method: run_tests 22:54:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:54:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:54:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:54:40 INFO - 2016-11-16 22:54:40.877 screenresolution[1622:7294] starting screenresolution argv=screenresolution get 22:54:40 INFO - 2016-11-16 22:54:40.880 screenresolution[1622:7294] Display 0: 1600x1200x32@60 22:54:40 INFO - 2016-11-16 22:54:40.886 screenresolution[1623:7296] starting screenresolution argv=screenresolution list 22:54:40 INFO - Available Modes on Display 0 22:54:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:54:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:54:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:54:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:54:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:54:41 INFO - Intel Iris: 22:54:41 INFO - Chipset Model: Intel Iris 22:54:41 INFO - Type: GPU 22:54:41 INFO - Bus: Built-In 22:54:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:54:41 INFO - Vendor: Intel (0x8086) 22:54:41 INFO - Device ID: 0x0a2e 22:54:41 INFO - Revision ID: 0x0009 22:54:41 INFO - Displays: 22:54:41 INFO - Display: 22:54:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:54:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:54:41 INFO - Display Serial Number: 1600x1200 60 22:54:41 INFO - Main Display: Yes 22:54:41 INFO - Mirror: Off 22:54:41 INFO - Online: Yes 22:54:41 INFO - Rotation: Supported 22:54:41 INFO - Return code: 0 22:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:54:41 INFO - Python 2.7.3 22:54:41 INFO - Return code: 0 22:54:41 INFO - grabbing minidump binary from tooltool 22:54:41 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')]} 22:54:41 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 0x10682dd30>, '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 0x106694db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106825b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:54:41 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 22:54:41 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 22:54:41 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 22:54:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:54:41 INFO - Return code: 0 22:54:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:54:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:54:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:54:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:54:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 22:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --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 22:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jXQM8UbmCv/Render', 22:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:41 INFO - 'HOME': '/Users/cltbld', 22:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:41 INFO - 'LOGNAME': 'cltbld', 22:54:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:54:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:41 INFO - 'MOZ_NO_REMOTE': '1', 22:54:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:54:41 INFO - 'NO_EM_RESTART': '1', 22:54:41 INFO - 'PAGER': '/bin/cat', 22:54:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:41 INFO - 'PWD': '/builds/slave/test', 22:54:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:41 INFO - 'SHELL': '/bin/bash', 22:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fncxmDmD1M/Listeners', 22:54:41 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 22:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:41 INFO - 'USER': 'cltbld', 22:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:41 INFO - 'XPC_FLAGS': '0x0', 22:54:41 INFO - 'XPC_SERVICE_NAME': '0', 22:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 22:54:41 INFO - mozversion application_buildid: 20161116203623 22:54:41 INFO - mozversion application_changeset: 13f49da109ea460665ad27c8497cb1489548450c 22:54:41 INFO - mozversion application_display_name: Nightly 22:54:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:54:41 INFO - mozversion application_name: Firefox 22:54:41 INFO - mozversion application_remotingname: firefox 22:54:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:54:41 INFO - mozversion application_vendor: Mozilla 22:54:41 INFO - mozversion application_version: 53.0a1 22:54:41 INFO - mozversion platform_buildid: 20161116203623 22:54:41 INFO - mozversion platform_changeset: 13f49da109ea460665ad27c8497cb1489548450c 22:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:54:41 INFO - mozversion platform_version: 53.0a1 22:54:41 INFO - using testdate: 1479365681 22:54:41 INFO - actual date: 1479365681 22:54:41 INFO - starting webserver on 'localhost:49236' 22:54:41 INFO - SUITE-START | Running 2 tests 22:54:41 INFO - TEST-START | dromaeo_css 22:54:41 INFO - operating with platform_type : mac_ 22:54:41 INFO - Initialising browser for dromaeo_css test... 22:54:41 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpTLlJUm/profile http://localhost:49236/getInfo.html) 22:54:44 INFO - PROCESS | 1633 | 2016-11-16 22:54:44.404 plugin-container[1635:7464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 22:54:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:54:44 INFO - PROCESS | 1633 | 2016-11-16 22:54:44.407 plugin-container[1635:7464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9317, name = 'com.apple.CFPasteboardClient' 22:54:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:54:44 INFO - PROCESS | 1633 | 2016-11-16 22:54:44.407 plugin-container[1635:7464] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:54:44 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 22:54:44 INFO - PROCESS | 1633 | colorDepth:24 22:54:44 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 22:54:44 INFO - PROCESS | 1633 | __metrics 22:54:44 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 22:54:45 INFO - TEST-INFO | 1633: exit 0 22:54:45 INFO - Browser initialized. 22:54:45 INFO - Running cycle 1/1 for dromaeo_css test... 22:54:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpTLlJUm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:47 INFO - PROCESS | 1636 | 2016-11-16 22:54:47.686 plugin-container[1637:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 22:54:47 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:54:47 INFO - PROCESS | 1636 | 2016-11-16 22:54:47.688 plugin-container[1637:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 22:54:47 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:54:47 INFO - PROCESS | 1636 | 2016-11-16 22:54:47.688 plugin-container[1637:7587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:54:57 INFO - PROCESS | 1636 | RSS: Main: 237555712 22:54:57 INFO - PROCESS | 1636 | 22:55:47 INFO - PROCESS | 1636 | 1479365747069 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] 22:58:00 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 22:58:02 INFO - PROCESS | 1636 | RSS: Main: 249597952 22:58:02 INFO - PROCESS | 1636 | 23:01:04 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 23:01:05 INFO - PROCESS | 1636 | RSS: Main: 260923392 23:01:05 INFO - PROCESS | 1636 | 23:04:07 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 23:04:07 INFO - PROCESS | 1636 | RSS: Main: 265490432 23:04:07 INFO - PROCESS | 1636 | 23:07:09 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 23:07:10 INFO - PROCESS | 1636 | RSS: Main: 263852032 23:07:10 INFO - PROCESS | 1636 | 23:10:13 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 23:10:14 INFO - PROCESS | 1636 | RSS: Main: 271790080 23:10:14 INFO - PROCESS | 1636 | 23:13:16 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 23:13:17 INFO - PROCESS | 1636 | RSS: Main: 274087936 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | __start_tp_report 23:13:17 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 23:13:17 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 23:13:17 INFO - PROCESS | 1636 | |i|pagename|runs| 23:13:17 INFO - PROCESS | 1636 | |0;dojo.html;3414.9487757683632;3428.5371474850904;3438.707709844663;3441.1741182116293;3456.6370531094217;5576.0241957657345;5675.460831221038;5742.913856292159;5804.129380592908;5822.097574875901;4342.5657434256555;5709.400512946143;5776.191333213354;5829.154772557977;5900.911486327708;5184.507471790174;5224;5235.790568377261;5288.788448462057;5393.946060539403;5371.838844834661;5374;5401.729913504329;5409.729513524328;5420.105682562392;4367.650587952972;5174.534291913708;5327.46725327468;5364.544013758835;5381.408045115034;5193.974030129825;5194.506521880416;5253.86865328366;5255.343082114736;5308.097623404031;5157.896842063175;5176.767045482962;5215.895682086337;5228.686278823283;5324.520793128599;5614.494695477386;5798.739056742456;5811.012127938261;5815.767369305223;5845.561582881259;5097.949020509822;6163.322034576193;6269.686515674198;6293.748250069992;6313.87372252557;6317.873642527124;6342.302346741854;6344.524160687967;6359.55483116182;6366.108744775735;5727.05503591911;5791.913121303184;5879.176915231871;5891.998360278806;5895.970520147372;6268.937310626927;6281.371862813682;6316.242050953915;6323.588966717149;6328.651924144124;6234.1583886174785;6237.251529816451;6239.126522286883;6245.437910588069;6248.843778905563;6066.4540191382985;6320.051992201114;6329.841753956188;6350.301466838644;6354.872902542016;5452.5365343946205;5745.253117094751;5950.018246989198;6145.740123274718;6161.106639537242;6238.837838107471;6279.183706118267;6289.779857704958;6294.8111556653375;6307.558470906993;4975.577075948512;4975.626827987915;4980.377452818398;4981.800727970913;5011.7494125294315;4374.978125109354;4394.560543945644;4399.49405818335;4446.510883802779;4470.977645111819;5067.758399192221;5319.5744340452675;5538.670719027405;5592.049351610236;5898.026825573818;6300.684965751694;6323.083152942798;6330.272018717815;6337.302896681362;6341.619502829845;5744.827655170351;5777.335606405233;5832.329282132524;5990.580659353804;5991.221141251609;5425.077736784677;5452.5365343946205;5477.233187353773;5485.4788795064405;5597.804076857372;6391.856390111645;6427.971524556048;6434.420902118832;6473.967630161725;6520.18497687789;6273.933431316778;6341.080543321101;6344.080108384351;6357.332480089709;6380.521460890359;6268.592541484789;6281.403266689657;6322.462590679844;6347.587406818542;6373.3945275198785;6267.341929097379;6277.121203031488;6284.151639528788;6290.182276304051;6299.30707622152;6187.443130118311;6317.9052314214405;6321.683915804099;6343.746250149942;6390.169277993831;5966.910496342472;6008;6062.060380641008;6107.158472117242;6253.062040693932;4982.526659967442;5786.276715410574;6088.360722124113;6096.512279017757;6101.420365065312;5763.884722305615;5973.313068996948;5988.700564971821;6054.182685337423;6097.317180440592;6148.938510614837;6177.2587289525545;6187.071939209155;6223.3465486125115;6252.531060170596;4752.976235118802;6074.848128796815;6076.422739839708;6089.89898485022;6117.296510901303 23:13:17 INFO - PROCESS | 1636 | |1;ext.html;484.12373603777144;487.8487668822663;520.3131865936963;522.6002108387084;529.489043073434;28967.696453659584;31786.20534486639;32973;33174.33651326975;33814.66185338146;32353;32546.67453325466;32907.34185316292;32962.50556241658;33011.33977320452;7277.053982982218;7449.515781474214;7467.663955122032;7470.514416562906;7472.29013243741;7621.923780762205;7638.465307428482;7717.49836260644;7795.103563090261;7795.883061754078;7630.198829122945;7734.961325193394;7791.571463569501;7795.6491957861745;7828.138904720477;7709.267619576131;7722.575258360788;7791.922080779233;7802.882956755653;7817.413693972918;7688.269614386624;7713.65288562016;7794.805129871742;7806.531608103532;7820.843583128362;25960.74039259602;30859.38281234363;31440;31474.842625786725;31598.842005790055;3351.5307856900054;3538.9913874545887;3617.801020943847;3901.8244179012013;3912.1784425270725;21467.892660536596;21545.030473628725;21623;21667.34997950064;21871.67192492114;2800.9159725208074;2813.6342275504257;2851.472477591652;2856.343041100538;2861.799674022819;355642;431378;449428;449813;451993;58806.411935880096;59064.70467647634;59306.40693593095;59313.40686593079;59452.405475945554;52858.47141528613;61414.38585614086;61767.38232617706;61895.38104618986;61936.38063619306;15531.84468155304;15607.21963901823;15755.054696718234;15762.896597238312;15780.368785248691;10323.74190645225;12194.634160975185;12265.509379624893;12309.815352769716;12398.5660501882;16818.747718784227;17107.828921710872;17113.005224085984;17135.74296385555;17149.91425042892;6357.318986774229;6371.426571608577;6390.520710946697;6456.483481321483;6481.740730370827;789.8222899847603;847.9321654267644;848.2323497235012;878.6221924572495;1071.3197119828808;25903.22290331293;29212.853935730185;29276.707232927823;30156.246093847392;30579.541306880416;1062.4315990944808;1075.5590208014676;1085.2023762534532;1099.5272033025715;1137.2948771761396;12779.380227053432;14952.70094598102;15150.621234469227;15167.317470713844;15186.46847360337;570368;571506;576422;577067;577745;50748;50795.492045079074;50851.491485084676;50864.745676271385;51125.23312150321;1300.6878349196124;1315.8881495073026;1323.272200289856;1345.656857501331;1374.154894739728;27855.16434506968;28116.015939442022;28308.85845570759;29136.27159321034;29582.964596239028;35298;48412.757936210095;48669.756651216514;49087.75456122697;49133;523160;526704;526844;527376;530965;23836.404089897893;24016.63975040411;24491.75508244966;24699.506009879344;24801.87599061993;41637.58362416337;42410.51563195273;42973.355399668406;43355.349669754345;43378.566214338716;35105;35139;35240.64759352374;36171;36422;25632.8718356407;30228.84885575646;33455.33089338248;33957.8114765982;35560.82219588885;23412.648810267518;26896.59655105136;28814.279643009093;29979.55030674498;31048.844755776077;27357.726422736316;27935.860320698266;30040.84979575088;32030.679693203703;33056;20601.587968240852;23407.765922340557;23801.880990594935;24652;25351.492970140862 23:13:17 INFO - PROCESS | 1636 | |2;jquery.html;367.9356112680281;374.4925625777072;380.3173303919464;386.01951044347356;401.7228112602304;14466.27668616569;14920.328585213665;15812.44656437025;15957.441489547868;15970.920145399257;15284.770728439054;18858.62282754344;19275.807241927578;19575.804241957576;19660.91864947427;4906.460289368176;5234.816781412651;5327.893442131155;5334.359876814788;5341.519263266306;4374.4313239278845;4637.86086417408;5163.328767260251;5299.28459657947;5321.627486075976;4641.187792136379;4669.9066018679605;4699.67102302839;4719.221328480797;4726.2201736713405;4475.552444755531;4709.858704238861;4709.952900471003;4717.174494463455;4832.395950506187;4721.881952951169;4752.310914917352;4755.35802666639;4757.548032936866;4783.162946484351;12161.027117830552;12392.442340094718;15269.847301526954;20738.585228295502;20769.480762980897;2092;2130.936071917845;2149.8172655324315;2151.3761009307436;2538.733432989528;8945.955270223672;9098.090190980914;9098.590563424661;9549.665761698378;9560.760980975461;1398.7132637809225;1627.1457484820446;1690.2309449200584;1693.3311342019956;1752.6056637256643;110930;111103;111840;113269;113279;9127.406718563405;11211.94394028041;11417.885821141683;11499;11562.595309164139;14303.856961430462;14402.351894164787;14413.639659008608;14461.204633745245;14477.986540942244;7524.811879702942;7612.80967975805;8423.368247381472;8424.452410593292;8465.576721163918;8185.181481851767;8213.34293256538;8231.794205144919;8361.581920903931;8363.790905227417;3770.434434834797;4426.44669416323;4503.639708823286;4538.092381523708;4605.078984203105;1619.7408414653598;1801.3965321617143;1803.1975770781876;1824.3705921457076;1904.561950751335;752.6124046116255;884.1909652667865;900.1313732248411;926.1895841139003;939.8637197606446;16420.589485262964;18785.15972381803;19993;20075;20160.69758953617;865.7144140950809;870.8432482153273;873.1835733589114;911.2800887299092;922.7693076730817;8514.616842242114;10207;10622.46887655614;10631.627893023708;10675.733106672395;519551;520553;521023;527659;535133;118339.40830295793;118388;118981.40509297398;120915.79084209222;124326;847.737201467547;937.4843835890233;945.9148676619137;947.5877993073035;950.9001554836781;17682;17922.283108675507;18069.457916262534;18263.0868456574;18292.542686432946;38226.80886595549;40952.385714214906;49254.014919702124;50883.74558127334;52202.95594088173;67749.3225067743;70179.64910175417;70612.64693676498;76777.23222767924;78723.60638196772;7045.330693584101;7925.167857375123;7926.048874135141;8131.455023545507;8379.329653627818;10789.946050269698;12588.054061330025;12882;12948.223106613634;13151.671208219872;10407.635732749317;10625.893741062491;10718.35689858611;10826;11102.61140860066;9510.620959960628;10381.740456488647;10634.946825265824;10935.507902144263;11024.669259922215;10871.075958543612;11003.779924401628;11332.716682083013;11444.599439019594;11522.654320370402;7919.247671471201;8127.431079824356;8167.509949403055;8180.427370084037;8224.794380140305;9214.631414743604;10270.435126068138;10779.838302425313;11052.11583073336;11080.5567777288 23:13:17 INFO - PROCESS | 1636 | |3;mootools.html;1353.9033382959803;1433.3120102350872;1454.6290695872551;1704.6335037966835;1804.9639007219855;7224.53040552365;7470.51441656292;7670.61646917654;8102.594870256493;8125.5530945797955;7622.695092196305;7902.762917112482;7910.248526389998;7994.280514753671;8001.319887809544;2023.3727544461228;2062.3194345865904;2065.380385884229;2065.648839697255;2069.0999415254337;1571.9371225151037;2058.83529317655;2085.14509051289;2089.707440958267;2112.6197284488785;2056.681214411761;2064.4838790302424;2077.885716285604;2080.0639712129528;2083.895805209741;2054.8767073975537;2066.2354928676336;2068.503559145809;2074.844386670998;2077.7610574783944;2060.042080432606;2062.3812856143095;2092.9476763081043;2098.244631932503;2104.1583366653463;7946.364290856775;8010.679572817138;8022.719204827861;8030.196980301981;8043.638036288381;4991.825286114969;5167.457416971201;5172.379314482248;5174.689518628894;5217.217417387385;3263.515100629208;3402.2344972381266;3441.0193094967813;3497.335506253829;3497.7726447780815;939.741571067955;983.6163896080534;1026.4764969865348;1027.4246422003703;1027.917766578672;10897.237193396466;10949.288296260796;11012.834807477895;11026.669199924016;11036.779264414667;4297.484301883794;4573.222552166139;4667.789949452283;4682.2508398656055;4687.062587482517;9377.156055954996;9444.433333999981;9492.572834222477;9493.0981556752;9500.714978550655;5209.322788037607;5211.009908117446;5215.035218484592;5227.085260079547;5230.137027390514;4273.572642735764;4637.188492013884;4717.622590192776;4742.407199100113;4793.808247670124;3442.7622045670014;3727.7204209684382;3761.6238376162164;3834.386498160281;3843.8078096095082;1195.235049568277;1283.736833949057;1288.2077522323702;1291.3865913691109;1297.8183054372396;770.5646309834925;854.256796586961;875.5272153037289;890.2032680750692;911.3028533172129;5471.124620060771;5488.780448782004;5538.47384498472;5631.915521267184;5747.741351639186;854.5300084953251;891.3359547137425;904.6878826804873;919.7562645898843;955.5843208204453;3387.8819989403437;3407.8125703086566;3664.780113193217;3696.704263658901;3753.5871054184017;9815.558299876522;10284;10288.97110288965;10289.073983341386;10295.897041029644;9561.043895610383;9985.500724963722;9988.350757200758;10289.742756431;10298.3820970742;7557.962210188914;7716.958210641492;7761.340286075747;7776.144624091346;7796.844063118706;5119.974400127976;5235.738213089407;5237.198099017008;5651.6043876928225;5727.341355743976;9099.31755118382;9395.201407880406;9547;9573.808523829624;9581.089796469325;9293.349465537347;9510.429374237292;9656.613735450503;9666;9702.951485242529;6684.598924064571;6712.932870671426;7135.82160445993;7202.71189152428;7315.926840731525;7843.4509584330835;7955.403344749051;7986.840263194587;7991.84016319682;7997;6027.186329845415;6207.286162091236;6286.27707813589;7067.787966361017;7122.893156602551;5824.5340372770925;7011.789646310619;7060.894086588808;7065.57606543609;7129.964350178216;6032.758689652364;6297.370262973849;6653.268140504444;6671.299513551007;6689.665516724242;4284.3145096784365;4816.084943860656;4868.196747536616;4909.852704418873;4958.826441074545;4098.200850834059;4181.954511372157;4736.928946065743;4830.516948305141;4992.2511623256805 23:13:17 INFO - PROCESS | 1636 | |4;prototype.html;257.49273930357197;305.59050871831744;307.9076277116864;309.2886361368852;310.9813411195327;4715.882102947419;5131.255967884655;5411.621186516941;5464.890702185959;5515.33815942087;4115.0166129556055;4897.363342765444;5358.4641535846395;5368.677879327243;5428.294321738178;3597.982010089946;3924.27400930828;3943.8619648312315;4057.9391309130388;4082.8154039848964;4097.856575019875;4120.237756015132;4160.417541544171;4160.916781664365;4166.437530933331;3251.536808436201;3925.7230806132375;4103.1793641271715;4128.731632443882;4131.772752498611;3301;3588.5334906462253;4004.3392840181336;4043.9191216175805;4145.937810932838;3065.9693403065908;3492.4412094065083;3501.964980350215;3502.159481724393;3506.9824650876835;5167.124656507619;5216.556592689626;5226.660267082628;5253.238280449314;5263.31576895005;4055.634992850628;4365.402585461646;4591.931121033187;4600.964782923849;4623;5609.831705048855;5674.631148975344;5696.572757043197;5708.686022268774;5734.311882574118;3322.5016247562817;3933.1347103637154;3982.2433737589763;3989.3218152914073;4007.6393124618635;4943.7775300111225;5710.800121995751;5961.791337303217;5964.7912323068485;6002.729877155495;3126.264518546272;3956.7032472564674;3963.632546771359;3978.703000759173;3986.402039694011;3682.3187710273687;3998.7200895937003;4045.6763458923215;4055.4322394864744;4071.694622903294;4666.929996050062;4668.439787225555;4674.719516829001;4703.459102203222;4727.220008698595;4484.228729651769;4726.747411935839;5642.463965923231;5733.71331433435;5734.971325143348;1068.787609648021;1237.826704261404;1261.1928365845683;1335.23606135299;1348.1439286053426;771.1517330936038;844.472204871955;855.7347222360963;892.683097500378;928.2713070239829;4255.659547236213;4678.157931572349;4859.975700121477;5223.973880130575;5311.7078560678765;4680.765961701902;5634.549236061187;5859.912101318484;5870.44230798082;5946.167536544887;5010.348654674869;5336.946630533723;5360.276362691065;5368.879292679369;5390.676559406448;2600.8309459885045;2791.274268690155;2869.9282517937218;2894.6815850256453;2954.1432984434273;5256.632035757537;5374.811881584125;5756.481916627524;5807.709614519257;5838.4453476919625;5278.812267239917;5284.749878824496;5290.180022096504;5448.727563621803;5768.08711600639;4972.108051958441;5210.062188805976;5416.7562459688625;5820.767169313265;5821.359650438533;4701.076574943843;4988.750562471934;5560.6654402942195;5611.270534830446;5626.5780066496;4748.3352330674425;5446.591505637014;5503.944960550503;5516.613837031369;5590.468905453975;4783.5888412917575;5571.303587051619;5754.324239228778;5817.94182058174;5879.559033072622;3253.4143854106487;3657.707383409374;3683.502727131803;3857.8456861725217;3864.826082826336;4683.601893839062;4717;5234.408967561081;5553.750406158582;5560.8293779665255;4452.755098469615;4511;5410.675359478444;5416.8104116355735;5487.314085739283;4611.769411529343;4892.431473476453;5005.524475174852;5143.71709555978;5417.485338892799;4892.706437613754;5347.545458635904;5604.747786349705;5621.156826476062;5666.773329066956;4941.579965702956;5399.217113518596;5609.579281553982;5728.112142617901;5794.362620111869;4730.722704869735;5507.449255074461;5643.830685079454;5666.943330566642;5732.197492350991 23:13:17 INFO - PROCESS | 1636 | |5;yui.html;684.3293572299146;696.9162951610248;754.2118486181939;761.2349588663393;775.9883601745975;12446.253224806507;13038.21770693759;13109.868901310983;13126.212427254359;13142.948564114871;12229.449674764635;12328.137030407852;12334.876651233484;12337.259764414119;12352.135350525466;630.9463695585857;647.2750519418258;769.6882762481176;784.674360140541;788.0936922539083;676.2932735291607;777.0830420104274;795.2087672765578;799.3006119645311;799.9920000799975;819.8278361544083;834.9958250208771;843.7173546861809;845.8900342955408;847.9364047696416;826.36782861111;838.2832678060262;839.131498898637;841.3017195727592;841.3605659698612;615.6706162203244;621.9378062193745;627.2347735762363;630.486153784664;632.0361448790585;10699.465026748632;11252.606158784485;11589.188756786943;11610.825837612443;11917.761644767055;10062.647807326708;10791.460426978698;10794.46027698612;10920.399378034204;11325.490352934137;9340.532973351304;9401.153896149312;9450.57472413743;9583.196586806745;9878.308518403715;3061.7397521210723;3066.402051599939;3076.292452735883;3171.4291427543035;3173.76196785242;85146;86839.13160868373;87259.12740872637;87733.56133219357;88004.6799298011;8319.542425166554;8366.665333386718;8455.323574114056;8536.103709110454;8569.957150214334;17031.82968170327;17145.485635430956;17162.914185428992;17166.57083572896;17188.828111718973;6928.930710692828;6934.757283495053;7038.190608080034;7053.964730176419;7193;3851.9037024074623;4140.358244472111;4245.61789438952;4245.8513952011535;4354.303311470149;2863.2412410711177;3141.7800753947004;3199.232184275804;3389.4407422774966;3414.829258537063;570.9950487142659;624.5097598385246;639.8432384065934;641.7946257197652;642.8304472405631;541.6019225868984;551.5026702502724;627.8461776864698;631.3875540725489;636.6243916089529;8161.795955101051;8336.291415229653;8741.562921853882;9010.414323068979;9015.323850711224;575.8502789274735;593.5577994394204;620.5439002333281;623.429561950819;640.9359064093553;3683.9079023024638;4105.712600118023;4147.730397524151;4367.781610919441;4421.9336709949375;71114;101462.3318318388;103627.48186259021;105029.47485262678;111509.44245278722;73963.89054164191;96149.0385096154;96508.51745741366;97897.51051244698;99767.50116249372;84071.15928840755;85499.86250343791;85995.57002214949;86051.5697421509;88390;8276.751697449086;8290.544020078954;8361.289290410383;8413.391848526977;8462.449940753831;37827.62172378241;42012;47680.76159619319;52151.73924130355;58573.70713146577;59608;61244.69377653083;68081;72903;78525.21474785179;11230;11290.774184516427;11325.263857849213;11387.829182562102;11589.884101158881;11846.289222646668;11883.702907427038;11989.520419183134;12040.458179382123;12047.277163370225;9238.907610924074;11198.888011119785;11214.102871770076;11383.54465821391;11434.542618295174;11114.666560003212;11122.555097796321;11420.257683250562;11606.70983225426;11673.883261167619;11263.887361126284;11302.771663894382;11481.540738370371;11706.414679266172;11942.880571194177;9102.635894564144;9193.770155745893;9277.860832087388;9524.238060955246;9570.61717531291;7532.887006694795;9617.519124043909;9914.950425247827;9932.503374831374;9936 23:13:17 INFO - PROCESS | 1636 | __end_tp_report 23:13:17 INFO - PROCESS | 1636 | __start_cc_report 23:13:17 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,372 23:13:17 INFO - PROCESS | 1636 | __end_cc_report 23:13:17 INFO - PROCESS | 1636 | __startTimestamp1479366797034__endTimestamp 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | ------- Summary: start ------- 23:13:17 INFO - PROCESS | 1636 | Number of tests: 6 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5759.53 Median:5973.31 stddev:669.06 (11.2%) stddev-sans-first:645.33 23:13:17 INFO - PROCESS | 1636 | Values: 3414.9 3428.5 3438.7 3441.2 3456.6 5576.0 5675.5 5742.9 5804.1 5822.1 4342.6 5709.4 5776.2 5829.2 5900.9 5184.5 5224.0 5235.8 5288.8 5393.9 5371.8 5374.0 5401.7 5409.7 5420.1 4367.7 5174.5 5327.5 5364.5 5381.4 5194.0 5194.5 5253.9 5255.3 5308.1 5157.9 5176.8 5215.9 5228.7 5324.5 5614.5 5798.7 5811.0 5815.8 5845.6 5097.9 6163.3 6269.7 6293.7 6313.9 6317.9 6342.3 6344.5 6359.6 6366.1 5727.1 5791.9 5879.2 5892.0 5896.0 6268.9 6281.4 6316.2 6323.6 6328.7 6234.2 6237.3 6239.1 6245.4 6248.8 6066.5 6320.1 6329.8 6350.3 6354.9 5452.5 5745.3 5950.0 6145.7 6161.1 6238.8 6279.2 6289.8 6294.8 6307.6 4975.6 4975.6 4980.4 4981.8 5011.7 4375.0 4394.6 4399.5 4446.5 4471.0 5067.8 5319.6 5538.7 5592.0 5898.0 6300.7 6323.1 6330.3 6337.3 6341.6 5744.8 5777.3 5832.3 5990.6 5991.2 5425.1 5452.5 5477.2 5485.5 5597.8 6391.9 6428.0 6434.4 6474.0 6520.2 6273.9 6341.1 6344.1 6357.3 6380.5 6268.6 6281.4 6322.5 6347.6 6373.4 6267.3 6277.1 6284.2 6290.2 6299.3 6187.4 6317.9 6321.7 6343.7 6390.2 5966.9 6008.0 6062.1 6107.2 6253.1 4982.5 5786.3 6088.4 6096.5 6101.4 5763.9 5973.3 5988.7 6054.2 6097.3 6148.9 6177.3 6187.1 6223.3 6252.5 4753.0 6074.8 6076.4 6089.9 6117.3 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:62514.21 Median:34531.41 stddev:137489.69 (398.2%) stddev-sans-first:137796.51 23:13:17 INFO - PROCESS | 1636 | Values: 484.1 487.8 520.3 522.6 529.5 28967.7 31786.2 32973.0 33174.3 33814.7 32353.0 32546.7 32907.3 32962.5 33011.3 7277.1 7449.5 7467.7 7470.5 7472.3 7621.9 7638.5 7717.5 7795.1 7795.9 7630.2 7735.0 7791.6 7795.6 7828.1 7709.3 7722.6 7791.9 7802.9 7817.4 7688.3 7713.7 7794.8 7806.5 7820.8 25960.7 30859.4 31440.0 31474.8 31598.8 3351.5 3539.0 3617.8 3901.8 3912.2 21467.9 21545.0 21623.0 21667.3 21871.7 2800.9 2813.6 2851.5 2856.3 2861.8 355642.0 431378.0 449428.0 449813.0 451993.0 58806.4 59064.7 59306.4 59313.4 59452.4 52858.5 61414.4 61767.4 61895.4 61936.4 15531.8 15607.2 15755.1 15762.9 15780.4 10323.7 12194.6 12265.5 12309.8 12398.6 16818.7 17107.8 17113.0 17135.7 17149.9 6357.3 6371.4 6390.5 6456.5 6481.7 789.8 847.9 848.2 878.6 1071.3 25903.2 29212.9 29276.7 30156.2 30579.5 1062.4 1075.6 1085.2 1099.5 1137.3 12779.4 14952.7 15150.6 15167.3 15186.5 570368.0 571506.0 576422.0 577067.0 577745.0 50748.0 50795.5 50851.5 50864.7 51125.2 1300.7 1315.9 1323.3 1345.7 1374.2 27855.2 28116.0 28308.9 29136.3 29583.0 35298.0 48412.8 48669.8 49087.8 49133.0 523160.0 526704.0 526844.0 527376.0 530965.0 23836.4 24016.6 24491.8 24699.5 24801.9 41637.6 42410.5 42973.4 43355.3 43378.6 35105.0 35139.0 35240.6 36171.0 36422.0 25632.9 30228.8 33455.3 33957.8 35560.8 23412.6 26896.6 28814.3 29979.6 31048.8 27357.7 27935.9 30040.8 32030.7 33056.0 20601.6 23407.8 23801.9 24652.0 25351.5 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:31485.39 Median:19306.41 stddev:88019.81 (455.9%) stddev-sans-first:88235.72 23:13:17 INFO - PROCESS | 1636 | Values: 367.9 374.5 380.3 386.0 401.7 14466.3 14920.3 15812.4 15957.4 15970.9 15284.8 18858.6 19275.8 19575.8 19660.9 4906.5 5234.8 5327.9 5334.4 5341.5 4374.4 4637.9 5163.3 5299.3 5321.6 4641.2 4669.9 4699.7 4719.2 4726.2 4475.6 4709.9 4710.0 4717.2 4832.4 4721.9 4752.3 4755.4 4757.5 4783.2 12161.0 12392.4 15269.8 20738.6 20769.5 2092.0 2130.9 2149.8 2151.4 2538.7 8946.0 9098.1 9098.6 9549.7 9560.8 1398.7 1627.1 1690.2 1693.3 1752.6 110930.0 111103.0 111840.0 113269.0 113279.0 9127.4 11211.9 11417.9 11499.0 11562.6 14303.9 14402.4 14413.6 14461.2 14478.0 7524.8 7612.8 8423.4 8424.5 8465.6 8185.2 8213.3 8231.8 8361.6 8363.8 3770.4 4426.4 4503.6 4538.1 4605.1 1619.7 1801.4 1803.2 1824.4 1904.6 752.6 884.2 900.1 926.2 939.9 16420.6 18785.2 19993.0 20075.0 20160.7 865.7 870.8 873.2 911.3 922.8 8514.6 10207.0 10622.5 10631.6 10675.7 519551.0 520553.0 521023.0 527659.0 535133.0 118339.4 118388.0 118981.4 120915.8 124326.0 847.7 937.5 945.9 947.6 950.9 17682.0 17922.3 18069.5 18263.1 18292.5 38226.8 40952.4 49254.0 50883.7 52203.0 67749.3 70179.6 70612.6 76777.2 78723.6 7045.3 7925.2 7926.0 8131.5 8379.3 10789.9 12588.1 12882.0 12948.2 13151.7 10407.6 10625.9 10718.4 10826.0 11102.6 9510.6 10381.7 10634.9 10935.5 11024.7 10871.1 11003.8 11332.7 11444.6 11522.7 7919.2 8127.4 8167.5 8180.4 8224.8 9214.6 10270.4 10779.8 11052.1 11080.6 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5340.68 Median:5213.02 stddev:3012.50 (57.8%) stddev-sans-first:3006.05 23:13:17 INFO - PROCESS | 1636 | Values: 1353.9 1433.3 1454.6 1704.6 1805.0 7224.5 7470.5 7670.6 8102.6 8125.6 7622.7 7902.8 7910.2 7994.3 8001.3 2023.4 2062.3 2065.4 2065.6 2069.1 1571.9 2058.8 2085.1 2089.7 2112.6 2056.7 2064.5 2077.9 2080.1 2083.9 2054.9 2066.2 2068.5 2074.8 2077.8 2060.0 2062.4 2092.9 2098.2 2104.2 7946.4 8010.7 8022.7 8030.2 8043.6 4991.8 5167.5 5172.4 5174.7 5217.2 3263.5 3402.2 3441.0 3497.3 3497.8 939.7 983.6 1026.5 1027.4 1027.9 10897.2 10949.3 11012.8 11026.7 11036.8 4297.5 4573.2 4667.8 4682.3 4687.1 9377.2 9444.4 9492.6 9493.1 9500.7 5209.3 5211.0 5215.0 5227.1 5230.1 4273.6 4637.2 4717.6 4742.4 4793.8 3442.8 3727.7 3761.6 3834.4 3843.8 1195.2 1283.7 1288.2 1291.4 1297.8 770.6 854.3 875.5 890.2 911.3 5471.1 5488.8 5538.5 5631.9 5747.7 854.5 891.3 904.7 919.8 955.6 3387.9 3407.8 3664.8 3696.7 3753.6 9815.6 10284.0 10289.0 10289.1 10295.9 9561.0 9985.5 9988.4 10289.7 10298.4 7558.0 7717.0 7761.3 7776.1 7796.8 5120.0 5235.7 5237.2 5651.6 5727.3 9099.3 9395.2 9547.0 9573.8 9581.1 9293.3 9510.4 9656.6 9666.0 9703.0 6684.6 6712.9 7135.8 7202.7 7315.9 7843.5 7955.4 7986.8 7991.8 7997.0 6027.2 6207.3 6286.3 7067.8 7122.9 5824.5 7011.8 7060.9 7065.6 7130.0 6032.8 6297.4 6653.3 6671.3 6689.7 4284.3 4816.1 4868.2 4909.9 4958.8 4098.2 4182.0 4736.9 4830.5 4992.3 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4474.07 Median:4942.68 stddev:1376.64 (27.9%) stddev-sans-first:1343.63 23:13:17 INFO - PROCESS | 1636 | Values: 257.5 305.6 307.9 309.3 311.0 4715.9 5131.3 5411.6 5464.9 5515.3 4115.0 4897.4 5358.5 5368.7 5428.3 3598.0 3924.3 3943.9 4057.9 4082.8 4097.9 4120.2 4160.4 4160.9 4166.4 3251.5 3925.7 4103.2 4128.7 4131.8 3301.0 3588.5 4004.3 4043.9 4145.9 3066.0 3492.4 3502.0 3502.2 3507.0 5167.1 5216.6 5226.7 5253.2 5263.3 4055.6 4365.4 4591.9 4601.0 4623.0 5609.8 5674.6 5696.6 5708.7 5734.3 3322.5 3933.1 3982.2 3989.3 4007.6 4943.8 5710.8 5961.8 5964.8 6002.7 3126.3 3956.7 3963.6 3978.7 3986.4 3682.3 3998.7 4045.7 4055.4 4071.7 4666.9 4668.4 4674.7 4703.5 4727.2 4484.2 4726.7 5642.5 5733.7 5735.0 1068.8 1237.8 1261.2 1335.2 1348.1 771.2 844.5 855.7 892.7 928.3 4255.7 4678.2 4860.0 5224.0 5311.7 4680.8 5634.5 5859.9 5870.4 5946.2 5010.3 5336.9 5360.3 5368.9 5390.7 2600.8 2791.3 2869.9 2894.7 2954.1 5256.6 5374.8 5756.5 5807.7 5838.4 5278.8 5284.7 5290.2 5448.7 5768.1 4972.1 5210.1 5416.8 5820.8 5821.4 4701.1 4988.8 5560.7 5611.3 5626.6 4748.3 5446.6 5503.9 5516.6 5590.5 4783.6 5571.3 5754.3 5817.9 5879.6 3253.4 3657.7 3683.5 3857.8 3864.8 4683.6 4717.0 5234.4 5553.8 5560.8 4452.8 4511.0 5410.7 5416.8 5487.3 4611.8 4892.4 5005.5 5143.7 5417.5 4892.7 5347.5 5604.7 5621.2 5666.8 4941.6 5399.2 5609.6 5728.1 5794.4 4730.7 5507.4 5643.8 5666.9 5732.2 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18989.59 Median:627.54 stddev:28815.33 (4591.8%) stddev-sans-first:28863.39 23:13:17 INFO - PROCESS | 1636 | Values: 684.3 696.9 754.2 761.2 776.0 12446.3 13038.2 13109.9 13126.2 13142.9 12229.4 12328.1 12334.9 12337.3 12352.1 630.9 647.3 769.7 784.7 788.1 676.3 777.1 795.2 799.3 800.0 819.8 835.0 843.7 845.9 847.9 826.4 838.3 839.1 841.3 841.4 615.7 621.9 627.2 630.5 632.0 10699.5 11252.6 11589.2 11610.8 11917.8 10062.6 10791.5 10794.5 10920.4 11325.5 9340.5 9401.2 9450.6 9583.2 9878.3 3061.7 3066.4 3076.3 3171.4 3173.8 85146.0 86839.1 87259.1 87733.6 88004.7 8319.5 8366.7 8455.3 8536.1 8570.0 17031.8 17145.5 17162.9 17166.6 17188.8 6928.9 6934.8 7038.2 7054.0 7193.0 3851.9 4140.4 4245.6 4245.9 4354.3 2863.2 3141.8 3199.2 3389.4 3414.8 571.0 624.5 639.8 641.8 642.8 541.6 551.5 627.8 631.4 636.6 8161.8 8336.3 8741.6 9010.4 9015.3 575.9 593.6 620.5 623.4 640.9 3683.9 4105.7 4147.7 4367.8 4421.9 71114.0 101462.3 103627.5 105029.5 111509.4 73963.9 96149.0 96508.5 97897.5 99767.5 84071.2 85499.9 85995.6 86051.6 88390.0 8276.8 8290.5 8361.3 8413.4 8462.4 37827.6 42012.0 47680.8 52151.7 58573.7 59608.0 61244.7 68081.0 72903.0 78525.2 11230.0 11290.8 11325.3 11387.8 11589.9 11846.3 11883.7 11989.5 12040.5 12047.3 9238.9 11198.9 11214.1 11383.5 11434.5 11114.7 11122.6 11420.3 11606.7 11673.9 11263.9 11302.8 11481.5 11706.4 11942.9 9102.6 9193.8 9277.9 9524.2 9570.6 7532.9 9617.5 9915.0 9932.5 9936.0 23:13:17 INFO - PROCESS | 1636 | -------- Summary: end -------- 23:13:17 INFO - PROCESS | 1636 | 23:13:17 INFO - TEST-INFO | 1636: exit 0 23:13:17 INFO - TEST-OK | dromaeo_css | took 1116321ms 23:13:17 INFO - TEST-START | kraken 23:13:17 INFO - operating with platform_type : mac_ 23:13:17 INFO - Initialising browser for kraken test... 23:13:17 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDlmF5N/profile http://localhost:49236/getInfo.html) 23:13:20 INFO - PROCESS | 1648 | 2016-11-16 23:13:20.659 plugin-container[1649:9981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 23:13:20 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:13:20 INFO - PROCESS | 1648 | 2016-11-16 23:13:20.662 plugin-container[1649:9981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 23:13:20 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:13:20 INFO - PROCESS | 1648 | 2016-11-16 23:13:20.662 plugin-container[1649:9981] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:13:20 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 23:13:20 INFO - PROCESS | 1648 | colorDepth:24 23:13:20 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 23:13:20 INFO - PROCESS | 1648 | __metrics 23:13:20 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 23:13:21 INFO - TEST-INFO | 1648: exit 0 23:13:21 INFO - Browser initialized. 23:13:21 INFO - Running cycle 1/1 for kraken test... 23:13:21 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDlmF5N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:23 INFO - PROCESS | 1650 | 2016-11-16 23:13:23.932 plugin-container[1651:10099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 23:13:23 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:13:23 INFO - PROCESS | 1650 | 2016-11-16 23:13:23.933 plugin-container[1651:10099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 23:13:23 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:13:23 INFO - PROCESS | 1650 | 2016-11-16 23:13:23.933 plugin-container[1651:10099] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:13:33 INFO - PROCESS | 1650 | RSS: Main: 237744128 23:13:33 INFO - PROCESS | 1650 | 23:13:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 23:13:37 INFO - PROCESS | 1650 | RSS: Main: 249970688 23:13:37 INFO - PROCESS | 1650 | 23:13:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 23:13:40 INFO - PROCESS | 1650 | RSS: Main: 257564672 23:13:40 INFO - PROCESS | 1650 | 23:13:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 23:13:44 INFO - PROCESS | 1650 | RSS: Main: 260411392 23:13:44 INFO - PROCESS | 1650 | 23:13:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 23:13:47 INFO - PROCESS | 1650 | RSS: Main: 262582272 23:13:47 INFO - PROCESS | 1650 | 23:13:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:13:50 INFO - PROCESS | 1650 | RSS: Main: 262877184 23:13:50 INFO - PROCESS | 1650 | 23:13:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 23:13:53 INFO - PROCESS | 1650 | RSS: Main: 268820480 23:13:53 INFO - PROCESS | 1650 | 23:13:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 23:13:57 INFO - PROCESS | 1650 | RSS: Main: 268107776 23:13:57 INFO - PROCESS | 1650 | 23:14:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 23:14:01 INFO - PROCESS | 1650 | RSS: Main: 268173312 23:14:01 INFO - PROCESS | 1650 | 23:14:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:14:03 INFO - PROCESS | 1650 | RSS: Main: 267177984 23:14:03 INFO - PROCESS | 1650 | 23:14:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:14:05 INFO - PROCESS | 1650 | RSS: Main: 267239424 23:14:05 INFO - PROCESS | 1650 | 23:14:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:14:08 INFO - PROCESS | 1650 | RSS: Main: 267845632 23:14:08 INFO - PROCESS | 1650 | 23:14:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:14:12 INFO - PROCESS | 1650 | RSS: Main: 267661312 23:14:12 INFO - PROCESS | 1650 | 23:14:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:14:16 INFO - PROCESS | 1650 | RSS: Main: 267399168 23:14:16 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 23:14:18 INFO - PROCESS | 1650 | RSS: Main: 269336576 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | __start_tp_report 23:14:18 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 23:14:18 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 23:14:18 INFO - PROCESS | 1650 | |i|pagename|runs| 23:14:18 INFO - PROCESS | 1650 | |0;ai-astar;109;138;118;118;116;118;121;119;118;120 23:14:18 INFO - PROCESS | 1650 | |1;audio-beat-detection;150;149;115;149;117;145;143;114;142;131 23:14:18 INFO - PROCESS | 1650 | |2;audio-dft;195;207;253;162;166;266;276;196;190;204 23:14:18 INFO - PROCESS | 1650 | |3;audio-fft;81;59;60;101;87;84;77;60;79;82 23:14:18 INFO - PROCESS | 1650 | |4;audio-oscillator;90;71;86;72;69;70;86;69;71;88 23:14:18 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;86;88;106;89;100;91;85;79;130 23:14:18 INFO - PROCESS | 1650 | |6;imaging-darkroom;154;153;95;97;99;95;95;105;131;94 23:14:18 INFO - PROCESS | 1650 | |7;imaging-desaturate;118;147;146;97;93;93;116;155;157;90 23:14:18 INFO - PROCESS | 1650 | |8;json-parse-financial;50;58;62;57;57;58;57;52;50;77 23:14:18 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;67;75;82;82;72;63;84;86;107;63 23:14:18 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;88;86;87;86;90;87;88;92;87;88 23:14:18 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;150;162;160;161;151;152;150;163;206;141 23:14:18 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;175;179;233;183;163;191;239;154;238;218 23:14:18 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;68;70;73;71;66;69;60;65;81 23:14:18 INFO - PROCESS | 1650 | __end_tp_report 23:14:18 INFO - PROCESS | 1650 | __start_cc_report 23:14:18 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,955 23:14:18 INFO - PROCESS | 1650 | __end_cc_report 23:14:18 INFO - PROCESS | 1650 | __startTimestamp1479366858355__endTimestamp 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | ------- Summary: start ------- 23:14:18 INFO - PROCESS | 1650 | Number of tests: 14 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:119.50 Median:118.50 stddev:7.28 (6.1%) stddev-sans-first:6.65 23:14:18 INFO - PROCESS | 1650 | Values: 109.0 138.0 118.0 118.0 116.0 118.0 121.0 119.0 118.0 120.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:144.00 stddev:14.94 (10.4%) stddev-sans-first:14.90 23:14:18 INFO - PROCESS | 1650 | Values: 150.0 149.0 115.0 149.0 117.0 145.0 143.0 114.0 142.0 131.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:211.50 Median:205.50 stddev:40.03 (19.5%) stddev-sans-first:42.01 23:14:18 INFO - PROCESS | 1650 | Values: 195.0 207.0 253.0 162.0 166.0 266.0 276.0 196.0 190.0 204.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:77.00 Median:80.00 stddev:13.63 (17.0%) stddev-sans-first:14.38 23:14:18 INFO - PROCESS | 1650 | Values: 81.0 59.0 60.0 101.0 87.0 84.0 77.0 60.0 79.0 82.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:79.00 stddev:8.98 (11.4%) stddev-sans-first:8.24 23:14:18 INFO - PROCESS | 1650 | Values: 90.0 71.0 86.0 72.0 69.0 70.0 86.0 69.0 71.0 88.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:87.00 stddev:14.73 (16.9%) stddev-sans-first:15.45 23:14:18 INFO - PROCESS | 1650 | Values: 88.0 86.0 88.0 106.0 89.0 100.0 91.0 85.0 79.0 130.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:95.00 stddev:24.58 (25.9%) stddev-sans-first:20.80 23:14:18 INFO - PROCESS | 1650 | Values: 154.0 153.0 95.0 97.0 99.0 95.0 95.0 105.0 131.0 94.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:121.20 Median:123.50 stddev:27.67 (22.4%) stddev-sans-first:29.33 23:14:18 INFO - PROCESS | 1650 | Values: 118.0 147.0 146.0 97.0 93.0 93.0 116.0 155.0 157.0 90.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:57.50 stddev:7.77 (13.5%) stddev-sans-first:7.71 23:14:18 INFO - PROCESS | 1650 | Values: 50.0 58.0 62.0 57.0 57.0 58.0 57.0 52.0 50.0 77.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:78.50 stddev:13.29 (16.9%) stddev-sans-first:13.47 23:14:18 INFO - PROCESS | 1650 | Values: 67.0 75.0 82.0 82.0 72.0 63.0 84.0 86.0 107.0 63.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.00 stddev:1.85 (2.1%) stddev-sans-first:1.96 23:14:18 INFO - PROCESS | 1650 | Values: 88.0 86.0 87.0 86.0 90.0 87.0 88.0 92.0 87.0 88.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:159.60 Median:160.50 stddev:17.75 (11.1%) stddev-sans-first:18.48 23:14:18 INFO - PROCESS | 1650 | Values: 150.0 162.0 160.0 161.0 151.0 152.0 150.0 163.0 206.0 141.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.30 Median:204.50 stddev:32.02 (15.7%) stddev-sans-first:32.93 23:14:18 INFO - PROCESS | 1650 | Values: 175.0 179.0 233.0 183.0 163.0 191.0 239.0 154.0 238.0 218.0 23:14:18 INFO - PROCESS | 1650 | 23:14:18 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:5.51 (7.9%) stddev-sans-first:5.83 23:14:18 INFO - PROCESS | 1650 | Values: 68.0 68.0 70.0 73.0 71.0 66.0 69.0 60.0 65.0 81.0 23:14:18 INFO - PROCESS | 1650 | -------- Summary: end -------- 23:14:18 INFO - PROCESS | 1650 | 23:14:19 INFO - TEST-INFO | 1650: exit 0 23:14:19 INFO - TEST-OK | kraken | took 61407ms 23:14:19 INFO - SUITE-END | took 1177s 23:14:19 INFO - Completed test suite (00:19:38) 23:14:19 INFO - javascript benchmark 23:14:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6828.213802087518, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.9487757683632, 3428.5371474850904, 3438.707709844663, 3441.1741182116293, 3456.6370531094217, 5576.0241957657345, 5675.460831221038, 5742.913856292159, 5804.129380592908, 5822.097574875901, 4342.5657434256555, 5709.400512946143, 5776.191333213354, 5829.154772557977, 5900.911486327708, 5184.507471790174, 5224.0, 5235.790568377261, 5288.788448462057, 5393.946060539403, 5371.838844834661, 5374.0, 5401.729913504329, 5409.729513524328, 5420.105682562392, 4367.650587952972, 5174.534291913708, 5327.46725327468, 5364.544013758835, 5381.408045115034, 5193.974030129825, 5194.506521880416, 5253.86865328366, 5255.343082114736, 5308.097623404031, 5157.896842063175, 5176.767045482962, 5215.895682086337, 5228.686278823283, 5324.520793128599, 5614.494695477386, 5798.739056742456, 5811.012127938261, 5815.767369305223, 5845.561582881259, 5097.949020509822, 6163.322034576193, 6269.686515674198, 6293.748250069992, 6313.87372252557, 6317.873642527124, 6342.302346741854, 6344.524160687967, 6359.55483116182, 6366.108744775735, 5727.05503591911, 5791.913121303184, 5879.176915231871, 5891.998360278806, 5895.970520147372, 6268.937310626927, 6281.371862813682, 6316.242050953915, 6323.588966717149, 6328.651924144124, 6234.1583886174785, 6237.251529816451, 6239.126522286883, 6245.437910588069, 6248.843778905563, 6066.4540191382985, 6320.051992201114, 6329.841753956188, 6350.301466838644, 6354.872902542016, 5452.5365343946205, 5745.253117094751, 5950.018246989198, 6145.740123274718, 6161.106639537242, 6238.837838107471, 6279.183706118267, 6289.779857704958, 6294.8111556653375, 6307.558470906993, 4975.577075948512, 4975.626827987915, 4980.377452818398, 4981.800727970913, 5011.7494125294315, 4374.978125109354, 4394.560543945644, 4399.49405818335, 4446.510883802779, 4470.977645111819, 5067.758399192221, 5319.5744340452675, 5538.670719027405, 5592.049351610236, 5898.026825573818, 6300.684965751694, 6323.083152942798, 6330.272018717815, 6337.302896681362, 6341.619502829845, 5744.827655170351, 5777.335606405233, 5832.329282132524, 5990.580659353804, 5991.221141251609, 5425.077736784677, 5452.5365343946205, 5477.233187353773, 5485.4788795064405, 5597.804076857372, 6391.856390111645, 6427.971524556048, 6434.420902118832, 6473.967630161725, 6520.18497687789, 6273.933431316778, 6341.080543321101, 6344.080108384351, 6357.332480089709, 6380.521460890359, 6268.592541484789, 6281.403266689657, 6322.462590679844, 6347.587406818542, 6373.3945275198785, 6267.341929097379, 6277.121203031488, 6284.151639528788, 6290.182276304051, 6299.30707622152, 6187.443130118311, 6317.9052314214405, 6321.683915804099, 6343.746250149942, 6390.169277993831, 5966.910496342472, 6008.0, 6062.060380641008, 6107.158472117242, 6253.062040693932, 4982.526659967442, 5786.276715410574, 6088.360722124113, 6096.512279017757, 6101.420365065312, 5763.884722305615, 5973.313068996948, 5988.700564971821, 6054.182685337423, 6097.317180440592, 6148.938510614837, 6177.2587289525545, 6187.071939209155, 6223.3465486125115, 6252.531060170596, 4752.976235118802, 6074.848128796815, 6076.422739839708, 6089.89898485022, 6117.296510901303], "value": 5718.561497469857, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.12373603777144, 487.8487668822663, 520.3131865936963, 522.6002108387084, 529.489043073434, 28967.696453659584, 31786.20534486639, 32973.0, 33174.33651326975, 33814.66185338146, 32353.0, 32546.67453325466, 32907.34185316292, 32962.50556241658, 33011.33977320452, 7277.053982982218, 7449.515781474214, 7467.663955122032, 7470.514416562906, 7472.29013243741, 7621.923780762205, 7638.465307428482, 7717.49836260644, 7795.103563090261, 7795.883061754078, 7630.198829122945, 7734.961325193394, 7791.571463569501, 7795.6491957861745, 7828.138904720477, 7709.267619576131, 7722.575258360788, 7791.922080779233, 7802.882956755653, 7817.413693972918, 7688.269614386624, 7713.65288562016, 7794.805129871742, 7806.531608103532, 7820.843583128362, 25960.74039259602, 30859.38281234363, 31440.0, 31474.842625786725, 31598.842005790055, 3351.5307856900054, 3538.9913874545887, 3617.801020943847, 3901.8244179012013, 3912.1784425270725, 21467.892660536596, 21545.030473628725, 21623.0, 21667.34997950064, 21871.67192492114, 2800.9159725208074, 2813.6342275504257, 2851.472477591652, 2856.343041100538, 2861.799674022819, 355642.0, 431378.0, 449428.0, 449813.0, 451993.0, 58806.411935880096, 59064.70467647634, 59306.40693593095, 59313.40686593079, 59452.405475945554, 52858.47141528613, 61414.38585614086, 61767.38232617706, 61895.38104618986, 61936.38063619306, 15531.84468155304, 15607.21963901823, 15755.054696718234, 15762.896597238312, 15780.368785248691, 10323.74190645225, 12194.634160975185, 12265.509379624893, 12309.815352769716, 12398.5660501882, 16818.747718784227, 17107.828921710872, 17113.005224085984, 17135.74296385555, 17149.91425042892, 6357.318986774229, 6371.426571608577, 6390.520710946697, 6456.483481321483, 6481.740730370827, 789.8222899847603, 847.9321654267644, 848.2323497235012, 878.6221924572495, 1071.3197119828808, 25903.22290331293, 29212.853935730185, 29276.707232927823, 30156.246093847392, 30579.541306880416, 1062.4315990944808, 1075.5590208014676, 1085.2023762534532, 1099.5272033025715, 1137.2948771761396, 12779.380227053432, 14952.70094598102, 15150.621234469227, 15167.317470713844, 15186.46847360337, 570368.0, 571506.0, 576422.0, 577067.0, 577745.0, 50748.0, 50795.492045079074, 50851.491485084676, 50864.745676271385, 51125.23312150321, 1300.6878349196124, 1315.8881495073026, 1323.272200289856, 1345.656857501331, 1374.154894739728, 27855.16434506968, 28116.015939442022, 28308.85845570759, 29136.27159321034, 29582.964596239028, 35298.0, 48412.757936210095, 48669.756651216514, 49087.75456122697, 49133.0, 523160.0, 526704.0, 526844.0, 527376.0, 530965.0, 23836.404089897893, 24016.63975040411, 24491.75508244966, 24699.506009879344, 24801.87599061993, 41637.58362416337, 42410.51563195273, 42973.355399668406, 43355.349669754345, 43378.566214338716, 35105.0, 35139.0, 35240.64759352374, 36171.0, 36422.0, 25632.8718356407, 30228.84885575646, 33455.33089338248, 33957.8114765982, 35560.82219588885, 23412.648810267518, 26896.59655105136, 28814.279643009093, 29979.55030674498, 31048.844755776077, 27357.726422736316, 27935.860320698266, 30040.84979575088, 32030.679693203703, 33056.0, 20601.587968240852, 23407.765922340557, 23801.880990594935, 24652.0, 25351.492970140862], "value": 17670.085095522114, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.9356112680281, 374.4925625777072, 380.3173303919464, 386.01951044347356, 401.7228112602304, 14466.27668616569, 14920.328585213665, 15812.44656437025, 15957.441489547868, 15970.920145399257, 15284.770728439054, 18858.62282754344, 19275.807241927578, 19575.804241957576, 19660.91864947427, 4906.460289368176, 5234.816781412651, 5327.893442131155, 5334.359876814788, 5341.519263266306, 4374.4313239278845, 4637.86086417408, 5163.328767260251, 5299.28459657947, 5321.627486075976, 4641.187792136379, 4669.9066018679605, 4699.67102302839, 4719.221328480797, 4726.2201736713405, 4475.552444755531, 4709.858704238861, 4709.952900471003, 4717.174494463455, 4832.395950506187, 4721.881952951169, 4752.310914917352, 4755.35802666639, 4757.548032936866, 4783.162946484351, 12161.027117830552, 12392.442340094718, 15269.847301526954, 20738.585228295502, 20769.480762980897, 2092.0, 2130.936071917845, 2149.8172655324315, 2151.3761009307436, 2538.733432989528, 8945.955270223672, 9098.090190980914, 9098.590563424661, 9549.665761698378, 9560.760980975461, 1398.7132637809225, 1627.1457484820446, 1690.2309449200584, 1693.3311342019956, 1752.6056637256643, 110930.0, 111103.0, 111840.0, 113269.0, 113279.0, 9127.406718563405, 11211.94394028041, 11417.885821141683, 11499.0, 11562.595309164139, 14303.856961430462, 14402.351894164787, 14413.639659008608, 14461.204633745245, 14477.986540942244, 7524.811879702942, 7612.80967975805, 8423.368247381472, 8424.452410593292, 8465.576721163918, 8185.181481851767, 8213.34293256538, 8231.794205144919, 8361.581920903931, 8363.790905227417, 3770.434434834797, 4426.44669416323, 4503.639708823286, 4538.092381523708, 4605.078984203105, 1619.7408414653598, 1801.3965321617143, 1803.1975770781876, 1824.3705921457076, 1904.561950751335, 752.6124046116255, 884.1909652667865, 900.1313732248411, 926.1895841139003, 939.8637197606446, 16420.589485262964, 18785.15972381803, 19993.0, 20075.0, 20160.69758953617, 865.7144140950809, 870.8432482153273, 873.1835733589114, 911.2800887299092, 922.7693076730817, 8514.616842242114, 10207.0, 10622.46887655614, 10631.627893023708, 10675.733106672395, 519551.0, 520553.0, 521023.0, 527659.0, 535133.0, 118339.40830295793, 118388.0, 118981.40509297398, 120915.79084209222, 124326.0, 847.737201467547, 937.4843835890233, 945.9148676619137, 947.5877993073035, 950.9001554836781, 17682.0, 17922.283108675507, 18069.457916262534, 18263.0868456574, 18292.542686432946, 38226.80886595549, 40952.385714214906, 49254.014919702124, 50883.74558127334, 52202.95594088173, 67749.3225067743, 70179.64910175417, 70612.64693676498, 76777.23222767924, 78723.60638196772, 7045.330693584101, 7925.167857375123, 7926.048874135141, 8131.455023545507, 8379.329653627818, 10789.946050269698, 12588.054061330025, 12882.0, 12948.223106613634, 13151.671208219872, 10407.635732749317, 10625.893741062491, 10718.35689858611, 10826.0, 11102.61140860066, 9510.620959960628, 10381.740456488647, 10634.946825265824, 10935.507902144263, 11024.669259922215, 10871.075958543612, 11003.779924401628, 11332.716682083013, 11444.599439019594, 11522.654320370402, 7919.247671471201, 8127.431079824356, 8167.509949403055, 8180.427370084037, 8224.794380140305, 9214.631414743604, 10270.435126068138, 10779.838302425313, 11052.11583073336, 11080.5567777288], "value": 8761.223251847165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.9033382959803, 1433.3120102350872, 1454.6290695872551, 1704.6335037966835, 1804.9639007219855, 7224.53040552365, 7470.51441656292, 7670.61646917654, 8102.594870256493, 8125.5530945797955, 7622.695092196305, 7902.762917112482, 7910.248526389998, 7994.280514753671, 8001.319887809544, 2023.3727544461228, 2062.3194345865904, 2065.380385884229, 2065.648839697255, 2069.0999415254337, 1571.9371225151037, 2058.83529317655, 2085.14509051289, 2089.707440958267, 2112.6197284488785, 2056.681214411761, 2064.4838790302424, 2077.885716285604, 2080.0639712129528, 2083.895805209741, 2054.8767073975537, 2066.2354928676336, 2068.503559145809, 2074.844386670998, 2077.7610574783944, 2060.042080432606, 2062.3812856143095, 2092.9476763081043, 2098.244631932503, 2104.1583366653463, 7946.364290856775, 8010.679572817138, 8022.719204827861, 8030.196980301981, 8043.638036288381, 4991.825286114969, 5167.457416971201, 5172.379314482248, 5174.689518628894, 5217.217417387385, 3263.515100629208, 3402.2344972381266, 3441.0193094967813, 3497.335506253829, 3497.7726447780815, 939.741571067955, 983.6163896080534, 1026.4764969865348, 1027.4246422003703, 1027.917766578672, 10897.237193396466, 10949.288296260796, 11012.834807477895, 11026.669199924016, 11036.779264414667, 4297.484301883794, 4573.222552166139, 4667.789949452283, 4682.2508398656055, 4687.062587482517, 9377.156055954996, 9444.433333999981, 9492.572834222477, 9493.0981556752, 9500.714978550655, 5209.322788037607, 5211.009908117446, 5215.035218484592, 5227.085260079547, 5230.137027390514, 4273.572642735764, 4637.188492013884, 4717.622590192776, 4742.407199100113, 4793.808247670124, 3442.7622045670014, 3727.7204209684382, 3761.6238376162164, 3834.386498160281, 3843.8078096095082, 1195.235049568277, 1283.736833949057, 1288.2077522323702, 1291.3865913691109, 1297.8183054372396, 770.5646309834925, 854.256796586961, 875.5272153037289, 890.2032680750692, 911.3028533172129, 5471.124620060771, 5488.780448782004, 5538.47384498472, 5631.915521267184, 5747.741351639186, 854.5300084953251, 891.3359547137425, 904.6878826804873, 919.7562645898843, 955.5843208204453, 3387.8819989403437, 3407.8125703086566, 3664.780113193217, 3696.704263658901, 3753.5871054184017, 9815.558299876522, 10284.0, 10288.97110288965, 10289.073983341386, 10295.897041029644, 9561.043895610383, 9985.500724963722, 9988.350757200758, 10289.742756431, 10298.3820970742, 7557.962210188914, 7716.958210641492, 7761.340286075747, 7776.144624091346, 7796.844063118706, 5119.974400127976, 5235.738213089407, 5237.198099017008, 5651.6043876928225, 5727.341355743976, 9099.31755118382, 9395.201407880406, 9547.0, 9573.808523829624, 9581.089796469325, 9293.349465537347, 9510.429374237292, 9656.613735450503, 9666.0, 9702.951485242529, 6684.598924064571, 6712.932870671426, 7135.82160445993, 7202.71189152428, 7315.926840731525, 7843.4509584330835, 7955.403344749051, 7986.840263194587, 7991.84016319682, 7997.0, 6027.186329845415, 6207.286162091236, 6286.27707813589, 7067.787966361017, 7122.893156602551, 5824.5340372770925, 7011.789646310619, 7060.894086588808, 7065.57606543609, 7129.964350178216, 6032.758689652364, 6297.370262973849, 6653.268140504444, 6671.299513551007, 6689.665516724242, 4284.3145096784365, 4816.084943860656, 4868.196747536616, 4909.852704418873, 4958.826441074545, 4098.200850834059, 4181.954511372157, 4736.928946065743, 4830.516948305141, 4992.2511623256805], "value": 4283.5244009800135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.49273930357197, 305.59050871831744, 307.9076277116864, 309.2886361368852, 310.9813411195327, 4715.882102947419, 5131.255967884655, 5411.621186516941, 5464.890702185959, 5515.33815942087, 4115.0166129556055, 4897.363342765444, 5358.4641535846395, 5368.677879327243, 5428.294321738178, 3597.982010089946, 3924.27400930828, 3943.8619648312315, 4057.9391309130388, 4082.8154039848964, 4097.856575019875, 4120.237756015132, 4160.417541544171, 4160.916781664365, 4166.437530933331, 3251.536808436201, 3925.7230806132375, 4103.1793641271715, 4128.731632443882, 4131.772752498611, 3301.0, 3588.5334906462253, 4004.3392840181336, 4043.9191216175805, 4145.937810932838, 3065.9693403065908, 3492.4412094065083, 3501.964980350215, 3502.159481724393, 3506.9824650876835, 5167.124656507619, 5216.556592689626, 5226.660267082628, 5253.238280449314, 5263.31576895005, 4055.634992850628, 4365.402585461646, 4591.931121033187, 4600.964782923849, 4623.0, 5609.831705048855, 5674.631148975344, 5696.572757043197, 5708.686022268774, 5734.311882574118, 3322.5016247562817, 3933.1347103637154, 3982.2433737589763, 3989.3218152914073, 4007.6393124618635, 4943.7775300111225, 5710.800121995751, 5961.791337303217, 5964.7912323068485, 6002.729877155495, 3126.264518546272, 3956.7032472564674, 3963.632546771359, 3978.703000759173, 3986.402039694011, 3682.3187710273687, 3998.7200895937003, 4045.6763458923215, 4055.4322394864744, 4071.694622903294, 4666.929996050062, 4668.439787225555, 4674.719516829001, 4703.459102203222, 4727.220008698595, 4484.228729651769, 4726.747411935839, 5642.463965923231, 5733.71331433435, 5734.971325143348, 1068.787609648021, 1237.826704261404, 1261.1928365845683, 1335.23606135299, 1348.1439286053426, 771.1517330936038, 844.472204871955, 855.7347222360963, 892.683097500378, 928.2713070239829, 4255.659547236213, 4678.157931572349, 4859.975700121477, 5223.973880130575, 5311.7078560678765, 4680.765961701902, 5634.549236061187, 5859.912101318484, 5870.44230798082, 5946.167536544887, 5010.348654674869, 5336.946630533723, 5360.276362691065, 5368.879292679369, 5390.676559406448, 2600.8309459885045, 2791.274268690155, 2869.9282517937218, 2894.6815850256453, 2954.1432984434273, 5256.632035757537, 5374.811881584125, 5756.481916627524, 5807.709614519257, 5838.4453476919625, 5278.812267239917, 5284.749878824496, 5290.180022096504, 5448.727563621803, 5768.08711600639, 4972.108051958441, 5210.062188805976, 5416.7562459688625, 5820.767169313265, 5821.359650438533, 4701.076574943843, 4988.750562471934, 5560.6654402942195, 5611.270534830446, 5626.5780066496, 4748.3352330674425, 5446.591505637014, 5503.944960550503, 5516.613837031369, 5590.468905453975, 4783.5888412917575, 5571.303587051619, 5754.324239228778, 5817.94182058174, 5879.559033072622, 3253.4143854106487, 3657.707383409374, 3683.502727131803, 3857.8456861725217, 3864.826082826336, 4683.601893839062, 4717.0, 5234.408967561081, 5553.750406158582, 5560.8293779665255, 4452.755098469615, 4511.0, 5410.675359478444, 5416.8104116355735, 5487.314085739283, 4611.769411529343, 4892.431473476453, 5005.524475174852, 5143.71709555978, 5417.485338892799, 4892.706437613754, 5347.545458635904, 5604.747786349705, 5621.156826476062, 5666.773329066956, 4941.579965702956, 5399.217113518596, 5609.579281553982, 5728.112142617901, 5794.362620111869, 4730.722704869735, 5507.449255074461, 5643.830685079454, 5666.943330566642, 5732.197492350991], "value": 4031.865969038787, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.3293572299146, 696.9162951610248, 754.2118486181939, 761.2349588663393, 775.9883601745975, 12446.253224806507, 13038.21770693759, 13109.868901310983, 13126.212427254359, 13142.948564114871, 12229.449674764635, 12328.137030407852, 12334.876651233484, 12337.259764414119, 12352.135350525466, 630.9463695585857, 647.2750519418258, 769.6882762481176, 784.674360140541, 788.0936922539083, 676.2932735291607, 777.0830420104274, 795.2087672765578, 799.3006119645311, 799.9920000799975, 819.8278361544083, 834.9958250208771, 843.7173546861809, 845.8900342955408, 847.9364047696416, 826.36782861111, 838.2832678060262, 839.131498898637, 841.3017195727592, 841.3605659698612, 615.6706162203244, 621.9378062193745, 627.2347735762363, 630.486153784664, 632.0361448790585, 10699.465026748632, 11252.606158784485, 11589.188756786943, 11610.825837612443, 11917.761644767055, 10062.647807326708, 10791.460426978698, 10794.46027698612, 10920.399378034204, 11325.490352934137, 9340.532973351304, 9401.153896149312, 9450.57472413743, 9583.196586806745, 9878.308518403715, 3061.7397521210723, 3066.402051599939, 3076.292452735883, 3171.4291427543035, 3173.76196785242, 85146.0, 86839.13160868373, 87259.12740872637, 87733.56133219357, 88004.6799298011, 8319.542425166554, 8366.665333386718, 8455.323574114056, 8536.103709110454, 8569.957150214334, 17031.82968170327, 17145.485635430956, 17162.914185428992, 17166.57083572896, 17188.828111718973, 6928.930710692828, 6934.757283495053, 7038.190608080034, 7053.964730176419, 7193.0, 3851.9037024074623, 4140.358244472111, 4245.61789438952, 4245.8513952011535, 4354.303311470149, 2863.2412410711177, 3141.7800753947004, 3199.232184275804, 3389.4407422774966, 3414.829258537063, 570.9950487142659, 624.5097598385246, 639.8432384065934, 641.7946257197652, 642.8304472405631, 541.6019225868984, 551.5026702502724, 627.8461776864698, 631.3875540725489, 636.6243916089529, 8161.795955101051, 8336.291415229653, 8741.562921853882, 9010.414323068979, 9015.323850711224, 575.8502789274735, 593.5577994394204, 620.5439002333281, 623.429561950819, 640.9359064093553, 3683.9079023024638, 4105.712600118023, 4147.730397524151, 4367.781610919441, 4421.9336709949375, 71114.0, 101462.3318318388, 103627.48186259021, 105029.47485262678, 111509.44245278722, 73963.89054164191, 96149.0385096154, 96508.51745741366, 97897.51051244698, 99767.50116249372, 84071.15928840755, 85499.86250343791, 85995.57002214949, 86051.5697421509, 88390.0, 8276.751697449086, 8290.544020078954, 8361.289290410383, 8413.391848526977, 8462.449940753831, 37827.62172378241, 42012.0, 47680.76159619319, 52151.73924130355, 58573.70713146577, 59608.0, 61244.69377653083, 68081.0, 72903.0, 78525.21474785179, 11230.0, 11290.774184516427, 11325.263857849213, 11387.829182562102, 11589.884101158881, 11846.289222646668, 11883.702907427038, 11989.520419183134, 12040.458179382123, 12047.277163370225, 9238.907610924074, 11198.888011119785, 11214.102871770076, 11383.54465821391, 11434.542618295174, 11114.666560003212, 11122.555097796321, 11420.257683250562, 11606.70983225426, 11673.883261167619, 11263.887361126284, 11302.771663894382, 11481.540738370371, 11706.414679266172, 11942.880571194177, 9102.635894564144, 9193.770155745893, 9277.860832087388, 9524.238060955246, 9570.61717531291, 7532.887006694795, 9617.519124043909, 9914.950425247827, 9932.503374831374, 9936.0], "value": 6628.298591761187, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 138.0, 118.0, 118.0, 116.0, 118.0, 121.0, 119.0, 118.0, 120.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 115.0, 149.0, 117.0, 145.0, 143.0, 114.0, 142.0, 131.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 207.0, 253.0, 162.0, 166.0, 266.0, 276.0, 196.0, 190.0, 204.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 101.0, 87.0, 84.0, 77.0, 60.0, 79.0, 82.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 72.0, 69.0, 70.0, 86.0, 69.0, 71.0, 88.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 106.0, 89.0, 100.0, 91.0, 85.0, 79.0, 130.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 95.0, 97.0, 99.0, 95.0, 95.0, 105.0, 131.0, 94.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 147.0, 146.0, 97.0, 93.0, 93.0, 116.0, 155.0, 157.0, 90.0], "value": 121.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 58.0, 62.0, 57.0, 57.0, 58.0, 57.0, 52.0, 50.0, 77.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 82.0, 82.0, 72.0, 63.0, 84.0, 86.0, 107.0, 63.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 90.0, 87.0, 88.0, 92.0, 87.0, 88.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 162.0, 160.0, 161.0, 151.0, 152.0, 150.0, 163.0, 206.0, 141.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 233.0, 183.0, 163.0, 191.0, 239.0, 154.0, 238.0, 218.0], "value": 197.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 73.0, 71.0, 66.0, 69.0, 60.0, 65.0, 81.0], "value": 69.1, "unit": "score"}]}]} 23:14:19 INFO - Return code: 0 23:14:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:14:19 ERROR - # TBPL SUCCESS # 23:14:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:14:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:14:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:14:19 INFO - 2016-11-16 23:14:19.668 screenresolution[1655:10486] starting screenresolution argv=screenresolution get 23:14:19 INFO - 2016-11-16 23:14:19.670 screenresolution[1655:10486] Display 0: 1600x1200x32@60 23:14:19 INFO - 2016-11-16 23:14:19.676 screenresolution[1656:10489] starting screenresolution argv=screenresolution list 23:14:19 INFO - Available Modes on Display 0 23:14:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:14:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:14:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:14:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:14:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:14:19 INFO - Intel Iris: 23:14:19 INFO - Chipset Model: Intel Iris 23:14:19 INFO - Type: GPU 23:14:19 INFO - Bus: Built-In 23:14:19 INFO - VRAM (Dynamic, Max): 1536 MB 23:14:19 INFO - Vendor: Intel (0x8086) 23:14:19 INFO - Device ID: 0x0a2e 23:14:19 INFO - Revision ID: 0x0009 23:14:19 INFO - Displays: 23:14:19 INFO - Display: 23:14:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:14:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:14:19 INFO - Display Serial Number: 1600x1200 60 23:14:19 INFO - Main Display: Yes 23:14:19 INFO - Mirror: Off 23:14:19 INFO - Online: Yes 23:14:19 INFO - Rotation: Supported 23:14:19 INFO - Return code: 0 23:14:19 INFO - Running post-action listener: _resource_record_post_action 23:14:19 INFO - [mozharness: 2016-11-17 07:14:19.909386Z] Finished run-tests step (success) 23:14:19 INFO - Running post-run listener: _resource_record_post_run 23:14:19 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38517248; Write bytes: 703669760; Read time: 247; Write time: 2020 23:14:19 INFO - TinderboxPrint: CPU usage
54.5% 23:14:19 INFO - TinderboxPrint: I/O read bytes / time
38,517,248 / 247 23:14:19 INFO - TinderboxPrint: I/O write bytes / time
703,669,760 / 2,020 23:14:19 INFO - TinderboxPrint: CPU idle
2,208.9 (45.5%) 23:14:19 INFO - TinderboxPrint: CPU system
981.0 (20.2%) 23:14:19 INFO - TinderboxPrint: CPU user
1,659.7 (34.2%) 23:14:19 INFO - TinderboxPrint: Swap in / out
437,231,616 / 0 23:14:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399344128; Write bytes: 400816128; Read time: 29806; Write time: 854 23:14:19 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35355136; Write bytes: 301276672; Read time: 223; Write time: 1158 23:14:20 INFO - Running post-run listener: _upload_blobber_files 23:14:20 INFO - Blob upload gear active. 23:14:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:14:20 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 23:14:20 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'] 23:14:20 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 23:14:20 INFO - (blobuploader) - INFO - Open directory for files ... 23:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:21 INFO - (blobuploader) - INFO - Done attempting. 23:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:22 INFO - (blobuploader) - INFO - Done attempting. 23:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:14:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:24 INFO - (blobuploader) - INFO - Done attempting. 23:14:24 INFO - (blobuploader) - INFO - Iteration through files over. 23:14:24 INFO - Return code: 0 23:14:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:14:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:14:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bab87b7ae9248e8739a9d450eeb2689a7d8261f67ccb095f524af3b7f41783f3822f2ac67acb02cfebbe217e611bc026cb5f5400f780df37920e11c61e8b0094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fdf406d0b1665b290896ee3e207bc49f55dbf520830e7c12989216c7e7e5a1437edb6d9c6b3905c24948b89ba0c7c6edea43f53d852780902c90a29b3a93899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5662886e2be356cb13c23839ff6fa2ffd402b35a126a9882d2774438eb96bb78c63a2f522781334b010acd6484ad711b37691b6c96b1c40121ea42fca3960223"} 23:14:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:14:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:14:24 INFO - Contents: 23:14:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bab87b7ae9248e8739a9d450eeb2689a7d8261f67ccb095f524af3b7f41783f3822f2ac67acb02cfebbe217e611bc026cb5f5400f780df37920e11c61e8b0094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fdf406d0b1665b290896ee3e207bc49f55dbf520830e7c12989216c7e7e5a1437edb6d9c6b3905c24948b89ba0c7c6edea43f53d852780902c90a29b3a93899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5662886e2be356cb13c23839ff6fa2ffd402b35a126a9882d2774438eb96bb78c63a2f522781334b010acd6484ad711b37691b6c96b1c40121ea42fca3960223"} 23:14:24 INFO - Running post-run listener: copy_logs_to_upload_dir 23:14:24 INFO - Copying logs to upload dir... 23:14:24 INFO - mkdir: /builds/slave/test/build/upload/logs 23:14:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.849066 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-16 23:14:25.029175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:25.032997) ========= 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.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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/bab87b7ae9248e8739a9d450eeb2689a7d8261f67ccb095f524af3b7f41783f3822f2ac67acb02cfebbe217e611bc026cb5f5400f780df37920e11c61e8b0094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fdf406d0b1665b290896ee3e207bc49f55dbf520830e7c12989216c7e7e5a1437edb6d9c6b3905c24948b89ba0c7c6edea43f53d852780902c90a29b3a93899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5662886e2be356cb13c23839ff6fa2ffd402b35a126a9882d2774438eb96bb78c63a2f522781334b010acd6484ad711b37691b6c96b1c40121ea42fca3960223"} build_url:https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/CMKJAHPbRwmmsuNQE7Ey3w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bab87b7ae9248e8739a9d450eeb2689a7d8261f67ccb095f524af3b7f41783f3822f2ac67acb02cfebbe217e611bc026cb5f5400f780df37920e11c61e8b0094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fdf406d0b1665b290896ee3e207bc49f55dbf520830e7c12989216c7e7e5a1437edb6d9c6b3905c24948b89ba0c7c6edea43f53d852780902c90a29b3a93899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5662886e2be356cb13c23839ff6fa2ffd402b35a126a9882d2774438eb96bb78c63a2f522781334b010acd6484ad711b37691b6c96b1c40121ea42fca3960223"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:25.068627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:25.069212) ========= 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.jXQM8UbmCv/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.fncxmDmD1M/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.004997 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 23:14:25.118553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 23:14:25.118875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 23:14:25.119195) ========= ========= Total master_lag: 2.60 =========