builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0288 starttime: 1480392450.23 results: success (0) buildid: 20161128180027 builduid: b75659fef1844ebd88399b33e9999f05 revision: 35d579c4ff1e15f81d3ef70a3137afc218a99353 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.227791) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.228444) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.229096) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.254804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.255254) ========= 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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-28 20:07:30-- 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% 8.28M=0.001s 2016-11-28 20:07:30 (8.28 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126074 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.404667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.404982) ========= 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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.020334 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.453776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:30.454152) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 35d579c4ff1e15f81d3ef70a3137afc218a99353 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 35d579c4ff1e15f81d3ef70a3137afc218a99353 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-28 20:07:30,534 truncating revision to first 12 chars 2016-11-28 20:07:30,534 Setting DEBUG logging. 2016-11-28 20:07:30,534 attempt 1/10 2016-11-28 20:07:30,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/35d579c4ff1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 20:07:31,164 unpacking tar archive at: mozilla-aurora-35d579c4ff1e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:31.390194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:31.390521) ========= script_repo_revision: 35d579c4ff1e15f81d3ef70a3137afc218a99353 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:31.390945) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:31.391249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 20:07:31.411445) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 20:07:31.411847) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:07:31 INFO - MultiFileLogger online at 20161128 20:07:31 in /builds/slave/test 20:07:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:07:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:07:31 INFO - {'append_to_log': False, 20:07:31 INFO - 'base_work_dir': '/builds/slave/test', 20:07:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:07:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:07:31 INFO - 'branch': 'Mozilla-Aurora', 20:07:31 INFO - 'buildbot_json_path': 'buildprops.json', 20:07:31 INFO - 'config_files': ('talos/mac_config.py',), 20:07:31 INFO - 'default_actions': ('clobber', 20:07:31 INFO - 'read-buildbot-config', 20:07:31 INFO - 'download-and-extract', 20:07:31 INFO - 'populate-webroot', 20:07:31 INFO - 'create-virtualenv', 20:07:31 INFO - 'install', 20:07:31 INFO - 'run-tests'), 20:07:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:07:31 INFO - 'download_minidump_stackwalk': True, 20:07:31 INFO - 'download_symbols': 'ondemand', 20:07:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:07:31 INFO - 'tooltool.py': '/tools/tooltool.py', 20:07:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:07:31 INFO - '/tools/misc-python/virtualenv.py')}, 20:07:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:07:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:07:31 INFO - 'installer_path': 'installer.exe', 20:07:31 INFO - 'log_level': 'info', 20:07:31 INFO - 'log_name': 'talos', 20:07:31 INFO - 'log_to_console': True, 20:07:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:07:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:07:31 INFO - 'opt_config_files': (), 20:07:31 INFO - 'pip_index': False, 20:07:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:31 INFO - 'cmd': ('bash', 20:07:31 INFO - '-c', 20:07:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:31 INFO - 'enabled': True, 20:07:31 INFO - 'halt_on_failure': False, 20:07:31 INFO - 'name': 'check_screen_resolution'},), 20:07:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:07:31 INFO - 'cmd': ('bash', 20:07:31 INFO - '-c', 20:07:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:07:31 INFO - 'enabled': True, 20:07:31 INFO - 'halt_on_failure': False, 20:07:31 INFO - 'name': 'check_screen_resolution'},), 20:07:31 INFO - 'run_cmd_checks_enabled': True, 20:07:31 INFO - 'sps_profile': False, 20:07:31 INFO - 'sps_profile_interval': 0, 20:07:31 INFO - 'suite': 'dromaeojs-e10s', 20:07:31 INFO - 'system_bits': '32', 20:07:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:07:31 INFO - 'title': 't-yosemite-r7-0288', 20:07:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:07:31 INFO - 'use_talos_json': True, 20:07:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:07:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:07:31 INFO - 'work_dir': 'build'} 20:07:31 INFO - [mozharness: 2016-11-29 04:07:31.611459Z] Running clobber step. 20:07:31 INFO - Running pre-action listener: _resource_record_pre_action 20:07:31 INFO - Running main action method: clobber 20:07:31 INFO - rmtree: /builds/slave/test/build 20:07:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:07:32 INFO - Running post-action listener: _resource_record_post_action 20:07:32 INFO - [mozharness: 2016-11-29 04:07:32.515356Z] Finished clobber step (success) 20:07:32 INFO - [mozharness: 2016-11-29 04:07:32.515508Z] Running read-buildbot-config step. 20:07:32 INFO - Running pre-action listener: _resource_record_pre_action 20:07:32 INFO - Running main action method: read_buildbot_config 20:07:32 INFO - Using buildbot properties: 20:07:32 INFO - { 20:07:32 INFO - "project": "", 20:07:32 INFO - "product": "firefox", 20:07:32 INFO - "script_repo_revision": "production", 20:07:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 20:07:32 INFO - "repository": "", 20:07:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 20:07:32 INFO - "buildid": "20161128180027", 20:07:32 INFO - "pgo_build": "False", 20:07:32 INFO - "basedir": "/builds/slave/test", 20:07:32 INFO - "buildnumber": 6, 20:07:32 INFO - "slavename": "t-yosemite-r7-0288", 20:07:32 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:07:32 INFO - "platform": "yosemite_r7", 20:07:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:07:32 INFO - "branch": "mozilla-aurora", 20:07:32 INFO - "repo_path": "releases/mozilla-aurora", 20:07:32 INFO - "revision": "35d579c4ff1e15f81d3ef70a3137afc218a99353", 20:07:32 INFO - "stage_platform": "macosx64", 20:07:32 INFO - "builduid": "b75659fef1844ebd88399b33e9999f05", 20:07:32 INFO - "slavebuilddir": "test" 20:07:32 INFO - } 20:07:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:07:32 INFO - Running post-action listener: _resource_record_post_action 20:07:32 INFO - [mozharness: 2016-11-29 04:07:32.518512Z] Finished read-buildbot-config step (success) 20:07:32 INFO - [mozharness: 2016-11-29 04:07:32.518616Z] Running download-and-extract step. 20:07:32 INFO - Running pre-action listener: _resource_record_pre_action 20:07:32 INFO - Running main action method: download_and_extract 20:07:32 INFO - mkdir: /builds/slave/test/build/tests 20:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:32 INFO - trying https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:32 INFO - Downloading https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 20:07:33 INFO - Downloaded 1199 bytes. 20:07:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:07:33 INFO - Using the following test package requirements: 20:07:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:07:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:07:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:07:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'jsshell-mac.zip'], 20:07:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:07:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:07:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:07:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:07:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:07:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:07:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:07:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:07:34 INFO - Http code: 200 20:07:34 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 20:07:34 INFO - x-amz-cf-id: 4Y8H2ug9flFOLDQM54kqaEVBdGhTsvbSM6096KJ57J1lrwend0SbQw== 20:07:34 INFO - x-amz-version-id: v4K.qznqByYX3m_bsVRR9cLI5eOagXAM 20:07:34 INFO - Expected file size: 16283354 20:07:34 INFO - Obtained file size: 16283354 20:07:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:07:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:07:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:07:41 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:07:41 INFO - Http code: 200 20:07:41 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 20:07:41 INFO - x-amz-cf-id: zrWY2nrJXZOeL60KVsFZot3qDxC9U3HQf_WFVeClr1efneKpi0F6yg== 20:07:41 INFO - x-amz-version-id: v4K.qznqByYX3m_bsVRR9cLI5eOagXAM 20:07:42 INFO - Expected file size: 16283354 20:07:42 INFO - Obtained file size: 16283354 20:07:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:07:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:07:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:07:49 INFO - Http code: 200 20:07:49 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 20:07:49 INFO - x-amz-cf-id: fkUlJzqhvLFbv9OBTDLUbzxGf1iARBR1MU2nxL2Wwn04rzPbEkJJow== 20:07:49 INFO - x-amz-version-id: qzORJGFzoJE7nq5zKTqqUhh7E6fH4Hq8 20:07:50 INFO - Expected file size: 14409038 20:07:50 INFO - Obtained file size: 14409038 20:07: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')]} 20:07:50 INFO - trying https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:50 INFO - Downloading https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 20:07:53 INFO - Downloaded 109810216 bytes. 20:07:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:53 INFO - mkdir: /builds/slave/test/properties 20:07:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:07:53 INFO - Writing to file /builds/slave/test/properties/build_url 20:07:53 INFO - Contents: 20:07:53 INFO - build_url:https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:07:54 INFO - Running post-action listener: _resource_record_post_action 20:07:54 INFO - Running post-action listener: set_extra_try_arguments 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.271945Z] Finished download-and-extract step (success) 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.272046Z] Running populate-webroot step. 20:07:54 INFO - Running pre-action listener: _resource_record_pre_action 20:07:54 INFO - Running main action method: populate_webroot 20:07:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:07:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:07:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:07:54 INFO - '--tppagecycles', 20:07:54 INFO - '7'], 20:07:54 INFO - 'tests': ['tsvgm']}}, 20:07:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:07:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:07:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:07:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:07:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['damp', 'tps']}, 20:07:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['damp', 'tps']}, 20:07:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['dromaeo_dom']}, 20:07:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:07:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['basic_compositor_video']}, 20:07:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:07:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['a11yr', 20:07:54 INFO - 'ts_paint', 20:07:54 INFO - 'tpaint', 20:07:54 INFO - 'sessionrestore', 20:07:54 INFO - 'sessionrestore_no_auto_restore', 20:07:54 INFO - 'tabpaint']}, 20:07:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tsvgx', 20:07:54 INFO - 'tsvgr_opacity', 20:07:54 INFO - 'tart', 20:07:54 INFO - 'tscrollx', 20:07:54 INFO - 'cart']}, 20:07:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:07:54 INFO - 'tsvgr_opacity', 20:07:54 INFO - 'tart', 20:07:54 INFO - 'tscrollx', 20:07:54 INFO - 'cart']}, 20:07:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s'], 20:07:54 INFO - 'tests': ['tp5o']}, 20:07:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'tests': ['tp5o']}, 20:07:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--disable-e10s', 20:07:54 INFO - '--xperf_path', 20:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:54 INFO - 'tests': ['tp5n']}, 20:07:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:54 INFO - 'talos_options': ['--xperf_path', 20:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:54 INFO - 'tests': ['tp5n']}}, 20:07:54 INFO - 'talos.zip': {'path': '', 20:07:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:07:54 INFO - Running post-action listener: _resource_record_post_action 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.291191Z] Finished populate-webroot step (success) 20:07:54 INFO - [mozharness: 2016-11-29 04:07:54.291343Z] Running create-virtualenv step. 20:07:54 INFO - Running pre-action listener: _resource_record_pre_action 20:07:54 INFO - Running main action method: create_virtualenv 20:07:54 INFO - Creating virtualenv /builds/slave/test/build/venv 20:07:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:07:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:07:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:07:54 INFO - Using real prefix '/tools/python27' 20:07:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:07:55 INFO - Installing distribute.............................................................................................................................................................................................done. 20:07:57 INFO - Installing pip.................done. 20:07:57 INFO - Return code: 0 20:07:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:07:58 INFO - Reading from file tmpfile_stdout 20:07:58 INFO - Output received: 20:07:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:07:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:58 INFO - 'HOME': '/Users/cltbld', 20:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:58 INFO - 'LOGNAME': 'cltbld', 20:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:58 INFO - 'MOZ_NO_REMOTE': '1', 20:07:58 INFO - 'NO_EM_RESTART': '1', 20:07:58 INFO - 'PAGER': '/bin/cat', 20:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:58 INFO - 'PWD': '/builds/slave/test', 20:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:58 INFO - 'SHELL': '/bin/bash', 20:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:07:58 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:58 INFO - 'USER': 'cltbld', 20:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:58 INFO - 'XPC_FLAGS': '0x0', 20:07:58 INFO - 'XPC_SERVICE_NAME': '0', 20:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:07:58 INFO - Cleaning up... 20:07:58 INFO - Return code: 0 20:07:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:58 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:58 INFO - 'HOME': '/Users/cltbld', 20:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:58 INFO - 'LOGNAME': 'cltbld', 20:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:58 INFO - 'MOZ_NO_REMOTE': '1', 20:07:58 INFO - 'NO_EM_RESTART': '1', 20:07:58 INFO - 'PAGER': '/bin/cat', 20:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:58 INFO - 'PWD': '/builds/slave/test', 20:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:58 INFO - 'SHELL': '/bin/bash', 20:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:07:58 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:58 INFO - 'USER': 'cltbld', 20:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:58 INFO - 'XPC_FLAGS': '0x0', 20:07:58 INFO - 'XPC_SERVICE_NAME': '0', 20:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:58 INFO - Downloading/unpacking psutil>=3.1.1 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:02 INFO - Installing collected packages: psutil 20:08:02 INFO - Running setup.py install for psutil 20:08:02 INFO - building 'psutil._psutil_osx' extension 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:08:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:08:02 INFO - building 'psutil._psutil_posix' extension 20:08:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:08:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:02 INFO - ^ 20:08:02 INFO - 1 warning generated. 20:08:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:03 INFO - Successfully installed psutil 20:08:03 INFO - Cleaning up... 20:08:03 INFO - Return code: 0 20:08:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:08:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:03 INFO - 'HOME': '/Users/cltbld', 20:08:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:03 INFO - 'LOGNAME': 'cltbld', 20:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:03 INFO - 'MOZ_NO_REMOTE': '1', 20:08:03 INFO - 'NO_EM_RESTART': '1', 20:08:03 INFO - 'PAGER': '/bin/cat', 20:08:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:03 INFO - 'PWD': '/builds/slave/test', 20:08:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:03 INFO - 'SHELL': '/bin/bash', 20:08:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:03 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:03 INFO - 'USER': 'cltbld', 20:08:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:03 INFO - 'XPC_FLAGS': '0x0', 20:08:03 INFO - 'XPC_SERVICE_NAME': '0', 20:08:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:06 INFO - Installing collected packages: mozsystemmonitor 20:08:06 INFO - Running setup.py install for mozsystemmonitor 20:08:06 INFO - Successfully installed mozsystemmonitor 20:08:06 INFO - Cleaning up... 20:08:06 INFO - Return code: 0 20:08:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08: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==2.5.1'] in /builds/slave/test/build 20:08: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==2.5.1 20:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:06 INFO - 'HOME': '/Users/cltbld', 20:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:06 INFO - 'LOGNAME': 'cltbld', 20:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:06 INFO - 'MOZ_NO_REMOTE': '1', 20:08:06 INFO - 'NO_EM_RESTART': '1', 20:08:06 INFO - 'PAGER': '/bin/cat', 20:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:06 INFO - 'PWD': '/builds/slave/test', 20:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:06 INFO - 'SHELL': '/bin/bash', 20:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:06 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:06 INFO - 'USER': 'cltbld', 20:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:06 INFO - 'XPC_FLAGS': '0x0', 20:08:06 INFO - 'XPC_SERVICE_NAME': '0', 20:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:06 INFO - Downloading/unpacking jsonschema==2.5.1 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:08:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:09 INFO - Downloading functools32-3.2.3-2.tar.gz 20:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:08:09 INFO - warning: no files found matching '*.txt' 20:08:09 INFO - no previously-included directories found matching 'build' 20:08:09 INFO - no previously-included directories found matching 'dist' 20:08:09 INFO - no previously-included directories found matching '.git*' 20:08:09 INFO - Installing collected packages: jsonschema, functools32 20:08:09 INFO - Running setup.py install for functools32 20:08:09 INFO - warning: no files found matching '*.txt' 20:08:09 INFO - no previously-included directories found matching 'build' 20:08:09 INFO - no previously-included directories found matching 'dist' 20:08:09 INFO - no previously-included directories found matching '.git*' 20:08:09 INFO - Successfully installed jsonschema functools32 20:08:09 INFO - Cleaning up... 20:08:09 INFO - Return code: 0 20:08:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:09 INFO - 'HOME': '/Users/cltbld', 20:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:09 INFO - 'LOGNAME': 'cltbld', 20:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:09 INFO - 'MOZ_NO_REMOTE': '1', 20:08:09 INFO - 'NO_EM_RESTART': '1', 20:08:09 INFO - 'PAGER': '/bin/cat', 20:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:09 INFO - 'PWD': '/builds/slave/test', 20:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:09 INFO - 'SHELL': '/bin/bash', 20:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:09 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:09 INFO - 'USER': 'cltbld', 20:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:09 INFO - 'XPC_FLAGS': '0x0', 20:08:09 INFO - 'XPC_SERVICE_NAME': '0', 20:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:08:10 INFO - Cleaning up... 20:08:10 INFO - Return code: 0 20:08:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:10 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:10 INFO - 'HOME': '/Users/cltbld', 20:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:10 INFO - 'LOGNAME': 'cltbld', 20:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:10 INFO - 'MOZ_NO_REMOTE': '1', 20:08:10 INFO - 'NO_EM_RESTART': '1', 20:08:10 INFO - 'PAGER': '/bin/cat', 20:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:10 INFO - 'PWD': '/builds/slave/test', 20:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:10 INFO - 'SHELL': '/bin/bash', 20:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:10 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:10 INFO - 'USER': 'cltbld', 20:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:10 INFO - 'XPC_FLAGS': '0x0', 20:08:10 INFO - 'XPC_SERVICE_NAME': '0', 20:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:10 INFO - Downloading/unpacking blobuploader==1.2.4 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:12 INFO - Downloading blobuploader-1.2.4.tar.gz 20:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:08:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:08:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:14 INFO - Downloading docopt-0.6.1.tar.gz 20:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:08:14 INFO - Installing collected packages: blobuploader, requests, docopt 20:08:14 INFO - Running setup.py install for blobuploader 20:08:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:08:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:08:14 INFO - Running setup.py install for requests 20:08:14 INFO - Running setup.py install for docopt 20:08:15 INFO - Successfully installed blobuploader requests docopt 20:08:15 INFO - Cleaning up... 20:08:15 INFO - Return code: 0 20:08:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:15 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:15 INFO - 'HOME': '/Users/cltbld', 20:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:15 INFO - 'LOGNAME': 'cltbld', 20:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:15 INFO - 'MOZ_NO_REMOTE': '1', 20:08:15 INFO - 'NO_EM_RESTART': '1', 20:08:15 INFO - 'PAGER': '/bin/cat', 20:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:15 INFO - 'PWD': '/builds/slave/test', 20:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:15 INFO - 'SHELL': '/bin/bash', 20:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:15 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:15 INFO - 'USER': 'cltbld', 20:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:15 INFO - 'XPC_FLAGS': '0x0', 20:08:15 INFO - 'XPC_SERVICE_NAME': '0', 20:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-9VSaCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-kszC2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-xI9uyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-wT04qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-PcJ7rV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-FF6jeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-_biVTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-Nghp1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-S0d7M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-wa0OJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-IjE6I2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-84sYw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-f5G63G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-BZmaZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-yLZx1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-v9qpzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-M6uoZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:08:17 INFO - Running setup.py install for manifestparser 20:08:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:08:17 INFO - Running setup.py install for mozcrash 20:08:17 INFO - Running setup.py install for mozdebug 20:08:17 INFO - Running setup.py install for mozdevice 20:08:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:08:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:08:17 INFO - Running setup.py install for mozfile 20:08:17 INFO - Running setup.py install for mozhttpd 20:08:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozinfo 20:08:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozInstall 20:08:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozleak 20:08:18 INFO - Running setup.py install for mozlog 20:08:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for moznetwork 20:08:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:08:18 INFO - Running setup.py install for mozprocess 20:08:18 INFO - Running setup.py install for mozprofile 20:08:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for mozrunner 20:08:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Running setup.py install for mozscreenshot 20:08:19 INFO - Running setup.py install for moztest 20:08:19 INFO - Running setup.py install for mozversion 20:08:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:08:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:08:19 INFO - Cleaning up... 20:08:19 INFO - Return code: 0 20:08:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:19 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:19 INFO - 'HOME': '/Users/cltbld', 20:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:19 INFO - 'LOGNAME': 'cltbld', 20:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:19 INFO - 'MOZ_NO_REMOTE': '1', 20:08:19 INFO - 'NO_EM_RESTART': '1', 20:08:19 INFO - 'PAGER': '/bin/cat', 20:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:19 INFO - 'PWD': '/builds/slave/test', 20:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:19 INFO - 'SHELL': '/bin/bash', 20:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:19 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:19 INFO - 'USER': 'cltbld', 20:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:19 INFO - 'XPC_FLAGS': '0x0', 20:08:19 INFO - 'XPC_SERVICE_NAME': '0', 20:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-_VIyYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ugRhbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-t2PEKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-8h0WS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-JFY2Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-fqtTqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-8B5pZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-JWAICn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:20 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-t_6zQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-J43WYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-tem1sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-cIyQJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-RUTtwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-u7cWKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-Pwr_Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-wlCEVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:21 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-0BA3u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:08:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:24 INFO - Downloading blessings-1.6.tar.gz 20:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:08:24 INFO - Installing collected packages: blessings 20:08:24 INFO - Running setup.py install for blessings 20:08:24 INFO - Successfully installed blessings 20:08:24 INFO - Cleaning up... 20:08:24 INFO - Return code: 0 20:08:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:25 INFO - Reading from file tmpfile_stdout 20:08:25 INFO - Current package versions: 20:08:25 INFO - blessings == 1.6 20:08:25 INFO - blobuploader == 1.2.4 20:08:25 INFO - docopt == 0.6.1 20:08:25 INFO - functools32 == 3.2.3-2 20:08:25 INFO - jsonschema == 2.5.1 20:08:25 INFO - manifestparser == 1.1 20:08:25 INFO - mozInstall == 1.12 20:08:25 INFO - mozcrash == 1.0 20:08:25 INFO - mozdebug == 0.1 20:08:25 INFO - mozdevice == 0.48 20:08:25 INFO - mozfile == 1.2 20:08:25 INFO - mozhttpd == 0.7 20:08:25 INFO - mozinfo == 0.9 20:08:25 INFO - mozleak == 0.1 20:08:25 INFO - mozlog == 3.4 20:08:25 INFO - moznetwork == 0.27 20:08:25 INFO - mozprocess == 0.23 20:08:25 INFO - mozprofile == 0.28 20:08:25 INFO - mozrunner == 6.13 20:08:25 INFO - mozscreenshot == 0.1 20:08:25 INFO - mozsystemmonitor == 0.3 20:08:25 INFO - moztest == 0.7 20:08:25 INFO - mozversion == 1.4 20:08:25 INFO - psutil == 3.1.1 20:08:25 INFO - requests == 1.2.3 20:08:25 INFO - wsgiref == 0.1.2 20:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:25 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:25 INFO - 'HOME': '/Users/cltbld', 20:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:25 INFO - 'LOGNAME': 'cltbld', 20:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:25 INFO - 'MOZ_NO_REMOTE': '1', 20:08:25 INFO - 'NO_EM_RESTART': '1', 20:08:25 INFO - 'PAGER': '/bin/cat', 20:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:25 INFO - 'PWD': '/builds/slave/test', 20:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:25 INFO - 'SHELL': '/bin/bash', 20:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:25 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:25 INFO - 'USER': 'cltbld', 20:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:25 INFO - 'XPC_FLAGS': '0x0', 20:08:25 INFO - 'XPC_SERVICE_NAME': '0', 20:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:08:25 INFO - Cleaning up... 20:08:25 INFO - Return code: 0 20:08:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:25 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 0x106b55490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106acb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c484d7850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, '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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:25 INFO - 'HOME': '/Users/cltbld', 20:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:25 INFO - 'LOGNAME': 'cltbld', 20:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:25 INFO - 'MOZ_NO_REMOTE': '1', 20:08:25 INFO - 'NO_EM_RESTART': '1', 20:08:25 INFO - 'PAGER': '/bin/cat', 20:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:25 INFO - 'PWD': '/builds/slave/test', 20:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:25 INFO - 'SHELL': '/bin/bash', 20:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:25 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:25 INFO - 'USER': 'cltbld', 20:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:25 INFO - 'XPC_FLAGS': '0x0', 20:08:25 INFO - 'XPC_SERVICE_NAME': '0', 20:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:08:26 INFO - Cleaning up... 20:08:26 INFO - Return code: 0 20:08:26 INFO - Running post-action listener: _resource_record_post_action 20:08:26 INFO - Running post-action listener: _start_resource_monitoring 20:08:26 INFO - Starting resource monitoring. 20:08:26 INFO - [mozharness: 2016-11-29 04:08:26.079673Z] Finished create-virtualenv step (success) 20:08:26 INFO - [mozharness: 2016-11-29 04:08:26.080675Z] Running install step. 20:08:26 INFO - Running pre-action listener: _resource_record_pre_action 20:08:26 INFO - Running main action method: install 20:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:26 INFO - Reading from file tmpfile_stdout 20:08:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:08:26 INFO - Reading from file tmpfile_stdout 20:08:26 INFO - Output received: 20:08:26 INFO - Usage: mozinstall [options] installer 20:08:26 INFO - Options: 20:08:26 INFO - -h, --help show this help message and exit 20:08:26 INFO - -d DEST, --destination=DEST 20:08:26 INFO - Directory to install application into. [default: 20:08:26 INFO - "/builds/slave/test"] 20:08:26 INFO - --app=APP Application being installed. [default: firefox] 20:08:26 INFO - mkdir: /builds/slave/test/build/application 20:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:08:57 INFO - Reading from file tmpfile_stdout 20:08:57 INFO - Output received: 20:08:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:08:57 INFO - Running post-action listener: _resource_record_post_action 20:08:57 INFO - [mozharness: 2016-11-29 04:08:57.799089Z] Finished install step (success) 20:08:57 INFO - [mozharness: 2016-11-29 04:08:57.799266Z] Running run-tests step. 20:08:57 INFO - Running pre-action listener: _resource_record_pre_action 20:08:57 INFO - Running main action method: run_tests 20:08:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:08:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:08:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:08:57 INFO - 2016-11-28 20:08:57.813 screenresolution[1957:15823] starting screenresolution argv=screenresolution get 20:08:57 INFO - 2016-11-28 20:08:57.816 screenresolution[1957:15823] Display 0: 1600x1200x32@60 20:08:57 INFO - 2016-11-28 20:08:57.821 screenresolution[1958:15825] starting screenresolution argv=screenresolution list 20:08:57 INFO - Available Modes on Display 0 20:08:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:08:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:08:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:08:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:08:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:08:58 INFO - Intel Iris: 20:08:58 INFO - Chipset Model: Intel Iris 20:08:58 INFO - Type: GPU 20:08:58 INFO - Bus: Built-In 20:08:58 INFO - VRAM (Dynamic, Max): 1536 MB 20:08:58 INFO - Vendor: Intel (0x8086) 20:08:58 INFO - Device ID: 0x0a2e 20:08:58 INFO - Revision ID: 0x0009 20:08:58 INFO - Displays: 20:08:58 INFO - Display: 20:08:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:08:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:08:58 INFO - Display Serial Number: 1600x1200 60 20:08:58 INFO - Main Display: Yes 20:08:58 INFO - Mirror: Off 20:08:58 INFO - Online: Yes 20:08:58 INFO - Rotation: Supported 20:08:58 INFO - Return code: 0 20:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:08:58 INFO - Python 2.7.3 20:08:58 INFO - Return code: 0 20:08:58 INFO - grabbing minidump binary from tooltool 20:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:58 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 0x106969d30>, '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 0x1067cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106961b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:08:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:08:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:08:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:08:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:08:58 INFO - Return code: 0 20:08:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:08:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:08:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0288 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DYJleDNtm0/Render', 20:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:58 INFO - 'HOME': '/Users/cltbld', 20:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:58 INFO - 'LOGNAME': 'cltbld', 20:08:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:08:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:58 INFO - 'MOZ_NO_REMOTE': '1', 20:08:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:08:58 INFO - 'NO_EM_RESTART': '1', 20:08:58 INFO - 'PAGER': '/bin/cat', 20:08:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:58 INFO - 'PWD': '/builds/slave/test', 20:08:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:58 INFO - 'SHELL': '/bin/bash', 20:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Uwj8OCJ0pC/Listeners', 20:08:58 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 20:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:58 INFO - 'USER': 'cltbld', 20:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:58 INFO - 'XPC_FLAGS': '0x0', 20:08:58 INFO - 'XPC_SERVICE_NAME': '0', 20:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:08:58 INFO - mozversion application_buildid: 20161128180027 20:08:58 INFO - mozversion application_changeset: 35d579c4ff1e15f81d3ef70a3137afc218a99353 20:08:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:08:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:08:58 INFO - mozversion application_name: Firefox 20:08:58 INFO - mozversion application_remotingname: firefox-dev 20:08:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:08:58 INFO - mozversion application_vendor: Mozilla 20:08:58 INFO - mozversion application_version: 52.0a2 20:08:58 INFO - mozversion platform_buildid: 20161128180027 20:08:58 INFO - mozversion platform_changeset: 35d579c4ff1e15f81d3ef70a3137afc218a99353 20:08:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:08:58 INFO - mozversion platform_version: 52.0a2 20:08:58 INFO - using testdate: 1480392538 20:08:58 INFO - actual date: 1480392538 20:08:58 INFO - starting webserver on 'localhost:52154' 20:08:58 INFO - SUITE-START | Running 2 tests 20:08:58 INFO - TEST-START | dromaeo_css 20:08:58 INFO - operating with platform_type : mac_ 20:08:58 INFO - Initialising browser for dromaeo_css test... 20:08:58 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpg34sZb/profile http://localhost:52154/getInfo.html) 20:09:01 INFO - PROCESS | 1968 | 2016-11-28 20:09:01.377 plugin-container[1970:15992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d33, name = 'com.apple.tsm.portname' 20:09:01 INFO - PROCESS | 1968 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:01 INFO - PROCESS | 1968 | 2016-11-28 20:09:01.380 plugin-container[1970:15992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951b, name = 'com.apple.CFPasteboardClient' 20:09:01 INFO - PROCESS | 1968 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:01 INFO - PROCESS | 1968 | 2016-11-28 20:09:01.380 plugin-container[1970:15992] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:01 INFO - PROCESS | 1968 | __metrics Screen width/height:1600/1200 20:09:01 INFO - PROCESS | 1968 | colorDepth:24 20:09:01 INFO - PROCESS | 1968 | Browser inner width/height: 1024/689 20:09:01 INFO - PROCESS | 1968 | __metrics 20:09:01 ERROR - PROCESS | 1968 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 20:09:02 INFO - TEST-INFO | 1968: exit 0 20:09:02 INFO - Browser initialized. 20:09:02 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:02 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpg34sZb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:05 INFO - PROCESS | 1971 | 2016-11-28 20:09:05.228 plugin-container[1972:16121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:09:05 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:05 INFO - PROCESS | 1971 | 2016-11-28 20:09:05.228 plugin-container[1972:16121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 20:09:05 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:05 INFO - PROCESS | 1971 | 2016-11-28 20:09:05.229 plugin-container[1972:16121] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:14 INFO - PROCESS | 1971 | RSS: Main: 230715392 20:09:14 INFO - PROCESS | 1971 | 20:10:04 INFO - PROCESS | 1971 | 1480392604520 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:12:18 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52154/tests/dromaeo/cssquery-ext.html) 20:12:20 INFO - PROCESS | 1971 | RSS: Main: 234782720 20:12:20 INFO - PROCESS | 1971 | 20:15:22 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-ext.html (next: http://localhost:52154/tests/dromaeo/cssquery-jquery.html) 20:15:23 INFO - PROCESS | 1971 | RSS: Main: 245420032 20:15:23 INFO - PROCESS | 1971 | 20:18:25 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52154/tests/dromaeo/cssquery-mootools.html) 20:18:25 INFO - PROCESS | 1971 | RSS: Main: 248406016 20:18:25 INFO - PROCESS | 1971 | 20:21:27 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52154/tests/dromaeo/cssquery-prototype.html) 20:21:28 INFO - PROCESS | 1971 | RSS: Main: 247517184 20:21:28 INFO - PROCESS | 1971 | 20:24:31 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52154/tests/dromaeo/cssquery-yui.html) 20:24:31 INFO - PROCESS | 1971 | RSS: Main: 250945536 20:24:31 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-yui.html (next: http://localhost:52154/tests/dromaeo/cssquery-dojo.html) 20:27:34 INFO - PROCESS | 1971 | RSS: Main: 252518400 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | __start_tp_report 20:27:34 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 20:27:34 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 20:27:34 INFO - PROCESS | 1971 | |i|pagename|runs| 20:27:34 INFO - PROCESS | 1971 | |0;dojo.html;3237.562929004584;3306.7023967842906;3357.244619960509;3372.3086767212726;3381.5096810962405;5658.434156584344;5684.914726279104;5733.885322293557;5773.971130144354;5789.8999190153845;5745;5793.913091303634;5822.09757487589;5829.9708501457435;5833.300003999515;5297.205419187124;5297.788088476456;5305.708186049766;5314.601404894629;5343.786248550054;5315.202719592054;5338.786448542053;5345.1447768357075;5345.893082138355;5385.057614917393;5195.480451954793;5207.661502002357;5344.839654810343;5346.064438723227;5349.7057661828585;5248.580113590903;5264.657797243186;5297.099493086184;5326.520613144836;5361.463853614647;5198.818041368571;5263.6841789492755;5265.7893684252585;5268.525832675077;5288.1538953767595;5543.196236545718;5800.361960184377;5808.12878068289;5884.823455296348;5891.469767720883;4534.36518887356;5599.356074051496;6134.161069136565;6195.380461953813;6240.719167637421;5141.76862041209;6200.410960958701;6223.1287619733275;6248.03155510892;6251.187345645082;5948.375420580776;5949.345571987078;5968.97015514924;5972.432618901241;5974.193483879708;6178.073289006595;6239.500839932794;6245.344238854855;6284.811455656338;6359.236891573041;6109.070667813081;6125.785597504066;6158.969205153946;6246.656433896089;6251.343608921089;6255.78104766331;6264.310925798159;6281.3404592518045;6282.277538083179;6284.528660350492;6012.549058820606;6138.204480310071;6254.249490061222;6277.435030847246;6283.151774510474;6094.512439004869;6124.625058545733;6153.784617538365;6189.473894718999;6193.4425901669065;4846.151923413388;4874.902501949941;4894.363732714724;4898;4901.289313049588;4398.5821346972625;4398.934015989763;4410.845620403271;4416.646668266531;4432.667549933768;4527.501974782771;5610.49505544503;5651.4348565143155;5743.511801496919;5800.1589769484035;6195.132681424604;6259.0924315973925;6261.436470717657;6263.843403914848;6281.968590157111;5545.0018996581;5576.302962129734;5903.675297858659;5947.5539334550085;5994.490468310242;5306.257124002643;5386.703731294817;5401.189821526802;5462.562994960394;5519.475649813261;6167.290761562298;6267;6292.276388215414;6293.811185664437;6299.748010079546;6156.168917196301;6195.876082478415;6196.256449226122;6242;6259.561830671809;6220.066989951362;6221.720022599085;6238.376162383725;6239.313675495601;6249.843753906007;6190.102435146969;6203.348648391854;6221.097940798469;6222.160008398808;6248.750049997949;6070.2412198475195;6141.938580614136;6210.192674952227;6217.533684973736;6252.187215661935;6124.075808605272;6189.721462534107;6201.224846894138;6244.65654389013;6263.6241825490615;4617.722936623813;6029.276486821434;6160.969195153995;6237;6241.126242325987;5908.202392677105;5958.612690175125;5997.190379298739;6029.095635654511;6137.815865524041;6113.932424433696;6119.593952096689;6137.989923150153;6148.3236843948025;6187.535934804818;5374.462553744751;6118.296395914349;6121.265448146251;6123.632582045091;6148.877022459436 20:27:34 INFO - PROCESS | 1971 | |1;ext.html;462.969906956048;466.61737375352203;470.35091573628387;499.3483504027245;528.9656172348797;37011.814940925295;38511.80744096276;38600.61399386009;38605.8069709651;38622.84131476053;40806.59193408065;41137.58862411375;41151.76544703656;41296;41327.79336103315;8009.759707208793;9329.06709329065;9417.48203848788;9540.856887146663;9550.426974381558;9404.388714733555;9489.857652135224;9490.857637135448;9502.952485237563;9504.619815207418;9321.953390233039;9427.717168484956;9474.91038530571;9498.762530936747;9539.18916892065;9416.576254068548;9479.857802132905;9480.051994800533;9484.051594840495;9510.619575216982;9284.07159284073;9288.489133097677;9315.72052838416;9371.953140234324;9382.296327775444;38935.41596876049;39079;39291.41062884059;39472.60527394747;39516.407253891215;3317.0712200583635;3370.1237678203597;3412.488126780978;3561.465780132975;3946.8815935521693;28976.263200974234;29481.852590736908;29524.70475295241;29789;30322.938697145713;2692.2596286021303;2804.4951908656435;2852.6434195725533;3101.8914337998285;3152.0701393089093;291192;379193.1040344781;381451;507944;508695;48252;56786.14820777692;57511.42488575145;57765;58157.418425816046;59349.10976335358;60290.69854650786;61116.77766444646;61446.463838403506;61525.07712384318;15791.921040394724;15853.207339632972;15921.203939802963;15992.840071599368;16037.358505659877;12431.813522797165;12443.191192572454;12465.5013799447;12468.625941221597;12493.18794278369;15102.471413500474;15828.208589570475;15847.762283565755;15878.285477153557;15978.60053498672;3171.984964811238;6288.434040936338;6379.617222966544;6429.035644653246;6438.6136831790245;840.206005324962;985.7486340983004;1010.2221289606962;1092.322759888874;1095.9342439453658;22239;28130.718692813218;29290.560641590393;29758.060115388882;30029;901.7655409593552;991.0040409388663;1001.1090129784498;1021.871638580128;1052.5684469367675;12714;14483.565493035227;15211.847881521264;15262.16058116792;15817.130057846698;611947;614587;616282.9185854042;618579.8142018522;619411;57257;57588;57665;57799.422005780245;57917.131243031385;1219.2867172704068;1338.2505796753655;1343.6439343573961;1375.77987521996;1382.3710211853584;28628.42743145167;29164.854175729834;29448.11655650334;29525.26186845346;30177;49642;50089;50109.997800043064;50133.74665633243;50270.49729502804;625889;628008;628293;628664;630205;25740.356491087125;25961.870190649683;26316;26396.340091497867;26422.86788566045;44687;45444.09111817811;45560.54439455563;45716.77141614404;47082.529174707815;38138.618613814615;38266.23467530689;38466.42300365441;40124.598754012084;40510.39234411427;38143.61856381401;38213.23573528569;39087.6091239084;39205.41191882181;39857.00357491086;37226;37517.24965500729;38124.61875381211;38188.618113818506;38207.808960955015;33092.1726956828;34135.65864341325;35008.649913500536;35028;35194.94415167549;25590.74409255958;25775.226743197734;25812.870935645202;25892.741072589033;26123.346916327242 20:27:34 INFO - PROCESS | 1971 | |2;jquery.html;350.78426767537974;356.39946689827644;362.20496011255295;362.3676684186096;381.0207766041273;18163;18678.252869885204;19256.51858703533;19870.205191792327;20091.397258082245;18909.432717018477;19195.904020479913;19397.83612983222;19448.513787155327;19542.511437214078;4831.661783675136;5737.225474560943;5738.8278351649515;5748.913766293509;5756.395578464264;4718.551737584924;5304.973475132619;5420.539254163401;5660.433956604327;5662.688552129631;4762.238041913294;5593.636413633121;5605.383407825157;5609.102543593026;5613.46672066153;5325.147976323789;5568.610197286192;5592.804251851166;5598.944010559883;5603.80386686464;4901.534354236342;4903.166461701519;4906.994066216453;4914.90170196594;4915.213565829486;18800.717989230172;22345;23564.64653030206;23732;24506.387340316458;2168.241115609524;2217.246136313645;2250.088714070804;2358.1864256831363;2423.8182136339847;9768.853467197998;10088.78934527854;10138.442385668784;12410.751784964341;14290.285485725775;1472.3595236072342;1844.7509586205824;1877.1740434208905;1930.44982180078;1996.5807180492113;110550;114025.28962065576;114126.42936785263;116241.41879290549;116263;9304.627814887328;11495.540178392937;11505;11698.35659038761;11777.352245626573;14351.497697580535;14469.92765036168;14487.78268325955;14496.927515362355;14501.419943202365;8239.382046346547;8386.454880432751;8387.245147936594;8412.915870841336;8520.659173632985;8296.502209867427;8323.417360784686;8353.081161072278;8450.985881694112;8473.915260847436;4354.804033818486;4468.218061839165;4481.1933851906315;4559;4590.724556526552;1885.377825317642;1909.608530251323;1925.1721759643208;1978.0703069557285;1978.871373360727;745.683084689005;923.0584803500392;947.488356287597;970.2092794372563;981.9558119884622;12554.686132846751;19167.808321916604;20136.49658758543;20411.693824592643;20473.07871145802;731.2504682700275;748.2031636307315;748.9852297320191;851.5614458553849;880.7401816464159;9454.90881838621;10852.60504369135;10905.945470272756;10922.071623911897;10939;543272;592006;594689;597354;609534;116721.8327816711;117753;121315.78684213044;123622;127888;802.8113393352558;945.1493655709779;951.6764300137986;956.3305686019953;958.5303201431249;17864;17984.19071141828;18243.726344104583;18576.721349180043;18933.526661833013;34934.30131397409;52419.47580524146;55873.72063139658;56397.154042690214;56607;48015.75992120017;58422.83154336974;60368.094478583735;63193;80105.59947200227;7893.250141236574;8029.678812847655;8029.7189598363775;8117;8135.471194372347;10533.683989480327;11061;12408.56570020045;12492.25046497213;12800.231986080866;8332.833343332979;10175;10696.090832279344;10741.570337186426;10766.300190487593;10226.07965283128;10409;10444.738881527719;10636.468176590985;10637.414942178255;9804.019598040139;9901.900980990098;10674.146068314361;10938.179636527133;11323.603673871705;7855.764327070197;8009.519428834288;8040.437169398086;8316.41785075039;8316.584170791315;9996.900030999597;10704.785904281714;10812;10879.401632910265;11028.503717332578 20:27:34 INFO - PROCESS | 1971 | |3;mootools.html;1254.3289340202991;1448.4857875454218;1578.6053486628336;1732.1858726398605;1751.7810273715786;7287.089113860768;7552.4713270071115;7750.14748377678;8228.094909559944;8253.174682531751;7688.538687678729;7949.602519874012;7981.92018079819;8061.435699501038;8072.55600941948;2261.683364328995;2286.3598192506124;2289.599320118981;2298.276043046435;2303.3320337102305;1998.9300374486895;2162.4918144236094;2272.522770218256;2273.863568185914;2282.417983414227;2257.3566533537946;2258.706368172135;2273.3975496493445;2277.1233075265977;2288.9084436622516;2147.527543940331;2164.599549083425;2173.8695678259196;2175.934721958336;2182.4216582605623;2277.223765462925;2293.6330187169974;2294.460801711613;2300.0454811253308;2303.527776805751;8003.759887203393;8018.0779210390965;8058.315043221333;8079.515229086215;8082.797930051795;4841.612670986313;4989.251612258154;5011.874703132451;5034.572061374787;5037.319961805146;3073.339232065094;3218.6942240487115;3352.664733526652;3435.587729472454;3446.9827650861835;984.4979060679105;1026.1790567545934;1041.9270651054353;1044.7858189071221;1045.5974449836792;10135.138513226384;10287.845682314772;10414.062734353869;10415.270931034755;10443.738906527322;4023.6982226333143;4093.4269202311702;4163.147399407295;4179.498460184797;4180.937285940713;8248.13394593571;8340.082590914995;8341.5412152331;8356.122607126286;8367.246947774729;4927.088488629615;4942.481039490874;4951.925721114186;4954.851354459372;4965.602751779849;3883.320418926733;4168.874933751932;4257.276263035292;4476.4180656514445;4638.721676699409;2719.6872359678496;3541.911452213715;3650.653187947141;3666.5416822897137;3670.9082272942856;1112.933224006562;1126.3861195648392;1137.7212582917239;1142.097742783192;1142.2118738070706;843.1189407069504;865.9696910608099;887.3389324952951;908.4646946660123;916.2395211974045;4178.435911151955;4353.455818022747;4805.3512775775525;5659.547236221092;5679.659220446786;849.0448245723561;856.1737922904427;894.8568179210883;898.797770501645;901.5627420700955;3201.983990080081;3545.858165673344;3720.8697695580527;3769.378052621342;3790.5261842269715;8900.198982091642;9136.314776391797;9756.90243097574;9790.902090979;9796.608135674493;9544.904550954401;9637.951810240904;9665.178459830853;9758.072983066597;9783.657571984946;7469.14104877946;7550.962245188739;7726.150123486412;7738.110117336466;7745.186755390716;5296.152615581488;5424.674519528841;5433.565314774887;5451.591130665137;5468.125099983983;8942.776430589287;8968.86546701787;9174.449533028039;9190.632374704937;9206.539673016456;8733.825323493367;9094.59074341643;9095.727128186165;9109.817803644022;9149.451032938045;6422.325655806073;6616.536842420985;6883.414909732529;7033.472489563201;7046.260142684944;7434.851302974019;7575;7628.1227658817725;7731.6520756567215;7756.185600512091;6807.21717002551;7005.334493223136;7022.508424410446;7032.156141262876;7064.788056358318;6804.761833336008;6852.040714299902;6887.035814985806;6891.069705589882;6910.516263861481;6420.325865784025;6429.132067170872;6440;6498.350164983463;6502.902456463063;4188.952761809548;4438.467383913951;4699.788509517011;4709.905801884011;4775.6657034007285;4171.687123465813;4359.367891655756;4525.208088584419;4771.355866957916;4833.250846118857 20:27:34 INFO - PROCESS | 1971 | |4;prototype.html;268.897818828845;290.1209335712791;292.19208887426265;299.613498586823;304.7531499485417;4632.8146874125;4897;5570.832875013746;5657;5660.773569057238;4142.689298302625;5354.705491197982;5413.188021796733;5413.621046526745;5467.425920278363;4102.548719640837;4144.46122004139;4154.896127596804;4167.583241675823;4172.228137794503;3976.065624578222;4141.9585804142025;4158.960259935016;4165.895852603694;4191.1827193697245;3847.3267178243836;3976.9403458947995;4076.2051399977017;4107.568705285947;4140.358244472111;3203.6315823680225;4099.200655872121;4105.97947010266;4106.301928672133;4142.150859073882;3585.229175727231;3593.1376469647357;3605.369060414417;3618.002107671012;3620.384534629093;5005.849824505271;5254.264402983586;5274.894502109936;5307.203919412119;5331.893362132774;4665.790039448233;4692.436907571113;4700.9187886785885;4702.882427939295;4740.170470167707;4601.274522054229;5194.818181363672;5437.456254374571;5689.947359738461;5763.538916886639;3577.9821100894587;3790.8104594770425;3841.1741475582603;3874.0508575398926;3978.622030907059;5561.944380556183;6036.185115009462;6083.726232319556;6124.693765311717;6179.84550386244;3190.7925984810913;3377.088186189715;3777.660010599059;3884.6309600587897;3892.5718171001167;3121.219695076231;3124.3438877835456;3419.26485805553;3675.22820207754;3951.407288906687;3931.803409829497;4606.746628935441;4623.861284161413;4633.837815676435;4635.304704294315;4223;4781.42622885256;5304.329136322046;5752.9424705753245;5856.94143058564;1216.5377156680581;1223.1682455930054;1253.9122261441612;1263.4630282130058;1275.7115313533213;648.4326214562258;739.48975207108;804.927556519916;863.9308855291562;866.5710473315623;4136.945079004895;4894.755262236874;4944.703317800943;5074.340335756328;5099.566536844335;5095;5566.610337276352;5585.69278689676;5596.580256480699;5628.493435590734;4774.618030557547;5025;5035.84892453227;5039;5062.974685126551;2394.4612462195864;2408.927732168038;2739.8493082880236;2801.8739156737997;2857.7285157909964;5611.494965453129;5731.8567035824435;5757.798477053283;5766.538676905838;5798.260122221228;4834.154023045953;5025.522575355335;5393.7572809223675;5659.26429564154;6142.047982562621;5707.914381284284;5935;6003.939960600338;6113.755449781959;6115.816525504242;5991.940080599138;5997.880042399215;5998.470390050509;6007.90988135187;6012.6392416455155;5179.948200517946;5601.314665614486;5890.440408161217;5973.701314934149;5978.611390259619;5659.839732854837;5682.659040457585;6057.818265452043;6128.32588415265;6133.693315334304;3302.9504557432138;3705.3330400527084;3705.81470926458;3916.6279203475624;3929.862454814068;5057.6459647824295;5642.858928526655;5954.37479064692;5971.552133589911;6045.607035542852;5101.964331222916;5551.472610102034;5595.804146854841;5742.971285143548;5898.675572843535;4854.80580776765;4877.414710234794;5802.390748971297;5842.298924129132;5853.209816674695;4758.762061896822;5220.477952204901;5613.270274864241;5662.57530685192;5685.914711279252;5053.67151135175;5134.486551344985;5780.479756821906;5786.33457152416;5829.76680932758;4736.976315118402;5473.452654734495;5496.120620700669;5614.894414594527;5719.7426115824055 20:27:34 INFO - PROCESS | 1971 | |5;yui.html;633.3913198626165;690.6822861483718;705.0446644796301;749.9400047996161;750.6884642873208;13983.720325593482;14673.486427975024;14688.192149431778;14693.559193224193;14703.48537801176;12979.415926283316;13162.670933226651;13205.141665791742;13217.801732974012;13286.867131328683;646.1245013007377;670.7585269303047;797.6330887791623;804.9718259860921;814.8696208606624;821.6918655504186;822.0464261456711;822.9588520573977;823.2672921100195;824.0976131136396;862.1938487514194;862.2756884217256;864.9913500864974;865.3034307382554;865.4158443050947;820.188013866271;826.7561069484457;851.0510780479751;859.7162936231028;859.9183077607618;620.8137558732362;624.7657128576784;641.8876696578127;643.4112786800073;643.5045015338163;12309.876901230962;12368.938155309255;12375.071869609725;12379.195352302162;12976;11873;11962.102842286864;12003;12236.265824050497;13120.540781072712;12001.459934302977;12070.517179312905;12081.758364832742;12140.45367958444;12141.817872731915;4278.529361770203;4352.7605981670995;4374.715643483163;4382.145481631083;4510.143072816154;81826;83058.16941830565;84866;86190;86513;9757.951210243904;9997.800043999225;10042.598296068076;10158.13655839247;10181.18550515957;16135.83864161367;18541.629167416577;18792.81207187938;18795.436136915916;18796.530086747942;7182.784516464514;7344.192138864721;7391.186969433358;7552;7612.3148916597775;4147.3986271991;4189.329707246887;4198.895027624334;4226.344916537942;4232.661387089025;2828.462592107493;3281.0648965044848;3373.1229880230826;3390.9827051884336;3396.167938854947;501.92471129330164;604.4106995679177;629.0784001437916;634.0805831544279;648.9318621544764;559.9692016939107;570.6946783470906;615.6521565315583;660.2736989311719;671.9025741267586;7976.361891048702;8095;8565.229129378262;9040.909590904139;9284.535773211448;545.1441237257546;575.9395263497356;636.2047440699126;637.8182218067828;638.2659941067809;3657.231981283907;4314.741115533078;4372.519022907478;4378.10248898975;4399.428074350378;107848.4607576957;116548;119111;119446;122417.38791305987;32322.676773232437;46243.07513849704;61982.38017619766;64242;94947;73346;73459;74461;88427;91275;9272.443653380818;9759.463229522444;9779.315447918578;9784.217262619115;9794.16749576294;54539.72730136324;55057.62355941134;55112;55474;56421.43578564326;77057;79092;81873;82708.17291827245;83370;9825.4104753715;11728.648140555797;12112.818307725567;12279.263244205376;12421.813672794735;10943.398113103538;13760.31198440094;13790.862091378958;13816.585502434944;14042.78935815943;12294.385280736105;12465.314407707663;12634.684132896384;13179.341032948507;13350.933245333712;12819.679508012374;13039.282839443922;13084.60746177578;13630.522931697344;13676.384562694902;12752.808707869575;12786.721327867512;13121.868781312065;13303.334833258492;13368.866311337151;10384.584616615553;10730.356178629307;10821.458927053773;11167.441627918734;11230.04544613717;10181.949090254502;11009.06422954058;11027.724306892393;11222.887771122185;11312.60405885823 20:27:34 INFO - PROCESS | 1971 | __end_tp_report 20:27:34 INFO - PROCESS | 1971 | __start_cc_report 20:27:34 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,357 20:27:34 INFO - PROCESS | 1971 | __end_cc_report 20:27:34 INFO - PROCESS | 1971 | __startTimestamp1480393654672__endTimestamp 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | ------- Summary: start ------- 20:27:34 INFO - PROCESS | 1971 | Number of tests: 6 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#0] dojo.html Cycles:165 Average:5740.58 Median:5997.19 stddev:655.03 (10.9%) stddev-sans-first:626.92 20:27:34 INFO - PROCESS | 1971 | Values: 3237.6 3306.7 3357.2 3372.3 3381.5 5658.4 5684.9 5733.9 5774.0 5789.9 5745.0 5793.9 5822.1 5830.0 5833.3 5297.2 5297.8 5305.7 5314.6 5343.8 5315.2 5338.8 5345.1 5345.9 5385.1 5195.5 5207.7 5344.8 5346.1 5349.7 5248.6 5264.7 5297.1 5326.5 5361.5 5198.8 5263.7 5265.8 5268.5 5288.2 5543.2 5800.4 5808.1 5884.8 5891.5 4534.4 5599.4 6134.2 6195.4 6240.7 5141.8 6200.4 6223.1 6248.0 6251.2 5948.4 5949.3 5969.0 5972.4 5974.2 6178.1 6239.5 6245.3 6284.8 6359.2 6109.1 6125.8 6159.0 6246.7 6251.3 6255.8 6264.3 6281.3 6282.3 6284.5 6012.5 6138.2 6254.2 6277.4 6283.2 6094.5 6124.6 6153.8 6189.5 6193.4 4846.2 4874.9 4894.4 4898.0 4901.3 4398.6 4398.9 4410.8 4416.6 4432.7 4527.5 5610.5 5651.4 5743.5 5800.2 6195.1 6259.1 6261.4 6263.8 6282.0 5545.0 5576.3 5903.7 5947.6 5994.5 5306.3 5386.7 5401.2 5462.6 5519.5 6167.3 6267.0 6292.3 6293.8 6299.7 6156.2 6195.9 6196.3 6242.0 6259.6 6220.1 6221.7 6238.4 6239.3 6249.8 6190.1 6203.3 6221.1 6222.2 6248.8 6070.2 6141.9 6210.2 6217.5 6252.2 6124.1 6189.7 6201.2 6244.7 6263.6 4617.7 6029.3 6161.0 6237.0 6241.1 5908.2 5958.6 5997.2 6029.1 6137.8 6113.9 6119.6 6138.0 6148.3 6187.5 5374.5 6118.3 6121.3 6123.6 6148.9 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#1] ext.html Cycles:180 Average:68264.81 Median:39083.30 stddev:150888.17 (386.1%) stddev-sans-first:151225.57 20:27:34 INFO - PROCESS | 1971 | Values: 463.0 466.6 470.4 499.3 529.0 37011.8 38511.8 38600.6 38605.8 38622.8 40806.6 41137.6 41151.8 41296.0 41327.8 8009.8 9329.1 9417.5 9540.9 9550.4 9404.4 9489.9 9490.9 9503.0 9504.6 9322.0 9427.7 9474.9 9498.8 9539.2 9416.6 9479.9 9480.1 9484.1 9510.6 9284.1 9288.5 9315.7 9372.0 9382.3 38935.4 39079.0 39291.4 39472.6 39516.4 3317.1 3370.1 3412.5 3561.5 3946.9 28976.3 29481.9 29524.7 29789.0 30322.9 2692.3 2804.5 2852.6 3101.9 3152.1 291192.0 379193.1 381451.0 507944.0 508695.0 48252.0 56786.1 57511.4 57765.0 58157.4 59349.1 60290.7 61116.8 61446.5 61525.1 15791.9 15853.2 15921.2 15992.8 16037.4 12431.8 12443.2 12465.5 12468.6 12493.2 15102.5 15828.2 15847.8 15878.3 15978.6 3172.0 6288.4 6379.6 6429.0 6438.6 840.2 985.7 1010.2 1092.3 1095.9 22239.0 28130.7 29290.6 29758.1 30029.0 901.8 991.0 1001.1 1021.9 1052.6 12714.0 14483.6 15211.8 15262.2 15817.1 611947.0 614587.0 616282.9 618579.8 619411.0 57257.0 57588.0 57665.0 57799.4 57917.1 1219.3 1338.3 1343.6 1375.8 1382.4 28628.4 29164.9 29448.1 29525.3 30177.0 49642.0 50089.0 50110.0 50133.7 50270.5 625889.0 628008.0 628293.0 628664.0 630205.0 25740.4 25961.9 26316.0 26396.3 26422.9 44687.0 45444.1 45560.5 45716.8 47082.5 38138.6 38266.2 38466.4 40124.6 40510.4 38143.6 38213.2 39087.6 39205.4 39857.0 37226.0 37517.2 38124.6 38188.6 38207.8 33092.2 34135.7 35008.6 35028.0 35194.9 25590.7 25775.2 25812.9 25892.7 26123.3 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#2] jquery.html Cycles:180 Average:33578.01 Median:359.30 stddev:97877.87 (27241.1%) stddev-sans-first:98120.65 20:27:34 INFO - PROCESS | 1971 | Values: 350.8 356.4 362.2 362.4 381.0 18163.0 18678.3 19256.5 19870.2 20091.4 18909.4 19195.9 19397.8 19448.5 19542.5 4831.7 5737.2 5738.8 5748.9 5756.4 4718.6 5305.0 5420.5 5660.4 5662.7 4762.2 5593.6 5605.4 5609.1 5613.5 5325.1 5568.6 5592.8 5598.9 5603.8 4901.5 4903.2 4907.0 4914.9 4915.2 18800.7 22345.0 23564.6 23732.0 24506.4 2168.2 2217.2 2250.1 2358.2 2423.8 9768.9 10088.8 10138.4 12410.8 14290.3 1472.4 1844.8 1877.2 1930.4 1996.6 110550.0 114025.3 114126.4 116241.4 116263.0 9304.6 11495.5 11505.0 11698.4 11777.4 14351.5 14469.9 14487.8 14496.9 14501.4 8239.4 8386.5 8387.2 8412.9 8520.7 8296.5 8323.4 8353.1 8451.0 8473.9 4354.8 4468.2 4481.2 4559.0 4590.7 1885.4 1909.6 1925.2 1978.1 1978.9 745.7 923.1 947.5 970.2 982.0 12554.7 19167.8 20136.5 20411.7 20473.1 731.3 748.2 749.0 851.6 880.7 9454.9 10852.6 10905.9 10922.1 10939.0 543272.0 592006.0 594689.0 597354.0 609534.0 116721.8 117753.0 121315.8 123622.0 127888.0 802.8 945.1 951.7 956.3 958.5 17864.0 17984.2 18243.7 18576.7 18933.5 34934.3 52419.5 55873.7 56397.2 56607.0 48015.8 58422.8 60368.1 63193.0 80105.6 7893.3 8029.7 8029.7 8117.0 8135.5 10533.7 11061.0 12408.6 12492.3 12800.2 8332.8 10175.0 10696.1 10741.6 10766.3 10226.1 10409.0 10444.7 10636.5 10637.4 9804.0 9901.9 10674.1 10938.2 11323.6 7855.8 8009.5 8040.4 8316.4 8316.6 9996.9 10704.8 10812.0 10879.4 11028.5 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#3] mootools.html Cycles:180 Average:5194.56 Median:5035.95 stddev:2849.88 (56.6%) stddev-sans-first:2842.49 20:27:34 INFO - PROCESS | 1971 | Values: 1254.3 1448.5 1578.6 1732.2 1751.8 7287.1 7552.5 7750.1 8228.1 8253.2 7688.5 7949.6 7981.9 8061.4 8072.6 2261.7 2286.4 2289.6 2298.3 2303.3 1998.9 2162.5 2272.5 2273.9 2282.4 2257.4 2258.7 2273.4 2277.1 2288.9 2147.5 2164.6 2173.9 2175.9 2182.4 2277.2 2293.6 2294.5 2300.0 2303.5 8003.8 8018.1 8058.3 8079.5 8082.8 4841.6 4989.3 5011.9 5034.6 5037.3 3073.3 3218.7 3352.7 3435.6 3447.0 984.5 1026.2 1041.9 1044.8 1045.6 10135.1 10287.8 10414.1 10415.3 10443.7 4023.7 4093.4 4163.1 4179.5 4180.9 8248.1 8340.1 8341.5 8356.1 8367.2 4927.1 4942.5 4951.9 4954.9 4965.6 3883.3 4168.9 4257.3 4476.4 4638.7 2719.7 3541.9 3650.7 3666.5 3670.9 1112.9 1126.4 1137.7 1142.1 1142.2 843.1 866.0 887.3 908.5 916.2 4178.4 4353.5 4805.4 5659.5 5679.7 849.0 856.2 894.9 898.8 901.6 3202.0 3545.9 3720.9 3769.4 3790.5 8900.2 9136.3 9756.9 9790.9 9796.6 9544.9 9638.0 9665.2 9758.1 9783.7 7469.1 7551.0 7726.2 7738.1 7745.2 5296.2 5424.7 5433.6 5451.6 5468.1 8942.8 8968.9 9174.4 9190.6 9206.5 8733.8 9094.6 9095.7 9109.8 9149.5 6422.3 6616.5 6883.4 7033.5 7046.3 7434.9 7575.0 7628.1 7731.7 7756.2 6807.2 7005.3 7022.5 7032.2 7064.8 6804.8 6852.0 6887.0 6891.1 6910.5 6420.3 6429.1 6440.0 6498.4 6502.9 4189.0 4438.5 4699.8 4709.9 4775.7 4171.7 4359.4 4525.2 4771.4 4833.3 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#4] prototype.html Cycles:180 Average:4554.32 Median:5055.66 stddev:1456.01 (28.8%) stddev-sans-first:1424.13 20:27:34 INFO - PROCESS | 1971 | Values: 268.9 290.1 292.2 299.6 304.8 4632.8 4897.0 5570.8 5657.0 5660.8 4142.7 5354.7 5413.2 5413.6 5467.4 4102.5 4144.5 4154.9 4167.6 4172.2 3976.1 4142.0 4159.0 4165.9 4191.2 3847.3 3976.9 4076.2 4107.6 4140.4 3203.6 4099.2 4106.0 4106.3 4142.2 3585.2 3593.1 3605.4 3618.0 3620.4 5005.8 5254.3 5274.9 5307.2 5331.9 4665.8 4692.4 4700.9 4702.9 4740.2 4601.3 5194.8 5437.5 5689.9 5763.5 3578.0 3790.8 3841.2 3874.1 3978.6 5561.9 6036.2 6083.7 6124.7 6179.8 3190.8 3377.1 3777.7 3884.6 3892.6 3121.2 3124.3 3419.3 3675.2 3951.4 3931.8 4606.7 4623.9 4633.8 4635.3 4223.0 4781.4 5304.3 5752.9 5856.9 1216.5 1223.2 1253.9 1263.5 1275.7 648.4 739.5 804.9 863.9 866.6 4136.9 4894.8 4944.7 5074.3 5099.6 5095.0 5566.6 5585.7 5596.6 5628.5 4774.6 5025.0 5035.8 5039.0 5063.0 2394.5 2408.9 2739.8 2801.9 2857.7 5611.5 5731.9 5757.8 5766.5 5798.3 4834.2 5025.5 5393.8 5659.3 6142.0 5707.9 5935.0 6003.9 6113.8 6115.8 5991.9 5997.9 5998.5 6007.9 6012.6 5179.9 5601.3 5890.4 5973.7 5978.6 5659.8 5682.7 6057.8 6128.3 6133.7 3303.0 3705.3 3705.8 3916.6 3929.9 5057.6 5642.9 5954.4 5971.6 6045.6 5102.0 5551.5 5595.8 5743.0 5898.7 4854.8 4877.4 5802.4 5842.3 5853.2 4758.8 5220.5 5613.3 5662.6 5685.9 5053.7 5134.5 5780.5 5786.3 5829.8 4737.0 5473.5 5496.1 5614.9 5719.7 20:27:34 INFO - PROCESS | 1971 | 20:27:34 INFO - PROCESS | 1971 | [#5] yui.html Cycles:180 Average:19574.04 Median:4390.79 stddev:28866.99 (657.4%) stddev-sans-first:28912.93 20:27:34 INFO - PROCESS | 1971 | Values: 633.4 690.7 705.0 749.9 750.7 13983.7 14673.5 14688.2 14693.6 14703.5 12979.4 13162.7 13205.1 13217.8 13286.9 646.1 670.8 797.6 805.0 814.9 821.7 822.0 823.0 823.3 824.1 862.2 862.3 865.0 865.3 865.4 820.2 826.8 851.1 859.7 859.9 620.8 624.8 641.9 643.4 643.5 12309.9 12368.9 12375.1 12379.2 12976.0 11873.0 11962.1 12003.0 12236.3 13120.5 12001.5 12070.5 12081.8 12140.5 12141.8 4278.5 4352.8 4374.7 4382.1 4510.1 81826.0 83058.2 84866.0 86190.0 86513.0 9758.0 9997.8 10042.6 10158.1 10181.2 16135.8 18541.6 18792.8 18795.4 18796.5 7182.8 7344.2 7391.2 7552.0 7612.3 4147.4 4189.3 4198.9 4226.3 4232.7 2828.5 3281.1 3373.1 3391.0 3396.2 501.9 604.4 629.1 634.1 648.9 560.0 570.7 615.7 660.3 671.9 7976.4 8095.0 8565.2 9040.9 9284.5 545.1 575.9 636.2 637.8 638.3 3657.2 4314.7 4372.5 4378.1 4399.4 107848.5 116548.0 119111.0 119446.0 122417.4 32322.7 46243.1 61982.4 64242.0 94947.0 73346.0 73459.0 74461.0 88427.0 91275.0 9272.4 9759.5 9779.3 9784.2 9794.2 54539.7 55057.6 55112.0 55474.0 56421.4 77057.0 79092.0 81873.0 82708.2 83370.0 9825.4 11728.6 12112.8 12279.3 12421.8 10943.4 13760.3 13790.9 13816.6 14042.8 12294.4 12465.3 12634.7 13179.3 13350.9 12819.7 13039.3 13084.6 13630.5 13676.4 12752.8 12786.7 13121.9 13303.3 13368.9 10384.6 10730.4 10821.5 11167.4 11230.0 10181.9 11009.1 11027.7 11222.9 11312.6 20:27:34 INFO - PROCESS | 1971 | -------- Summary: end -------- 20:27:34 INFO - PROCESS | 1971 | 20:27:35 INFO - TEST-INFO | 1971: exit 0 20:27:35 INFO - TEST-OK | dromaeo_css | took 1116958ms 20:27:35 INFO - TEST-START | kraken 20:27:35 INFO - operating with platform_type : mac_ 20:27:35 INFO - Initialising browser for kraken test... 20:27:35 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpZxeJbJ/profile http://localhost:52154/getInfo.html) 20:27:38 INFO - PROCESS | 1977 | 2016-11-28 20:27:38.452 plugin-container[1978:18211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 20:27:38 INFO - PROCESS | 1977 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:38 INFO - PROCESS | 1977 | 2016-11-28 20:27:38.455 plugin-container[1978:18211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 20:27:38 INFO - PROCESS | 1977 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:38 INFO - PROCESS | 1977 | 2016-11-28 20:27:38.455 plugin-container[1978:18211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:27:38 INFO - PROCESS | 1977 | __metrics Screen width/height:1600/1200 20:27:38 INFO - PROCESS | 1977 | colorDepth:24 20:27:38 INFO - PROCESS | 1977 | Browser inner width/height: 1024/689 20:27:38 INFO - PROCESS | 1977 | __metrics 20:27:38 ERROR - PROCESS | 1977 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 20:27:39 INFO - TEST-INFO | 1977: exit 0 20:27:39 INFO - Browser initialized. 20:27:39 INFO - Running cycle 1/1 for kraken test... 20:27:39 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpZxeJbJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:42 INFO - PROCESS | 1979 | 2016-11-28 20:27:42.049 plugin-container[1980:18347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:27:42 INFO - PROCESS | 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:42 INFO - PROCESS | 1979 | 2016-11-28 20:27:42.050 plugin-container[1980:18347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:27:42 INFO - PROCESS | 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:42 INFO - PROCESS | 1979 | 2016-11-28 20:27:42.050 plugin-container[1980:18347] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:27:51 INFO - PROCESS | 1979 | RSS: Main: 230440960 20:27:51 INFO - PROCESS | 1979 | 20:27:54 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-beat-detection) 20:27:55 INFO - PROCESS | 1979 | RSS: Main: 242647040 20:27:55 INFO - PROCESS | 1979 | 20:27:58 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-dft) 20:27:59 INFO - PROCESS | 1979 | RSS: Main: 250204160 20:27:59 INFO - PROCESS | 1979 | 20:28:02 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-fft) 20:28:02 INFO - PROCESS | 1979 | RSS: Main: 253911040 20:28:02 INFO - PROCESS | 1979 | 20:28:04 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-oscillator) 20:28:05 INFO - PROCESS | 1979 | RSS: Main: 254636032 20:28:05 INFO - PROCESS | 1979 | 20:28:07 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:08 INFO - PROCESS | 1979 | RSS: Main: 254578688 20:28:08 INFO - PROCESS | 1979 | 20:28:11 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-darkroom) 20:28:11 INFO - PROCESS | 1979 | RSS: Main: 258048000 20:28:11 INFO - PROCESS | 1979 | 20:28:14 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-desaturate) 20:28:15 INFO - PROCESS | 1979 | RSS: Main: 257863680 20:28:15 INFO - PROCESS | 1979 | 20:28:18 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52154/tests/kraken/driver.html?testName=json-parse-financial) 20:28:19 INFO - PROCESS | 1979 | RSS: Main: 259112960 20:28:19 INFO - PROCESS | 1979 | 20:28:20 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52154/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:28:21 INFO - PROCESS | 1979 | RSS: Main: 258027520 20:28:21 INFO - PROCESS | 1979 | 20:28:23 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:28:23 INFO - PROCESS | 1979 | RSS: Main: 258265088 20:28:23 INFO - PROCESS | 1979 | 20:28:26 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:26 INFO - PROCESS | 1979 | RSS: Main: 258387968 20:28:26 INFO - PROCESS | 1979 | 20:28:29 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:28:30 INFO - PROCESS | 1979 | RSS: Main: 258625536 20:28:30 INFO - PROCESS | 1979 | 20:28:33 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:28:34 INFO - PROCESS | 1979 | RSS: Main: 258453504 20:28:34 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52154/tests/kraken/driver.html?testName=ai-astar) 20:28:36 INFO - PROCESS | 1979 | RSS: Main: 260329472 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | __start_tp_report 20:28:36 INFO - PROCESS | 1979 | _x_x_mozilla_page_load 20:28:36 INFO - PROCESS | 1979 | _x_x_mozilla_page_load_details 20:28:36 INFO - PROCESS | 1979 | |i|pagename|runs| 20:28:36 INFO - PROCESS | 1979 | |0;ai-astar;106;120;118;126;174;118;120;119;119;118 20:28:36 INFO - PROCESS | 1979 | |1;audio-beat-detection;145;121;100;169;152;149;125;117;162;154 20:28:36 INFO - PROCESS | 1979 | |2;audio-dft;208;202;195;197;193;202;200;199;204;198 20:28:36 INFO - PROCESS | 1979 | |3;audio-fft;83;62;74;115;87;89;62;60;78;59 20:28:36 INFO - PROCESS | 1979 | |4;audio-oscillator;87;72;90;75;68;71;85;69;72;71 20:28:36 INFO - PROCESS | 1979 | |5;imaging-gaussian-blur;92;89;103;132;137;85;136;134;92;87 20:28:36 INFO - PROCESS | 1979 | |6;imaging-darkroom;116;153;113;98;95;97;95;105;96;94 20:28:36 INFO - PROCESS | 1979 | |7;imaging-desaturate;90;81;72;102;141;139;90;91;88;89 20:28:36 INFO - PROCESS | 1979 | |8;json-parse-financial;57;56;57;61;61;61;61;61;62;60 20:28:36 INFO - PROCESS | 1979 | |9;json-stringify-tinderbox;70;71;85;70;67;62;65;98;105;108 20:28:36 INFO - PROCESS | 1979 | |10;stanford-crypto-aes;105;75;79;98;107;108;110;77;108;112 20:28:36 INFO - PROCESS | 1979 | |11;stanford-crypto-ccm;163;164;171;154;162;166;155;151;189;164 20:28:36 INFO - PROCESS | 1979 | |12;stanford-crypto-pbkdf2;163;174;173;174;173;217;158;233;237;166 20:28:36 INFO - PROCESS | 1979 | |13;stanford-crypto-sha256-iterative;69;79;81;83;92;93;91;93;69;66 20:28:36 INFO - PROCESS | 1979 | __end_tp_report 20:28:36 INFO - PROCESS | 1979 | __start_cc_report 20:28:36 INFO - PROCESS | 1979 | _x_x_mozilla_cycle_collect,974 20:28:36 INFO - PROCESS | 1979 | __end_cc_report 20:28:36 INFO - PROCESS | 1979 | __startTimestamp1480393716442__endTimestamp 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | ------- Summary: start ------- 20:28:36 INFO - PROCESS | 1979 | Number of tests: 14 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#0] ai-astar Cycles:10 Average:123.80 Median:119.50 stddev:18.31 (15.3%) stddev-sans-first:18.25 20:28:36 INFO - PROCESS | 1979 | Values: 106.0 120.0 118.0 126.0 174.0 118.0 120.0 119.0 119.0 118.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:150.50 stddev:22.32 (14.8%) stddev-sans-first:23.58 20:28:36 INFO - PROCESS | 1979 | Values: 145.0 121.0 100.0 169.0 152.0 149.0 125.0 117.0 162.0 154.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#2] audio-dft Cycles:10 Average:199.80 Median:201.00 stddev:4.42 (2.2%) stddev-sans-first:3.55 20:28:36 INFO - PROCESS | 1979 | Values: 208.0 202.0 195.0 197.0 193.0 202.0 200.0 199.0 204.0 198.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#3] audio-fft Cycles:10 Average:76.90 Median:76.00 stddev:17.63 (23.2%) stddev-sans-first:18.56 20:28:36 INFO - PROCESS | 1979 | Values: 83.0 62.0 74.0 115.0 87.0 89.0 62.0 60.0 78.0 59.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:73.50 stddev:8.12 (11.1%) stddev-sans-first:7.58 20:28:36 INFO - PROCESS | 1979 | Values: 87.0 72.0 90.0 75.0 68.0 71.0 85.0 69.0 72.0 71.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#5] imaging-gaussian-blur Cycles:10 Average:108.70 Median:86.00 stddev:22.95 (26.7%) stddev-sans-first:23.53 20:28:36 INFO - PROCESS | 1979 | Values: 92.0 89.0 103.0 132.0 137.0 85.0 136.0 134.0 92.0 87.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:95.00 stddev:18.23 (19.2%) stddev-sans-first:18.98 20:28:36 INFO - PROCESS | 1979 | Values: 116.0 153.0 113.0 98.0 95.0 97.0 95.0 105.0 96.0 94.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:88.50 stddev:23.26 (26.3%) stddev-sans-first:24.47 20:28:36 INFO - PROCESS | 1979 | Values: 90.0 81.0 72.0 102.0 141.0 139.0 90.0 91.0 88.0 89.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:61.00 stddev:2.16 (3.5%) stddev-sans-first:2.06 20:28:36 INFO - PROCESS | 1979 | Values: 57.0 56.0 57.0 61.0 61.0 61.0 61.0 61.0 62.0 60.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:70.00 stddev:17.50 (25.0%) stddev-sans-first:18.18 20:28:36 INFO - PROCESS | 1979 | Values: 70.0 71.0 85.0 70.0 67.0 62.0 65.0 98.0 105.0 108.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:93.50 stddev:14.91 (15.9%) stddev-sans-first:15.59 20:28:36 INFO - PROCESS | 1979 | Values: 105.0 75.0 79.0 98.0 107.0 108.0 110.0 77.0 108.0 112.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:164.00 stddev:10.71 (6.5%) stddev-sans-first:11.36 20:28:36 INFO - PROCESS | 1979 | Values: 163.0 164.0 171.0 154.0 162.0 166.0 155.0 151.0 189.0 164.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:174.00 stddev:30.01 (17.2%) stddev-sans-first:30.57 20:28:36 INFO - PROCESS | 1979 | Values: 163.0 174.0 173.0 174.0 173.0 217.0 158.0 233.0 237.0 166.0 20:28:36 INFO - PROCESS | 1979 | 20:28:36 INFO - PROCESS | 1979 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.60 Median:87.00 stddev:10.68 (12.3%) stddev-sans-first:10.31 20:28:36 INFO - PROCESS | 1979 | Values: 69.0 79.0 81.0 83.0 92.0 93.0 91.0 93.0 69.0 66.0 20:28:36 INFO - PROCESS | 1979 | -------- Summary: end -------- 20:28:36 INFO - PROCESS | 1979 | 20:28:37 INFO - TEST-INFO | 1979: exit 0 20:28:37 INFO - TEST-OK | kraken | took 62313ms 20:28:37 INFO - SUITE-END | took 1179s 20:28:38 INFO - Completed test suite (00:19:39) 20:28:38 INFO - javascript benchmark 20:28:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7041.81570098428, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3237.562929004584, 3306.7023967842906, 3357.244619960509, 3372.3086767212726, 3381.5096810962405, 5658.434156584344, 5684.914726279104, 5733.885322293557, 5773.971130144354, 5789.8999190153845, 5745.0, 5793.913091303634, 5822.09757487589, 5829.9708501457435, 5833.300003999515, 5297.205419187124, 5297.788088476456, 5305.708186049766, 5314.601404894629, 5343.786248550054, 5315.202719592054, 5338.786448542053, 5345.1447768357075, 5345.893082138355, 5385.057614917393, 5195.480451954793, 5207.661502002357, 5344.839654810343, 5346.064438723227, 5349.7057661828585, 5248.580113590903, 5264.657797243186, 5297.099493086184, 5326.520613144836, 5361.463853614647, 5198.818041368571, 5263.6841789492755, 5265.7893684252585, 5268.525832675077, 5288.1538953767595, 5543.196236545718, 5800.361960184377, 5808.12878068289, 5884.823455296348, 5891.469767720883, 4534.36518887356, 5599.356074051496, 6134.161069136565, 6195.380461953813, 6240.719167637421, 5141.76862041209, 6200.410960958701, 6223.1287619733275, 6248.03155510892, 6251.187345645082, 5948.375420580776, 5949.345571987078, 5968.97015514924, 5972.432618901241, 5974.193483879708, 6178.073289006595, 6239.500839932794, 6245.344238854855, 6284.811455656338, 6359.236891573041, 6109.070667813081, 6125.785597504066, 6158.969205153946, 6246.656433896089, 6251.343608921089, 6255.78104766331, 6264.310925798159, 6281.3404592518045, 6282.277538083179, 6284.528660350492, 6012.549058820606, 6138.204480310071, 6254.249490061222, 6277.435030847246, 6283.151774510474, 6094.512439004869, 6124.625058545733, 6153.784617538365, 6189.473894718999, 6193.4425901669065, 4846.151923413388, 4874.902501949941, 4894.363732714724, 4898.0, 4901.289313049588, 4398.5821346972625, 4398.934015989763, 4410.845620403271, 4416.646668266531, 4432.667549933768, 4527.501974782771, 5610.49505544503, 5651.4348565143155, 5743.511801496919, 5800.1589769484035, 6195.132681424604, 6259.0924315973925, 6261.436470717657, 6263.843403914848, 6281.968590157111, 5545.0018996581, 5576.302962129734, 5903.675297858659, 5947.5539334550085, 5994.490468310242, 5306.257124002643, 5386.703731294817, 5401.189821526802, 5462.562994960394, 5519.475649813261, 6167.290761562298, 6267.0, 6292.276388215414, 6293.811185664437, 6299.748010079546, 6156.168917196301, 6195.876082478415, 6196.256449226122, 6242.0, 6259.561830671809, 6220.066989951362, 6221.720022599085, 6238.376162383725, 6239.313675495601, 6249.843753906007, 6190.102435146969, 6203.348648391854, 6221.097940798469, 6222.160008398808, 6248.750049997949, 6070.2412198475195, 6141.938580614136, 6210.192674952227, 6217.533684973736, 6252.187215661935, 6124.075808605272, 6189.721462534107, 6201.224846894138, 6244.65654389013, 6263.6241825490615, 4617.722936623813, 6029.276486821434, 6160.969195153995, 6237.0, 6241.126242325987, 5908.202392677105, 5958.612690175125, 5997.190379298739, 6029.095635654511, 6137.815865524041, 6113.932424433696, 6119.593952096689, 6137.989923150153, 6148.3236843948025, 6187.535934804818, 5374.462553744751, 6118.296395914349, 6121.265448146251, 6123.632582045091, 6148.877022459436], "value": 5700.186078810947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.969906956048, 466.61737375352203, 470.35091573628387, 499.3483504027245, 528.9656172348797, 37011.814940925295, 38511.80744096276, 38600.61399386009, 38605.8069709651, 38622.84131476053, 40806.59193408065, 41137.58862411375, 41151.76544703656, 41296.0, 41327.79336103315, 8009.759707208793, 9329.06709329065, 9417.48203848788, 9540.856887146663, 9550.426974381558, 9404.388714733555, 9489.857652135224, 9490.857637135448, 9502.952485237563, 9504.619815207418, 9321.953390233039, 9427.717168484956, 9474.91038530571, 9498.762530936747, 9539.18916892065, 9416.576254068548, 9479.857802132905, 9480.051994800533, 9484.051594840495, 9510.619575216982, 9284.07159284073, 9288.489133097677, 9315.72052838416, 9371.953140234324, 9382.296327775444, 38935.41596876049, 39079.0, 39291.41062884059, 39472.60527394747, 39516.407253891215, 3317.0712200583635, 3370.1237678203597, 3412.488126780978, 3561.465780132975, 3946.8815935521693, 28976.263200974234, 29481.852590736908, 29524.70475295241, 29789.0, 30322.938697145713, 2692.2596286021303, 2804.4951908656435, 2852.6434195725533, 3101.8914337998285, 3152.0701393089093, 291192.0, 379193.1040344781, 381451.0, 507944.0, 508695.0, 48252.0, 56786.14820777692, 57511.42488575145, 57765.0, 58157.418425816046, 59349.10976335358, 60290.69854650786, 61116.77766444646, 61446.463838403506, 61525.07712384318, 15791.921040394724, 15853.207339632972, 15921.203939802963, 15992.840071599368, 16037.358505659877, 12431.813522797165, 12443.191192572454, 12465.5013799447, 12468.625941221597, 12493.18794278369, 15102.471413500474, 15828.208589570475, 15847.762283565755, 15878.285477153557, 15978.60053498672, 3171.984964811238, 6288.434040936338, 6379.617222966544, 6429.035644653246, 6438.6136831790245, 840.206005324962, 985.7486340983004, 1010.2221289606962, 1092.322759888874, 1095.9342439453658, 22239.0, 28130.718692813218, 29290.560641590393, 29758.060115388882, 30029.0, 901.7655409593552, 991.0040409388663, 1001.1090129784498, 1021.871638580128, 1052.5684469367675, 12714.0, 14483.565493035227, 15211.847881521264, 15262.16058116792, 15817.130057846698, 611947.0, 614587.0, 616282.9185854042, 618579.8142018522, 619411.0, 57257.0, 57588.0, 57665.0, 57799.422005780245, 57917.131243031385, 1219.2867172704068, 1338.2505796753655, 1343.6439343573961, 1375.77987521996, 1382.3710211853584, 28628.42743145167, 29164.854175729834, 29448.11655650334, 29525.26186845346, 30177.0, 49642.0, 50089.0, 50109.997800043064, 50133.74665633243, 50270.49729502804, 625889.0, 628008.0, 628293.0, 628664.0, 630205.0, 25740.356491087125, 25961.870190649683, 26316.0, 26396.340091497867, 26422.86788566045, 44687.0, 45444.09111817811, 45560.54439455563, 45716.77141614404, 47082.529174707815, 38138.618613814615, 38266.23467530689, 38466.42300365441, 40124.598754012084, 40510.39234411427, 38143.61856381401, 38213.23573528569, 39087.6091239084, 39205.41191882181, 39857.00357491086, 37226.0, 37517.24965500729, 38124.61875381211, 38188.618113818506, 38207.808960955015, 33092.1726956828, 34135.65864341325, 35008.649913500536, 35028.0, 35194.94415167549, 25590.74409255958, 25775.226743197734, 25812.870935645202, 25892.741072589033, 26123.346916327242], "value": 19280.29502363872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.78426767537974, 356.39946689827644, 362.20496011255295, 362.3676684186096, 381.0207766041273, 18163.0, 18678.252869885204, 19256.51858703533, 19870.205191792327, 20091.397258082245, 18909.432717018477, 19195.904020479913, 19397.83612983222, 19448.513787155327, 19542.511437214078, 4831.661783675136, 5737.225474560943, 5738.8278351649515, 5748.913766293509, 5756.395578464264, 4718.551737584924, 5304.973475132619, 5420.539254163401, 5660.433956604327, 5662.688552129631, 4762.238041913294, 5593.636413633121, 5605.383407825157, 5609.102543593026, 5613.46672066153, 5325.147976323789, 5568.610197286192, 5592.804251851166, 5598.944010559883, 5603.80386686464, 4901.534354236342, 4903.166461701519, 4906.994066216453, 4914.90170196594, 4915.213565829486, 18800.717989230172, 22345.0, 23564.64653030206, 23732.0, 24506.387340316458, 2168.241115609524, 2217.246136313645, 2250.088714070804, 2358.1864256831363, 2423.8182136339847, 9768.853467197998, 10088.78934527854, 10138.442385668784, 12410.751784964341, 14290.285485725775, 1472.3595236072342, 1844.7509586205824, 1877.1740434208905, 1930.44982180078, 1996.5807180492113, 110550.0, 114025.28962065576, 114126.42936785263, 116241.41879290549, 116263.0, 9304.627814887328, 11495.540178392937, 11505.0, 11698.35659038761, 11777.352245626573, 14351.497697580535, 14469.92765036168, 14487.78268325955, 14496.927515362355, 14501.419943202365, 8239.382046346547, 8386.454880432751, 8387.245147936594, 8412.915870841336, 8520.659173632985, 8296.502209867427, 8323.417360784686, 8353.081161072278, 8450.985881694112, 8473.915260847436, 4354.804033818486, 4468.218061839165, 4481.1933851906315, 4559.0, 4590.724556526552, 1885.377825317642, 1909.608530251323, 1925.1721759643208, 1978.0703069557285, 1978.871373360727, 745.683084689005, 923.0584803500392, 947.488356287597, 970.2092794372563, 981.9558119884622, 12554.686132846751, 19167.808321916604, 20136.49658758543, 20411.693824592643, 20473.07871145802, 731.2504682700275, 748.2031636307315, 748.9852297320191, 851.5614458553849, 880.7401816464159, 9454.90881838621, 10852.60504369135, 10905.945470272756, 10922.071623911897, 10939.0, 543272.0, 592006.0, 594689.0, 597354.0, 609534.0, 116721.8327816711, 117753.0, 121315.78684213044, 123622.0, 127888.0, 802.8113393352558, 945.1493655709779, 951.6764300137986, 956.3305686019953, 958.5303201431249, 17864.0, 17984.19071141828, 18243.726344104583, 18576.721349180043, 18933.526661833013, 34934.30131397409, 52419.47580524146, 55873.72063139658, 56397.154042690214, 56607.0, 48015.75992120017, 58422.83154336974, 60368.094478583735, 63193.0, 80105.59947200227, 7893.250141236574, 8029.678812847655, 8029.7189598363775, 8117.0, 8135.471194372347, 10533.683989480327, 11061.0, 12408.56570020045, 12492.25046497213, 12800.231986080866, 8332.833343332979, 10175.0, 10696.090832279344, 10741.570337186426, 10766.300190487593, 10226.07965283128, 10409.0, 10444.738881527719, 10636.468176590985, 10637.414942178255, 9804.019598040139, 9901.900980990098, 10674.146068314361, 10938.179636527133, 11323.603673871705, 7855.764327070197, 8009.519428834288, 8040.437169398086, 8316.41785075039, 8316.584170791315, 9996.900030999597, 10704.785904281714, 10812.0, 10879.401632910265, 11028.503717332578], "value": 9118.699543257275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1254.3289340202991, 1448.4857875454218, 1578.6053486628336, 1732.1858726398605, 1751.7810273715786, 7287.089113860768, 7552.4713270071115, 7750.14748377678, 8228.094909559944, 8253.174682531751, 7688.538687678729, 7949.602519874012, 7981.92018079819, 8061.435699501038, 8072.55600941948, 2261.683364328995, 2286.3598192506124, 2289.599320118981, 2298.276043046435, 2303.3320337102305, 1998.9300374486895, 2162.4918144236094, 2272.522770218256, 2273.863568185914, 2282.417983414227, 2257.3566533537946, 2258.706368172135, 2273.3975496493445, 2277.1233075265977, 2288.9084436622516, 2147.527543940331, 2164.599549083425, 2173.8695678259196, 2175.934721958336, 2182.4216582605623, 2277.223765462925, 2293.6330187169974, 2294.460801711613, 2300.0454811253308, 2303.527776805751, 8003.759887203393, 8018.0779210390965, 8058.315043221333, 8079.515229086215, 8082.797930051795, 4841.612670986313, 4989.251612258154, 5011.874703132451, 5034.572061374787, 5037.319961805146, 3073.339232065094, 3218.6942240487115, 3352.664733526652, 3435.587729472454, 3446.9827650861835, 984.4979060679105, 1026.1790567545934, 1041.9270651054353, 1044.7858189071221, 1045.5974449836792, 10135.138513226384, 10287.845682314772, 10414.062734353869, 10415.270931034755, 10443.738906527322, 4023.6982226333143, 4093.4269202311702, 4163.147399407295, 4179.498460184797, 4180.937285940713, 8248.13394593571, 8340.082590914995, 8341.5412152331, 8356.122607126286, 8367.246947774729, 4927.088488629615, 4942.481039490874, 4951.925721114186, 4954.851354459372, 4965.602751779849, 3883.320418926733, 4168.874933751932, 4257.276263035292, 4476.4180656514445, 4638.721676699409, 2719.6872359678496, 3541.911452213715, 3650.653187947141, 3666.5416822897137, 3670.9082272942856, 1112.933224006562, 1126.3861195648392, 1137.7212582917239, 1142.097742783192, 1142.2118738070706, 843.1189407069504, 865.9696910608099, 887.3389324952951, 908.4646946660123, 916.2395211974045, 4178.435911151955, 4353.455818022747, 4805.3512775775525, 5659.547236221092, 5679.659220446786, 849.0448245723561, 856.1737922904427, 894.8568179210883, 898.797770501645, 901.5627420700955, 3201.983990080081, 3545.858165673344, 3720.8697695580527, 3769.378052621342, 3790.5261842269715, 8900.198982091642, 9136.314776391797, 9756.90243097574, 9790.902090979, 9796.608135674493, 9544.904550954401, 9637.951810240904, 9665.178459830853, 9758.072983066597, 9783.657571984946, 7469.14104877946, 7550.962245188739, 7726.150123486412, 7738.110117336466, 7745.186755390716, 5296.152615581488, 5424.674519528841, 5433.565314774887, 5451.591130665137, 5468.125099983983, 8942.776430589287, 8968.86546701787, 9174.449533028039, 9190.632374704937, 9206.539673016456, 8733.825323493367, 9094.59074341643, 9095.727128186165, 9109.817803644022, 9149.451032938045, 6422.325655806073, 6616.536842420985, 6883.414909732529, 7033.472489563201, 7046.260142684944, 7434.851302974019, 7575.0, 7628.1227658817725, 7731.6520756567215, 7756.185600512091, 6807.21717002551, 7005.334493223136, 7022.508424410446, 7032.156141262876, 7064.788056358318, 6804.761833336008, 6852.040714299902, 6887.035814985806, 6891.069705589882, 6910.516263861481, 6420.325865784025, 6429.132067170872, 6440.0, 6498.350164983463, 6502.902456463063, 4188.952761809548, 4438.467383913951, 4699.788509517011, 4709.905801884011, 4775.6657034007285, 4171.687123465813, 4359.367891655756, 4525.208088584419, 4771.355866957916, 4833.250846118857], "value": 4217.308893222254, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.897818828845, 290.1209335712791, 292.19208887426265, 299.613498586823, 304.7531499485417, 4632.8146874125, 4897.0, 5570.832875013746, 5657.0, 5660.773569057238, 4142.689298302625, 5354.705491197982, 5413.188021796733, 5413.621046526745, 5467.425920278363, 4102.548719640837, 4144.46122004139, 4154.896127596804, 4167.583241675823, 4172.228137794503, 3976.065624578222, 4141.9585804142025, 4158.960259935016, 4165.895852603694, 4191.1827193697245, 3847.3267178243836, 3976.9403458947995, 4076.2051399977017, 4107.568705285947, 4140.358244472111, 3203.6315823680225, 4099.200655872121, 4105.97947010266, 4106.301928672133, 4142.150859073882, 3585.229175727231, 3593.1376469647357, 3605.369060414417, 3618.002107671012, 3620.384534629093, 5005.849824505271, 5254.264402983586, 5274.894502109936, 5307.203919412119, 5331.893362132774, 4665.790039448233, 4692.436907571113, 4700.9187886785885, 4702.882427939295, 4740.170470167707, 4601.274522054229, 5194.818181363672, 5437.456254374571, 5689.947359738461, 5763.538916886639, 3577.9821100894587, 3790.8104594770425, 3841.1741475582603, 3874.0508575398926, 3978.622030907059, 5561.944380556183, 6036.185115009462, 6083.726232319556, 6124.693765311717, 6179.84550386244, 3190.7925984810913, 3377.088186189715, 3777.660010599059, 3884.6309600587897, 3892.5718171001167, 3121.219695076231, 3124.3438877835456, 3419.26485805553, 3675.22820207754, 3951.407288906687, 3931.803409829497, 4606.746628935441, 4623.861284161413, 4633.837815676435, 4635.304704294315, 4223.0, 4781.42622885256, 5304.329136322046, 5752.9424705753245, 5856.94143058564, 1216.5377156680581, 1223.1682455930054, 1253.9122261441612, 1263.4630282130058, 1275.7115313533213, 648.4326214562258, 739.48975207108, 804.927556519916, 863.9308855291562, 866.5710473315623, 4136.945079004895, 4894.755262236874, 4944.703317800943, 5074.340335756328, 5099.566536844335, 5095.0, 5566.610337276352, 5585.69278689676, 5596.580256480699, 5628.493435590734, 4774.618030557547, 5025.0, 5035.84892453227, 5039.0, 5062.974685126551, 2394.4612462195864, 2408.927732168038, 2739.8493082880236, 2801.8739156737997, 2857.7285157909964, 5611.494965453129, 5731.8567035824435, 5757.798477053283, 5766.538676905838, 5798.260122221228, 4834.154023045953, 5025.522575355335, 5393.7572809223675, 5659.26429564154, 6142.047982562621, 5707.914381284284, 5935.0, 6003.939960600338, 6113.755449781959, 6115.816525504242, 5991.940080599138, 5997.880042399215, 5998.470390050509, 6007.90988135187, 6012.6392416455155, 5179.948200517946, 5601.314665614486, 5890.440408161217, 5973.701314934149, 5978.611390259619, 5659.839732854837, 5682.659040457585, 6057.818265452043, 6128.32588415265, 6133.693315334304, 3302.9504557432138, 3705.3330400527084, 3705.81470926458, 3916.6279203475624, 3929.862454814068, 5057.6459647824295, 5642.858928526655, 5954.37479064692, 5971.552133589911, 6045.607035542852, 5101.964331222916, 5551.472610102034, 5595.804146854841, 5742.971285143548, 5898.675572843535, 4854.80580776765, 4877.414710234794, 5802.390748971297, 5842.298924129132, 5853.209816674695, 4758.762061896822, 5220.477952204901, 5613.270274864241, 5662.57530685192, 5685.914711279252, 5053.67151135175, 5134.486551344985, 5780.479756821906, 5786.33457152416, 5829.76680932758, 4736.976315118402, 5473.452654734495, 5496.120620700669, 5614.894414594527, 5719.7426115824055], "value": 4074.1898263118533, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.3913198626165, 690.6822861483718, 705.0446644796301, 749.9400047996161, 750.6884642873208, 13983.720325593482, 14673.486427975024, 14688.192149431778, 14693.559193224193, 14703.48537801176, 12979.415926283316, 13162.670933226651, 13205.141665791742, 13217.801732974012, 13286.867131328683, 646.1245013007377, 670.7585269303047, 797.6330887791623, 804.9718259860921, 814.8696208606624, 821.6918655504186, 822.0464261456711, 822.9588520573977, 823.2672921100195, 824.0976131136396, 862.1938487514194, 862.2756884217256, 864.9913500864974, 865.3034307382554, 865.4158443050947, 820.188013866271, 826.7561069484457, 851.0510780479751, 859.7162936231028, 859.9183077607618, 620.8137558732362, 624.7657128576784, 641.8876696578127, 643.4112786800073, 643.5045015338163, 12309.876901230962, 12368.938155309255, 12375.071869609725, 12379.195352302162, 12976.0, 11873.0, 11962.102842286864, 12003.0, 12236.265824050497, 13120.540781072712, 12001.459934302977, 12070.517179312905, 12081.758364832742, 12140.45367958444, 12141.817872731915, 4278.529361770203, 4352.7605981670995, 4374.715643483163, 4382.145481631083, 4510.143072816154, 81826.0, 83058.16941830565, 84866.0, 86190.0, 86513.0, 9757.951210243904, 9997.800043999225, 10042.598296068076, 10158.13655839247, 10181.18550515957, 16135.83864161367, 18541.629167416577, 18792.81207187938, 18795.436136915916, 18796.530086747942, 7182.784516464514, 7344.192138864721, 7391.186969433358, 7552.0, 7612.3148916597775, 4147.3986271991, 4189.329707246887, 4198.895027624334, 4226.344916537942, 4232.661387089025, 2828.462592107493, 3281.0648965044848, 3373.1229880230826, 3390.9827051884336, 3396.167938854947, 501.92471129330164, 604.4106995679177, 629.0784001437916, 634.0805831544279, 648.9318621544764, 559.9692016939107, 570.6946783470906, 615.6521565315583, 660.2736989311719, 671.9025741267586, 7976.361891048702, 8095.0, 8565.229129378262, 9040.909590904139, 9284.535773211448, 545.1441237257546, 575.9395263497356, 636.2047440699126, 637.8182218067828, 638.2659941067809, 3657.231981283907, 4314.741115533078, 4372.519022907478, 4378.10248898975, 4399.428074350378, 107848.4607576957, 116548.0, 119111.0, 119446.0, 122417.38791305987, 32322.676773232437, 46243.07513849704, 61982.38017619766, 64242.0, 94947.0, 73346.0, 73459.0, 74461.0, 88427.0, 91275.0, 9272.443653380818, 9759.463229522444, 9779.315447918578, 9784.217262619115, 9794.16749576294, 54539.72730136324, 55057.62355941134, 55112.0, 55474.0, 56421.43578564326, 77057.0, 79092.0, 81873.0, 82708.17291827245, 83370.0, 9825.4104753715, 11728.648140555797, 12112.818307725567, 12279.263244205376, 12421.813672794735, 10943.398113103538, 13760.31198440094, 13790.862091378958, 13816.585502434944, 14042.78935815943, 12294.385280736105, 12465.314407707663, 12634.684132896384, 13179.341032948507, 13350.933245333712, 12819.679508012374, 13039.282839443922, 13084.60746177578, 13630.522931697344, 13676.384562694902, 12752.808707869575, 12786.721327867512, 13121.868781312065, 13303.334833258492, 13368.866311337151, 10384.584616615553, 10730.356178629307, 10821.458927053773, 11167.441627918734, 11230.04544613717, 10181.949090254502, 11009.06422954058, 11027.724306892393, 11222.887771122185, 11312.60405885823], "value": 7080.2475088760075, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 118.0, 126.0, 174.0, 118.0, 120.0, 119.0, 119.0, 118.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 100.0, 169.0, 152.0, 149.0, 125.0, 117.0, 162.0, 154.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 202.0, 195.0, 197.0, 193.0, 202.0, 200.0, 199.0, 204.0, 198.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 74.0, 115.0, 87.0, 89.0, 62.0, 60.0, 78.0, 59.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 90.0, 75.0, 68.0, 71.0, 85.0, 69.0, 72.0, 71.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 103.0, 132.0, 137.0, 85.0, 136.0, 134.0, 92.0, 87.0], "value": 108.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 153.0, 113.0, 98.0, 95.0, 97.0, 95.0, 105.0, 96.0, 94.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 81.0, 72.0, 102.0, 141.0, 139.0, 90.0, 91.0, 88.0, 89.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 85.0, 70.0, 67.0, 62.0, 65.0, 98.0, 105.0, 108.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 75.0, 79.0, 98.0, 107.0, 108.0, 110.0, 77.0, 108.0, 112.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 171.0, 154.0, 162.0, 166.0, 155.0, 151.0, 189.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 174.0, 173.0, 174.0, 173.0, 217.0, 158.0, 233.0, 237.0, 166.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 81.0, 83.0, 92.0, 93.0, 91.0, 93.0, 69.0, 66.0], "value": 81.6, "unit": "score"}]}]} 20:28:38 INFO - Return code: 0 20:28:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:28:38 ERROR - # TBPL SUCCESS # 20:28:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:28:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:28:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:28:38 INFO - 2016-11-28 20:28:38.269 screenresolution[1984:18729] starting screenresolution argv=screenresolution get 20:28:38 INFO - 2016-11-28 20:28:38.272 screenresolution[1984:18729] Display 0: 1600x1200x32@60 20:28:38 INFO - 2016-11-28 20:28:38.277 screenresolution[1985:18732] starting screenresolution argv=screenresolution list 20:28:38 INFO - Available Modes on Display 0 20:28:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:28:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:28:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:28:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:28:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:28:38 INFO - Intel Iris: 20:28:38 INFO - Chipset Model: Intel Iris 20:28:38 INFO - Type: GPU 20:28:38 INFO - Bus: Built-In 20:28:38 INFO - VRAM (Dynamic, Max): 1536 MB 20:28:38 INFO - Vendor: Intel (0x8086) 20:28:38 INFO - Device ID: 0x0a2e 20:28:38 INFO - Revision ID: 0x0009 20:28:38 INFO - Displays: 20:28:38 INFO - Display: 20:28:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:28:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:28:38 INFO - Display Serial Number: 1600x1200 60 20:28:38 INFO - Main Display: Yes 20:28:38 INFO - Mirror: Off 20:28:38 INFO - Online: Yes 20:28:38 INFO - Rotation: Supported 20:28:38 INFO - Return code: 0 20:28:38 INFO - Running post-action listener: _resource_record_post_action 20:28:38 INFO - [mozharness: 2016-11-29 04:28:38.518623Z] Finished run-tests step (success) 20:28:38 INFO - Running post-run listener: _resource_record_post_run 20:28:38 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 847872; Write bytes: 660258816; Read time: 13; Write time: 1956 20:28:38 INFO - TinderboxPrint: CPU usage
54.3% 20:28:38 INFO - TinderboxPrint: I/O read bytes / time
847,872 / 13 20:28:38 INFO - TinderboxPrint: I/O write bytes / time
660,258,816 / 1,956 20:28:38 INFO - TinderboxPrint: CPU idle
2,213.0 (45.7%) 20:28:38 INFO - TinderboxPrint: CPU system
981.1 (20.2%) 20:28:38 INFO - TinderboxPrint: CPU user
1,651.7 (34.1%) 20:28:38 INFO - TinderboxPrint: Swap in / out
349,253,632 / 0 20:28:38 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 339995136; Write bytes: 334989312; Read time: 26774; Write time: 703 20:28:38 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 847872; Write bytes: 308586496; Read time: 13; Write time: 1200 20:28:39 INFO - Running post-run listener: _upload_blobber_files 20:28:39 INFO - Blob upload gear active. 20:28:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:28:39 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 20:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:28:39 INFO - (blobuploader) - INFO - Open directory for files ... 20:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:40 INFO - (blobuploader) - INFO - Done attempting. 20:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:41 INFO - (blobuploader) - INFO - Done attempting. 20:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:28:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:44 INFO - (blobuploader) - INFO - Done attempting. 20:28:44 INFO - (blobuploader) - INFO - Iteration through files over. 20:28:44 INFO - Return code: 0 20:28:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:28:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:28:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30cf65417cd7be37bd0ae900d64170d53406ce2dfb4c02bf8ec41026ab595ce6b83f0dd5af5327658f3cd2971da5f67f46bc7f0d55aa4fca3e0b22897bf81a9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ecc2b47289fb5c9d2c18b3197234e5b0fb1561c7b4afe329b51d4a149b3b68420f95bcdf9d3247ed7dcf629bc05a391d8cb3cbb8263b6fafbee7a75523eb06a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd4b5c14da7aa16851e07af7cf0d626fe75f72639a97314c152078372c3cb2a360ecf05d99a75e081e6abcce00abe8d00f28923a551e680aff4b1b2feb9199e4"} 20:28:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:28:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:28:44 INFO - Contents: 20:28:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30cf65417cd7be37bd0ae900d64170d53406ce2dfb4c02bf8ec41026ab595ce6b83f0dd5af5327658f3cd2971da5f67f46bc7f0d55aa4fca3e0b22897bf81a9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ecc2b47289fb5c9d2c18b3197234e5b0fb1561c7b4afe329b51d4a149b3b68420f95bcdf9d3247ed7dcf629bc05a391d8cb3cbb8263b6fafbee7a75523eb06a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd4b5c14da7aa16851e07af7cf0d626fe75f72639a97314c152078372c3cb2a360ecf05d99a75e081e6abcce00abe8d00f28923a551e680aff4b1b2feb9199e4"} 20:28:44 INFO - Running post-run listener: copy_logs_to_upload_dir 20:28:44 INFO - Copying logs to upload dir... 20:28:44 INFO - mkdir: /builds/slave/test/build/upload/logs 20:28:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.638378 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 20:28:44.089353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:44.093077) ========= 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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30cf65417cd7be37bd0ae900d64170d53406ce2dfb4c02bf8ec41026ab595ce6b83f0dd5af5327658f3cd2971da5f67f46bc7f0d55aa4fca3e0b22897bf81a9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ecc2b47289fb5c9d2c18b3197234e5b0fb1561c7b4afe329b51d4a149b3b68420f95bcdf9d3247ed7dcf629bc05a391d8cb3cbb8263b6fafbee7a75523eb06a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd4b5c14da7aa16851e07af7cf0d626fe75f72639a97314c152078372c3cb2a360ecf05d99a75e081e6abcce00abe8d00f28923a551e680aff4b1b2feb9199e4"} build_url:https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010784 build_url: 'https://queue.taskcluster.net/v1/task/YwQ19fn8RNqME9yMEDkZ8A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30cf65417cd7be37bd0ae900d64170d53406ce2dfb4c02bf8ec41026ab595ce6b83f0dd5af5327658f3cd2971da5f67f46bc7f0d55aa4fca3e0b22897bf81a9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ecc2b47289fb5c9d2c18b3197234e5b0fb1561c7b4afe329b51d4a149b3b68420f95bcdf9d3247ed7dcf629bc05a391d8cb3cbb8263b6fafbee7a75523eb06a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd4b5c14da7aa16851e07af7cf0d626fe75f72639a97314c152078372c3cb2a360ecf05d99a75e081e6abcce00abe8d00f28923a551e680aff4b1b2feb9199e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:44.127594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:44.128027) ========= 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.DYJleDNtm0/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.Uwj8OCJ0pC/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.005229 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 20:28:44.180048) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 20:28:44.180387) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 20:28:44.180754) ========= ========= Total master_lag: 0.19 =========