builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0165 starttime: 1476935427.53 results: success (0) buildid: 20161019182749 builduid: 27a4272ec8634dfc9635c1e956f1bc96 revision: dcfc4c3e4b552a47d3bd5748f3292c11acb12efc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.529191) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.529627) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.529902) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.552374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.552677) ========= 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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-19 20:50:27-- 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% 9.79M=0.001s 2016-10-19 20:50:27 (9.79 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.722864) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.723179) ========= 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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.019522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:27.771711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 20:50:27.772100) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dcfc4c3e4b552a47d3bd5748f3292c11acb12efc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dcfc4c3e4b552a47d3bd5748f3292c11acb12efc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-19 20:50:27,846 truncating revision to first 12 chars 2016-10-19 20:50:27,846 Setting DEBUG logging. 2016-10-19 20:50:27,846 attempt 1/10 2016-10-19 20:50:27,846 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dcfc4c3e4b55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 20:50:29,225 unpacking tar archive at: mozilla-central-dcfc4c3e4b55/testing/mozharness/ program finished with exit code 0 elapsedTime=1.632054 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 20:50:29.427589) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:29.427957) ========= script_repo_revision: dcfc4c3e4b552a47d3bd5748f3292c11acb12efc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:29.428367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:29.428643) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 20:50:29.446838) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-19 20:50:29.447148) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:50:29 INFO - MultiFileLogger online at 20161019 20:50:29 in /builds/slave/test 20:50:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:50:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:50:29 INFO - {'append_to_log': False, 20:50:29 INFO - 'base_work_dir': '/builds/slave/test', 20:50:29 INFO - 'blob_upload_branch': 'Firefox', 20:50:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:50:29 INFO - 'branch': 'Firefox', 20:50:29 INFO - 'buildbot_json_path': 'buildprops.json', 20:50:29 INFO - 'config_files': ('talos/mac_config.py',), 20:50:29 INFO - 'default_actions': ('clobber', 20:50:29 INFO - 'read-buildbot-config', 20:50:29 INFO - 'download-and-extract', 20:50:29 INFO - 'populate-webroot', 20:50:29 INFO - 'create-virtualenv', 20:50:29 INFO - 'install', 20:50:29 INFO - 'run-tests'), 20:50:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:50:29 INFO - 'download_minidump_stackwalk': True, 20:50:29 INFO - 'download_symbols': 'ondemand', 20:50:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:50:29 INFO - 'tooltool.py': '/tools/tooltool.py', 20:50:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:50:29 INFO - '/tools/misc-python/virtualenv.py')}, 20:50:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:50:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:50:29 INFO - 'installer_path': 'installer.exe', 20:50:29 INFO - 'log_level': 'info', 20:50:29 INFO - 'log_name': 'talos', 20:50:29 INFO - 'log_to_console': True, 20:50:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:50:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:50:29 INFO - 'opt_config_files': (), 20:50:29 INFO - 'pip_index': False, 20:50:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:50:29 INFO - 'cmd': ('bash', 20:50:29 INFO - '-c', 20:50:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:50:29 INFO - 'enabled': True, 20:50:29 INFO - 'halt_on_failure': False, 20:50:29 INFO - 'name': 'check_screen_resolution'},), 20:50:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:50:29 INFO - 'cmd': ('bash', 20:50:29 INFO - '-c', 20:50:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:50:29 INFO - 'enabled': True, 20:50:29 INFO - 'halt_on_failure': False, 20:50:29 INFO - 'name': 'check_screen_resolution'},), 20:50:29 INFO - 'run_cmd_checks_enabled': True, 20:50:29 INFO - 'sps_profile': False, 20:50:29 INFO - 'sps_profile_interval': 0, 20:50:29 INFO - 'suite': 'dromaeojs-e10s', 20:50:29 INFO - 'system_bits': '32', 20:50:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:50:29 INFO - 'title': 't-yosemite-r7-0165', 20:50:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:50:29 INFO - 'use_talos_json': True, 20:50:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:50:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:50:29 INFO - 'work_dir': 'build'} 20:50:29 INFO - [mozharness: 2016-10-20 03:50:29.644596Z] Running clobber step. 20:50:29 INFO - Running pre-action listener: _resource_record_pre_action 20:50:29 INFO - Running main action method: clobber 20:50:29 INFO - rmtree: /builds/slave/test/build 20:50:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:50:30 INFO - Running post-action listener: _resource_record_post_action 20:50:30 INFO - [mozharness: 2016-10-20 03:50:30.552928Z] Finished clobber step (success) 20:50:30 INFO - [mozharness: 2016-10-20 03:50:30.553092Z] Running read-buildbot-config step. 20:50:30 INFO - Running pre-action listener: _resource_record_pre_action 20:50:30 INFO - Running main action method: read_buildbot_config 20:50:30 INFO - Using buildbot properties: 20:50:30 INFO - { 20:50:30 INFO - "project": "", 20:50:30 INFO - "product": "firefox", 20:50:30 INFO - "script_repo_revision": "production", 20:50:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:50:30 INFO - "repository": "", 20:50:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:50:30 INFO - "buildid": "20161019182749", 20:50:30 INFO - "pgo_build": "False", 20:50:30 INFO - "basedir": "/builds/slave/test", 20:50:30 INFO - "buildnumber": 13, 20:50:30 INFO - "slavename": "t-yosemite-r7-0165", 20:50:30 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:50:30 INFO - "platform": "yosemite_r7", 20:50:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:50:30 INFO - "branch": "mozilla-central", 20:50:30 INFO - "repo_path": "mozilla-central", 20:50:30 INFO - "revision": "dcfc4c3e4b552a47d3bd5748f3292c11acb12efc", 20:50:30 INFO - "stage_platform": "macosx64", 20:50:30 INFO - "builduid": "27a4272ec8634dfc9635c1e956f1bc96", 20:50:30 INFO - "slavebuilddir": "test" 20:50:30 INFO - } 20:50:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:50:30 INFO - Running post-action listener: _resource_record_post_action 20:50:30 INFO - [mozharness: 2016-10-20 03:50:30.556487Z] Finished read-buildbot-config step (success) 20:50:30 INFO - [mozharness: 2016-10-20 03:50:30.556589Z] Running download-and-extract step. 20:50:30 INFO - Running pre-action listener: _resource_record_pre_action 20:50:30 INFO - Running main action method: download_and_extract 20:50:30 INFO - mkdir: /builds/slave/test/build/tests 20:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:30 INFO - trying https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:50:30 INFO - Downloading https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:50:31 INFO - Downloaded 1199 bytes. 20:50:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:50:31 INFO - Using the following test package requirements: 20:50:31 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:50:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:50:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:50:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'jsshell-mac.zip'], 20:50:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:50:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:50:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:50:31 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:50:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:50:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:50:31 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:50:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:50:32 INFO - Http code: 200 20:50:32 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 20:50:32 INFO - x-amz-cf-id: IMZtqoM15urp1R3NErxfYiYxgtjB__9_pmycOsiyQ7rLDxHi2jnM0g== 20:50:32 INFO - x-amz-version-id: IP2PBwRFbPwX5fHRaWNATjSss_JkrWVO 20:50:32 INFO - Expected file size: 16501279 20:50:32 INFO - Obtained file size: 16501279 20:50:39 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:50:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:50:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:50:39 INFO - Fetch https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:50:40 INFO - Http code: 200 20:50:40 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 20:50:40 INFO - x-amz-cf-id: JN2iibkcOqjbYi-IKK6jfQYph04q3EtL5HNa-FMoRMAiDkrx66uyRg== 20:50:40 INFO - x-amz-version-id: IP2PBwRFbPwX5fHRaWNATjSss_JkrWVO 20:50:40 INFO - Expected file size: 16501279 20:50:40 INFO - Obtained file size: 16501279 20:50:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:50:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:50:46 INFO - Fetch https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:50:47 INFO - Http code: 200 20:50:47 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 20:50:47 INFO - x-amz-cf-id: ia13uGyV6DkuTYEDfJ7ILKMUofdvcuhagHBfuOU1JmOarcZfkyy1Eg== 20:50:47 INFO - x-amz-version-id: fEZgfMJ5R31bIrZjHtPaaYjH02tMEHia 20:50:47 INFO - Expected file size: 14409028 20:50:47 INFO - Obtained file size: 14409028 20:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:48 INFO - trying https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:50:48 INFO - Downloading https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:50:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:50:51 INFO - Downloaded 111622239 bytes. 20:50:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:50:51 INFO - mkdir: /builds/slave/test/properties 20:50:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:50:51 INFO - Writing to file /builds/slave/test/properties/build_url 20:50:51 INFO - Contents: 20:50:51 INFO - build_url:https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:50:51 INFO - Running post-action listener: _resource_record_post_action 20:50:51 INFO - Running post-action listener: set_extra_try_arguments 20:50:51 INFO - [mozharness: 2016-10-20 03:50:51.808063Z] Finished download-and-extract step (success) 20:50:51 INFO - [mozharness: 2016-10-20 03:50:51.808187Z] Running populate-webroot step. 20:50:51 INFO - Running pre-action listener: _resource_record_pre_action 20:50:51 INFO - Running main action method: populate_webroot 20:50:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:50:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:50:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:50:51 INFO - '--tppagecycles', 20:50:51 INFO - '7'], 20:50:51 INFO - 'tests': ['tsvgm']}}, 20:50:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:50:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:50:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:50:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:50:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['damp', 'tps']}, 20:50:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'tests': ['damp', 'tps']}, 20:50:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['dromaeo_dom']}, 20:50:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:50:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['basic_compositor_video']}, 20:50:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:50:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'other-e10s': {'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['a11yr', 20:50:51 INFO - 'ts_paint', 20:50:51 INFO - 'tpaint', 20:50:51 INFO - 'sessionrestore', 20:50:51 INFO - 'sessionrestore_no_auto_restore', 20:50:51 INFO - 'tabpaint']}, 20:50:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['tsvgx', 20:50:51 INFO - 'tsvgr_opacity', 20:50:51 INFO - 'tart', 20:50:51 INFO - 'tscrollx', 20:50:51 INFO - 'cart']}, 20:50:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:50:51 INFO - 'tsvgr_opacity', 20:50:51 INFO - 'tart', 20:50:51 INFO - 'tscrollx', 20:50:51 INFO - 'cart']}, 20:50:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'talos_options': ['--disable-e10s'], 20:50:51 INFO - 'tests': ['tp5o']}, 20:50:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'tests': ['tp5o']}, 20:50:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'talos_options': ['--disable-e10s', 20:50:51 INFO - '--xperf_path', 20:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:51 INFO - 'tests': ['tp5n']}, 20:50:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:50:51 INFO - 'talos_options': ['--xperf_path', 20:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:51 INFO - 'tests': ['tp5n']}}, 20:50:51 INFO - 'talos.zip': {'path': '', 20:50:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:50:51 INFO - Running post-action listener: _resource_record_post_action 20:50:51 INFO - [mozharness: 2016-10-20 03:50:51.827110Z] Finished populate-webroot step (success) 20:50:51 INFO - [mozharness: 2016-10-20 03:50:51.827219Z] Running create-virtualenv step. 20:50:51 INFO - Running pre-action listener: _resource_record_pre_action 20:50:51 INFO - Running main action method: create_virtualenv 20:50:51 INFO - Creating virtualenv /builds/slave/test/build/venv 20:50:51 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:50:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:50:51 INFO - Using real prefix '/tools/python27' 20:50:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:50:52 INFO - Installing distribute.............................................................................................................................................................................................done. 20:50:55 INFO - Installing pip.................done. 20:50:55 INFO - Return code: 0 20:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:50:55 INFO - Reading from file tmpfile_stdout 20:50:55 INFO - Output received: 20:50:55 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:50:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:50:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:50:55 INFO - 'HOME': '/Users/cltbld', 20:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:50:55 INFO - 'LOGNAME': 'cltbld', 20:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:55 INFO - 'MOZ_NO_REMOTE': '1', 20:50:55 INFO - 'NO_EM_RESTART': '1', 20:50:55 INFO - 'PAGER': '/bin/cat', 20:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:55 INFO - 'PWD': '/builds/slave/test', 20:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:50:55 INFO - 'SHELL': '/bin/bash', 20:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:50:55 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:50:55 INFO - 'USER': 'cltbld', 20:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:55 INFO - 'XPC_FLAGS': '0x0', 20:50:55 INFO - 'XPC_SERVICE_NAME': '0', 20:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:56 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:50:56 INFO - Cleaning up... 20:50:56 INFO - Return code: 0 20:50:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:50:56 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:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:50:56 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:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:50:56 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:50:56 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:50:56 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:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:50:56 INFO - 'HOME': '/Users/cltbld', 20:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:50:56 INFO - 'LOGNAME': 'cltbld', 20:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:56 INFO - 'MOZ_NO_REMOTE': '1', 20:50:56 INFO - 'NO_EM_RESTART': '1', 20:50:56 INFO - 'PAGER': '/bin/cat', 20:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:56 INFO - 'PWD': '/builds/slave/test', 20:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:50:56 INFO - 'SHELL': '/bin/bash', 20:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:50:56 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:50:56 INFO - 'USER': 'cltbld', 20:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:56 INFO - 'XPC_FLAGS': '0x0', 20:50:56 INFO - 'XPC_SERVICE_NAME': '0', 20:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:56 INFO - Downloading/unpacking psutil>=3.1.1 20:50:56 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:50:56 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:50:56 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:50:56 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:50:56 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:50:56 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:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:50:59 INFO - Installing collected packages: psutil 20:50:59 INFO - Running setup.py install for psutil 20:51:00 INFO - building 'psutil._psutil_osx' extension 20:51:00 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:51:00 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:51:00 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:51:00 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:51:00 INFO - building 'psutil._psutil_posix' extension 20:51:00 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:51:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:51:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:51:00 INFO - ^ 20:51:00 INFO - 1 warning generated. 20:51:00 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:51:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:51:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:51:00 INFO - Successfully installed psutil 20:51:00 INFO - Cleaning up... 20:51:00 INFO - Return code: 0 20:51:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:51:00 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:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:00 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:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:00 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:00 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:51:00 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:51:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:00 INFO - 'HOME': '/Users/cltbld', 20:51:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:00 INFO - 'LOGNAME': 'cltbld', 20:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:00 INFO - 'MOZ_NO_REMOTE': '1', 20:51:00 INFO - 'NO_EM_RESTART': '1', 20:51:00 INFO - 'PAGER': '/bin/cat', 20:51:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:00 INFO - 'PWD': '/builds/slave/test', 20:51:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:00 INFO - 'SHELL': '/bin/bash', 20:51:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:00 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:00 INFO - 'USER': 'cltbld', 20:51:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:00 INFO - 'XPC_FLAGS': '0x0', 20:51:00 INFO - 'XPC_SERVICE_NAME': '0', 20:51:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:51:03 INFO - Installing collected packages: mozsystemmonitor 20:51:03 INFO - Running setup.py install for mozsystemmonitor 20:51:03 INFO - Successfully installed mozsystemmonitor 20:51:03 INFO - Cleaning up... 20:51:03 INFO - Return code: 0 20:51:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:51: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:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51: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:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51: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', '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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:51: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 jsonschema==2.5.1 20:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:03 INFO - 'HOME': '/Users/cltbld', 20:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:03 INFO - 'LOGNAME': 'cltbld', 20:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:03 INFO - 'MOZ_NO_REMOTE': '1', 20:51:03 INFO - 'NO_EM_RESTART': '1', 20:51:03 INFO - 'PAGER': '/bin/cat', 20:51:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:03 INFO - 'PWD': '/builds/slave/test', 20:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:03 INFO - 'SHELL': '/bin/bash', 20:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:03 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:03 INFO - 'USER': 'cltbld', 20:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:03 INFO - 'XPC_FLAGS': '0x0', 20:51:03 INFO - 'XPC_SERVICE_NAME': '0', 20:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:04 INFO - Downloading/unpacking jsonschema==2.5.1 20:51:04 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:51:04 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:51:04 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:51:04 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:51:04 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:51:04 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:51:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:51:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:51: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:51: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:51: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:51: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:51: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:51: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:51:07 INFO - Downloading functools32-3.2.3-2.tar.gz 20:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:51:07 INFO - warning: no files found matching '*.txt' 20:51:07 INFO - no previously-included directories found matching 'build' 20:51:07 INFO - no previously-included directories found matching 'dist' 20:51:07 INFO - no previously-included directories found matching '.git*' 20:51:07 INFO - Installing collected packages: jsonschema, functools32 20:51:07 INFO - Running setup.py install for functools32 20:51:07 INFO - warning: no files found matching '*.txt' 20:51:07 INFO - no previously-included directories found matching 'build' 20:51:07 INFO - no previously-included directories found matching 'dist' 20:51:07 INFO - no previously-included directories found matching '.git*' 20:51:07 INFO - Successfully installed jsonschema functools32 20:51:07 INFO - Cleaning up... 20:51:07 INFO - Return code: 0 20:51:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:51:07 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:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:07 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:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:07 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:07 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:51:07 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:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:07 INFO - 'HOME': '/Users/cltbld', 20:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:07 INFO - 'LOGNAME': 'cltbld', 20:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:07 INFO - 'MOZ_NO_REMOTE': '1', 20:51:07 INFO - 'NO_EM_RESTART': '1', 20:51:07 INFO - 'PAGER': '/bin/cat', 20:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:07 INFO - 'PWD': '/builds/slave/test', 20:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:07 INFO - 'SHELL': '/bin/bash', 20:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:07 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:07 INFO - 'USER': 'cltbld', 20:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:07 INFO - 'XPC_FLAGS': '0x0', 20:51:07 INFO - 'XPC_SERVICE_NAME': '0', 20:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:51:07 INFO - Cleaning up... 20:51:07 INFO - Return code: 0 20:51:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:51:07 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:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:07 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:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:07 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:07 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:51:07 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:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:07 INFO - 'HOME': '/Users/cltbld', 20:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:07 INFO - 'LOGNAME': 'cltbld', 20:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:07 INFO - 'MOZ_NO_REMOTE': '1', 20:51:07 INFO - 'NO_EM_RESTART': '1', 20:51:07 INFO - 'PAGER': '/bin/cat', 20:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:07 INFO - 'PWD': '/builds/slave/test', 20:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:07 INFO - 'SHELL': '/bin/bash', 20:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:07 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:07 INFO - 'USER': 'cltbld', 20:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:07 INFO - 'XPC_FLAGS': '0x0', 20:51:07 INFO - 'XPC_SERVICE_NAME': '0', 20:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:08 INFO - Downloading/unpacking blobuploader==1.2.4 20:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:10 INFO - Downloading blobuploader-1.2.4.tar.gz 20:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:51:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:51: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:51: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:51: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:51: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:51: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:51: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:51:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:51:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:51:11 INFO - Downloading docopt-0.6.1.tar.gz 20:51:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:51:11 INFO - Installing collected packages: blobuploader, requests, docopt 20:51:11 INFO - Running setup.py install for blobuploader 20:51:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:51:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:51:12 INFO - Running setup.py install for requests 20:51:12 INFO - Running setup.py install for docopt 20:51:12 INFO - Successfully installed blobuploader requests docopt 20:51:12 INFO - Cleaning up... 20:51:12 INFO - Return code: 0 20:51:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:51:12 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:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:12 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:51:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:12 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:12 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:51:12 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:51:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:12 INFO - 'HOME': '/Users/cltbld', 20:51:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:12 INFO - 'LOGNAME': 'cltbld', 20:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:12 INFO - 'MOZ_NO_REMOTE': '1', 20:51:12 INFO - 'NO_EM_RESTART': '1', 20:51:12 INFO - 'PAGER': '/bin/cat', 20:51:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:12 INFO - 'PWD': '/builds/slave/test', 20:51:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:12 INFO - 'SHELL': '/bin/bash', 20:51:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:12 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:12 INFO - 'USER': 'cltbld', 20:51:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:12 INFO - 'XPC_FLAGS': '0x0', 20:51:12 INFO - 'XPC_SERVICE_NAME': '0', 20:51:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-vQtFi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0XJnLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-hUecWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-LPHe59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-hDy3fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-qDXR0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-nS__dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-K6kolW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:51:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-DQBeSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-6Gc0yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-nWkIIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-laenUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-FPF8qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-baIZC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-txHiXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-N7RKWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:51:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-mFSrgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:51:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:51:14 INFO - Running setup.py install for manifestparser 20:51:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:51:15 INFO - Running setup.py install for mozcrash 20:51:15 INFO - Running setup.py install for mozdebug 20:51:15 INFO - Running setup.py install for mozdevice 20:51:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:51:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:51:15 INFO - Running setup.py install for mozfile 20:51:15 INFO - Running setup.py install for mozhttpd 20:51:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:51:15 INFO - Running setup.py install for mozinfo 20:51:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:51:15 INFO - Running setup.py install for mozInstall 20:51:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Running setup.py install for mozleak 20:51:16 INFO - Running setup.py install for mozlog 20:51:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Running setup.py install for moznetwork 20:51:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Running setup.py install for mozprocess 20:51:16 INFO - Running setup.py install for mozprofile 20:51:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:51:16 INFO - Running setup.py install for mozrunner 20:51:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:51:17 INFO - Running setup.py install for mozscreenshot 20:51:17 INFO - Running setup.py install for moztest 20:51:17 INFO - Running setup.py install for mozversion 20:51:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:51:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:51:17 INFO - Cleaning up... 20:51:17 INFO - Return code: 0 20:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:17 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:17 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:51:17 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:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:17 INFO - 'HOME': '/Users/cltbld', 20:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:17 INFO - 'LOGNAME': 'cltbld', 20:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:17 INFO - 'MOZ_NO_REMOTE': '1', 20:51:17 INFO - 'NO_EM_RESTART': '1', 20:51:17 INFO - 'PAGER': '/bin/cat', 20:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:17 INFO - 'PWD': '/builds/slave/test', 20:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:17 INFO - 'SHELL': '/bin/bash', 20:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:17 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:17 INFO - 'USER': 'cltbld', 20:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:17 INFO - 'XPC_FLAGS': '0x0', 20:51:17 INFO - 'XPC_SERVICE_NAME': '0', 20:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:51:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-FfITGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:51:17 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:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:51:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-wsVFgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:51:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-n_lb1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-giEhb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-skv0xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-IfEelV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-wHy0U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-HAvJFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-OlBETR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-v1E2Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-iXXZ5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:51:18 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:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:51:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-cOmJ4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:51:19 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:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:51:19 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-JE0jUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:51:19 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:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:51:19 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-WEYxbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:19 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-sG21E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:19 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:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:51:19 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-gmH1Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:51:19 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:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:51:19 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-7oEGmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:51:19 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:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:51:19 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:51:19 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:51:19 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:51:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:51:19 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:51:19 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:51:19 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:51:19 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:51:19 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:51:19 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:51:22 INFO - Downloading blessings-1.6.tar.gz 20:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:51:22 INFO - Installing collected packages: blessings 20:51:22 INFO - Running setup.py install for blessings 20:51:22 INFO - Successfully installed blessings 20:51:22 INFO - Cleaning up... 20:51:22 INFO - Return code: 0 20:51:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:51:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:51:22 INFO - Reading from file tmpfile_stdout 20:51:22 INFO - Current package versions: 20:51:22 INFO - blessings == 1.6 20:51:22 INFO - blobuploader == 1.2.4 20:51:22 INFO - docopt == 0.6.1 20:51:22 INFO - functools32 == 3.2.3-2 20:51:22 INFO - jsonschema == 2.5.1 20:51:22 INFO - manifestparser == 1.1 20:51:22 INFO - mozInstall == 1.12 20:51:22 INFO - mozcrash == 0.17 20:51:22 INFO - mozdebug == 0.1 20:51:22 INFO - mozdevice == 0.48 20:51:22 INFO - mozfile == 1.2 20:51:22 INFO - mozhttpd == 0.7 20:51:22 INFO - mozinfo == 0.9 20:51:22 INFO - mozleak == 0.1 20:51:22 INFO - mozlog == 3.4 20:51:22 INFO - moznetwork == 0.27 20:51:22 INFO - mozprocess == 0.23 20:51:22 INFO - mozprofile == 0.28 20:51:22 INFO - mozrunner == 6.12 20:51:22 INFO - mozscreenshot == 0.1 20:51:22 INFO - mozsystemmonitor == 0.3 20:51:22 INFO - moztest == 0.7 20:51:22 INFO - mozversion == 1.4 20:51:22 INFO - psutil == 3.1.1 20:51:22 INFO - requests == 1.2.3 20:51:22 INFO - wsgiref == 0.1.2 20:51:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:51:22 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:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:22 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:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:22 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:22 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:51:22 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:51:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:22 INFO - 'HOME': '/Users/cltbld', 20:51:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:22 INFO - 'LOGNAME': 'cltbld', 20:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:22 INFO - 'MOZ_NO_REMOTE': '1', 20:51:22 INFO - 'NO_EM_RESTART': '1', 20:51:22 INFO - 'PAGER': '/bin/cat', 20:51:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:22 INFO - 'PWD': '/builds/slave/test', 20:51:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:22 INFO - 'SHELL': '/bin/bash', 20:51:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:22 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:22 INFO - 'USER': 'cltbld', 20:51:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:22 INFO - 'XPC_FLAGS': '0x0', 20:51:22 INFO - 'XPC_SERVICE_NAME': '0', 20:51:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 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:51:23 INFO - Cleaning up... 20:51:23 INFO - Return code: 0 20:51:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:51:23 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:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:23 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:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:51:23 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 0x10af90490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af06570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8aca4780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, '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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:51:23 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:51:23 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:51:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:23 INFO - 'HOME': '/Users/cltbld', 20:51:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:23 INFO - 'LOGNAME': 'cltbld', 20:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:23 INFO - 'MOZ_NO_REMOTE': '1', 20:51:23 INFO - 'NO_EM_RESTART': '1', 20:51:23 INFO - 'PAGER': '/bin/cat', 20:51:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:23 INFO - 'PWD': '/builds/slave/test', 20:51:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:23 INFO - 'SHELL': '/bin/bash', 20:51:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:23 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:23 INFO - 'USER': 'cltbld', 20:51:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:23 INFO - 'XPC_FLAGS': '0x0', 20:51:23 INFO - 'XPC_SERVICE_NAME': '0', 20:51:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:51:23 INFO - Cleaning up... 20:51:23 INFO - Return code: 0 20:51:23 INFO - Running post-action listener: _resource_record_post_action 20:51:23 INFO - Running post-action listener: _start_resource_monitoring 20:51:23 INFO - Starting resource monitoring. 20:51:23 INFO - [mozharness: 2016-10-20 03:51:23.333447Z] Finished create-virtualenv step (success) 20:51:23 INFO - [mozharness: 2016-10-20 03:51:23.334450Z] Running install step. 20:51:23 INFO - Running pre-action listener: _resource_record_pre_action 20:51:23 INFO - Running main action method: install 20:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:51:23 INFO - Reading from file tmpfile_stdout 20:51:23 INFO - Detecting whether we're running mozinstall >=1.0... 20:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:51:23 INFO - Reading from file tmpfile_stdout 20:51:23 INFO - Output received: 20:51:23 INFO - Usage: mozinstall [options] installer 20:51:23 INFO - Options: 20:51:23 INFO - -h, --help show this help message and exit 20:51:23 INFO - -d DEST, --destination=DEST 20:51:23 INFO - Directory to install application into. [default: 20:51:23 INFO - "/builds/slave/test"] 20:51:23 INFO - --app=APP Application being installed. [default: firefox] 20:51:23 INFO - mkdir: /builds/slave/test/build/application 20:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:51:58 INFO - Reading from file tmpfile_stdout 20:51:58 INFO - Output received: 20:51:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:51:58 INFO - Running post-action listener: _resource_record_post_action 20:51:58 INFO - [mozharness: 2016-10-20 03:51:58.067084Z] Finished install step (success) 20:51:58 INFO - [mozharness: 2016-10-20 03:51:58.067214Z] Running run-tests step. 20:51:58 INFO - Running pre-action listener: _resource_record_pre_action 20:51:58 INFO - Running main action method: run_tests 20:51:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:51:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:51:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:51:58 INFO - 2016-10-19 20:51:58.081 screenresolution[1911:14177] starting screenresolution argv=screenresolution get 20:51:58 INFO - 2016-10-19 20:51:58.084 screenresolution[1911:14177] Display 0: 1600x1200x32@60 20:51:58 INFO - 2016-10-19 20:51:58.090 screenresolution[1912:14179] starting screenresolution argv=screenresolution list 20:51:58 INFO - Available Modes on Display 0 20:51:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:51:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:51:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:51:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:51:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:51:58 INFO - Intel Iris: 20:51:58 INFO - Chipset Model: Intel Iris 20:51:58 INFO - Type: GPU 20:51:58 INFO - Bus: Built-In 20:51:58 INFO - VRAM (Dynamic, Max): 1536 MB 20:51:58 INFO - Vendor: Intel (0x8086) 20:51:58 INFO - Device ID: 0x0a2e 20:51:58 INFO - Revision ID: 0x0009 20:51:58 INFO - Displays: 20:51:58 INFO - Display: 20:51:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:51:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:51:58 INFO - Display Serial Number: 1600x1200 60 20:51:58 INFO - Main Display: Yes 20:51:58 INFO - Mirror: Off 20:51:58 INFO - Online: Yes 20:51:58 INFO - Rotation: Supported 20:51:58 INFO - Return code: 0 20:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:51:58 INFO - Python 2.7.3 20:51:58 INFO - Return code: 0 20:51:58 INFO - grabbing minidump binary from tooltool 20:51: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:51: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 0x10ada4d30>, '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 0x10abcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad9cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:51: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:51: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:51: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:51:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:51:58 INFO - Return code: 0 20:51:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:51:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:51:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:51:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:51:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --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:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tqt1wNMamX/Render', 20:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:51:58 INFO - 'HOME': '/Users/cltbld', 20:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:51:58 INFO - 'LOGNAME': 'cltbld', 20:51:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:51:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:58 INFO - 'MOZ_NO_REMOTE': '1', 20:51:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:51:58 INFO - 'NO_EM_RESTART': '1', 20:51:58 INFO - 'PAGER': '/bin/cat', 20:51: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:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:58 INFO - 'PWD': '/builds/slave/test', 20:51:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:51:58 INFO - 'SHELL': '/bin/bash', 20:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EA3LXbvqhY/Listeners', 20:51:58 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 20:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:51:58 INFO - 'USER': 'cltbld', 20:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:58 INFO - 'XPC_FLAGS': '0x0', 20:51:58 INFO - 'XPC_SERVICE_NAME': '0', 20:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:51:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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:51:58 INFO - mozversion application_buildid: 20161019182749 20:51:58 INFO - mozversion application_changeset: dcfc4c3e4b552a47d3bd5748f3292c11acb12efc 20:51:58 INFO - mozversion application_display_name: Nightly 20:51:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:51:58 INFO - mozversion application_name: Firefox 20:51:58 INFO - mozversion application_remotingname: firefox 20:51:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:51:58 INFO - mozversion application_vendor: Mozilla 20:51:58 INFO - mozversion application_version: 52.0a1 20:51:58 INFO - mozversion platform_buildid: 20161019182749 20:51:58 INFO - mozversion platform_changeset: dcfc4c3e4b552a47d3bd5748f3292c11acb12efc 20:51:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:51:58 INFO - mozversion platform_version: 52.0a1 20:51:58 INFO - using testdate: 1476935518 20:51:58 INFO - actual date: 1476935518 20:51:58 INFO - starting webserver on 'localhost:49436' 20:51:58 INFO - SUITE-START | Running 2 tests 20:51:58 INFO - TEST-START | dromaeo_css 20:51:58 INFO - operating with platform_type : mac_ 20:51:58 INFO - Initialising browser for dromaeo_css test... 20:51:58 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpzf5RsT/profile http://localhost:49436/getInfo.html) 20:52:01 INFO - PROCESS | 1922 | 2016-10-19 20:52:01.322 plugin-container[1924:14314] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f47, name = 'com.apple.tsm.portname' 20:52:01 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:52:01 INFO - PROCESS | 1922 | 2016-10-19 20:52:01.325 plugin-container[1924:14314] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 20:52:01 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:52:01 INFO - PROCESS | 1922 | 2016-10-19 20:52:01.325 plugin-container[1924:14314] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:52:01 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 20:52:01 INFO - PROCESS | 1922 | colorDepth:24 20:52:01 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 20:52:01 INFO - PROCESS | 1922 | __metrics 20:52:01 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 20:52:02 INFO - TEST-INFO | 1922: exit 0 20:52:02 INFO - Browser initialized. 20:52:02 INFO - Running cycle 1/1 for dromaeo_css test... 20:52:02 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpzf5RsT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:52:04 INFO - PROCESS | 1925 | 2016-10-19 20:52:04.348 plugin-container[1926:14426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 20:52:04 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:52:04 INFO - PROCESS | 1925 | 2016-10-19 20:52:04.351 plugin-container[1926:14426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 20:52:04 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:52:04 INFO - PROCESS | 1925 | 2016-10-19 20:52:04.351 plugin-container[1926:14426] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:52:14 INFO - PROCESS | 1925 | RSS: Main: 237158400 20:52:14 INFO - PROCESS | 1925 | 20:53:03 INFO - PROCESS | 1925 | 1476935583699 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:55:16 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49436/tests/dromaeo/cssquery-ext.html) 20:55:18 INFO - PROCESS | 1925 | RSS: Main: 251731968 20:55:18 INFO - PROCESS | 1925 | 20:58:20 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-ext.html (next: http://localhost:49436/tests/dromaeo/cssquery-jquery.html) 20:58:21 INFO - PROCESS | 1925 | RSS: Main: 264024064 20:58:21 INFO - PROCESS | 1925 | 21:01:23 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49436/tests/dromaeo/cssquery-mootools.html) 21:01:24 INFO - PROCESS | 1925 | RSS: Main: 268779520 21:01:24 INFO - PROCESS | 1925 | 21:04:26 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49436/tests/dromaeo/cssquery-prototype.html) 21:04:26 INFO - PROCESS | 1925 | RSS: Main: 268472320 21:04:26 INFO - PROCESS | 1925 | 21:07:29 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49436/tests/dromaeo/cssquery-yui.html) 21:07:30 INFO - PROCESS | 1925 | RSS: Main: 273719296 21:07:30 INFO - PROCESS | 1925 | 21:10:32 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49436/tests/dromaeo/cssquery-yui.html (next: http://localhost:49436/tests/dromaeo/cssquery-dojo.html) 21:10:33 INFO - PROCESS | 1925 | RSS: Main: 277356544 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | __start_tp_report 21:10:33 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 21:10:33 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 21:10:33 INFO - PROCESS | 1925 | |i|pagename|runs| 21:10:33 INFO - PROCESS | 1925 | |0;dojo.html;3296.472564389697;3322.252493189032;3322.4185767490685;3343.3981883261004;3353.7820041697287;4366.607005369524;5438.592105592076;5467.972660136698;5472.589555783317;5528.723563821808;5498.697571633558;5505.394406615269;5509.449055094488;5601.495865372116;5609.467100625444;5013.147764879979;5040;5073.644844860862;5145.202493613478;5178.14560597501;4161.979190104061;5110.386753589574;5175.24958880962;5199.922001169985;5206.609504287175;5017.974910125426;5076.771545280471;5120.078385890538;5123.564497017758;5154.201098829669;5004.299398084235;5021.69869807809;5036.967421678583;5074.873128171788;5100.183970564729;4994.375703037121;5020.171671674169;5043.672161309503;5065.569426598743;5122.897542049138;4588;4589.5410458954175;5361;5579.804706835282;5603.859903502404;4681.297805329194;4969.925451118236;6045.486133678643;6103.450689437972;6106.48094911933;5065.544101030925;5262.447443018505;6113.083037544359;6174.320824709278;6226.750929962796;4625.167469855391;5578.721063946868;5594;5644.322681278273;5646.802361917313;4639.930401043987;5909.2908850938165;6130.601510901777;6177.598456100339;6250.656213908188;5973.701314934236;5975.462208401178;5994.910076348859;5995.670238136857;6000.759969601167;6009.06859436788;6041.818745437644;6139.938600614026;6148.323684394713;6150.569460137837;6014.48876845473;6052.092186172109;6054.636721796706;6078.696065196811;6088.086786981899;5286.550643195372;6075.605085669506;6106.969465152646;6107.144999700045;6107.633541987495;4611.746353950565;4723.716577005391;4778.426588809365;4810.59109975656;4824.107540105022;4311.288637374861;4317.266064768997;4321.783910804447;4336.804843782037;4372.147431250875;5396.1366181410785;5770.5095066919785;5775.48020678141;5780.1907732917425;5792.739326730307;5990.64056156632;6160.969195153995;6165;6180.289266734383;6186.628802271878;5595.38450770415;5615.494605485526;5647.491725744702;5649.237352957381;5739.081746920473;5247.606429517725;5251.737413129328;5253.894922101613;5254.133068043807;5265.76304066318;6217.347178546368;6231.158793562901;6256.43692067707;6264.404881536338;6272.749090036347;5997.220361352996;6013.158157857816;6064;6076.665783381956;6108.633481990917;6203.751849925952;6212.378762123933;6240.781572644935;6241.594296370721;6249.781257656142;5868.119782032729;5916.733746981482;5959;5963.612365196248;6026.849328766816;5972.044472884318;6009.90985135232;6094.11635312886;6121.4490695837585;6189.81430557084;5978.671173085522;5982.042873140276;5995.070764031582;6009.549283803644;6021.277446706424;5792.536597072309;5918.319393269829;5947.197128387612;6013.9097913532205;6023.30731965812;5156.61325400604;5257.2639830423;5686.60193786431;5758.309002919509;5782.3639399666845;5875.618084824473;5878.647281163144;5890;5906.055031194988;5907.616004959664;5713.057345538106;5725.914111288251;5731.455511726379;5740.253767010262;5741.684207368635 21:10:33 INFO - PROCESS | 1925 | |1;ext.html;479.4773696670628;500.54200406627893;512.4567957964558;528.9656172348798;531.1501597444089;31697.68302316976;41782.955426114364;41811.37282940755;41903.58096419035;41992.79003604982;42321.78839105808;42854;42881.785591072;42947.35578966318;42993.785031074876;9508.572114254841;9675.516224188797;9692.224622030257;9751.366161199534;9775.289324368243;8478.406511544194;9569.138777510023;9612.615495380176;9621.037896210393;9652.565634546427;9584.856227156562;9645.276604254674;9648.986856380085;9650.179734722557;9695.078967498112;9619.374740641872;9630.711078667651;9633.133018028377;9635.710928672152;9655.951720241424;9466.90533094674;9493.05069493052;9530.618775248982;9549.283803714749;9610.855837162448;40132.19735605301;40561.985950351474;40566.79716601398;40581.59418405808;40621.1875762486;3188.58548388708;3200.0399880035898;3209.502527108302;3283.6059672839174;3294.8682052717863;31183;31576.684233157604;31661;31839.52240716391;32361.029169124962;3027.0022928821295;3058.663547009827;3062.2038270049948;3064.8161110333454;3142.5757522734366;485923;496520;496636;496747;497808;50502.49497505051;61317.69341153266;61572;61709.76580468365;61796.69101654463;54113.72943135259;63955;64408.35591644023;64567.70864582682;64608.35391646117;15987.920060399623;16225.756613650805;16227.513174604781;16238.837611623732;16268.837311626969;12456.31490268044;12547.874521254853;12586.748265034648;12609.495620175274;12664.6833829155;16381.344746210256;16709.992201403864;16844.831551684572;16879.493615191564;16909.577260568338;7272.96363518179;7315.63421828917;7364.852702946018;7376.114866216089;7422.740204092939;763.5952944939191;852.9914700852912;959.932804703664;1017.2319898476603;1043.8538604595362;28972.710272897002;29738.40523189524;29775.702242977728;29873.551896721565;30621;965.2471072563412;982.4596471940404;1004.2167109654335;1029.4286670897723;1058.0266155137292;14260;15323.387064517294;15596.766048509282;15672.529824105295;15780.526584202493;620256;620549;623899;627094;634235;50955;58714.4128558709;59329.40670593325;59421.108683369785;59735.70132149399;1202.6572426858304;1325.6752095736413;1371.7873729571734;1372.1972646002;1383.4397069186793;26411.019173561675;28182.85908570444;29750.553741694326;29799.851000744857;29867.402651947275;47381;56221.71889140528;57675.711621441624;60830.39169608247;62284;571737;574866;574925;576631.1168444131;580825;26214.344641383355;26346.077887273852;26426.735732643196;26726.866365668047;26779.732202677726;45282.77358613186;48641.75679121582;48969.0206195867;49846.753831154514;49938.750306248236;33044.00867973964;39646.60353396545;41452;41458.47131410806;41761;39993.60006399899;40279;40410.98972525593;40885.795571021954;41599;38317.80841095777;39298.410523841594;39339.60660393474;39454.802725986185;39458;33760.324793504486;34965.47551786776;35620.64379356277;35958.64041359553;36045.63954360423;25814;25825.483490329712;25970.48059038771;26154.953801848475;26571.202863914114 21:10:33 INFO - PROCESS | 1925 | |2;jquery.html;369.6248307967414;370.1061935425946;374.2197518174605;378.61192277915114;397.30669980883346;19155.329563465282;20154.193832246692;20359.1856325747;20776.792232077674;21011.579768404623;19693.409197724057;19855.106520206624;20321.898390508064;20481.283155089575;20857.165713371483;5622.550195984311;5642.689652069134;5889.234399528077;5905;5939.287285525721;4084.4281800547947;4309.396684464177;5819.388964158766;5932.08052751822;5946.791862284821;6152.384761523835;6473.320301368359;6531.987541930985;6591.472682185414;6593.406593406592;6318.862604731146;6499.675016249193;6541.378569035982;6553.3446655334565;6561.901571476432;5539.362973258087;5573.721313934287;5606.943930560683;5660.2075709400715;5694.914576281359;24983;25330.87334563334;25380.87309563459;25384;25445.23664290074;2116.1958455786857;2129.2547608337113;2156.676498525234;2195.297504798465;2313.4972184484786;15111.773323400157;15375.461858834999;15396.615084622974;15748.212589370485;15911.681766364607;1719.5958949646822;1761.9207135678796;1762.0572993448443;1769.9380521681808;1783.8216178382188;109505;114870;114907.27639085421;115542.42228788887;115659;9014.909850901538;9550.474723890253;9657.806843863083;9670.90329096714;10588.047075763217;12262.938685306517;12307.323097229739;12322.260664360167;12329.876701233052;12341.814872776915;7859.72490962825;7876.803079923047;8079.232472915063;8081.757547273592;8111.148329425326;7359.080114985627;7965.163657815961;8369.49783013021;8539.316854651614;8559.400841941004;3249.5938007749032;3311.2218628622254;3579.9260221933314;3778.981105094457;3785.5268091488565;1592.3869310315613;1646.7118254305449;1658.1377683604458;1959.8824070555813;1972.3589833304234;717.8156042529763;884.478157886837;922.8246633139682;935.5836652689624;955.19763398744;19309.613807723767;20684;21452.034658440407;21554.56890862174;21662.350129495826;891.7859713668673;918.2535256154872;925.250547056886;947.9194268487256;994.9701508954743;9751.512424378752;11110.889090927218;11262;11278.09775217997;11301.49143288554;540155;544918;568374;582604;587139;126712;127773.3611331956;130710;131028.51771858419;132058.3588328228;944.3248077624394;951.3245595627183;988.2736188901152;991.9355241909253;993.7131414817828;18726.344577939708;18772.7184092236;18849.340273090376;18933.33733319327;19017.144228509474;54595.90808183735;54736;55016;55117.44882551123;55498.44501555094;77467;79233;79253;79747.4050518998;81481;6905.654717264217;6952.965235173791;7197.71209151628;7347.926520734938;7372;11069.391183484986;11815.350155741173;11851.644450666494;12139.453724582238;12161;8038.276555110068;8583.00890086113;9789.902100979183;10035.197184225099;10044.799104018024;10124.443155626512;10385.688429347132;10529.42088185157;10537.262391632512;11017.944910275397;9963.701088967342;10074.345167564084;10569.735756606146;10804.945975270073;10990.670279891616;5601.7396085880355;6718.596884186964;7002.809522381105;7043.964780176067;7182.060843572311;7763.573003484863;9747.951260243653;9938.503074846374;10551.577936882746;10666.253362264877 21:10:33 INFO - PROCESS | 1925 | |3;mootools.html;1513.4702854001098;1567.8197007344154;1773.405909020478;1866.4867161530585;1894.393793985925;8133.8779918301125;8391.412601117925;8582.91417085829;9238.30712696548;9253.398529095612;7723.497972631775;8596.355273354493;8839.690610828622;8982.326325525579;8995.685151019716;2393.9521209575796;2415.6376543518436;2421.7457166997474;2428.0651948999675;2434.184548176363;1994.7107669388004;2374.6556749271344;2424.260600516842;2433.5376278507115;2438.17102185257;2404.5431368040017;2405.446747248125;2417.987910060447;2433.1119141513395;2433.6957880264968;2383.6305372667266;2391.2228525729206;2401.6997875265592;2409.204962362434;2409.7831195192343;2374.4657452073147;2378.9881050594636;2379.1791831817995;2381.892814823337;2384.070212617081;9042.728718138467;9080.40977336471;9091.772705682344;9091.818163636757;9129.087091290885;5319.707416092114;5406.270153529263;5410.5401040911565;5497.285352904137;5501.669899806025;3566.1619519412916;3612.8554857805657;3632.6367363263726;3676.540432445944;3715.535558055243;927.420362273579;1042.8331466965326;1043.6295115234059;1052.5684469367523;1092.63396762084;10852.186086043499;11417.771644567061;11734.882651173464;11822.995045421063;11825.763484730258;4717.410323709536;4735.83424580138;4746.836985933994;4847.491013443609;4892.143874821892;10105.5452504636;10118.696439106838;10145.34055286404;10190.847137292947;10299.485025748683;5406.189071639207;5443.237946687467;5454.890902182014;5466.9179962300595;5469.07025805614;4251.256030194703;4446.644268458515;4613.261878099488;4786.377770889762;4932.988737308843;3930.115723962143;4005.178938317638;4052.250333688277;4059.7970101494807;4100.938485922714;1302.9348532573333;1309.3387839141362;1315.8026296055668;1319.4458327502473;1325.1386598710915;632.8797528469576;805.6817557065044;902.4810733828075;905.6965916417942;928.9442633442015;5798.246227990334;6000.249968753906;6027.397260274025;6136.693165341715;6215.782447614311;837.6021389839777;926.9432846554932;927.9072092790667;961.077365728908;1008.6973907827769;3323.952954819224;3348.3638108759747;3617.9276414471565;3968.722189446769;4095.9795201023803;10484.737881553023;10595.894041059491;10778.167711489066;10998.230123891411;11001.064909482624;10699.946500267604;10880.966308200866;10980.890191097988;10995.890041099647;11042.668719938414;8220.917790822135;8301.501909885306;8346.499210047417;8352.749417517363;8361.331093512505;5801.477866991991;5866.41335866427;5941.91087133702;5954.37479064692;5996.400359963969;8552.828943421222;10167.491625418847;10198.898011020092;10213.591456341663;10216.744581385525;9834.311598188058;10400.375977441377;10401.583936642752;10451.68644940653;10485;6559.8032059038305;6754.2570317266045;6924.3421874921805;7125.893111603434;7157.463190260647;8277.130901255281;8581.742547723577;8596.828063438821;8607.139286071342;8612.267957223456;7363.889541656987;7535.773926782205;7631.694732210872;7700.537967721954;7824.491408058458;7276.599787011553;7470.663820127998;7492.138404083382;7584.62076896164;7625.123110842324;6889.724411023503;7072.115985501812;7080.539764915264;7084.929150708427;7089.751858684921;4973.079980203674;4973.129702302155;5084.669496482717;5174.223866419917;5252.133397989289;4107.466029416276;4216.936745948752;5012.273220383097;5151.304573882628;5536.52939500147 21:10:33 INFO - PROCESS | 1925 | |4;prototype.html;301.17928644443884;303.1421078348273;309.0666188111902;312.6154829559642;315.62598320989645;4444.97765513932;4796.520347965202;5034.496550344964;5111.9744401277985;5293.9676763031175;4690.859274221779;4830.565249127578;4900.093482705693;5051.737065733567;5199.960007998397;3553.4492153716214;4041.636252737253;4089.284375234322;4100.425940368336;4124.8143833527565;3407.5740532433447;3702.870399536017;4083.2650122977852;4119.393436559744;4128.772917489537;3931.4495970564144;3982.362821948489;3986.40203969404;3993.321135406973;4004.7797371144575;3966.2860685076676;3976.840926362942;3989.5810939851476;3991.9600803992025;4001.939970900424;3485.599156097056;3487.616362200156;3490.9301813963834;3492.6681965213265;3514.6819942521556;4614;4786.7845946932475;5118.057117157612;5124.052050370693;5161.4322424533275;4525.185466616008;4529.09418116378;4529.818807247706;4553.817847286105;4583.358329833793;4586.096769840512;5233.7700640349485;5234.92147617786;5503.669779813184;5559.249501317311;3339.5491608632765;3361.9327613447595;3529.1530032791943;3725.329440700673;3765.4540091686827;5605.243292155547;5625;5625.46558076982;5632.324121105494;5657.434256574351;3283.441814891474;3691.667749902522;3728.3475391806323;3799.981000094982;3809.1619843634357;3845.307844587945;3854.075021994757;3860.3823388258306;3870.283997460479;3876.8255428505786;3977.3636218204947;4178.582141785858;4390.934135987963;4496.9325460118125;4519.841805536857;5057.106028358144;5554.277943867352;5574.414686457944;5588.972055139699;5592.832215033555;1127.0926903842485;1137.1300954769608;1167.3540308165445;1168.9479468478437;1278.3097127551018;692.1002696494537;742.2392048150581;860.2429861721645;885.1414128295543;896.9237614802685;5235.65968212065;5247.2451962719115;5273.736313184402;5283.894322113536;5306.548943339782;4798.424189097331;5626.718664066781;5654.971725141348;5677.886442271131;5688.488036076773;4609.72554265095;4753.667243293005;5217.808280760667;5246.898151388251;5265.894682106336;2036.2771216218325;2210.7789221077765;2262.7624099469317;2400.2319257837676;2505;4454.554544545519;4462.553744625511;4672.766361681903;4826.32431459596;4994.850154495371;4351.891202719958;4388.27593447084;4627.8611641650805;4692.343071969927;4868.172410690191;4332.67504937131;4854.854354369375;4901.607871370282;4908.435529914034;4946.604271658259;4045.3705091690636;4634;4748.643851711066;4772.785224664968;4790.520947905182;4217.2919967413245;4722.252766476288;4734.502877197845;4735.195016847213;4762.523487786262;4788.664793464424;5728.310148506284;5793.449622285876;5921.940780592311;5997.380707209117;3929.351656976681;3982.4623675803396;3992.9600703992587;4031.536373316989;4038.939415908705;5065.290069812632;5293.364796224478;5456.263404440349;5784.248047753933;5806.158107074535;5384.19237114436;5555.638883472561;5570.220169176399;5629.437056294338;5641.915371269516;5578.497935185853;5584.776608935597;5593.216949626974;5612.775488980558;5631.099024156116;4643.349931009594;5271.156614941591;5290.603204759582;5473.8357849264585;5480.451954804487;4975;5536.861578460571;5570.080936645408;5582.52548533363;5584.246126772834;5177.326947496952;5470.617056805985;5477.643953143033;5512.476314750093;5535.16972454119 21:10:33 INFO - PROCESS | 1925 | |5;yui.html;648.7080813633866;660.1220376898724;673.552087861572;683.9623820689864;729.3800269770692;15017.774733378994;16007.83992160081;16190;16217.189140542956;16253.349866005361;14662;14743.852561474381;14769.483068092619;14783.92608036961;14809.777853332209;697.8115908704647;717.0534893940002;872.7032808844975;884.9026607073217;894.1952242981304;906.9138431848963;912.3431129586687;921.1295325917011;921.5484412637793;924.3113880159291;965.715114041356;971.3103696375582;979.6718099436697;979.8383266761011;979.9559019844124;929.330881765128;966.7003228998963;972.0134063925121;973.0755782007086;975.2246963663904;585.2099665451649;611.8436155665751;709.7232079489004;722.743426083738;723.2225357740398;13277.402516886763;15728.134952577604;15746.291416886268;15815.446459373867;15825.52523424299;11710.53157873684;12375.690607734789;13654.385552650154;14691;14776.926115369462;10123.949380253125;10569.841452378221;11236;11331;11640.476178571984;4316.848910288125;4507.323901414781;4532.7280363178315;4564.657650676179;4582.1064892346;87509.24981500398;108556;111107.99999999919;112371;115253.74581923103;10660.41367724767;10670.359778413165;10689.518971646294;10729.22967710319;10965.725856853642;21053.578928421346;21091.261805837028;21099.945002749802;21116.78883211179;21142;7401.703931842773;7452.366548843409;7562.584057876759;7654.464187506821;7705.537667739954;3448.8448019839166;3469.7224222062173;3474;3482;3599.2441587266444;3412.7611067225057;3494.5631796025496;3497.720182385403;3521.894343169709;3526.523919270917;580.7909152705023;606.8543549548165;615.5875563372462;628.7811167311298;642.9517786165962;480.4930798008135;563.7512908906791;565;583.6585597425553;593.430306905367;6927.93072069283;8391.454555453876;8503.489790612583;8512.70205542803;8654.956725216334;550.5925615044918;561.2787567975132;566.8973845869826;588.9028310328748;601.2004034634014;3799.1831756172555;3995.6004399560393;4009.699272554512;4143.6118900168785;4176.937345939813;123681.38159309326;127160;138371;139313;141343;110351;111768;120245.39877300558;124174;125151.23941324202;102676.97323026824;105260;105627;106091.93908060821;109471.45264273627;9527.190188833749;9672.903270967201;9770.75573110678;9856.21150307988;9980.978179681359;48543.3301168425;52835.735821320646;61013;61027.69486152541;63012.684936575024;72577.27422725705;77095;89108.55445722729;90879.54560227157;92042;12955;13099.541516046893;13214.669633259246;13305.46778128864;13523.932380338036;14430.567082987527;14542.781858271923;14753.188574628499;14759.188244646648;15432.691346173238;13520.59438216855;13538.390772415069;13545.32273386307;13602.25187614651;14364.640883977567;13695.178289302674;13697.657558561115;13881.79177312321;14117.870570354142;14331.641708957359;12973;13267.46930122823;13441;13558.322083895568;13591.116577422436;10380.844287335847;10405.58377664885;10541.841872372073;10692;10733.570657173937;8688.478691278544;10008.899911000797;10156.847647285149;10656.62701805433;10668.679939601825 21:10:33 INFO - PROCESS | 1925 | __end_tp_report 21:10:33 INFO - PROCESS | 1925 | __start_cc_report 21:10:33 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,367 21:10:33 INFO - PROCESS | 1925 | __end_cc_report 21:10:33 INFO - PROCESS | 1925 | __startTimestamp1476936633096__endTimestamp 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | ------- Summary: start ------- 21:10:33 INFO - PROCESS | 1925 | Number of tests: 6 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5535.28 Median:5739.08 stddev:657.70 (11.5%) stddev-sans-first:635.83 21:10:33 INFO - PROCESS | 1925 | Values: 3296.5 3322.3 3322.4 3343.4 3353.8 4366.6 5438.6 5468.0 5472.6 5528.7 5498.7 5505.4 5509.4 5601.5 5609.5 5013.1 5040.0 5073.6 5145.2 5178.1 4162.0 5110.4 5175.2 5199.9 5206.6 5018.0 5076.8 5120.1 5123.6 5154.2 5004.3 5021.7 5037.0 5074.9 5100.2 4994.4 5020.2 5043.7 5065.6 5122.9 4588.0 4589.5 5361.0 5579.8 5603.9 4681.3 4969.9 6045.5 6103.5 6106.5 5065.5 5262.4 6113.1 6174.3 6226.8 4625.2 5578.7 5594.0 5644.3 5646.8 4639.9 5909.3 6130.6 6177.6 6250.7 5973.7 5975.5 5994.9 5995.7 6000.8 6009.1 6041.8 6139.9 6148.3 6150.6 6014.5 6052.1 6054.6 6078.7 6088.1 5286.6 6075.6 6107.0 6107.1 6107.6 4611.7 4723.7 4778.4 4810.6 4824.1 4311.3 4317.3 4321.8 4336.8 4372.1 5396.1 5770.5 5775.5 5780.2 5792.7 5990.6 6161.0 6165.0 6180.3 6186.6 5595.4 5615.5 5647.5 5649.2 5739.1 5247.6 5251.7 5253.9 5254.1 5265.8 6217.3 6231.2 6256.4 6264.4 6272.7 5997.2 6013.2 6064.0 6076.7 6108.6 6203.8 6212.4 6240.8 6241.6 6249.8 5868.1 5916.7 5959.0 5963.6 6026.8 5972.0 6009.9 6094.1 6121.4 6189.8 5978.7 5982.0 5995.1 6009.5 6021.3 5792.5 5918.3 5947.2 6013.9 6023.3 5156.6 5257.3 5686.6 5758.3 5782.4 5875.6 5878.6 5890.0 5906.1 5907.6 5713.1 5725.9 5731.5 5740.3 5741.7 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:70200.02 Median:41771.98 stddev:151609.80 (362.9%) stddev-sans-first:151944.73 21:10:33 INFO - PROCESS | 1925 | Values: 479.5 500.5 512.5 529.0 531.2 31697.7 41783.0 41811.4 41903.6 41992.8 42321.8 42854.0 42881.8 42947.4 42993.8 9508.6 9675.5 9692.2 9751.4 9775.3 8478.4 9569.1 9612.6 9621.0 9652.6 9584.9 9645.3 9649.0 9650.2 9695.1 9619.4 9630.7 9633.1 9635.7 9656.0 9466.9 9493.1 9530.6 9549.3 9610.9 40132.2 40562.0 40566.8 40581.6 40621.2 3188.6 3200.0 3209.5 3283.6 3294.9 31183.0 31576.7 31661.0 31839.5 32361.0 3027.0 3058.7 3062.2 3064.8 3142.6 485923.0 496520.0 496636.0 496747.0 497808.0 50502.5 61317.7 61572.0 61709.8 61796.7 54113.7 63955.0 64408.4 64567.7 64608.4 15987.9 16225.8 16227.5 16238.8 16268.8 12456.3 12547.9 12586.7 12609.5 12664.7 16381.3 16710.0 16844.8 16879.5 16909.6 7273.0 7315.6 7364.9 7376.1 7422.7 763.6 853.0 959.9 1017.2 1043.9 28972.7 29738.4 29775.7 29873.6 30621.0 965.2 982.5 1004.2 1029.4 1058.0 14260.0 15323.4 15596.8 15672.5 15780.5 620256.0 620549.0 623899.0 627094.0 634235.0 50955.0 58714.4 59329.4 59421.1 59735.7 1202.7 1325.7 1371.8 1372.2 1383.4 26411.0 28182.9 29750.6 29799.9 29867.4 47381.0 56221.7 57675.7 60830.4 62284.0 571737.0 574866.0 574925.0 576631.1 580825.0 26214.3 26346.1 26426.7 26726.9 26779.7 45282.8 48641.8 48969.0 49846.8 49938.8 33044.0 39646.6 41452.0 41458.5 41761.0 39993.6 40279.0 40411.0 40885.8 41599.0 38317.8 39298.4 39339.6 39454.8 39458.0 33760.3 34965.5 35620.6 35958.6 36045.6 25814.0 25825.5 25970.5 26155.0 26571.2 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:33910.82 Median:2082.07 stddev:94661.51 (4546.5%) stddev-sans-first:94893.56 21:10:33 INFO - PROCESS | 1925 | Values: 369.6 370.1 374.2 378.6 397.3 19155.3 20154.2 20359.2 20776.8 21011.6 19693.4 19855.1 20321.9 20481.3 20857.2 5622.6 5642.7 5889.2 5905.0 5939.3 4084.4 4309.4 5819.4 5932.1 5946.8 6152.4 6473.3 6532.0 6591.5 6593.4 6318.9 6499.7 6541.4 6553.3 6561.9 5539.4 5573.7 5606.9 5660.2 5694.9 24983.0 25330.9 25380.9 25384.0 25445.2 2116.2 2129.3 2156.7 2195.3 2313.5 15111.8 15375.5 15396.6 15748.2 15911.7 1719.6 1761.9 1762.1 1769.9 1783.8 109505.0 114870.0 114907.3 115542.4 115659.0 9014.9 9550.5 9657.8 9670.9 10588.0 12262.9 12307.3 12322.3 12329.9 12341.8 7859.7 7876.8 8079.2 8081.8 8111.1 7359.1 7965.2 8369.5 8539.3 8559.4 3249.6 3311.2 3579.9 3779.0 3785.5 1592.4 1646.7 1658.1 1959.9 1972.4 717.8 884.5 922.8 935.6 955.2 19309.6 20684.0 21452.0 21554.6 21662.4 891.8 918.3 925.3 947.9 995.0 9751.5 11110.9 11262.0 11278.1 11301.5 540155.0 544918.0 568374.0 582604.0 587139.0 126712.0 127773.4 130710.0 131028.5 132058.4 944.3 951.3 988.3 991.9 993.7 18726.3 18772.7 18849.3 18933.3 19017.1 54595.9 54736.0 55016.0 55117.4 55498.4 77467.0 79233.0 79253.0 79747.4 81481.0 6905.7 6953.0 7197.7 7347.9 7372.0 11069.4 11815.4 11851.6 12139.5 12161.0 8038.3 8583.0 9789.9 10035.2 10044.8 10124.4 10385.7 10529.4 10537.3 11017.9 9963.7 10074.3 10569.7 10804.9 10990.7 5601.7 6718.6 7002.8 7044.0 7182.1 7763.6 9748.0 9938.5 10551.6 10666.3 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5754.32 Median:4766.61 stddev:3233.02 (67.8%) stddev-sans-first:3226.38 21:10:33 INFO - PROCESS | 1925 | Values: 1513.5 1567.8 1773.4 1866.5 1894.4 8133.9 8391.4 8582.9 9238.3 9253.4 7723.5 8596.4 8839.7 8982.3 8995.7 2394.0 2415.6 2421.7 2428.1 2434.2 1994.7 2374.7 2424.3 2433.5 2438.2 2404.5 2405.4 2418.0 2433.1 2433.7 2383.6 2391.2 2401.7 2409.2 2409.8 2374.5 2379.0 2379.2 2381.9 2384.1 9042.7 9080.4 9091.8 9091.8 9129.1 5319.7 5406.3 5410.5 5497.3 5501.7 3566.2 3612.9 3632.6 3676.5 3715.5 927.4 1042.8 1043.6 1052.6 1092.6 10852.2 11417.8 11734.9 11823.0 11825.8 4717.4 4735.8 4746.8 4847.5 4892.1 10105.5 10118.7 10145.3 10190.8 10299.5 5406.2 5443.2 5454.9 5466.9 5469.1 4251.3 4446.6 4613.3 4786.4 4933.0 3930.1 4005.2 4052.3 4059.8 4100.9 1302.9 1309.3 1315.8 1319.4 1325.1 632.9 805.7 902.5 905.7 928.9 5798.2 6000.2 6027.4 6136.7 6215.8 837.6 926.9 927.9 961.1 1008.7 3324.0 3348.4 3617.9 3968.7 4096.0 10484.7 10595.9 10778.2 10998.2 11001.1 10699.9 10881.0 10980.9 10995.9 11042.7 8220.9 8301.5 8346.5 8352.7 8361.3 5801.5 5866.4 5941.9 5954.4 5996.4 8552.8 10167.5 10198.9 10213.6 10216.7 9834.3 10400.4 10401.6 10451.7 10485.0 6559.8 6754.3 6924.3 7125.9 7157.5 8277.1 8581.7 8596.8 8607.1 8612.3 7363.9 7535.8 7631.7 7700.5 7824.5 7276.6 7470.7 7492.1 7584.6 7625.1 6889.7 7072.1 7080.5 7084.9 7089.8 4973.1 4973.1 5084.7 5174.2 5252.1 4107.5 4216.9 5012.3 5151.3 5536.5 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4346.02 Median:4751.16 stddev:1342.48 (28.3%) stddev-sans-first:1311.47 21:10:33 INFO - PROCESS | 1925 | Values: 301.2 303.1 309.1 312.6 315.6 4445.0 4796.5 5034.5 5112.0 5294.0 4690.9 4830.6 4900.1 5051.7 5200.0 3553.4 4041.6 4089.3 4100.4 4124.8 3407.6 3702.9 4083.3 4119.4 4128.8 3931.4 3982.4 3986.4 3993.3 4004.8 3966.3 3976.8 3989.6 3992.0 4001.9 3485.6 3487.6 3490.9 3492.7 3514.7 4614.0 4786.8 5118.1 5124.1 5161.4 4525.2 4529.1 4529.8 4553.8 4583.4 4586.1 5233.8 5234.9 5503.7 5559.2 3339.5 3361.9 3529.2 3725.3 3765.5 5605.2 5625.0 5625.5 5632.3 5657.4 3283.4 3691.7 3728.3 3800.0 3809.2 3845.3 3854.1 3860.4 3870.3 3876.8 3977.4 4178.6 4390.9 4496.9 4519.8 5057.1 5554.3 5574.4 5589.0 5592.8 1127.1 1137.1 1167.4 1168.9 1278.3 692.1 742.2 860.2 885.1 896.9 5235.7 5247.2 5273.7 5283.9 5306.5 4798.4 5626.7 5655.0 5677.9 5688.5 4609.7 4753.7 5217.8 5246.9 5265.9 2036.3 2210.8 2262.8 2400.2 2505.0 4454.6 4462.6 4672.8 4826.3 4994.9 4351.9 4388.3 4627.9 4692.3 4868.2 4332.7 4854.9 4901.6 4908.4 4946.6 4045.4 4634.0 4748.6 4772.8 4790.5 4217.3 4722.3 4734.5 4735.2 4762.5 4788.7 5728.3 5793.4 5921.9 5997.4 3929.4 3982.5 3993.0 4031.5 4038.9 5065.3 5293.4 5456.3 5784.2 5806.2 5384.2 5555.6 5570.2 5629.4 5641.9 5578.5 5584.8 5593.2 5612.8 5631.1 4643.3 5271.2 5290.6 5473.8 5480.5 4975.0 5536.9 5570.1 5582.5 5584.2 5177.3 5470.6 5477.6 5512.5 5535.2 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:23510.15 Median:3471.86 stddev:36769.11 (1059.1%) stddev-sans-first:36832.19 21:10:33 INFO - PROCESS | 1925 | Values: 648.7 660.1 673.6 684.0 729.4 15017.8 16007.8 16190.0 16217.2 16253.3 14662.0 14743.9 14769.5 14783.9 14809.8 697.8 717.1 872.7 884.9 894.2 906.9 912.3 921.1 921.5 924.3 965.7 971.3 979.7 979.8 980.0 929.3 966.7 972.0 973.1 975.2 585.2 611.8 709.7 722.7 723.2 13277.4 15728.1 15746.3 15815.4 15825.5 11710.5 12375.7 13654.4 14691.0 14776.9 10123.9 10569.8 11236.0 11331.0 11640.5 4316.8 4507.3 4532.7 4564.7 4582.1 87509.2 108556.0 111108.0 112371.0 115253.7 10660.4 10670.4 10689.5 10729.2 10965.7 21053.6 21091.3 21099.9 21116.8 21142.0 7401.7 7452.4 7562.6 7654.5 7705.5 3448.8 3469.7 3474.0 3482.0 3599.2 3412.8 3494.6 3497.7 3521.9 3526.5 580.8 606.9 615.6 628.8 643.0 480.5 563.8 565.0 583.7 593.4 6927.9 8391.5 8503.5 8512.7 8655.0 550.6 561.3 566.9 588.9 601.2 3799.2 3995.6 4009.7 4143.6 4176.9 123681.4 127160.0 138371.0 139313.0 141343.0 110351.0 111768.0 120245.4 124174.0 125151.2 102677.0 105260.0 105627.0 106091.9 109471.5 9527.2 9672.9 9770.8 9856.2 9981.0 48543.3 52835.7 61013.0 61027.7 63012.7 72577.3 77095.0 89108.6 90879.5 92042.0 12955.0 13099.5 13214.7 13305.5 13523.9 14430.6 14542.8 14753.2 14759.2 15432.7 13520.6 13538.4 13545.3 13602.3 14364.6 13695.2 13697.7 13881.8 14117.9 14331.6 12973.0 13267.5 13441.0 13558.3 13591.1 10380.8 10405.6 10541.8 10692.0 10733.6 8688.5 10008.9 10156.8 10656.6 10668.7 21:10:33 INFO - PROCESS | 1925 | -------- Summary: end -------- 21:10:33 INFO - PROCESS | 1925 | 21:10:33 INFO - TEST-INFO | 1925: exit 0 21:10:33 INFO - TEST-OK | dromaeo_css | took 1115056ms 21:10:33 INFO - TEST-START | kraken 21:10:33 INFO - operating with platform_type : mac_ 21:10:33 INFO - Initialising browser for kraken test... 21:10:33 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpFcwQav/profile http://localhost:49436/getInfo.html) 21:10:36 INFO - PROCESS | 1930 | 2016-10-19 21:10:36.428 plugin-container[1931:16559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 21:10:36 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:10:36 INFO - PROCESS | 1930 | 2016-10-19 21:10:36.433 plugin-container[1931:16559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 21:10:36 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:10:36 INFO - PROCESS | 1930 | 2016-10-19 21:10:36.433 plugin-container[1931:16559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:10:36 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 21:10:36 INFO - PROCESS | 1930 | colorDepth:24 21:10:36 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 21:10:36 INFO - PROCESS | 1930 | __metrics 21:10:36 ERROR - PROCESS | 1930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 21:10:37 INFO - TEST-INFO | 1930: exit 0 21:10:37 INFO - Browser initialized. 21:10:37 INFO - Running cycle 1/1 for kraken test... 21:10:37 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpFcwQav/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:10:39 INFO - PROCESS | 1932 | 2016-10-19 21:10:39.636 plugin-container[1933:16675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 21:10:39 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:10:39 INFO - PROCESS | 1932 | 2016-10-19 21:10:39.636 plugin-container[1933:16675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 21:10:39 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:10:39 INFO - PROCESS | 1932 | 2016-10-19 21:10:39.636 plugin-container[1933:16675] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:10:49 INFO - PROCESS | 1932 | RSS: Main: 237531136 21:10:49 INFO - PROCESS | 1932 | 21:10:52 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49436/tests/kraken/driver.html?testName=audio-beat-detection) 21:10:53 INFO - PROCESS | 1932 | RSS: Main: 249933824 21:10:53 INFO - PROCESS | 1932 | 21:10:55 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49436/tests/kraken/driver.html?testName=audio-dft) 21:10:56 INFO - PROCESS | 1932 | RSS: Main: 258711552 21:10:56 INFO - PROCESS | 1932 | 21:11:00 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49436/tests/kraken/driver.html?testName=audio-fft) 21:11:00 INFO - PROCESS | 1932 | RSS: Main: 260755456 21:11:00 INFO - PROCESS | 1932 | 21:11:02 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49436/tests/kraken/driver.html?testName=audio-oscillator) 21:11:03 INFO - PROCESS | 1932 | RSS: Main: 262230016 21:11:03 INFO - PROCESS | 1932 | 21:11:05 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49436/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:11:05 INFO - PROCESS | 1932 | RSS: Main: 263180288 21:11:05 INFO - PROCESS | 1932 | 21:11:08 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49436/tests/kraken/driver.html?testName=imaging-darkroom) 21:11:09 INFO - PROCESS | 1932 | RSS: Main: 263667712 21:11:09 INFO - PROCESS | 1932 | 21:11:12 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49436/tests/kraken/driver.html?testName=imaging-desaturate) 21:11:12 INFO - PROCESS | 1932 | RSS: Main: 263774208 21:11:12 INFO - PROCESS | 1932 | 21:11:15 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49436/tests/kraken/driver.html?testName=json-parse-financial) 21:11:16 INFO - PROCESS | 1932 | RSS: Main: 264138752 21:11:16 INFO - PROCESS | 1932 | 21:11:18 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49436/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:11:18 INFO - PROCESS | 1932 | RSS: Main: 263802880 21:11:18 INFO - PROCESS | 1932 | 21:11:20 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:11:21 INFO - PROCESS | 1932 | RSS: Main: 263745536 21:11:21 INFO - PROCESS | 1932 | 21:11:23 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:11:24 INFO - PROCESS | 1932 | RSS: Main: 263782400 21:11:24 INFO - PROCESS | 1932 | 21:11:27 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:11:28 INFO - PROCESS | 1932 | RSS: Main: 264142848 21:11:28 INFO - PROCESS | 1932 | 21:11:31 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:11:31 INFO - PROCESS | 1932 | RSS: Main: 264146944 21:11:31 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49436/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49436/tests/kraken/driver.html?testName=ai-astar) 21:11:33 INFO - PROCESS | 1932 | RSS: Main: 265588736 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | __start_tp_report 21:11:33 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 21:11:33 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 21:11:33 INFO - PROCESS | 1932 | |i|pagename|runs| 21:11:33 INFO - PROCESS | 1932 | |0;ai-astar;105;132;173;170;119;117;119;117;121;118 21:11:33 INFO - PROCESS | 1932 | |1;audio-beat-detection;148;114;117;180;123;118;117;115;145;115 21:11:33 INFO - PROCESS | 1932 | |2;audio-dft;205;207;262;271;196;211;195;198;196;197 21:11:33 INFO - PROCESS | 1932 | |3;audio-fft;81;59;58;84;61;61;60;59;79;60 21:11:33 INFO - PROCESS | 1932 | |4;audio-oscillator;84;71;86;72;68;69;87;69;70;86 21:11:33 INFO - PROCESS | 1932 | |5;imaging-gaussian-blur;89;88;91;88;91;86;91;95;119;86 21:11:33 INFO - PROCESS | 1932 | |6;imaging-darkroom;145;112;111;101;101;100;101;105;169;169 21:11:33 INFO - PROCESS | 1932 | |7;imaging-desaturate;90;93;88;87;93;134;147;116;88;109 21:11:33 INFO - PROCESS | 1932 | |8;json-parse-financial;56;58;57;56;57;63;68;85;84;88 21:11:33 INFO - PROCESS | 1932 | |9;json-stringify-tinderbox;69;77;73;78;75;76;61;88;101;102 21:11:33 INFO - PROCESS | 1932 | |10;stanford-crypto-aes;93;88;87;108;86;90;89;88;86;95 21:11:33 INFO - PROCESS | 1932 | |11;stanford-crypto-ccm;148;149;147;159;150;182;160;159;208;153 21:11:33 INFO - PROCESS | 1932 | |12;stanford-crypto-pbkdf2;175;177;174;176;162;215;234;238;172;174 21:11:33 INFO - PROCESS | 1932 | |13;stanford-crypto-sha256-iterative;71;71;70;73;69;69;81;90;89;74 21:11:33 INFO - PROCESS | 1932 | __end_tp_report 21:11:33 INFO - PROCESS | 1932 | __start_cc_report 21:11:33 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,931 21:11:33 INFO - PROCESS | 1932 | __end_cc_report 21:11:33 INFO - PROCESS | 1932 | __startTimestamp1476936693782__endTimestamp 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | ------- Summary: start ------- 21:11:33 INFO - PROCESS | 1932 | Number of tests: 14 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#0] ai-astar Cycles:10 Average:129.10 Median:120.00 stddev:23.27 (19.4%) stddev-sans-first:22.99 21:11:33 INFO - PROCESS | 1932 | Values: 105.0 132.0 173.0 170.0 119.0 117.0 119.0 117.0 121.0 118.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:120.50 stddev:21.81 (18.1%) stddev-sans-first:22.04 21:11:33 INFO - PROCESS | 1932 | Values: 148.0 114.0 117.0 180.0 123.0 118.0 117.0 115.0 145.0 115.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#2] audio-dft Cycles:10 Average:213.80 Median:206.00 stddev:28.37 (13.8%) stddev-sans-first:29.92 21:11:33 INFO - PROCESS | 1932 | Values: 205.0 207.0 262.0 271.0 196.0 211.0 195.0 198.0 196.0 197.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.00 stddev:10.55 (17.3%) stddev-sans-first:9.74 21:11:33 INFO - PROCESS | 1932 | Values: 81.0 59.0 58.0 84.0 61.0 61.0 60.0 59.0 79.0 60.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:78.00 stddev:8.32 (10.7%) stddev-sans-first:8.34 21:11:33 INFO - PROCESS | 1932 | Values: 84.0 71.0 86.0 72.0 68.0 69.0 87.0 69.0 70.0 86.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:90.00 stddev:9.73 (10.8%) stddev-sans-first:10.24 21:11:33 INFO - PROCESS | 1932 | Values: 89.0 88.0 91.0 88.0 91.0 86.0 91.0 95.0 119.0 86.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#6] imaging-darkroom Cycles:10 Average:121.40 Median:111.50 stddev:28.40 (25.5%) stddev-sans-first:28.81 21:11:33 INFO - PROCESS | 1932 | Values: 145.0 112.0 111.0 101.0 101.0 100.0 101.0 105.0 169.0 169.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:88.00 stddev:21.49 (24.4%) stddev-sans-first:22.14 21:11:33 INFO - PROCESS | 1932 | Values: 90.0 93.0 88.0 87.0 93.0 134.0 147.0 116.0 88.0 109.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:65.50 stddev:13.31 (20.3%) stddev-sans-first:13.48 21:11:33 INFO - PROCESS | 1932 | Values: 56.0 58.0 57.0 56.0 57.0 63.0 68.0 85.0 84.0 88.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:80.00 Median:75.50 stddev:13.22 (17.5%) stddev-sans-first:13.41 21:11:33 INFO - PROCESS | 1932 | Values: 69.0 77.0 73.0 78.0 75.0 76.0 61.0 88.0 101.0 102.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:88.50 stddev:6.65 (7.5%) stddev-sans-first:7.01 21:11:33 INFO - PROCESS | 1932 | Values: 93.0 88.0 87.0 108.0 86.0 90.0 89.0 88.0 86.0 95.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:161.50 Median:159.00 stddev:19.29 (12.1%) stddev-sans-first:19.84 21:11:33 INFO - PROCESS | 1932 | Values: 148.0 149.0 147.0 159.0 150.0 182.0 160.0 159.0 208.0 153.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.70 Median:176.50 stddev:28.04 (15.9%) stddev-sans-first:29.23 21:11:33 INFO - PROCESS | 1932 | Values: 175.0 177.0 174.0 176.0 162.0 215.0 234.0 238.0 172.0 174.0 21:11:33 INFO - PROCESS | 1932 | 21:11:33 INFO - PROCESS | 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:73.50 stddev:8.07 (11.0%) stddev-sans-first:8.38 21:11:33 INFO - PROCESS | 1932 | Values: 71.0 71.0 70.0 73.0 69.0 69.0 81.0 90.0 89.0 74.0 21:11:33 INFO - PROCESS | 1932 | -------- Summary: end -------- 21:11:33 INFO - PROCESS | 1932 | 21:11:34 INFO - TEST-INFO | 1932: exit 0 21:11:34 INFO - TEST-OK | kraken | took 60918ms 21:11:34 INFO - SUITE-END | took 1175s 21:11:34 INFO - Completed test suite (00:19:36) 21:11:34 INFO - javascript benchmark 21:11:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7193.12808334852, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.472564389697, 3322.252493189032, 3322.4185767490685, 3343.3981883261004, 3353.7820041697287, 4366.607005369524, 5438.592105592076, 5467.972660136698, 5472.589555783317, 5528.723563821808, 5498.697571633558, 5505.394406615269, 5509.449055094488, 5601.495865372116, 5609.467100625444, 5013.147764879979, 5040.0, 5073.644844860862, 5145.202493613478, 5178.14560597501, 4161.979190104061, 5110.386753589574, 5175.24958880962, 5199.922001169985, 5206.609504287175, 5017.974910125426, 5076.771545280471, 5120.078385890538, 5123.564497017758, 5154.201098829669, 5004.299398084235, 5021.69869807809, 5036.967421678583, 5074.873128171788, 5100.183970564729, 4994.375703037121, 5020.171671674169, 5043.672161309503, 5065.569426598743, 5122.897542049138, 4588.0, 4589.5410458954175, 5361.0, 5579.804706835282, 5603.859903502404, 4681.297805329194, 4969.925451118236, 6045.486133678643, 6103.450689437972, 6106.48094911933, 5065.544101030925, 5262.447443018505, 6113.083037544359, 6174.320824709278, 6226.750929962796, 4625.167469855391, 5578.721063946868, 5594.0, 5644.322681278273, 5646.802361917313, 4639.930401043987, 5909.2908850938165, 6130.601510901777, 6177.598456100339, 6250.656213908188, 5973.701314934236, 5975.462208401178, 5994.910076348859, 5995.670238136857, 6000.759969601167, 6009.06859436788, 6041.818745437644, 6139.938600614026, 6148.323684394713, 6150.569460137837, 6014.48876845473, 6052.092186172109, 6054.636721796706, 6078.696065196811, 6088.086786981899, 5286.550643195372, 6075.605085669506, 6106.969465152646, 6107.144999700045, 6107.633541987495, 4611.746353950565, 4723.716577005391, 4778.426588809365, 4810.59109975656, 4824.107540105022, 4311.288637374861, 4317.266064768997, 4321.783910804447, 4336.804843782037, 4372.147431250875, 5396.1366181410785, 5770.5095066919785, 5775.48020678141, 5780.1907732917425, 5792.739326730307, 5990.64056156632, 6160.969195153995, 6165.0, 6180.289266734383, 6186.628802271878, 5595.38450770415, 5615.494605485526, 5647.491725744702, 5649.237352957381, 5739.081746920473, 5247.606429517725, 5251.737413129328, 5253.894922101613, 5254.133068043807, 5265.76304066318, 6217.347178546368, 6231.158793562901, 6256.43692067707, 6264.404881536338, 6272.749090036347, 5997.220361352996, 6013.158157857816, 6064.0, 6076.665783381956, 6108.633481990917, 6203.751849925952, 6212.378762123933, 6240.781572644935, 6241.594296370721, 6249.781257656142, 5868.119782032729, 5916.733746981482, 5959.0, 5963.612365196248, 6026.849328766816, 5972.044472884318, 6009.90985135232, 6094.11635312886, 6121.4490695837585, 6189.81430557084, 5978.671173085522, 5982.042873140276, 5995.070764031582, 6009.549283803644, 6021.277446706424, 5792.536597072309, 5918.319393269829, 5947.197128387612, 6013.9097913532205, 6023.30731965812, 5156.61325400604, 5257.2639830423, 5686.60193786431, 5758.309002919509, 5782.3639399666845, 5875.618084824473, 5878.647281163144, 5890.0, 5906.055031194988, 5907.616004959664, 5713.057345538106, 5725.914111288251, 5731.455511726379, 5740.253767010262, 5741.684207368635], "value": 5496.405854527515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.4773696670628, 500.54200406627893, 512.4567957964558, 528.9656172348798, 531.1501597444089, 31697.68302316976, 41782.955426114364, 41811.37282940755, 41903.58096419035, 41992.79003604982, 42321.78839105808, 42854.0, 42881.785591072, 42947.35578966318, 42993.785031074876, 9508.572114254841, 9675.516224188797, 9692.224622030257, 9751.366161199534, 9775.289324368243, 8478.406511544194, 9569.138777510023, 9612.615495380176, 9621.037896210393, 9652.565634546427, 9584.856227156562, 9645.276604254674, 9648.986856380085, 9650.179734722557, 9695.078967498112, 9619.374740641872, 9630.711078667651, 9633.133018028377, 9635.710928672152, 9655.951720241424, 9466.90533094674, 9493.05069493052, 9530.618775248982, 9549.283803714749, 9610.855837162448, 40132.19735605301, 40561.985950351474, 40566.79716601398, 40581.59418405808, 40621.1875762486, 3188.58548388708, 3200.0399880035898, 3209.502527108302, 3283.6059672839174, 3294.8682052717863, 31183.0, 31576.684233157604, 31661.0, 31839.52240716391, 32361.029169124962, 3027.0022928821295, 3058.663547009827, 3062.2038270049948, 3064.8161110333454, 3142.5757522734366, 485923.0, 496520.0, 496636.0, 496747.0, 497808.0, 50502.49497505051, 61317.69341153266, 61572.0, 61709.76580468365, 61796.69101654463, 54113.72943135259, 63955.0, 64408.35591644023, 64567.70864582682, 64608.35391646117, 15987.920060399623, 16225.756613650805, 16227.513174604781, 16238.837611623732, 16268.837311626969, 12456.31490268044, 12547.874521254853, 12586.748265034648, 12609.495620175274, 12664.6833829155, 16381.344746210256, 16709.992201403864, 16844.831551684572, 16879.493615191564, 16909.577260568338, 7272.96363518179, 7315.63421828917, 7364.852702946018, 7376.114866216089, 7422.740204092939, 763.5952944939191, 852.9914700852912, 959.932804703664, 1017.2319898476603, 1043.8538604595362, 28972.710272897002, 29738.40523189524, 29775.702242977728, 29873.551896721565, 30621.0, 965.2471072563412, 982.4596471940404, 1004.2167109654335, 1029.4286670897723, 1058.0266155137292, 14260.0, 15323.387064517294, 15596.766048509282, 15672.529824105295, 15780.526584202493, 620256.0, 620549.0, 623899.0, 627094.0, 634235.0, 50955.0, 58714.4128558709, 59329.40670593325, 59421.108683369785, 59735.70132149399, 1202.6572426858304, 1325.6752095736413, 1371.7873729571734, 1372.1972646002, 1383.4397069186793, 26411.019173561675, 28182.85908570444, 29750.553741694326, 29799.851000744857, 29867.402651947275, 47381.0, 56221.71889140528, 57675.711621441624, 60830.39169608247, 62284.0, 571737.0, 574866.0, 574925.0, 576631.1168444131, 580825.0, 26214.344641383355, 26346.077887273852, 26426.735732643196, 26726.866365668047, 26779.732202677726, 45282.77358613186, 48641.75679121582, 48969.0206195867, 49846.753831154514, 49938.750306248236, 33044.00867973964, 39646.60353396545, 41452.0, 41458.47131410806, 41761.0, 39993.60006399899, 40279.0, 40410.98972525593, 40885.795571021954, 41599.0, 38317.80841095777, 39298.410523841594, 39339.60660393474, 39454.802725986185, 39458.0, 33760.324793504486, 34965.47551786776, 35620.64379356277, 35958.64041359553, 36045.63954360423, 25814.0, 25825.483490329712, 25970.48059038771, 26154.953801848475, 26571.202863914114], "value": 19916.90808628295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.6248307967414, 370.1061935425946, 374.2197518174605, 378.61192277915114, 397.30669980883346, 19155.329563465282, 20154.193832246692, 20359.1856325747, 20776.792232077674, 21011.579768404623, 19693.409197724057, 19855.106520206624, 20321.898390508064, 20481.283155089575, 20857.165713371483, 5622.550195984311, 5642.689652069134, 5889.234399528077, 5905.0, 5939.287285525721, 4084.4281800547947, 4309.396684464177, 5819.388964158766, 5932.08052751822, 5946.791862284821, 6152.384761523835, 6473.320301368359, 6531.987541930985, 6591.472682185414, 6593.406593406592, 6318.862604731146, 6499.675016249193, 6541.378569035982, 6553.3446655334565, 6561.901571476432, 5539.362973258087, 5573.721313934287, 5606.943930560683, 5660.2075709400715, 5694.914576281359, 24983.0, 25330.87334563334, 25380.87309563459, 25384.0, 25445.23664290074, 2116.1958455786857, 2129.2547608337113, 2156.676498525234, 2195.297504798465, 2313.4972184484786, 15111.773323400157, 15375.461858834999, 15396.615084622974, 15748.212589370485, 15911.681766364607, 1719.5958949646822, 1761.9207135678796, 1762.0572993448443, 1769.9380521681808, 1783.8216178382188, 109505.0, 114870.0, 114907.27639085421, 115542.42228788887, 115659.0, 9014.909850901538, 9550.474723890253, 9657.806843863083, 9670.90329096714, 10588.047075763217, 12262.938685306517, 12307.323097229739, 12322.260664360167, 12329.876701233052, 12341.814872776915, 7859.72490962825, 7876.803079923047, 8079.232472915063, 8081.757547273592, 8111.148329425326, 7359.080114985627, 7965.163657815961, 8369.49783013021, 8539.316854651614, 8559.400841941004, 3249.5938007749032, 3311.2218628622254, 3579.9260221933314, 3778.981105094457, 3785.5268091488565, 1592.3869310315613, 1646.7118254305449, 1658.1377683604458, 1959.8824070555813, 1972.3589833304234, 717.8156042529763, 884.478157886837, 922.8246633139682, 935.5836652689624, 955.19763398744, 19309.613807723767, 20684.0, 21452.034658440407, 21554.56890862174, 21662.350129495826, 891.7859713668673, 918.2535256154872, 925.250547056886, 947.9194268487256, 994.9701508954743, 9751.512424378752, 11110.889090927218, 11262.0, 11278.09775217997, 11301.49143288554, 540155.0, 544918.0, 568374.0, 582604.0, 587139.0, 126712.0, 127773.3611331956, 130710.0, 131028.51771858419, 132058.3588328228, 944.3248077624394, 951.3245595627183, 988.2736188901152, 991.9355241909253, 993.7131414817828, 18726.344577939708, 18772.7184092236, 18849.340273090376, 18933.33733319327, 19017.144228509474, 54595.90808183735, 54736.0, 55016.0, 55117.44882551123, 55498.44501555094, 77467.0, 79233.0, 79253.0, 79747.4050518998, 81481.0, 6905.654717264217, 6952.965235173791, 7197.71209151628, 7347.926520734938, 7372.0, 11069.391183484986, 11815.350155741173, 11851.644450666494, 12139.453724582238, 12161.0, 8038.276555110068, 8583.00890086113, 9789.902100979183, 10035.197184225099, 10044.799104018024, 10124.443155626512, 10385.688429347132, 10529.42088185157, 10537.262391632512, 11017.944910275397, 9963.701088967342, 10074.345167564084, 10569.735756606146, 10804.945975270073, 10990.670279891616, 5601.7396085880355, 6718.596884186964, 7002.809522381105, 7043.964780176067, 7182.060843572311, 7763.573003484863, 9747.951260243653, 9938.503074846374, 10551.577936882746, 10666.253362264877], "value": 9242.93109028432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.4702854001098, 1567.8197007344154, 1773.405909020478, 1866.4867161530585, 1894.393793985925, 8133.8779918301125, 8391.412601117925, 8582.91417085829, 9238.30712696548, 9253.398529095612, 7723.497972631775, 8596.355273354493, 8839.690610828622, 8982.326325525579, 8995.685151019716, 2393.9521209575796, 2415.6376543518436, 2421.7457166997474, 2428.0651948999675, 2434.184548176363, 1994.7107669388004, 2374.6556749271344, 2424.260600516842, 2433.5376278507115, 2438.17102185257, 2404.5431368040017, 2405.446747248125, 2417.987910060447, 2433.1119141513395, 2433.6957880264968, 2383.6305372667266, 2391.2228525729206, 2401.6997875265592, 2409.204962362434, 2409.7831195192343, 2374.4657452073147, 2378.9881050594636, 2379.1791831817995, 2381.892814823337, 2384.070212617081, 9042.728718138467, 9080.40977336471, 9091.772705682344, 9091.818163636757, 9129.087091290885, 5319.707416092114, 5406.270153529263, 5410.5401040911565, 5497.285352904137, 5501.669899806025, 3566.1619519412916, 3612.8554857805657, 3632.6367363263726, 3676.540432445944, 3715.535558055243, 927.420362273579, 1042.8331466965326, 1043.6295115234059, 1052.5684469367523, 1092.63396762084, 10852.186086043499, 11417.771644567061, 11734.882651173464, 11822.995045421063, 11825.763484730258, 4717.410323709536, 4735.83424580138, 4746.836985933994, 4847.491013443609, 4892.143874821892, 10105.5452504636, 10118.696439106838, 10145.34055286404, 10190.847137292947, 10299.485025748683, 5406.189071639207, 5443.237946687467, 5454.890902182014, 5466.9179962300595, 5469.07025805614, 4251.256030194703, 4446.644268458515, 4613.261878099488, 4786.377770889762, 4932.988737308843, 3930.115723962143, 4005.178938317638, 4052.250333688277, 4059.7970101494807, 4100.938485922714, 1302.9348532573333, 1309.3387839141362, 1315.8026296055668, 1319.4458327502473, 1325.1386598710915, 632.8797528469576, 805.6817557065044, 902.4810733828075, 905.6965916417942, 928.9442633442015, 5798.246227990334, 6000.249968753906, 6027.397260274025, 6136.693165341715, 6215.782447614311, 837.6021389839777, 926.9432846554932, 927.9072092790667, 961.077365728908, 1008.6973907827769, 3323.952954819224, 3348.3638108759747, 3617.9276414471565, 3968.722189446769, 4095.9795201023803, 10484.737881553023, 10595.894041059491, 10778.167711489066, 10998.230123891411, 11001.064909482624, 10699.946500267604, 10880.966308200866, 10980.890191097988, 10995.890041099647, 11042.668719938414, 8220.917790822135, 8301.501909885306, 8346.499210047417, 8352.749417517363, 8361.331093512505, 5801.477866991991, 5866.41335866427, 5941.91087133702, 5954.37479064692, 5996.400359963969, 8552.828943421222, 10167.491625418847, 10198.898011020092, 10213.591456341663, 10216.744581385525, 9834.311598188058, 10400.375977441377, 10401.583936642752, 10451.68644940653, 10485.0, 6559.8032059038305, 6754.2570317266045, 6924.3421874921805, 7125.893111603434, 7157.463190260647, 8277.130901255281, 8581.742547723577, 8596.828063438821, 8607.139286071342, 8612.267957223456, 7363.889541656987, 7535.773926782205, 7631.694732210872, 7700.537967721954, 7824.491408058458, 7276.599787011553, 7470.663820127998, 7492.138404083382, 7584.62076896164, 7625.123110842324, 6889.724411023503, 7072.115985501812, 7080.539764915264, 7084.929150708427, 7089.751858684921, 4973.079980203674, 4973.129702302155, 5084.669496482717, 5174.223866419917, 5252.133397989289, 4107.466029416276, 4216.936745948752, 5012.273220383097, 5151.304573882628, 5536.52939500147], "value": 4616.694660910391, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.17928644443884, 303.1421078348273, 309.0666188111902, 312.6154829559642, 315.62598320989645, 4444.97765513932, 4796.520347965202, 5034.496550344964, 5111.9744401277985, 5293.9676763031175, 4690.859274221779, 4830.565249127578, 4900.093482705693, 5051.737065733567, 5199.960007998397, 3553.4492153716214, 4041.636252737253, 4089.284375234322, 4100.425940368336, 4124.8143833527565, 3407.5740532433447, 3702.870399536017, 4083.2650122977852, 4119.393436559744, 4128.772917489537, 3931.4495970564144, 3982.362821948489, 3986.40203969404, 3993.321135406973, 4004.7797371144575, 3966.2860685076676, 3976.840926362942, 3989.5810939851476, 3991.9600803992025, 4001.939970900424, 3485.599156097056, 3487.616362200156, 3490.9301813963834, 3492.6681965213265, 3514.6819942521556, 4614.0, 4786.7845946932475, 5118.057117157612, 5124.052050370693, 5161.4322424533275, 4525.185466616008, 4529.09418116378, 4529.818807247706, 4553.817847286105, 4583.358329833793, 4586.096769840512, 5233.7700640349485, 5234.92147617786, 5503.669779813184, 5559.249501317311, 3339.5491608632765, 3361.9327613447595, 3529.1530032791943, 3725.329440700673, 3765.4540091686827, 5605.243292155547, 5625.0, 5625.46558076982, 5632.324121105494, 5657.434256574351, 3283.441814891474, 3691.667749902522, 3728.3475391806323, 3799.981000094982, 3809.1619843634357, 3845.307844587945, 3854.075021994757, 3860.3823388258306, 3870.283997460479, 3876.8255428505786, 3977.3636218204947, 4178.582141785858, 4390.934135987963, 4496.9325460118125, 4519.841805536857, 5057.106028358144, 5554.277943867352, 5574.414686457944, 5588.972055139699, 5592.832215033555, 1127.0926903842485, 1137.1300954769608, 1167.3540308165445, 1168.9479468478437, 1278.3097127551018, 692.1002696494537, 742.2392048150581, 860.2429861721645, 885.1414128295543, 896.9237614802685, 5235.65968212065, 5247.2451962719115, 5273.736313184402, 5283.894322113536, 5306.548943339782, 4798.424189097331, 5626.718664066781, 5654.971725141348, 5677.886442271131, 5688.488036076773, 4609.72554265095, 4753.667243293005, 5217.808280760667, 5246.898151388251, 5265.894682106336, 2036.2771216218325, 2210.7789221077765, 2262.7624099469317, 2400.2319257837676, 2505.0, 4454.554544545519, 4462.553744625511, 4672.766361681903, 4826.32431459596, 4994.850154495371, 4351.891202719958, 4388.27593447084, 4627.8611641650805, 4692.343071969927, 4868.172410690191, 4332.67504937131, 4854.854354369375, 4901.607871370282, 4908.435529914034, 4946.604271658259, 4045.3705091690636, 4634.0, 4748.643851711066, 4772.785224664968, 4790.520947905182, 4217.2919967413245, 4722.252766476288, 4734.502877197845, 4735.195016847213, 4762.523487786262, 4788.664793464424, 5728.310148506284, 5793.449622285876, 5921.940780592311, 5997.380707209117, 3929.351656976681, 3982.4623675803396, 3992.9600703992587, 4031.536373316989, 4038.939415908705, 5065.290069812632, 5293.364796224478, 5456.263404440349, 5784.248047753933, 5806.158107074535, 5384.19237114436, 5555.638883472561, 5570.220169176399, 5629.437056294338, 5641.915371269516, 5578.497935185853, 5584.776608935597, 5593.216949626974, 5612.775488980558, 5631.099024156116, 4643.349931009594, 5271.156614941591, 5290.603204759582, 5473.8357849264585, 5480.451954804487, 4975.0, 5536.861578460571, 5570.080936645408, 5582.52548533363, 5584.246126772834, 5177.326947496952, 5470.617056805985, 5477.643953143033, 5512.476314750093, 5535.16972454119], "value": 3911.21282325452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.7080813633866, 660.1220376898724, 673.552087861572, 683.9623820689864, 729.3800269770692, 15017.774733378994, 16007.83992160081, 16190.0, 16217.189140542956, 16253.349866005361, 14662.0, 14743.852561474381, 14769.483068092619, 14783.92608036961, 14809.777853332209, 697.8115908704647, 717.0534893940002, 872.7032808844975, 884.9026607073217, 894.1952242981304, 906.9138431848963, 912.3431129586687, 921.1295325917011, 921.5484412637793, 924.3113880159291, 965.715114041356, 971.3103696375582, 979.6718099436697, 979.8383266761011, 979.9559019844124, 929.330881765128, 966.7003228998963, 972.0134063925121, 973.0755782007086, 975.2246963663904, 585.2099665451649, 611.8436155665751, 709.7232079489004, 722.743426083738, 723.2225357740398, 13277.402516886763, 15728.134952577604, 15746.291416886268, 15815.446459373867, 15825.52523424299, 11710.53157873684, 12375.690607734789, 13654.385552650154, 14691.0, 14776.926115369462, 10123.949380253125, 10569.841452378221, 11236.0, 11331.0, 11640.476178571984, 4316.848910288125, 4507.323901414781, 4532.7280363178315, 4564.657650676179, 4582.1064892346, 87509.24981500398, 108556.0, 111107.99999999919, 112371.0, 115253.74581923103, 10660.41367724767, 10670.359778413165, 10689.518971646294, 10729.22967710319, 10965.725856853642, 21053.578928421346, 21091.261805837028, 21099.945002749802, 21116.78883211179, 21142.0, 7401.703931842773, 7452.366548843409, 7562.584057876759, 7654.464187506821, 7705.537667739954, 3448.8448019839166, 3469.7224222062173, 3474.0, 3482.0, 3599.2441587266444, 3412.7611067225057, 3494.5631796025496, 3497.720182385403, 3521.894343169709, 3526.523919270917, 580.7909152705023, 606.8543549548165, 615.5875563372462, 628.7811167311298, 642.9517786165962, 480.4930798008135, 563.7512908906791, 565.0, 583.6585597425553, 593.430306905367, 6927.93072069283, 8391.454555453876, 8503.489790612583, 8512.70205542803, 8654.956725216334, 550.5925615044918, 561.2787567975132, 566.8973845869826, 588.9028310328748, 601.2004034634014, 3799.1831756172555, 3995.6004399560393, 4009.699272554512, 4143.6118900168785, 4176.937345939813, 123681.38159309326, 127160.0, 138371.0, 139313.0, 141343.0, 110351.0, 111768.0, 120245.39877300558, 124174.0, 125151.23941324202, 102676.97323026824, 105260.0, 105627.0, 106091.93908060821, 109471.45264273627, 9527.190188833749, 9672.903270967201, 9770.75573110678, 9856.21150307988, 9980.978179681359, 48543.3301168425, 52835.735821320646, 61013.0, 61027.69486152541, 63012.684936575024, 72577.27422725705, 77095.0, 89108.55445722729, 90879.54560227157, 92042.0, 12955.0, 13099.541516046893, 13214.669633259246, 13305.46778128864, 13523.932380338036, 14430.567082987527, 14542.781858271923, 14753.188574628499, 14759.188244646648, 15432.691346173238, 13520.59438216855, 13538.390772415069, 13545.32273386307, 13602.25187614651, 14364.640883977567, 13695.178289302674, 13697.657558561115, 13881.79177312321, 14117.870570354142, 14331.641708957359, 12973.0, 13267.46930122823, 13441.0, 13558.322083895568, 13591.116577422436, 10380.844287335847, 10405.58377664885, 10541.841872372073, 10692.0, 10733.570657173937, 8688.478691278544, 10008.899911000797, 10156.847647285149, 10656.62701805433, 10668.679939601825], "value": 7580.638719206452, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 132.0, 173.0, 170.0, 119.0, 117.0, 119.0, 117.0, 121.0, 118.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 117.0, 180.0, 123.0, 118.0, 117.0, 115.0, 145.0, 115.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 207.0, 262.0, 271.0, 196.0, 211.0, 195.0, 198.0, 196.0, 197.0], "value": 213.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 84.0, 61.0, 61.0, 60.0, 59.0, 79.0, 60.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 86.0, 72.0, 68.0, 69.0, 87.0, 69.0, 70.0, 86.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 91.0, 88.0, 91.0, 86.0, 91.0, 95.0, 119.0, 86.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 111.0, 101.0, 101.0, 100.0, 101.0, 105.0, 169.0, 169.0], "value": 121.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 88.0, 87.0, 93.0, 134.0, 147.0, 116.0, 88.0, 109.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 56.0, 57.0, 63.0, 68.0, 85.0, 84.0, 88.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 73.0, 78.0, 75.0, 76.0, 61.0, 88.0, 101.0, 102.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 87.0, 108.0, 86.0, 90.0, 89.0, 88.0, 86.0, 95.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 159.0, 150.0, 182.0, 160.0, 159.0, 208.0, 153.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 174.0, 176.0, 162.0, 215.0, 234.0, 238.0, 172.0, 174.0], "value": 189.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 70.0, 73.0, 69.0, 69.0, 81.0, 90.0, 89.0, 74.0], "value": 75.7, "unit": "score"}]}]} 21:11:35 INFO - Return code: 0 21:11:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:11:35 ERROR - # TBPL SUCCESS # 21:11:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:11:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:11:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:11:35 INFO - 2016-10-19 21:11:35.126 screenresolution[1937:17059] starting screenresolution argv=screenresolution get 21:11:35 INFO - 2016-10-19 21:11:35.129 screenresolution[1937:17059] Display 0: 1600x1200x32@60 21:11:35 INFO - 2016-10-19 21:11:35.134 screenresolution[1938:17062] starting screenresolution argv=screenresolution list 21:11:35 INFO - Available Modes on Display 0 21:11:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:11:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:11:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:11:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:11:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:11:35 INFO - Intel Iris: 21:11:35 INFO - Chipset Model: Intel Iris 21:11:35 INFO - Type: GPU 21:11:35 INFO - Bus: Built-In 21:11:35 INFO - VRAM (Dynamic, Max): 1536 MB 21:11:35 INFO - Vendor: Intel (0x8086) 21:11:35 INFO - Device ID: 0x0a2e 21:11:35 INFO - Revision ID: 0x0009 21:11:35 INFO - Displays: 21:11:35 INFO - Display: 21:11:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:11:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:11:35 INFO - Display Serial Number: 1600x1200 60 21:11:35 INFO - Main Display: Yes 21:11:35 INFO - Mirror: Off 21:11:35 INFO - Online: Yes 21:11:35 INFO - Rotation: Supported 21:11:35 INFO - Return code: 0 21:11:35 INFO - Running post-action listener: _resource_record_post_action 21:11:35 INFO - [mozharness: 2016-10-20 04:11:35.378449Z] Finished run-tests step (success) 21:11:35 INFO - Running post-run listener: _resource_record_post_run 21:11:35 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 1992192; Write bytes: 711102976; Read time: 23; Write time: 1943 21:11:35 INFO - TinderboxPrint: CPU usage
54.8% 21:11:35 INFO - TinderboxPrint: I/O read bytes / time
1,992,192 / 23 21:11:35 INFO - TinderboxPrint: I/O write bytes / time
711,102,976 / 1,943 21:11:35 INFO - TinderboxPrint: CPU idle
2,189.4 (45.2%) 21:11:35 INFO - TinderboxPrint: CPU system
986.7 (20.4%) 21:11:35 INFO - TinderboxPrint: CPU user
1,669.7 (34.5%) 21:11:35 INFO - TinderboxPrint: Swap in / out
408,981,504 / 0 21:11:35 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392819200; Write bytes: 386492416; Read time: 29139; Write time: 736 21:11:35 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1992192; Write bytes: 300890624; Read time: 23; Write time: 1157 21:11:35 INFO - Running post-run listener: _upload_blobber_files 21:11:35 INFO - Blob upload gear active. 21:11:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:11:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:11:36 INFO - (blobuploader) - INFO - Open directory for files ... 21:11:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:11:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:11:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:11:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:11:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:11:36 INFO - (blobuploader) - INFO - Done attempting. 21:11:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:11:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:11:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:11:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:11:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:11:37 INFO - (blobuploader) - INFO - Done attempting. 21:11:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:11:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:11:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:11:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:11:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:11:40 INFO - (blobuploader) - INFO - Done attempting. 21:11:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:11:40 INFO - Return code: 0 21:11:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:11:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:11:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6057c1d301d89a3d078281ee84f63b1a5152aaef8eec60b58ff700942e532f7f0a741d2878eaef98bbc670f177d5ab04c7eeb969f5fff6e71b6c4c2d8e15debf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40c417a589aae6698de0ff132138687666bb22a87dd3c7230332183f168862afc7931d12c1bdc4ab26b4a9921d27ddbad1eeabb55180ba58541b8ffe70d63d95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7844dc34f417c1bb2d8ecd5e9af5bfaa4ed03213c9c5faf27b5e883e83f82c2eb85973859aafe167f9caf8133ab3fc18c099522d7dcb44439553ea6c11e7a707"} 21:11:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:11:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:11:40 INFO - Contents: 21:11:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6057c1d301d89a3d078281ee84f63b1a5152aaef8eec60b58ff700942e532f7f0a741d2878eaef98bbc670f177d5ab04c7eeb969f5fff6e71b6c4c2d8e15debf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40c417a589aae6698de0ff132138687666bb22a87dd3c7230332183f168862afc7931d12c1bdc4ab26b4a9921d27ddbad1eeabb55180ba58541b8ffe70d63d95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7844dc34f417c1bb2d8ecd5e9af5bfaa4ed03213c9c5faf27b5e883e83f82c2eb85973859aafe167f9caf8133ab3fc18c099522d7dcb44439553ea6c11e7a707"} 21:11:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:11:40 INFO - Copying logs to upload dir... 21:11:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:11:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.742905 ========= master_lag: 0.56 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-19 21:11:40.750738) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 21:11:40.754080) ========= 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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6057c1d301d89a3d078281ee84f63b1a5152aaef8eec60b58ff700942e532f7f0a741d2878eaef98bbc670f177d5ab04c7eeb969f5fff6e71b6c4c2d8e15debf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40c417a589aae6698de0ff132138687666bb22a87dd3c7230332183f168862afc7931d12c1bdc4ab26b4a9921d27ddbad1eeabb55180ba58541b8ffe70d63d95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7844dc34f417c1bb2d8ecd5e9af5bfaa4ed03213c9c5faf27b5e883e83f82c2eb85973859aafe167f9caf8133ab3fc18c099522d7dcb44439553ea6c11e7a707"} build_url:https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://queue.taskcluster.net/v1/task/W6FUT-VyQpy9txVfzjlRbg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6057c1d301d89a3d078281ee84f63b1a5152aaef8eec60b58ff700942e532f7f0a741d2878eaef98bbc670f177d5ab04c7eeb969f5fff6e71b6c4c2d8e15debf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40c417a589aae6698de0ff132138687666bb22a87dd3c7230332183f168862afc7931d12c1bdc4ab26b4a9921d27ddbad1eeabb55180ba58541b8ffe70d63d95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7844dc34f417c1bb2d8ecd5e9af5bfaa4ed03213c9c5faf27b5e883e83f82c2eb85973859aafe167f9caf8133ab3fc18c099522d7dcb44439553ea6c11e7a707"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 21:11:40.797145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 21:11:40.797502) ========= 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.Tqt1wNMamX/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.EA3LXbvqhY/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.004569 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 21:11:40.849321) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 21:11:40.850100) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 21:11:40.850845) ========= ========= Total master_lag: 0.71 =========