builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0103 starttime: 1483807561.77 results: success (0) buildid: 20170107073256 builduid: 6aeb6334eea3492897794b1e9920a0ed revision: 9a9493b6820804ccb4d2089f337179341b151133 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:01.768032) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:01.768489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:01.769049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:01.883940) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:01.884266) ========= 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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 --2017-01-07 08:46:01-- 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% 66.4M=0s 2017-01-07 08:46:02 (66.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.608805 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:02.576201) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:02.576552) ========= 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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.026279 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:02.625218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:02.625576) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9a9493b6820804ccb4d2089f337179341b151133 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9a9493b6820804ccb4d2089f337179341b151133 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 2017-01-07 08:46:02,719 truncating revision to first 12 chars 2017-01-07 08:46:02,719 Setting DEBUG logging. 2017-01-07 08:46:02,719 attempt 1/10 2017-01-07 08:46:02,719 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9a9493b68208?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 08:46:03,387 unpacking tar archive at: mozilla-aurora-9a9493b68208/testing/mozharness/ program finished with exit code 0 elapsedTime=0.916748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:03.566996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:03.567299) ========= script_repo_revision: 9a9493b6820804ccb4d2089f337179341b151133 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:03.567687) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:03.567978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 08:46:03.589344) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-07 08:46:03.589632) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:03 INFO - MultiFileLogger online at 20170107 08:46:03 in /builds/slave/test 08:46:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:46:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:46:03 INFO - {'append_to_log': False, 08:46:03 INFO - 'base_work_dir': '/builds/slave/test', 08:46:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:46:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:46:03 INFO - 'branch': 'Mozilla-Aurora', 08:46:03 INFO - 'buildbot_json_path': 'buildprops.json', 08:46:03 INFO - 'config_files': ('talos/mac_config.py',), 08:46:03 INFO - 'default_actions': ('clobber', 08:46:03 INFO - 'read-buildbot-config', 08:46:03 INFO - 'download-and-extract', 08:46:03 INFO - 'populate-webroot', 08:46:03 INFO - 'create-virtualenv', 08:46:03 INFO - 'install', 08:46:03 INFO - 'run-tests'), 08:46:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:46:03 INFO - 'download_minidump_stackwalk': True, 08:46:03 INFO - 'download_symbols': 'ondemand', 08:46:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:46:03 INFO - 'tooltool.py': '/tools/tooltool.py', 08:46:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:46:03 INFO - '/tools/misc-python/virtualenv.py')}, 08:46:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:46:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:46:03 INFO - 'installer_path': 'installer.exe', 08:46:03 INFO - 'log_level': 'info', 08:46:03 INFO - 'log_name': 'talos', 08:46:03 INFO - 'log_to_console': True, 08:46:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:46:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:46:03 INFO - 'opt_config_files': (), 08:46:03 INFO - 'pip_index': False, 08:46:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:03 INFO - 'cmd': ('bash', 08:46:03 INFO - '-c', 08:46:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:03 INFO - 'enabled': True, 08:46:03 INFO - 'halt_on_failure': False, 08:46:03 INFO - 'name': 'check_screen_resolution'},), 08:46:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:03 INFO - 'cmd': ('bash', 08:46:03 INFO - '-c', 08:46:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:03 INFO - 'enabled': True, 08:46:03 INFO - 'halt_on_failure': False, 08:46:03 INFO - 'name': 'check_screen_resolution'},), 08:46:03 INFO - 'run_cmd_checks_enabled': True, 08:46:03 INFO - 'sps_profile': False, 08:46:03 INFO - 'sps_profile_interval': 0, 08:46:03 INFO - 'suite': 'dromaeojs-e10s', 08:46:03 INFO - 'system_bits': '32', 08:46:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:46:03 INFO - 'title': 't-yosemite-r7-0103', 08:46:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:46:03 INFO - 'use_talos_json': True, 08:46:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:46:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:46:03 INFO - 'work_dir': 'build'} 08:46:03 INFO - [mozharness: 2017-01-07 16:46:03.791132Z] Running clobber step. 08:46:03 INFO - Running pre-action listener: _resource_record_pre_action 08:46:03 INFO - Running main action method: clobber 08:46:03 INFO - rmtree: /builds/slave/test/build 08:46:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:46:06 INFO - Running post-action listener: _resource_record_post_action 08:46:06 INFO - [mozharness: 2017-01-07 16:46:06.128092Z] Finished clobber step (success) 08:46:06 INFO - [mozharness: 2017-01-07 16:46:06.128247Z] Running read-buildbot-config step. 08:46:06 INFO - Running pre-action listener: _resource_record_pre_action 08:46:06 INFO - Running main action method: read_buildbot_config 08:46:06 INFO - Using buildbot properties: 08:46:06 INFO - { 08:46:06 INFO - "project": "", 08:46:06 INFO - "product": "firefox", 08:46:06 INFO - "script_repo_revision": "production", 08:46:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:46:06 INFO - "repository": "", 08:46:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:46:06 INFO - "buildid": "20170107073256", 08:46:06 INFO - "pgo_build": "False", 08:46:06 INFO - "basedir": "/builds/slave/test", 08:46:06 INFO - "buildnumber": 26, 08:46:06 INFO - "slavename": "t-yosemite-r7-0103", 08:46:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:46:06 INFO - "platform": "yosemite_r7", 08:46:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:46:06 INFO - "branch": "mozilla-aurora", 08:46:06 INFO - "repo_path": "releases/mozilla-aurora", 08:46:06 INFO - "revision": "9a9493b6820804ccb4d2089f337179341b151133", 08:46:06 INFO - "stage_platform": "macosx64", 08:46:06 INFO - "builduid": "6aeb6334eea3492897794b1e9920a0ed", 08:46:06 INFO - "slavebuilddir": "test" 08:46:06 INFO - } 08:46:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:46:06 INFO - Running post-action listener: _resource_record_post_action 08:46:06 INFO - [mozharness: 2017-01-07 16:46:06.131671Z] Finished read-buildbot-config step (success) 08:46:06 INFO - [mozharness: 2017-01-07 16:46:06.131786Z] Running download-and-extract step. 08:46:06 INFO - Running pre-action listener: _resource_record_pre_action 08:46:06 INFO - Running main action method: download_and_extract 08:46:06 INFO - mkdir: /builds/slave/test/build/tests 08:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:06 INFO - trying https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:46:06 INFO - Downloading https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:46:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:46:07 INFO - Downloaded 1199 bytes. 08:46:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:46:07 INFO - Using the following test package requirements: 08:46:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:46:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:46:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:46:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'jsshell-mac.zip'], 08:46:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:46:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:46:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:46:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:46:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:46:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:46:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:46:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:46:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:46:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:46:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:46:08 INFO - Http code: 200 08:46:08 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 08:46:08 INFO - x-amz-cf-id: UzaL1ngApxqR9Pr6Qf88L0ThEokMh3ZJ6zf-DkRWhel9IvXVhTuHjg== 08:46:08 INFO - x-amz-version-id: VnMEy6ZDYITk9FLc49_Tc9XimLbauxiv 08:46:08 INFO - Expected file size: 16269706 08:46:08 INFO - Obtained file size: 16269706 08:46:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:46:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:46:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:46:14 INFO - Fetch https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:46:15 INFO - Http code: 200 08:46:15 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 08:46:15 INFO - x-amz-cf-id: ZW_w_WWn0c28hUdFPM0QJllGJaI53VaFlb1Un1dcMJR6ekpey7wj8A== 08:46:15 INFO - x-amz-version-id: VnMEy6ZDYITk9FLc49_Tc9XimLbauxiv 08:46:15 INFO - Expected file size: 16269706 08:46:15 INFO - Obtained file size: 16269706 08:46:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:46:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:46:22 INFO - Fetch https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:46:22 INFO - Http code: 200 08:46:22 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:46:22 INFO - x-amz-cf-id: LbgHLH9__QaHUUNa4W-Oj8xMxNLLGtWtzpQt4EpZT7RxwpZBvJpHbg== 08:46:22 INFO - x-amz-version-id: JtpzMdzF4s9wrHZNAk8X7Vuls2NCcAR. 08:46:23 INFO - Expected file size: 14409038 08:46:23 INFO - Obtained file size: 14409038 08:46: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')]} 08:46:23 INFO - trying https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:23 INFO - Downloading https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:46:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:46:26 INFO - Downloaded 109860853 bytes. 08:46:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:26 INFO - mkdir: /builds/slave/test/properties 08:46:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:26 INFO - Contents: 08:46:26 INFO - build_url:https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:27 INFO - Running post-action listener: _resource_record_post_action 08:46:27 INFO - Running post-action listener: set_extra_try_arguments 08:46:27 INFO - [mozharness: 2017-01-07 16:46:27.612956Z] Finished download-and-extract step (success) 08:46:27 INFO - [mozharness: 2017-01-07 16:46:27.613092Z] Running populate-webroot step. 08:46:27 INFO - Running pre-action listener: _resource_record_pre_action 08:46:27 INFO - Running main action method: populate_webroot 08:46:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:27 INFO - '--tppagecycles', 08:46:27 INFO - '7'], 08:46:27 INFO - 'tests': ['tsvgm']}}, 08:46:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['damp', 'tps']}, 08:46:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'tests': ['damp', 'tps']}, 08:46:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['dromaeo_dom']}, 08:46:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['basic_compositor_video']}, 08:46:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['a11yr', 08:46:27 INFO - 'ts_paint', 08:46:27 INFO - 'tpaint', 08:46:27 INFO - 'sessionrestore', 08:46:27 INFO - 'sessionrestore_no_auto_restore', 08:46:27 INFO - 'tabpaint']}, 08:46:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tsvgx', 08:46:27 INFO - 'tsvgr_opacity', 08:46:27 INFO - 'tart', 08:46:27 INFO - 'tscrollx', 08:46:27 INFO - 'cart']}, 08:46:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:27 INFO - 'tsvgr_opacity', 08:46:27 INFO - 'tart', 08:46:27 INFO - 'tscrollx', 08:46:27 INFO - 'cart']}, 08:46:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s'], 08:46:27 INFO - 'tests': ['tp5o']}, 08:46:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'tests': ['tp5o']}, 08:46:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'talos_options': ['--disable-e10s', 08:46:27 INFO - '--xperf_path', 08:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:27 INFO - 'tests': ['tp5n']}, 08:46:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:27 INFO - 'talos_options': ['--xperf_path', 08:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:27 INFO - 'tests': ['tp5n']}}, 08:46:27 INFO - 'talos.zip': {'path': '', 08:46:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:27 INFO - Running post-action listener: _resource_record_post_action 08:46:27 INFO - [mozharness: 2017-01-07 16:46:27.631420Z] Finished populate-webroot step (success) 08:46:27 INFO - [mozharness: 2017-01-07 16:46:27.631551Z] Running create-virtualenv step. 08:46:27 INFO - Running pre-action listener: _resource_record_pre_action 08:46:27 INFO - Running main action method: create_virtualenv 08:46:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:27 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 08:46:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:27 INFO - Using real prefix '/tools/python27' 08:46:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:31 INFO - Installing pip.................done. 08:46:31 INFO - Return code: 0 08:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:31 INFO - Reading from file tmpfile_stdout 08:46:31 INFO - Output received: 08:46:31 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) 08:46:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:31 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 08:46:31 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 08:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:31 INFO - 'HOME': '/Users/cltbld', 08:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:31 INFO - 'LOGNAME': 'cltbld', 08:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:31 INFO - 'MOZ_NO_REMOTE': '1', 08:46:31 INFO - 'NO_EM_RESTART': '1', 08:46:31 INFO - 'PAGER': '/bin/cat', 08:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:31 INFO - 'PWD': '/builds/slave/test', 08:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:31 INFO - 'SHELL': '/bin/bash', 08:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:31 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:31 INFO - 'USER': 'cltbld', 08:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:31 INFO - 'XPC_FLAGS': '0x0', 08:46:31 INFO - 'XPC_SERVICE_NAME': '0', 08:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:31 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 08:46:31 INFO - Cleaning up... 08:46:31 INFO - Return code: 0 08:46:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:31 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:31 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 08:46:31 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 08:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:31 INFO - 'HOME': '/Users/cltbld', 08:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:31 INFO - 'LOGNAME': 'cltbld', 08:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:31 INFO - 'MOZ_NO_REMOTE': '1', 08:46:31 INFO - 'NO_EM_RESTART': '1', 08:46:31 INFO - 'PAGER': '/bin/cat', 08:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:31 INFO - 'PWD': '/builds/slave/test', 08:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:31 INFO - 'SHELL': '/bin/bash', 08:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:31 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:31 INFO - 'USER': 'cltbld', 08:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:31 INFO - 'XPC_FLAGS': '0x0', 08:46:31 INFO - 'XPC_SERVICE_NAME': '0', 08:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:32 INFO - Downloading/unpacking psutil>=3.1.1 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:36 INFO - Installing collected packages: psutil 08:46:36 INFO - Running setup.py install for psutil 08:46:36 INFO - building 'psutil._psutil_osx' extension 08:46:36 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 08:46:36 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 08:46:36 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 08:46:37 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 08:46:37 INFO - building 'psutil._psutil_posix' extension 08:46:37 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 08:46:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:46:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:46:37 INFO - ^ 08:46:37 INFO - 1 warning generated. 08:46:37 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 08:46:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:37 INFO - Successfully installed psutil 08:46:37 INFO - Cleaning up... 08:46:37 INFO - Return code: 0 08:46:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:37 INFO - 'HOME': '/Users/cltbld', 08:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:37 INFO - 'LOGNAME': 'cltbld', 08:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:37 INFO - 'MOZ_NO_REMOTE': '1', 08:46:37 INFO - 'NO_EM_RESTART': '1', 08:46:37 INFO - 'PAGER': '/bin/cat', 08:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:37 INFO - 'PWD': '/builds/slave/test', 08:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:37 INFO - 'SHELL': '/bin/bash', 08:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:37 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:37 INFO - 'USER': 'cltbld', 08:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:37 INFO - 'XPC_FLAGS': '0x0', 08:46:37 INFO - 'XPC_SERVICE_NAME': '0', 08:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:40 INFO - Installing collected packages: mozsystemmonitor 08:46:40 INFO - Running setup.py install for mozsystemmonitor 08:46:40 INFO - Successfully installed mozsystemmonitor 08:46:40 INFO - Cleaning up... 08:46:40 INFO - Return code: 0 08:46:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:40 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')]} 08:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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')]} 08:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:40 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 08:46:40 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 08:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:40 INFO - 'HOME': '/Users/cltbld', 08:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:40 INFO - 'LOGNAME': 'cltbld', 08:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:40 INFO - 'MOZ_NO_REMOTE': '1', 08:46:40 INFO - 'NO_EM_RESTART': '1', 08:46:40 INFO - 'PAGER': '/bin/cat', 08:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:40 INFO - 'PWD': '/builds/slave/test', 08:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:40 INFO - 'SHELL': '/bin/bash', 08:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:40 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:40 INFO - 'USER': 'cltbld', 08:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:40 INFO - 'XPC_FLAGS': '0x0', 08:46:40 INFO - 'XPC_SERVICE_NAME': '0', 08:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:44 INFO - warning: no files found matching '*.txt' 08:46:44 INFO - no previously-included directories found matching 'build' 08:46:44 INFO - no previously-included directories found matching 'dist' 08:46:44 INFO - no previously-included directories found matching '.git*' 08:46:44 INFO - Installing collected packages: jsonschema, functools32 08:46:44 INFO - Running setup.py install for functools32 08:46:44 INFO - warning: no files found matching '*.txt' 08:46:44 INFO - no previously-included directories found matching 'build' 08:46:44 INFO - no previously-included directories found matching 'dist' 08:46:44 INFO - no previously-included directories found matching '.git*' 08:46:44 INFO - Successfully installed jsonschema functools32 08:46:44 INFO - Cleaning up... 08:46:44 INFO - Return code: 0 08:46:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:44 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 08:46:44 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 08:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:44 INFO - 'HOME': '/Users/cltbld', 08:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:44 INFO - 'LOGNAME': 'cltbld', 08:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:44 INFO - 'MOZ_NO_REMOTE': '1', 08:46:44 INFO - 'NO_EM_RESTART': '1', 08:46:44 INFO - 'PAGER': '/bin/cat', 08:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:44 INFO - 'PWD': '/builds/slave/test', 08:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:44 INFO - 'SHELL': '/bin/bash', 08:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:44 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:44 INFO - 'USER': 'cltbld', 08:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:44 INFO - 'XPC_FLAGS': '0x0', 08:46:44 INFO - 'XPC_SERVICE_NAME': '0', 08:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:44 INFO - Cleaning up... 08:46:44 INFO - Return code: 0 08:46:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:44 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 08:46:44 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 08:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:44 INFO - 'HOME': '/Users/cltbld', 08:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:44 INFO - 'LOGNAME': 'cltbld', 08:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:44 INFO - 'MOZ_NO_REMOTE': '1', 08:46:44 INFO - 'NO_EM_RESTART': '1', 08:46:44 INFO - 'PAGER': '/bin/cat', 08:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:44 INFO - 'PWD': '/builds/slave/test', 08:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:44 INFO - 'SHELL': '/bin/bash', 08:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:44 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:44 INFO - 'USER': 'cltbld', 08:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:44 INFO - 'XPC_FLAGS': '0x0', 08:46:44 INFO - 'XPC_SERVICE_NAME': '0', 08:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:44 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 08:46:44 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 08:46:44 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 08:46:44 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 08:46:44 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 08:46:44 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 08:46:47 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:47 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 08:46:47 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 08:46:47 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 08:46:47 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 08:46:47 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 08:46:47 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 08:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:48 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 08:46:48 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 08:46:48 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 08:46:48 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 08:46:48 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 08:46:48 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 08:46:48 INFO - Downloading docopt-0.6.1.tar.gz 08:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:48 INFO - Running setup.py install for blobuploader 08:46:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:48 INFO - Running setup.py install for requests 08:46:49 INFO - Running setup.py install for docopt 08:46:49 INFO - Successfully installed blobuploader requests docopt 08:46:49 INFO - Cleaning up... 08:46:49 INFO - Return code: 0 08:46:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:49 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')]} 08:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:49 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')]} 08:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:49 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:49 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 08:46:49 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 08:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:49 INFO - 'HOME': '/Users/cltbld', 08:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:49 INFO - 'LOGNAME': 'cltbld', 08:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:49 INFO - 'MOZ_NO_REMOTE': '1', 08:46:49 INFO - 'NO_EM_RESTART': '1', 08:46:49 INFO - 'PAGER': '/bin/cat', 08:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:49 INFO - 'PWD': '/builds/slave/test', 08:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:49 INFO - 'SHELL': '/bin/bash', 08:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:49 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:49 INFO - 'USER': 'cltbld', 08:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:49 INFO - 'XPC_FLAGS': '0x0', 08:46:49 INFO - 'XPC_SERVICE_NAME': '0', 08:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:49 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-WwewSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-bDLQAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5bHr61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-tb4BQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-vKIOIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-qpMFzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-q4tCee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NkVnsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NN0Yl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-SmyAqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-_s10Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NMUNlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NuOUYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-TqP2NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-yQE3K1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-VvosMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-vMlT1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:51 INFO - Running setup.py install for manifestparser 08:46:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:51 INFO - Running setup.py install for mozcrash 08:46:51 INFO - Running setup.py install for mozdebug 08:46:52 INFO - Running setup.py install for mozdevice 08:46:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Running setup.py install for mozfile 08:46:52 INFO - Running setup.py install for mozhttpd 08:46:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Running setup.py install for mozinfo 08:46:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Running setup.py install for mozInstall 08:46:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:52 INFO - Running setup.py install for mozleak 08:46:53 INFO - Running setup.py install for mozlog 08:46:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Running setup.py install for moznetwork 08:46:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Running setup.py install for mozprocess 08:46:53 INFO - Running setup.py install for mozprofile 08:46:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Running setup.py install for mozrunner 08:46:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:53 INFO - Running setup.py install for mozscreenshot 08:46:53 INFO - Running setup.py install for moztest 08:46:54 INFO - Running setup.py install for mozversion 08:46:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:54 INFO - Cleaning up... 08:46:54 INFO - Return code: 0 08:46:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:54 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')]} 08:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:54 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')]} 08:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:54 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:54 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 08:46:54 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 08:46:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:54 INFO - 'HOME': '/Users/cltbld', 08:46:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:54 INFO - 'LOGNAME': 'cltbld', 08:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:54 INFO - 'MOZ_NO_REMOTE': '1', 08:46:54 INFO - 'NO_EM_RESTART': '1', 08:46:54 INFO - 'PAGER': '/bin/cat', 08:46:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:54 INFO - 'PWD': '/builds/slave/test', 08:46:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:54 INFO - 'SHELL': '/bin/bash', 08:46:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:54 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:54 INFO - 'USER': 'cltbld', 08:46:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:54 INFO - 'XPC_FLAGS': '0x0', 08:46:54 INFO - 'XPC_SERVICE_NAME': '0', 08:46:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:54 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7Xqr_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:54 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)) 08:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:54 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5eHW78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:54 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-A1a1fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:54 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)) 08:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:54 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-3uGfzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:54 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)) 08:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:54 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7Xov8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7jMLD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-6sISAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-0QoX3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-YHeQnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-8KDkzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-z7j4bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-V_LU34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-38MTMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:55 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)) 08:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jDqXLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ttybgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:56 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)) 08:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-dH8XzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-VaZTcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:56 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)) 08:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:56 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)) 08:46:56 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)) 08:46:56 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)) 08:46:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:58 INFO - Downloading blessings-1.6.tar.gz 08:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:59 INFO - Installing collected packages: blessings 08:46:59 INFO - Running setup.py install for blessings 08:46:59 INFO - Successfully installed blessings 08:46:59 INFO - Cleaning up... 08:46:59 INFO - Return code: 0 08:46:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:59 INFO - Reading from file tmpfile_stdout 08:46:59 INFO - Current package versions: 08:46:59 INFO - blessings == 1.6 08:46:59 INFO - blobuploader == 1.2.4 08:46:59 INFO - docopt == 0.6.1 08:46:59 INFO - functools32 == 3.2.3-2 08:46:59 INFO - jsonschema == 2.5.1 08:46:59 INFO - manifestparser == 1.1 08:46:59 INFO - mozInstall == 1.12 08:46:59 INFO - mozcrash == 1.0 08:46:59 INFO - mozdebug == 0.1 08:46:59 INFO - mozdevice == 0.48 08:46:59 INFO - mozfile == 1.2 08:46:59 INFO - mozhttpd == 0.7 08:46:59 INFO - mozinfo == 0.9 08:46:59 INFO - mozleak == 0.1 08:46:59 INFO - mozlog == 3.4 08:46:59 INFO - moznetwork == 0.27 08:46:59 INFO - mozprocess == 0.23 08:46:59 INFO - mozprofile == 0.28 08:46:59 INFO - mozrunner == 6.13 08:46:59 INFO - mozscreenshot == 0.1 08:46:59 INFO - mozsystemmonitor == 0.3 08:46:59 INFO - moztest == 0.8 08:46:59 INFO - mozversion == 1.4 08:46:59 INFO - psutil == 3.1.1 08:46:59 INFO - requests == 1.2.3 08:46:59 INFO - wsgiref == 0.1.2 08:46:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:59 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:59 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 08:46:59 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 08:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:59 INFO - 'HOME': '/Users/cltbld', 08:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:59 INFO - 'LOGNAME': 'cltbld', 08:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:59 INFO - 'MOZ_NO_REMOTE': '1', 08:46:59 INFO - 'NO_EM_RESTART': '1', 08:46:59 INFO - 'PAGER': '/bin/cat', 08:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:59 INFO - 'PWD': '/builds/slave/test', 08:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:59 INFO - 'SHELL': '/bin/bash', 08:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:59 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:59 INFO - 'USER': 'cltbld', 08:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:59 INFO - 'XPC_FLAGS': '0x0', 08:46:59 INFO - 'XPC_SERVICE_NAME': '0', 08:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 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)) 08:46:59 INFO - Cleaning up... 08:46:59 INFO - Return code: 0 08:46:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:59 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 0x101d92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede957f9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, '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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:46:59 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 08:46:59 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 08:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:59 INFO - 'HOME': '/Users/cltbld', 08:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:59 INFO - 'LOGNAME': 'cltbld', 08:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:59 INFO - 'MOZ_NO_REMOTE': '1', 08:46:59 INFO - 'NO_EM_RESTART': '1', 08:46:59 INFO - 'PAGER': '/bin/cat', 08:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:59 INFO - 'PWD': '/builds/slave/test', 08:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:59 INFO - 'SHELL': '/bin/bash', 08:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:46:59 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:59 INFO - 'USER': 'cltbld', 08:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:59 INFO - 'XPC_FLAGS': '0x0', 08:46:59 INFO - 'XPC_SERVICE_NAME': '0', 08:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:47:00 INFO - Cleaning up... 08:47:00 INFO - Return code: 0 08:47:00 INFO - Running post-action listener: _resource_record_post_action 08:47:00 INFO - Running post-action listener: _start_resource_monitoring 08:47:00 INFO - Starting resource monitoring. 08:47:00 INFO - [mozharness: 2017-01-07 16:47:00.172437Z] Finished create-virtualenv step (success) 08:47:00 INFO - [mozharness: 2017-01-07 16:47:00.173415Z] Running install step. 08:47:00 INFO - Running pre-action listener: _resource_record_pre_action 08:47:00 INFO - Running main action method: install 08:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:00 INFO - Reading from file tmpfile_stdout 08:47:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:47:00 INFO - Reading from file tmpfile_stdout 08:47:00 INFO - Output received: 08:47:00 INFO - Usage: mozinstall [options] installer 08:47:00 INFO - Options: 08:47:00 INFO - -h, --help show this help message and exit 08:47:00 INFO - -d DEST, --destination=DEST 08:47:00 INFO - Directory to install application into. [default: 08:47:00 INFO - "/builds/slave/test"] 08:47:00 INFO - --app=APP Application being installed. [default: firefox] 08:47:00 INFO - mkdir: /builds/slave/test/build/application 08:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:47:32 INFO - Reading from file tmpfile_stdout 08:47:32 INFO - Output received: 08:47:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:47:32 INFO - Running post-action listener: _resource_record_post_action 08:47:32 INFO - [mozharness: 2017-01-07 16:47:32.191924Z] Finished install step (success) 08:47:32 INFO - [mozharness: 2017-01-07 16:47:32.192073Z] Running run-tests step. 08:47:32 INFO - Running pre-action listener: _resource_record_pre_action 08:47:32 INFO - Running main action method: run_tests 08:47:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:32 INFO - 2017-01-07 08:47:32.206 screenresolution[1639:8749] starting screenresolution argv=screenresolution get 08:47:32 INFO - 2017-01-07 08:47:32.209 screenresolution[1639:8749] Display 0: 1600x1200x32@60 08:47:32 INFO - 2017-01-07 08:47:32.215 screenresolution[1640:8752] starting screenresolution argv=screenresolution list 08:47:32 INFO - Available Modes on Display 0 08:47:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:32 INFO - Intel Iris: 08:47:32 INFO - Chipset Model: Intel Iris 08:47:32 INFO - Type: GPU 08:47:32 INFO - Bus: Built-In 08:47:32 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:32 INFO - Vendor: Intel (0x8086) 08:47:32 INFO - Device ID: 0x0a2e 08:47:32 INFO - Revision ID: 0x0009 08:47:32 INFO - Displays: 08:47:32 INFO - Display: 08:47:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:32 INFO - Display Serial Number: 1600x1200 60 08:47:32 INFO - Main Display: Yes 08:47:32 INFO - Mirror: Off 08:47:32 INFO - Online: Yes 08:47:32 INFO - Rotation: Supported 08:47:32 INFO - Return code: 0 08:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:32 INFO - Python 2.7.3 08:47:32 INFO - Return code: 0 08:47:32 INFO - grabbing minidump binary from tooltool 08:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:32 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 0x101ba6d30>, '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 0x101a0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b9eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:32 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 08:47:32 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 08:47:32 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 08:47:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:32 INFO - Return code: 0 08:47:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:47:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:47:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 08:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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 08:47:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7v8WLmJpH0/Render', 08:47:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:32 INFO - 'HOME': '/Users/cltbld', 08:47:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:32 INFO - 'LOGNAME': 'cltbld', 08:47:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:32 INFO - 'MOZ_NO_REMOTE': '1', 08:47:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:32 INFO - 'NO_EM_RESTART': '1', 08:47:32 INFO - 'PAGER': '/bin/cat', 08:47:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:32 INFO - 'PWD': '/builds/slave/test', 08:47:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:32 INFO - 'SHELL': '/bin/bash', 08:47:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ceNgxQcjKB/Listeners', 08:47:32 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:47:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:32 INFO - 'USER': 'cltbld', 08:47:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:32 INFO - 'XPC_FLAGS': '0x0', 08:47:32 INFO - 'XPC_SERVICE_NAME': '0', 08:47:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 08:47:32 INFO - mozversion application_buildid: 20170107073256 08:47:32 INFO - mozversion application_changeset: 9a9493b6820804ccb4d2089f337179341b151133 08:47:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:47:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:32 INFO - mozversion application_name: Firefox 08:47:32 INFO - mozversion application_remotingname: firefox-dev 08:47:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:32 INFO - mozversion application_vendor: Mozilla 08:47:32 INFO - mozversion application_version: 52.0a2 08:47:32 INFO - mozversion platform_buildid: 20170107073256 08:47:32 INFO - mozversion platform_changeset: 9a9493b6820804ccb4d2089f337179341b151133 08:47:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:32 INFO - mozversion platform_version: 52.0a2 08:47:32 INFO - using testdate: 1483807652 08:47:32 INFO - actual date: 1483807652 08:47:32 INFO - starting webserver on 'localhost:49250' 08:47:32 INFO - SUITE-START | Running 2 tests 08:47:32 INFO - TEST-START | dromaeo_css 08:47:32 INFO - operating with platform_type : mac_ 08:47:32 INFO - Initialising browser for dromaeo_css test... 08:47:32 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpmwM4Q3/profile http://localhost:49250/getInfo.html) 08:47:35 INFO - PROCESS | 1650 | 2017-01-07 08:47:35.848 plugin-container[1653:8925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 08:47:35 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:35 INFO - PROCESS | 1650 | 2017-01-07 08:47:35.852 plugin-container[1653:8925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 08:47:35 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:35 INFO - PROCESS | 1650 | 2017-01-07 08:47:35.852 plugin-container[1653:8925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:35 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 08:47:35 INFO - PROCESS | 1650 | colorDepth:24 08:47:35 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 08:47:35 INFO - PROCESS | 1650 | __metrics 08:47:36 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:47:36 INFO - TEST-INFO | 1650: exit 0 08:47:36 INFO - Browser initialized. 08:47:36 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:36 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpmwM4Q3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:39 INFO - PROCESS | 1654 | 2017-01-07 08:47:39.407 plugin-container[1655:9056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 08:47:39 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:39 INFO - PROCESS | 1654 | 2017-01-07 08:47:39.407 plugin-container[1655:9056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:47:39 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:39 INFO - PROCESS | 1654 | 2017-01-07 08:47:39.407 plugin-container[1655:9056] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:48 INFO - PROCESS | 1654 | RSS: Main: 229945344 08:47:48 INFO - PROCESS | 1654 | 08:50:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 08:50:53 INFO - PROCESS | 1654 | RSS: Main: 235470848 08:50:53 INFO - PROCESS | 1654 | 08:53:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 08:53:56 INFO - PROCESS | 1654 | RSS: Main: 243548160 08:53:56 INFO - PROCESS | 1654 | 08:56:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 08:56:59 INFO - PROCESS | 1654 | RSS: Main: 246509568 08:56:59 INFO - PROCESS | 1654 | 09:00:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 09:00:01 INFO - PROCESS | 1654 | RSS: Main: 245821440 09:00:01 INFO - PROCESS | 1654 | 09:03:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 09:03:05 INFO - PROCESS | 1654 | RSS: Main: 249221120 09:03:05 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 09:06:08 INFO - PROCESS | 1654 | RSS: Main: 250445824 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | __start_tp_report 09:06:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:06:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:06:08 INFO - PROCESS | 1654 | |i|pagename|runs| 09:06:08 INFO - PROCESS | 1654 | |0;dojo.html;2737.698853126156;3294.5881764779397;3301.2077101495647;3324.16895776056;3324.6010478742546;4698.271767875976;5445.292112025436;5557.27755391799;5623.216646948292;5685.289338832646;4775.450823155338;5753.539716822645;5803.100519419486;5840.941590584093;5885.8822823543505;5205.505476979681;5252.684838909678;5260.316158899338;5304.973475132619;5345.518903298703;4311.223979683656;4979.402471703401;5202.479752024786;5315.468453154673;5355.839324820262;4683.742394168319;5099.209622508517;5151.8196863109615;5231.973840130775;5240.213967904845;5075.847724568269;5154.613403994678;5184.144616138333;5200.739963001834;5215.608829337815;5052.393712754456;5079.314292570492;5108.974455127738;5113.948860511422;5149.588032957355;5515.007298686235;5692.0608099663505;5720.599558030939;5748.770049198027;5795.3335366432975;6122.1735065766;6145.12573665032;6162.815115546541;6224.066390041485;6236.220472440945;6197.287311959138;6223.284322302948;6232.283287421914;6244.3755624437645;6250.812475625738;5657.830265092054;5725.1698503716725;5821.79623713168;5857.907377694506;5870.412958704095;6141.455323060992;6175.536834737323;6201.286852012076;6206.007038873759;6243.063540468966;6128.754849805958;6135;6141.84645383869;6154.138420621117;6189.6286222826775;6214.875702485925;6215.81352559424;6235.438810507076;6235.625862448177;6245.75016999324;5971.343152253248;6114.510839132859;6127.509799216052;6166.845828854314;6185.41238582334;6118.253719382578;6124.960759790135;6188.133661287423;6189.133521306931;6195.31851496335;4897.314375987365;4916.655834091651;4933.851984440473;4936.308916751657;4946.035523073038;4414.293713005968;4415.183191109655;4427.645788336989;4439.66702497314;4458.8629899375455;4495.77521123949;5439.972800135974;5612;5612.607117501817;5704.201411802351;6129.11429013075;6132.630864714964;6197.03945888388;6199.690015499207;6201.658908760061;5630.761232528837;5646.237757902631;5672.886542269214;5719.942800571941;5726;4648.697834640738;5385.7576409061685;5386.892262154735;5398.028354896156;5405.918911216335;5188.896222075613;6141.36327073057;6207.037909124093;6223.906641400473;6229.2524897012645;4845.370101886732;5060.822871199491;5574.358948720949;5953.255843019622;6143.542582966849;6204.976178930607;6218.160548325918;6221.253449586078;6225.066240063846;6268.655223962726;5211.530962213419;5697.544196464356;5776.584736739358;6035.426634469718;6206.658633775186;4664.883377915579;6112.685862051747;6150.630962142286;6160.291566469913;6240.063990401476;6113.51091912637;6167.784127555514;6181.1655426516845;6194.101855231056;6201.224846894138;6093.177421048279;6139.631622102688;6180.381961803783;6196.783112591218;6209.223847019122;5466.207399926909;5665.866826634607;5805.41945805433;5872.942870283219;5991.011483105413;6054.7275372607455;6096.146539484387;6096.939030609815;6102.023676211785;6110.4980065817745;5910.290765108214;5910.734016969161;5916.230889984275;5921.822345329647;5939.020061689773 09:06:08 INFO - PROCESS | 1654 | |1;ext.html;484.36064394998607;490.61731849157667;519.5142541723488;550.246162757023;551.0466892276362;29330;38643.420348694795;38646.033849153784;38688.83933481993;38697.806510967406;38980.80509597448;39480.21039579207;39569.40645890307;39598.80200598993;39616;9110.817783644323;9282.350235483518;9287.674931377403;9322.021187775288;9342.813143737121;9263.305252106085;9335.626574936994;9346.719598412059;9359.906400935972;9372.578233979453;9321.300902432311;9332.253419726405;9335.393199442015;9367.15695587397;9378.577963991602;9271.721848344527;9326.813463730687;9353.204977576881;9389.201917836992;9394.436333819993;9135.314851386172;9186.770330741718;9224.907750922472;9236.815263694756;9284.350095493319;37435.625643743486;37752;37831.05422364435;37903.81048094769;37904.620953790385;3272.2473831018997;3319.751018673609;3373.578302712161;3758.8684396046006;3821.4458903458844;28132;28187;28787.136385908456;28887.277818054506;29537.852310738523;3092.9536056959164;3181.9681803182134;3226.1611980884904;3233.7898036627544;3248.399046176441;487865;489806;491580;494754;496175;59239.40760592425;59581.70209149013;59586.70206648939;59894.80210395768;59938.70030649819;62443.6877815608;62449.37550624526;62575.6871215641;62808.68595656993;63871.6806415965;16051.598710032342;16268.75596866048;16293.429729959395;16327.938683985503;16331.42840000618;12287.201331913397;12385.62843114708;12448.81326780099;12464.065195110403;12519.123661343801;17305.30778768835;18357.7246341305;18369.26522939094;18402.26390944374;18412.81587184138;7498.837680159584;7709.38324934004;7831.960840195762;7848.960755196187;7851.489653172525;829.7635173975387;919.8114386550875;923.8013827027221;990.9693918325005;1051.673981065872;28947.56578651322;28977.13068607903;29444.411111778074;29807.40385192284;29954.55068173979;839.5004972041626;896.7444278380631;933.7945651956559;972.8005758819424;1165.7551914097965;14768.556943291718;15105.018173818886;15323.23383830804;15564.143972081643;15568.610784730472;687835;693662;696413;698319;704132;55000;55418;55432.99999999919;55601.1659825103;55815.44184558184;1337.6187786481;1339.9330033498286;1375.4498200719593;1376.3462355381114;1380.2615600653803;28345.574816377357;29013.709862901946;29060.418791623626;29098.41803163967;29407.85296073506;47063.529364705915;47325.52674473211;47801.04397912092;47896;48063;609910;614214;615098;616240;616476;25794.871025644752;26001.739982600688;26002.479950401263;26127.869360653076;26134.607980879922;37892.43161352527;40843.59156408398;42057.57942420537;44496.33255501235;45900;37455.625443745215;37629.81185094057;37814.810925945196;38188.42717359297;38307.425388618634;37146.07134821651;37610.05974850651;38233.617663823;38486;38823.805880970416;35812;36180.81909590435;36859;37148.62851371452;37233.6276637241;31865;33231.0874028697;33604.663953360156;33776.83111584427;34026.14934626555;24776;25014.999400024524;25066.74933250717;25365;25509.744902550738 09:06:08 INFO - PROCESS | 1654 | |2;jquery.html;329.8383791941948;370.11542413631423;376.886933919824;389.3595036165506;395.49376797698955;18314.35899743509;18812.435626931197;19854.90072549637;19900.30348937784;20094;19325;19691.606167876635;19706.901465492687;19755.81465112094;19757;5595.104783234683;5637.098064309701;5839.708014599253;5872.265966754156;5880.059190529516;5643.209950606918;5811.709414529278;5818.127280907876;5821.243238379005;5831.679257640828;5589.496945274925;5607.43925607438;5780.50865676418;5781.075027995501;5783.855403614923;5739.913901291484;5790.189373487715;5808.041673123929;5817.41825817417;5820.825375238728;4867.805287788484;5019.548240658321;5094.439611642716;5107.284980102789;5110.821121260738;21445.356639300848;21494.355169344944;24458;24687.753122468905;24867.50264994708;2219.7447293561286;2228.821694264455;2294.300238427279;2456.6192240202795;2979.3594377208788;14105.64735881601;14656.853431465655;14853.702925941529;15091.019083759631;15234.771478427832;1809.7013992691323;1811.7735283089614;1882.0401595186554;1908.5419499320203;1921.3371386871506;105877.47061264722;119920;120351;120736.79263207255;122491.71279005281;10615.84076238857;10799.622013229657;11250.156238282161;11327.433628318717;11530.942345288388;10976.560937562408;11831.467583958742;13050.282234477196;13384.732305353838;13606.523771667944;7262.527935684164;7787.987561616954;8004.279614834692;8131.512109273463;8228.30059444942;8104.432689711663;8307.750767476986;8463.873041904377;8477.448965817326;8593.484390936565;5472.50747432725;6580.934190658128;6627.105340778933;6627.834304142339;6668.899966500507;1579.3998280653277;1624.3908534299637;1897.4820143884838;1900.6958886578082;1906.5614908571106;751.5678484871221;854.1287886356006;868.2446271743497;928.8885333759857;975.7121649113528;18734.906325468284;19617;19632.803671963382;21162.894185529283;21319.573608527742;920.4201353147474;926.1525703980917;939.4832841936908;949.9715008549755;954.7994921066515;10839.566417343376;11276.01764837846;11414.143939204592;11430.485628146753;11465;506694;619204;621336;623285;633378;105681;116468;131978;138270;147588.52411475749;940.9811803763887;969.3553786731885;984.0897170117613;988.2489308125732;1001.8747656542787;17982.370617028864;18487.16807743628;18502;18619.81380186235;18739.250429982647;45666.543334567556;55628.721856392076;56558;57844.71077644585;58892.70553647204;85191.29617407493;86583;87714;88325;90946.54526727324;8001.119876813429;8087.433879628605;8191.918080819354;8295.792605184919;8392.4544904581;12276.693082673004;12742;12841.036922230684;13075.215487070805;13075.346232688138;5456.91814622773;8997.865032024656;11119.999200072032;11142.387168705798;11375.26060806045;9761.658341958282;10237.84643230367;11053.392063436588;11143.33140011602;11458.427078645867;10876.02115809581;10956.397398142859;11127.554897803997;11347.886521134684;11498.310101393943;7796.844063118592;7965;7988.121306656147;8031.397645176752;8218.137095604876;8742.34432417584;9133.949595796568;10172.338797978404;10533.36799792015;10665.840012399665 09:06:08 INFO - PROCESS | 1654 | |3;mootools.html;1228.551578673784;1444.1696024785747;1589.5708158797124;1759.9208035638394;1778.5197996540935;7498.775036748906;7736.535807851519;8099.7165099221465;8535;8549.658013679446;8025.638846251918;8204.466709663868;8254.876176857351;8456.957715211416;8489.830203395928;1912.2924517928386;2317.432229103872;2357.174988753931;2363.6927199464044;2383;2352.1179557665873;2390.2590197038885;2402.7116745990506;2403.4231784371714;2403.663487111806;2329.9883500582473;2334.708161479815;2358.3868194269444;2363.361892289081;2363.846349987254;2330.0912644068744;2340.028888011473;2352.764723527651;2358.50471401006;2369.7630236976424;2360.197532838843;2377.1323466934446;2387.6060450025725;2387.6418537219556;2399.8200134989947;8068.193180681944;8442.493450392996;8495.830083398298;8499.320054395634;8566.271866891322;4582.908341833178;5131.025105229995;5186.766595503173;5187.922181167285;5194.818181363634;3324.3018966017166;3487.302539492112;3608.4226523756333;3608.711303095746;3659.652333028358;1043.12377602814;1059.2691043180182;1095.8137116690182;1101.5648818716643;1107.1143085531542;10840.512176952056;11010.284331518345;11017.779644407066;11034.393108379036;11048.447577621168;4537.6143027842345;4652.883677908079;4749.905001899942;4793.400824896888;4802.327674125625;8448;9456.669016584525;9457.763555911157;9468.668596599086;9703.417794932186;5238.2404551340605;5254.448282930313;5258.526732594085;5265.368155821326;5268.78924843002;4109.671226301948;4764.737939413296;4775.044991001813;4813.446453657804;4814.253790662453;3842.269968705937;3869.052082239813;3938.6849052076336;3971.0866500705;3975.363941769303;1260.6470188347278;1260.6785269756338;1263.444084602772;1266.1390254626758;1274.3182397417338;764.9502782319131;798.0104670208899;861.5132450165636;894.6466145872344;897.5736525150631;5469.617126801086;5527.115661494142;5563.831595364938;5779.248697669276;5799.420057994167;391.43046866808726;446.7453551475693;446.8838102093479;453.50114873639393;457.78941686824436;3786.6402691744242;3790.298794722985;3834.827432765532;3858.977370996688;3914.2758589661003;9862.852057219148;10356.948215258875;10357.533910974023;10391.064804167661;10398.844017339747;10302.484875756183;10316.226283028804;10324.587016519405;10324.793504129875;10331.483425828828;6939;7814.335781458526;7941.64262608184;8007.839843203104;8039.47743396689;4709.505501922249;5510.972445137749;5599.944000560105;5622.662640241599;5752.13717942312;9316.48759318244;9334.579943902405;9433.764155896157;9494.572744226389;9567.521623918637;9547.42715437076;9610.615575377185;9614.9038509614;9723.56243969009;9738.026197380204;6557.868842623026;6624.337566243337;7065.752698655522;7209.927900721135;7297.635118244173;7998.5600791957;8070.556119413253;8073.15231900642;8089.271965523131;8092.71675491355;7104.715811367488;7129.536580122275;7214.675339609626;7231.3130252625915;7341.522801017917;7016.36852683261;7150.499465037397;7175.856482870209;7269.963650181715;7293;6787.423069039187;6804.6257455840405;6841.62371069596;6845.315468453115;6846.897296540456;4806.375171227858;4886.853394398174;4902.754862256802;4911.582515486224;4980.551750342486;4122.0107174278555;4463.843765468193;4829.13075646387;4852.975735121302;4873.293372461044 09:06:08 INFO - PROCESS | 1654 | |4;prototype.html;303.9027511196417;307.3330872007743;310.3188501239778;313.6643791143476;313.8791565247379;4593.655475839309;5285.86785330366;5406.945930540694;5430.3755068167175;5437.048516509605;4846.539578740023;4940.505044952294;5306.310179676637;5323.494268044539;5332.440093790155;3445.8277086145717;3837.656820112966;3974.383970484565;3992.4011398290345;4068.4710987571575;3371.853569786272;4044.1911617676437;4053.250148722481;4090.897727556805;4096.6927480438935;4058.585141840615;4078.6125318094882;4081.1429599784083;4132.938005929914;4142.937855932163;3248.59392575928;3997;4007.238624661334;4071.9389209161886;4078.5105787305415;2905.6513218413925;3440.501127336522;3459.238967427162;3462.549868517102;3487;4950.257461380786;5134.358205224347;5170.811642569531;5183.922241166386;5216.817411390621;4317.438732964726;4590.898184435711;4619.399478067863;4622.9075418491775;4656;4906.607471402279;5016.949830501685;5593.412691667357;5607.355154157283;5741;3835.4822099016556;3838.7120965927666;3839.462475253467;3853.9807300963316;3867.8259478323544;4664.766761661904;4952.108620448318;5440.727963601844;5791.594588378815;5879;3262.624798148196;3315.93368132636;3684.8710295140077;3808;3815.389537673959;3310.6358300586917;3949.822257998397;3953.7034722395933;3959.208158368338;3967.4048892666333;4000.6199411055904;4092.2429350569646;4576.078047220176;4577.656675749332;4597.6896634423165;5068.290439338495;5330.840074797762;5573.386927437979;5578.63738856973;5707.771689132388;1221.5113954418305;1267.2333238390825;1283.608499407671;1289.1427200911476;1293.5084667826354;690.3752104345475;721.055417403193;816.3917881178442;865.264525153627;875.6646114675044;4750.5249475052215;4768.33108412053;5056.595472362203;5108.773894265424;5379.757910894019;4775.73733444657;5465.781368745285;5504.587155963319;5818.2436283282905;5843.487300460758;4710.976445117821;4771.5228477152;5271.525562699376;5306.867328316823;5360.0083984463;2750.2024412920027;2757.379589592326;2824.8587570621385;2905.7675385969073;2914.1548950804454;5378.273933019071;5695.689991301941;5697.373288938214;5707.315122185364;5723.54211663066;4957.20684690455;5079.212722028018;5523.917141242885;5652.745626446737;5657.2928383952;4502.617277531446;5255.894882102337;5590.664560126324;5704.543636509069;5734.3692193857805;4799.368214806982;4821.421429428491;4851.805927762991;5320.44135365781;5547.750351234285;4966.832794293266;5122.923156152586;5253.422123566328;5416.106342453451;5472.91790623149;5071.568916641979;5089.720065396439;5692.31692196939;5707.514861236841;5740.3685594585395;3386.5428167197115;3684.59469458354;3703.833327500215;3703.8703645372284;3720.377134120493;4526.736631684158;4931.926021109614;5853.824385268449;5872.94127058741;5880.735916672026;4356.411884395693;5396.947595218895;5724.4561766632105;5734.426557344233;5749.453801888814;5020.949790502048;5376.301080859464;5748.568857335633;5750.7284622573015;5802.245708057925;4550.749708765918;5566.721663916707;5612.045952188219;5627.465390787868;5641.26663533738;5521.5306698931045;5575.776968921197;5613.691246981291;5761.193432919478;5777.191193233035;4774.761261936958;5340.706261155674;5617.494425501724;5632.324121105494;5639.802606908738 09:06:08 INFO - PROCESS | 1654 | |5;yui.html;641.5220660607848;669.6538967526235;677.0386051806438;758.5600351795958;765.2538774694673;13939.163650180997;14882.330295136719;14885.404583816662;14899.106053636775;14948.103113813166;13387.59837204883;13539.729205415886;13550.18698878068;13555.59333220005;13589.980751443656;639.7536948274925;654.9050387693782;793.0562630469738;813.3046245460106;814.6782021101661;789.159545084486;805.2390490986021;818.4843548564426;820.9630566624517;823.8599438095508;852.9360297977645;855.5209082913589;858.1161403754112;860.1269711243093;862.9137086291352;847.3475423923607;850.0479463001478;851.0425771007616;851.6678495386835;851.9701810436636;640.5708175522411;640.5868215001298;641.4547634510675;642.9003504456816;643.009764961958;13562.525311614047;13585.252811095386;13744.312784360842;13744.65638359049;13751.862481375258;10849.620263290826;11546.36494992775;12623.684407889785;12894.290814005226;13393.330333483384;11065.225434219608;11138.721531961684;11193.440327983568;11271.26736762116;11271.943640281828;3534.964650353489;3539.3983022886173;3539.539859818233;3633.1280492681567;3684.0053185639727;60520.69739651273;78659.42681146403;82150;83025;89681;10139.898601014043;10204.336718113298;10468.371897686164;10513.789724205473;10721.678349649523;19732;19735.605287894163;19744.30894918671;19825.405237842886;19831.30590429328;7408.222136675571;7610.847783044308;7804;7837.177096404909;7845.0585929688805;4123.670106391482;4285.807138678767;4292.871213863589;4320.222359975234;4332.393464914914;2902.782291328159;3259.1852036990754;3347.966520334814;3364.6635336466156;3393.9490907636405;584.1238142785821;627.8322320483896;635.5700765663411;649.7336092202174;655.1646650520624;489.87997940504806;637.528229110455;665.2947875252248;669.1869378704799;678.375894177358;7713.8457230855065;8842.557872106368;8966.378558401355;9258.95370523143;9279.39683920543;580.3616022375353;582.5146372928384;627.0813258576208;641.377863472431;665.351282499559;3774.735768496234;3832.80835958201;3971.72197946141;4395.516493185747;4532.0709254602725;78064;91239;107867.46066269619;114846.27730584049;116730.832691672;79299;94083.58874616749;97931.51034244926;99951;103733.44399834008;76174.23825761782;76617;77288;88965;89898.55050724835;8946.642134314814;9112.316576256675;9508.381955172892;9924.751881203028;9961.153301969414;37230;50967.74516127395;55345;55568.44431555633;55849.72075139598;74835;74905.87641185496;75884;76996.84504732545;78733.60633196797;12403.317817519763;12492.625221243377;12586.559470418491;12605.684857878627;12749.043821713223;13777.724445510834;14195.64510887236;14196.503122390666;14433.278336082943;15127.405173829022;12636.494540218288;13012.28432436225;13752.587422377344;14122.293885305899;14124.92937535347;12874.356282186041;12957.935210323889;13450.663733406744;13501;13949.372278247707;12828.935855320664;13628.659283517594;13690.794638080635;13743.656408589864;13798.586042418743;10460.537409365654;10721.678349649523;11223.663290100983;11270.83093753578;11495.080393568662;9476.763080923032;10439.37363758177;11429.828552571551;11562;11576.768464630828 09:06:08 INFO - PROCESS | 1654 | __end_tp_report 09:06:08 INFO - PROCESS | 1654 | __start_cc_report 09:06:08 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,380 09:06:08 INFO - PROCESS | 1654 | __end_cc_report 09:06:08 INFO - PROCESS | 1654 | __startTimestamp1483808768320__endTimestamp 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:06:08 INFO - PROCESS | 1654 | Number of tests: 6 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5634.22 Median:5840.94 stddev:679.17 (11.6%) stddev-sans-first:642.12 09:06:08 INFO - PROCESS | 1654 | Values: 2737.7 3294.6 3301.2 3324.2 3324.6 4698.3 5445.3 5557.3 5623.2 5685.3 4775.5 5753.5 5803.1 5840.9 5885.9 5205.5 5252.7 5260.3 5305.0 5345.5 4311.2 4979.4 5202.5 5315.5 5355.8 4683.7 5099.2 5151.8 5232.0 5240.2 5075.8 5154.6 5184.1 5200.7 5215.6 5052.4 5079.3 5109.0 5113.9 5149.6 5515.0 5692.1 5720.6 5748.8 5795.3 6122.2 6145.1 6162.8 6224.1 6236.2 6197.3 6223.3 6232.3 6244.4 6250.8 5657.8 5725.2 5821.8 5857.9 5870.4 6141.5 6175.5 6201.3 6206.0 6243.1 6128.8 6135.0 6141.8 6154.1 6189.6 6214.9 6215.8 6235.4 6235.6 6245.8 5971.3 6114.5 6127.5 6166.8 6185.4 6118.3 6125.0 6188.1 6189.1 6195.3 4897.3 4916.7 4933.9 4936.3 4946.0 4414.3 4415.2 4427.6 4439.7 4458.9 4495.8 5440.0 5612.0 5612.6 5704.2 6129.1 6132.6 6197.0 6199.7 6201.7 5630.8 5646.2 5672.9 5719.9 5726.0 4648.7 5385.8 5386.9 5398.0 5405.9 5188.9 6141.4 6207.0 6223.9 6229.3 4845.4 5060.8 5574.4 5953.3 6143.5 6205.0 6218.2 6221.3 6225.1 6268.7 5211.5 5697.5 5776.6 6035.4 6206.7 4664.9 6112.7 6150.6 6160.3 6240.1 6113.5 6167.8 6181.2 6194.1 6201.2 6093.2 6139.6 6180.4 6196.8 6209.2 5466.2 5665.9 5805.4 5872.9 5991.0 6054.7 6096.1 6096.9 6102.0 6110.5 5910.3 5910.7 5916.2 5921.8 5939.0 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:72092.71 Median:38693.32 stddev:162616.28 (420.3%) stddev-sans-first:162983.59 09:06:08 INFO - PROCESS | 1654 | Values: 484.4 490.6 519.5 550.2 551.0 29330.0 38643.4 38646.0 38688.8 38697.8 38980.8 39480.2 39569.4 39598.8 39616.0 9110.8 9282.4 9287.7 9322.0 9342.8 9263.3 9335.6 9346.7 9359.9 9372.6 9321.3 9332.3 9335.4 9367.2 9378.6 9271.7 9326.8 9353.2 9389.2 9394.4 9135.3 9186.8 9224.9 9236.8 9284.4 37435.6 37752.0 37831.1 37903.8 37904.6 3272.2 3319.8 3373.6 3758.9 3821.4 28132.0 28187.0 28787.1 28887.3 29537.9 3093.0 3182.0 3226.2 3233.8 3248.4 487865.0 489806.0 491580.0 494754.0 496175.0 59239.4 59581.7 59586.7 59894.8 59938.7 62443.7 62449.4 62575.7 62808.7 63871.7 16051.6 16268.8 16293.4 16327.9 16331.4 12287.2 12385.6 12448.8 12464.1 12519.1 17305.3 18357.7 18369.3 18402.3 18412.8 7498.8 7709.4 7832.0 7849.0 7851.5 829.8 919.8 923.8 991.0 1051.7 28947.6 28977.1 29444.4 29807.4 29954.6 839.5 896.7 933.8 972.8 1165.8 14768.6 15105.0 15323.2 15564.1 15568.6 687835.0 693662.0 696413.0 698319.0 704132.0 55000.0 55418.0 55433.0 55601.2 55815.4 1337.6 1339.9 1375.4 1376.3 1380.3 28345.6 29013.7 29060.4 29098.4 29407.9 47063.5 47325.5 47801.0 47896.0 48063.0 609910.0 614214.0 615098.0 616240.0 616476.0 25794.9 26001.7 26002.5 26127.9 26134.6 37892.4 40843.6 42057.6 44496.3 45900.0 37455.6 37629.8 37814.8 38188.4 38307.4 37146.1 37610.1 38233.6 38486.0 38823.8 35812.0 36180.8 36859.0 37148.6 37233.6 31865.0 33231.1 33604.7 33776.8 34026.1 24776.0 25015.0 25066.7 25365.0 25509.7 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:35289.65 Median:1654.60 stddev:100749.85 (6089.1%) stddev-sans-first:100998.28 09:06:08 INFO - PROCESS | 1654 | Values: 329.8 370.1 376.9 389.4 395.5 18314.4 18812.4 19854.9 19900.3 20094.0 19325.0 19691.6 19706.9 19755.8 19757.0 5595.1 5637.1 5839.7 5872.3 5880.1 5643.2 5811.7 5818.1 5821.2 5831.7 5589.5 5607.4 5780.5 5781.1 5783.9 5739.9 5790.2 5808.0 5817.4 5820.8 4867.8 5019.5 5094.4 5107.3 5110.8 21445.4 21494.4 24458.0 24687.8 24867.5 2219.7 2228.8 2294.3 2456.6 2979.4 14105.6 14656.9 14853.7 15091.0 15234.8 1809.7 1811.8 1882.0 1908.5 1921.3 105877.5 119920.0 120351.0 120736.8 122491.7 10615.8 10799.6 11250.2 11327.4 11530.9 10976.6 11831.5 13050.3 13384.7 13606.5 7262.5 7788.0 8004.3 8131.5 8228.3 8104.4 8307.8 8463.9 8477.4 8593.5 5472.5 6580.9 6627.1 6627.8 6668.9 1579.4 1624.4 1897.5 1900.7 1906.6 751.6 854.1 868.2 928.9 975.7 18734.9 19617.0 19632.8 21162.9 21319.6 920.4 926.2 939.5 950.0 954.8 10839.6 11276.0 11414.1 11430.5 11465.0 506694.0 619204.0 621336.0 623285.0 633378.0 105681.0 116468.0 131978.0 138270.0 147588.5 941.0 969.4 984.1 988.2 1001.9 17982.4 18487.2 18502.0 18619.8 18739.3 45666.5 55628.7 56558.0 57844.7 58892.7 85191.3 86583.0 87714.0 88325.0 90946.5 8001.1 8087.4 8191.9 8295.8 8392.5 12276.7 12742.0 12841.0 13075.2 13075.3 5456.9 8997.9 11120.0 11142.4 11375.3 9761.7 10237.8 11053.4 11143.3 11458.4 10876.0 10956.4 11127.6 11347.9 11498.3 7796.8 7965.0 7988.1 8031.4 8218.1 8742.3 9133.9 10172.3 10533.4 10665.8 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5478.30 Median:4863.13 stddev:3038.46 (62.5%) stddev-sans-first:3030.19 09:06:08 INFO - PROCESS | 1654 | Values: 1228.6 1444.2 1589.6 1759.9 1778.5 7498.8 7736.5 8099.7 8535.0 8549.7 8025.6 8204.5 8254.9 8457.0 8489.8 1912.3 2317.4 2357.2 2363.7 2383.0 2352.1 2390.3 2402.7 2403.4 2403.7 2330.0 2334.7 2358.4 2363.4 2363.8 2330.1 2340.0 2352.8 2358.5 2369.8 2360.2 2377.1 2387.6 2387.6 2399.8 8068.2 8442.5 8495.8 8499.3 8566.3 4582.9 5131.0 5186.8 5187.9 5194.8 3324.3 3487.3 3608.4 3608.7 3659.7 1043.1 1059.3 1095.8 1101.6 1107.1 10840.5 11010.3 11017.8 11034.4 11048.4 4537.6 4652.9 4749.9 4793.4 4802.3 8448.0 9456.7 9457.8 9468.7 9703.4 5238.2 5254.4 5258.5 5265.4 5268.8 4109.7 4764.7 4775.0 4813.4 4814.3 3842.3 3869.1 3938.7 3971.1 3975.4 1260.6 1260.7 1263.4 1266.1 1274.3 765.0 798.0 861.5 894.6 897.6 5469.6 5527.1 5563.8 5779.2 5799.4 391.4 446.7 446.9 453.5 457.8 3786.6 3790.3 3834.8 3859.0 3914.3 9862.9 10356.9 10357.5 10391.1 10398.8 10302.5 10316.2 10324.6 10324.8 10331.5 6939.0 7814.3 7941.6 8007.8 8039.5 4709.5 5511.0 5599.9 5622.7 5752.1 9316.5 9334.6 9433.8 9494.6 9567.5 9547.4 9610.6 9614.9 9723.6 9738.0 6557.9 6624.3 7065.8 7209.9 7297.6 7998.6 8070.6 8073.2 8089.3 8092.7 7104.7 7129.5 7214.7 7231.3 7341.5 7016.4 7150.5 7175.9 7270.0 7293.0 6787.4 6804.6 6841.6 6845.3 6846.9 4806.4 4886.9 4902.8 4911.6 4980.6 4122.0 4463.8 4829.1 4853.0 4873.3 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4454.36 Median:4954.66 stddev:1377.96 (27.8%) stddev-sans-first:1346.16 09:06:08 INFO - PROCESS | 1654 | Values: 303.9 307.3 310.3 313.7 313.9 4593.7 5285.9 5406.9 5430.4 5437.0 4846.5 4940.5 5306.3 5323.5 5332.4 3445.8 3837.7 3974.4 3992.4 4068.5 3371.9 4044.2 4053.3 4090.9 4096.7 4058.6 4078.6 4081.1 4132.9 4142.9 3248.6 3997.0 4007.2 4071.9 4078.5 2905.7 3440.5 3459.2 3462.5 3487.0 4950.3 5134.4 5170.8 5183.9 5216.8 4317.4 4590.9 4619.4 4622.9 4656.0 4906.6 5016.9 5593.4 5607.4 5741.0 3835.5 3838.7 3839.5 3854.0 3867.8 4664.8 4952.1 5440.7 5791.6 5879.0 3262.6 3315.9 3684.9 3808.0 3815.4 3310.6 3949.8 3953.7 3959.2 3967.4 4000.6 4092.2 4576.1 4577.7 4597.7 5068.3 5330.8 5573.4 5578.6 5707.8 1221.5 1267.2 1283.6 1289.1 1293.5 690.4 721.1 816.4 865.3 875.7 4750.5 4768.3 5056.6 5108.8 5379.8 4775.7 5465.8 5504.6 5818.2 5843.5 4711.0 4771.5 5271.5 5306.9 5360.0 2750.2 2757.4 2824.9 2905.8 2914.2 5378.3 5695.7 5697.4 5707.3 5723.5 4957.2 5079.2 5523.9 5652.7 5657.3 4502.6 5255.9 5590.7 5704.5 5734.4 4799.4 4821.4 4851.8 5320.4 5547.8 4966.8 5122.9 5253.4 5416.1 5472.9 5071.6 5089.7 5692.3 5707.5 5740.4 3386.5 3684.6 3703.8 3703.9 3720.4 4526.7 4931.9 5853.8 5872.9 5880.7 4356.4 5396.9 5724.5 5734.4 5749.5 5020.9 5376.3 5748.6 5750.7 5802.2 4550.7 5566.7 5612.0 5627.5 5641.3 5521.5 5575.8 5613.7 5761.2 5777.2 4774.8 5340.7 5617.5 5632.3 5639.8 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:19919.86 Median:4326.31 stddev:28800.39 (665.7%) stddev-sans-first:28844.81 09:06:08 INFO - PROCESS | 1654 | Values: 641.5 669.7 677.0 758.6 765.3 13939.2 14882.3 14885.4 14899.1 14948.1 13387.6 13539.7 13550.2 13555.6 13590.0 639.8 654.9 793.1 813.3 814.7 789.2 805.2 818.5 821.0 823.9 852.9 855.5 858.1 860.1 862.9 847.3 850.0 851.0 851.7 852.0 640.6 640.6 641.5 642.9 643.0 13562.5 13585.3 13744.3 13744.7 13751.9 10849.6 11546.4 12623.7 12894.3 13393.3 11065.2 11138.7 11193.4 11271.3 11271.9 3535.0 3539.4 3539.5 3633.1 3684.0 60520.7 78659.4 82150.0 83025.0 89681.0 10139.9 10204.3 10468.4 10513.8 10721.7 19732.0 19735.6 19744.3 19825.4 19831.3 7408.2 7610.8 7804.0 7837.2 7845.1 4123.7 4285.8 4292.9 4320.2 4332.4 2902.8 3259.2 3348.0 3364.7 3393.9 584.1 627.8 635.6 649.7 655.2 489.9 637.5 665.3 669.2 678.4 7713.8 8842.6 8966.4 9259.0 9279.4 580.4 582.5 627.1 641.4 665.4 3774.7 3832.8 3971.7 4395.5 4532.1 78064.0 91239.0 107867.5 114846.3 116730.8 79299.0 94083.6 97931.5 99951.0 103733.4 76174.2 76617.0 77288.0 88965.0 89898.6 8946.6 9112.3 9508.4 9924.8 9961.2 37230.0 50967.7 55345.0 55568.4 55849.7 74835.0 74905.9 75884.0 76996.8 78733.6 12403.3 12492.6 12586.6 12605.7 12749.0 13777.7 14195.6 14196.5 14433.3 15127.4 12636.5 13012.3 13752.6 14122.3 14124.9 12874.4 12957.9 13450.7 13501.0 13949.4 12828.9 13628.7 13690.8 13743.7 13798.6 10460.5 10721.7 11223.7 11270.8 11495.1 9476.8 10439.4 11429.8 11562.0 11576.8 09:06:08 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:06:08 INFO - PROCESS | 1654 | 09:06:08 INFO - TEST-INFO | 1654: exit 0 09:06:08 INFO - TEST-OK | dromaeo_css | took 1116170ms 09:06:08 INFO - TEST-START | kraken 09:06:08 INFO - operating with platform_type : mac_ 09:06:08 INFO - Initialising browser for kraken test... 09:06:08 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpMfRVrs/profile http://localhost:49250/getInfo.html) 09:06:11 INFO - PROCESS | 1661 | 2017-01-07 09:06:11.918 plugin-container[1662:11410] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 09:06:11 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:11 INFO - PROCESS | 1661 | 2017-01-07 09:06:11.921 plugin-container[1662:11410] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 09:06:11 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:11 INFO - PROCESS | 1661 | 2017-01-07 09:06:11.921 plugin-container[1662:11410] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:06:11 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 09:06:11 INFO - PROCESS | 1661 | colorDepth:24 09:06:11 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 09:06:11 INFO - PROCESS | 1661 | __metrics 09:06:12 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:06:12 INFO - TEST-INFO | 1661: exit 0 09:06:12 INFO - Browser initialized. 09:06:12 INFO - Running cycle 1/1 for kraken test... 09:06:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpMfRVrs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:15 INFO - PROCESS | 1663 | 2017-01-07 09:06:15.373 plugin-container[1664:11531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8433, name = 'com.apple.tsm.portname' 09:06:15 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:15 INFO - PROCESS | 1663 | 2017-01-07 09:06:15.375 plugin-container[1664:11531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x875f, name = 'com.apple.CFPasteboardClient' 09:06:15 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:15 INFO - PROCESS | 1663 | 2017-01-07 09:06:15.375 plugin-container[1664:11531] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:06:25 INFO - PROCESS | 1663 | RSS: Main: 229789696 09:06:25 INFO - PROCESS | 1663 | 09:06:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:28 INFO - PROCESS | 1663 | RSS: Main: 241750016 09:06:28 INFO - PROCESS | 1663 | 09:06:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 09:06:32 INFO - PROCESS | 1663 | RSS: Main: 249249792 09:06:32 INFO - PROCESS | 1663 | 09:06:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 09:06:35 INFO - PROCESS | 1663 | RSS: Main: 252919808 09:06:35 INFO - PROCESS | 1663 | 09:06:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 09:06:38 INFO - PROCESS | 1663 | RSS: Main: 253276160 09:06:38 INFO - PROCESS | 1663 | 09:06:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:41 INFO - PROCESS | 1663 | RSS: Main: 253313024 09:06:41 INFO - PROCESS | 1663 | 09:06:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:44 INFO - PROCESS | 1663 | RSS: Main: 257208320 09:06:44 INFO - PROCESS | 1663 | 09:06:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:48 INFO - PROCESS | 1663 | RSS: Main: 256868352 09:06:48 INFO - PROCESS | 1663 | 09:06:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 09:06:51 INFO - PROCESS | 1663 | RSS: Main: 258297856 09:06:51 INFO - PROCESS | 1663 | 09:06:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:54 INFO - PROCESS | 1663 | RSS: Main: 257077248 09:06:54 INFO - PROCESS | 1663 | 09:06:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:56 INFO - PROCESS | 1663 | RSS: Main: 257159168 09:06:56 INFO - PROCESS | 1663 | 09:06:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:59 INFO - PROCESS | 1663 | RSS: Main: 257220608 09:06:59 INFO - PROCESS | 1663 | 09:07:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:03 INFO - PROCESS | 1663 | RSS: Main: 257667072 09:07:03 INFO - PROCESS | 1663 | 09:07:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:07 INFO - PROCESS | 1663 | RSS: Main: 258162688 09:07:07 INFO - PROCESS | 1663 | 09:07:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 09:07:09 INFO - PROCESS | 1663 | RSS: Main: 259530752 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | __start_tp_report 09:07:09 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:07:09 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:07:09 INFO - PROCESS | 1663 | |i|pagename|runs| 09:07:09 INFO - PROCESS | 1663 | |0;ai-astar;108;120;119;122;119;122;122;121;121;121 09:07:09 INFO - PROCESS | 1663 | |1;audio-beat-detection;141;111;112;143;112;114;130;116;146;112 09:07:09 INFO - PROCESS | 1663 | |2;audio-dft;196;201;202;198;193;197;199;198;200;197 09:07:09 INFO - PROCESS | 1663 | |3;audio-fft;80;60;60;88;59;67;73;83;98;59 09:07:09 INFO - PROCESS | 1663 | |4;audio-oscillator;82;71;89;99;90;71;87;68;73;88 09:07:09 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;121;90;93;90;95;76;121;90;91;89 09:07:09 INFO - PROCESS | 1663 | |6;imaging-darkroom;99;99;98;98;99;97;97;103;130;99 09:07:09 INFO - PROCESS | 1663 | |7;imaging-desaturate;119;106;134;82;142;141;90;89;88;87 09:07:09 INFO - PROCESS | 1663 | |8;json-parse-financial;56;58;57;58;60;59;60;64;51;56 09:07:09 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;76;79;73;70;71;75;63;71;83 09:07:09 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;77;96;105;84;87;101;106;106;84;86 09:07:09 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;147;157;133;144;151;148;179;158;221;176 09:07:09 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;169;170;167;185;230;228;193;169;165;166 09:07:09 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;70;70;68;70;69;81;91;89;87;81 09:07:09 INFO - PROCESS | 1663 | __end_tp_report 09:07:09 INFO - PROCESS | 1663 | __start_cc_report 09:07:09 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,977 09:07:09 INFO - PROCESS | 1663 | __end_cc_report 09:07:09 INFO - PROCESS | 1663 | __startTimestamp1483808829171__endTimestamp 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:07:09 INFO - PROCESS | 1663 | Number of tests: 14 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:119.50 Median:121.00 stddev:4.20 (3.5%) stddev-sans-first:1.20 09:07:09 INFO - PROCESS | 1663 | Values: 108.0 120.0 119.0 122.0 119.0 122.0 122.0 121.0 121.0 121.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:123.00 stddev:14.66 (11.9%) stddev-sans-first:14.15 09:07:09 INFO - PROCESS | 1663 | Values: 141.0 111.0 112.0 143.0 112.0 114.0 130.0 116.0 146.0 112.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:198.10 Median:198.50 stddev:2.60 (1.3%) stddev-sans-first:2.65 09:07:09 INFO - PROCESS | 1663 | Values: 196.0 201.0 202.0 198.0 193.0 197.0 199.0 198.0 200.0 197.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:72.70 Median:76.50 stddev:14.00 (18.3%) stddev-sans-first:14.60 09:07:09 INFO - PROCESS | 1663 | Values: 80.0 60.0 60.0 88.0 59.0 67.0 73.0 83.0 98.0 59.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:87.50 stddev:10.44 (11.9%) stddev-sans-first:11.08 09:07:09 INFO - PROCESS | 1663 | Values: 82.0 71.0 89.0 99.0 90.0 71.0 87.0 68.0 73.0 88.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:90.00 stddev:14.30 (15.9%) stddev-sans-first:11.85 09:07:09 INFO - PROCESS | 1663 | Values: 121.0 90.0 93.0 90.0 95.0 76.0 121.0 90.0 91.0 89.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:98.50 stddev:10.02 (10.2%) stddev-sans-first:10.57 09:07:09 INFO - PROCESS | 1663 | Values: 99.0 99.0 98.0 98.0 99.0 97.0 97.0 103.0 130.0 99.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:107.80 Median:84.50 stddev:24.15 (28.6%) stddev-sans-first:25.27 09:07:09 INFO - PROCESS | 1663 | Values: 119.0 106.0 134.0 82.0 142.0 141.0 90.0 89.0 88.0 87.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.50 stddev:3.38 (5.8%) stddev-sans-first:3.52 09:07:09 INFO - PROCESS | 1663 | Values: 56.0 58.0 57.0 58.0 60.0 59.0 60.0 64.0 51.0 56.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:74.00 stddev:5.69 (7.7%) stddev-sans-first:5.75 09:07:09 INFO - PROCESS | 1663 | Values: 68.0 76.0 79.0 73.0 70.0 71.0 75.0 63.0 71.0 83.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:84.00 stddev:10.84 (12.9%) stddev-sans-first:9.79 09:07:09 INFO - PROCESS | 1663 | Values: 77.0 96.0 105.0 84.0 87.0 101.0 106.0 106.0 84.0 86.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:161.40 Median:157.50 stddev:25.19 (16.0%) stddev-sans-first:26.17 09:07:09 INFO - PROCESS | 1663 | Values: 147.0 157.0 133.0 144.0 151.0 148.0 179.0 158.0 221.0 176.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:177.50 stddev:25.28 (14.2%) stddev-sans-first:26.21 09:07:09 INFO - PROCESS | 1663 | Values: 169.0 170.0 167.0 185.0 230.0 228.0 193.0 169.0 165.0 166.0 09:07:09 INFO - PROCESS | 1663 | 09:07:09 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.60 Median:81.00 stddev:9.19 (11.3%) stddev-sans-first:9.33 09:07:09 INFO - PROCESS | 1663 | Values: 70.0 70.0 68.0 70.0 69.0 81.0 91.0 89.0 87.0 81.0 09:07:09 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:07:09 INFO - PROCESS | 1663 | 09:07:10 INFO - TEST-INFO | 1663: exit 0 09:07:10 INFO - TEST-OK | kraken | took 61200ms 09:07:10 INFO - SUITE-END | took 1177s 09:07:10 INFO - Completed test suite (00:19:37) 09:07:10 INFO - javascript benchmark 09:07:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7123.90736390402, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2737.698853126156, 3294.5881764779397, 3301.2077101495647, 3324.16895776056, 3324.6010478742546, 4698.271767875976, 5445.292112025436, 5557.27755391799, 5623.216646948292, 5685.289338832646, 4775.450823155338, 5753.539716822645, 5803.100519419486, 5840.941590584093, 5885.8822823543505, 5205.505476979681, 5252.684838909678, 5260.316158899338, 5304.973475132619, 5345.518903298703, 4311.223979683656, 4979.402471703401, 5202.479752024786, 5315.468453154673, 5355.839324820262, 4683.742394168319, 5099.209622508517, 5151.8196863109615, 5231.973840130775, 5240.213967904845, 5075.847724568269, 5154.613403994678, 5184.144616138333, 5200.739963001834, 5215.608829337815, 5052.393712754456, 5079.314292570492, 5108.974455127738, 5113.948860511422, 5149.588032957355, 5515.007298686235, 5692.0608099663505, 5720.599558030939, 5748.770049198027, 5795.3335366432975, 6122.1735065766, 6145.12573665032, 6162.815115546541, 6224.066390041485, 6236.220472440945, 6197.287311959138, 6223.284322302948, 6232.283287421914, 6244.3755624437645, 6250.812475625738, 5657.830265092054, 5725.1698503716725, 5821.79623713168, 5857.907377694506, 5870.412958704095, 6141.455323060992, 6175.536834737323, 6201.286852012076, 6206.007038873759, 6243.063540468966, 6128.754849805958, 6135.0, 6141.84645383869, 6154.138420621117, 6189.6286222826775, 6214.875702485925, 6215.81352559424, 6235.438810507076, 6235.625862448177, 6245.75016999324, 5971.343152253248, 6114.510839132859, 6127.509799216052, 6166.845828854314, 6185.41238582334, 6118.253719382578, 6124.960759790135, 6188.133661287423, 6189.133521306931, 6195.31851496335, 4897.314375987365, 4916.655834091651, 4933.851984440473, 4936.308916751657, 4946.035523073038, 4414.293713005968, 4415.183191109655, 4427.645788336989, 4439.66702497314, 4458.8629899375455, 4495.77521123949, 5439.972800135974, 5612.0, 5612.607117501817, 5704.201411802351, 6129.11429013075, 6132.630864714964, 6197.03945888388, 6199.690015499207, 6201.658908760061, 5630.761232528837, 5646.237757902631, 5672.886542269214, 5719.942800571941, 5726.0, 4648.697834640738, 5385.7576409061685, 5386.892262154735, 5398.028354896156, 5405.918911216335, 5188.896222075613, 6141.36327073057, 6207.037909124093, 6223.906641400473, 6229.2524897012645, 4845.370101886732, 5060.822871199491, 5574.358948720949, 5953.255843019622, 6143.542582966849, 6204.976178930607, 6218.160548325918, 6221.253449586078, 6225.066240063846, 6268.655223962726, 5211.530962213419, 5697.544196464356, 5776.584736739358, 6035.426634469718, 6206.658633775186, 4664.883377915579, 6112.685862051747, 6150.630962142286, 6160.291566469913, 6240.063990401476, 6113.51091912637, 6167.784127555514, 6181.1655426516845, 6194.101855231056, 6201.224846894138, 6093.177421048279, 6139.631622102688, 6180.381961803783, 6196.783112591218, 6209.223847019122, 5466.207399926909, 5665.866826634607, 5805.41945805433, 5872.942870283219, 5991.011483105413, 6054.7275372607455, 6096.146539484387, 6096.939030609815, 6102.023676211785, 6110.4980065817745, 5910.290765108214, 5910.734016969161, 5916.230889984275, 5921.822345329647, 5939.020061689773], "value": 5591.171878953237, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.36064394998607, 490.61731849157667, 519.5142541723488, 550.246162757023, 551.0466892276362, 29330.0, 38643.420348694795, 38646.033849153784, 38688.83933481993, 38697.806510967406, 38980.80509597448, 39480.21039579207, 39569.40645890307, 39598.80200598993, 39616.0, 9110.817783644323, 9282.350235483518, 9287.674931377403, 9322.021187775288, 9342.813143737121, 9263.305252106085, 9335.626574936994, 9346.719598412059, 9359.906400935972, 9372.578233979453, 9321.300902432311, 9332.253419726405, 9335.393199442015, 9367.15695587397, 9378.577963991602, 9271.721848344527, 9326.813463730687, 9353.204977576881, 9389.201917836992, 9394.436333819993, 9135.314851386172, 9186.770330741718, 9224.907750922472, 9236.815263694756, 9284.350095493319, 37435.625643743486, 37752.0, 37831.05422364435, 37903.81048094769, 37904.620953790385, 3272.2473831018997, 3319.751018673609, 3373.578302712161, 3758.8684396046006, 3821.4458903458844, 28132.0, 28187.0, 28787.136385908456, 28887.277818054506, 29537.852310738523, 3092.9536056959164, 3181.9681803182134, 3226.1611980884904, 3233.7898036627544, 3248.399046176441, 487865.0, 489806.0, 491580.0, 494754.0, 496175.0, 59239.40760592425, 59581.70209149013, 59586.70206648939, 59894.80210395768, 59938.70030649819, 62443.6877815608, 62449.37550624526, 62575.6871215641, 62808.68595656993, 63871.6806415965, 16051.598710032342, 16268.75596866048, 16293.429729959395, 16327.938683985503, 16331.42840000618, 12287.201331913397, 12385.62843114708, 12448.81326780099, 12464.065195110403, 12519.123661343801, 17305.30778768835, 18357.7246341305, 18369.26522939094, 18402.26390944374, 18412.81587184138, 7498.837680159584, 7709.38324934004, 7831.960840195762, 7848.960755196187, 7851.489653172525, 829.7635173975387, 919.8114386550875, 923.8013827027221, 990.9693918325005, 1051.673981065872, 28947.56578651322, 28977.13068607903, 29444.411111778074, 29807.40385192284, 29954.55068173979, 839.5004972041626, 896.7444278380631, 933.7945651956559, 972.8005758819424, 1165.7551914097965, 14768.556943291718, 15105.018173818886, 15323.23383830804, 15564.143972081643, 15568.610784730472, 687835.0, 693662.0, 696413.0, 698319.0, 704132.0, 55000.0, 55418.0, 55432.99999999919, 55601.1659825103, 55815.44184558184, 1337.6187786481, 1339.9330033498286, 1375.4498200719593, 1376.3462355381114, 1380.2615600653803, 28345.574816377357, 29013.709862901946, 29060.418791623626, 29098.41803163967, 29407.85296073506, 47063.529364705915, 47325.52674473211, 47801.04397912092, 47896.0, 48063.0, 609910.0, 614214.0, 615098.0, 616240.0, 616476.0, 25794.871025644752, 26001.739982600688, 26002.479950401263, 26127.869360653076, 26134.607980879922, 37892.43161352527, 40843.59156408398, 42057.57942420537, 44496.33255501235, 45900.0, 37455.625443745215, 37629.81185094057, 37814.810925945196, 38188.42717359297, 38307.425388618634, 37146.07134821651, 37610.05974850651, 38233.617663823, 38486.0, 38823.805880970416, 35812.0, 36180.81909590435, 36859.0, 37148.62851371452, 37233.6276637241, 31865.0, 33231.0874028697, 33604.663953360156, 33776.83111584427, 34026.14934626555, 24776.0, 25014.999400024524, 25066.74933250717, 25365.0, 25509.744902550738], "value": 19539.340660695052, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8383791941948, 370.11542413631423, 376.886933919824, 389.3595036165506, 395.49376797698955, 18314.35899743509, 18812.435626931197, 19854.90072549637, 19900.30348937784, 20094.0, 19325.0, 19691.606167876635, 19706.901465492687, 19755.81465112094, 19757.0, 5595.104783234683, 5637.098064309701, 5839.708014599253, 5872.265966754156, 5880.059190529516, 5643.209950606918, 5811.709414529278, 5818.127280907876, 5821.243238379005, 5831.679257640828, 5589.496945274925, 5607.43925607438, 5780.50865676418, 5781.075027995501, 5783.855403614923, 5739.913901291484, 5790.189373487715, 5808.041673123929, 5817.41825817417, 5820.825375238728, 4867.805287788484, 5019.548240658321, 5094.439611642716, 5107.284980102789, 5110.821121260738, 21445.356639300848, 21494.355169344944, 24458.0, 24687.753122468905, 24867.50264994708, 2219.7447293561286, 2228.821694264455, 2294.300238427279, 2456.6192240202795, 2979.3594377208788, 14105.64735881601, 14656.853431465655, 14853.702925941529, 15091.019083759631, 15234.771478427832, 1809.7013992691323, 1811.7735283089614, 1882.0401595186554, 1908.5419499320203, 1921.3371386871506, 105877.47061264722, 119920.0, 120351.0, 120736.79263207255, 122491.71279005281, 10615.84076238857, 10799.622013229657, 11250.156238282161, 11327.433628318717, 11530.942345288388, 10976.560937562408, 11831.467583958742, 13050.282234477196, 13384.732305353838, 13606.523771667944, 7262.527935684164, 7787.987561616954, 8004.279614834692, 8131.512109273463, 8228.30059444942, 8104.432689711663, 8307.750767476986, 8463.873041904377, 8477.448965817326, 8593.484390936565, 5472.50747432725, 6580.934190658128, 6627.105340778933, 6627.834304142339, 6668.899966500507, 1579.3998280653277, 1624.3908534299637, 1897.4820143884838, 1900.6958886578082, 1906.5614908571106, 751.5678484871221, 854.1287886356006, 868.2446271743497, 928.8885333759857, 975.7121649113528, 18734.906325468284, 19617.0, 19632.803671963382, 21162.894185529283, 21319.573608527742, 920.4201353147474, 926.1525703980917, 939.4832841936908, 949.9715008549755, 954.7994921066515, 10839.566417343376, 11276.01764837846, 11414.143939204592, 11430.485628146753, 11465.0, 506694.0, 619204.0, 621336.0, 623285.0, 633378.0, 105681.0, 116468.0, 131978.0, 138270.0, 147588.52411475749, 940.9811803763887, 969.3553786731885, 984.0897170117613, 988.2489308125732, 1001.8747656542787, 17982.370617028864, 18487.16807743628, 18502.0, 18619.81380186235, 18739.250429982647, 45666.543334567556, 55628.721856392076, 56558.0, 57844.71077644585, 58892.70553647204, 85191.29617407493, 86583.0, 87714.0, 88325.0, 90946.54526727324, 8001.119876813429, 8087.433879628605, 8191.918080819354, 8295.792605184919, 8392.4544904581, 12276.693082673004, 12742.0, 12841.036922230684, 13075.215487070805, 13075.346232688138, 5456.91814622773, 8997.865032024656, 11119.999200072032, 11142.387168705798, 11375.26060806045, 9761.658341958282, 10237.84643230367, 11053.392063436588, 11143.33140011602, 11458.427078645867, 10876.02115809581, 10956.397398142859, 11127.554897803997, 11347.886521134684, 11498.310101393943, 7796.844063118592, 7965.0, 7988.121306656147, 8031.397645176752, 8218.137095604876, 8742.34432417584, 9133.949595796568, 10172.338797978404, 10533.36799792015, 10665.840012399665], "value": 9540.086829643793, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.551578673784, 1444.1696024785747, 1589.5708158797124, 1759.9208035638394, 1778.5197996540935, 7498.775036748906, 7736.535807851519, 8099.7165099221465, 8535.0, 8549.658013679446, 8025.638846251918, 8204.466709663868, 8254.876176857351, 8456.957715211416, 8489.830203395928, 1912.2924517928386, 2317.432229103872, 2357.174988753931, 2363.6927199464044, 2383.0, 2352.1179557665873, 2390.2590197038885, 2402.7116745990506, 2403.4231784371714, 2403.663487111806, 2329.9883500582473, 2334.708161479815, 2358.3868194269444, 2363.361892289081, 2363.846349987254, 2330.0912644068744, 2340.028888011473, 2352.764723527651, 2358.50471401006, 2369.7630236976424, 2360.197532838843, 2377.1323466934446, 2387.6060450025725, 2387.6418537219556, 2399.8200134989947, 8068.193180681944, 8442.493450392996, 8495.830083398298, 8499.320054395634, 8566.271866891322, 4582.908341833178, 5131.025105229995, 5186.766595503173, 5187.922181167285, 5194.818181363634, 3324.3018966017166, 3487.302539492112, 3608.4226523756333, 3608.711303095746, 3659.652333028358, 1043.12377602814, 1059.2691043180182, 1095.8137116690182, 1101.5648818716643, 1107.1143085531542, 10840.512176952056, 11010.284331518345, 11017.779644407066, 11034.393108379036, 11048.447577621168, 4537.6143027842345, 4652.883677908079, 4749.905001899942, 4793.400824896888, 4802.327674125625, 8448.0, 9456.669016584525, 9457.763555911157, 9468.668596599086, 9703.417794932186, 5238.2404551340605, 5254.448282930313, 5258.526732594085, 5265.368155821326, 5268.78924843002, 4109.671226301948, 4764.737939413296, 4775.044991001813, 4813.446453657804, 4814.253790662453, 3842.269968705937, 3869.052082239813, 3938.6849052076336, 3971.0866500705, 3975.363941769303, 1260.6470188347278, 1260.6785269756338, 1263.444084602772, 1266.1390254626758, 1274.3182397417338, 764.9502782319131, 798.0104670208899, 861.5132450165636, 894.6466145872344, 897.5736525150631, 5469.617126801086, 5527.115661494142, 5563.831595364938, 5779.248697669276, 5799.420057994167, 391.43046866808726, 446.7453551475693, 446.8838102093479, 453.50114873639393, 457.78941686824436, 3786.6402691744242, 3790.298794722985, 3834.827432765532, 3858.977370996688, 3914.2758589661003, 9862.852057219148, 10356.948215258875, 10357.533910974023, 10391.064804167661, 10398.844017339747, 10302.484875756183, 10316.226283028804, 10324.587016519405, 10324.793504129875, 10331.483425828828, 6939.0, 7814.335781458526, 7941.64262608184, 8007.839843203104, 8039.47743396689, 4709.505501922249, 5510.972445137749, 5599.944000560105, 5622.662640241599, 5752.13717942312, 9316.48759318244, 9334.579943902405, 9433.764155896157, 9494.572744226389, 9567.521623918637, 9547.42715437076, 9610.615575377185, 9614.9038509614, 9723.56243969009, 9738.026197380204, 6557.868842623026, 6624.337566243337, 7065.752698655522, 7209.927900721135, 7297.635118244173, 7998.5600791957, 8070.556119413253, 8073.15231900642, 8089.271965523131, 8092.71675491355, 7104.715811367488, 7129.536580122275, 7214.675339609626, 7231.3130252625915, 7341.522801017917, 7016.36852683261, 7150.499465037397, 7175.856482870209, 7269.963650181715, 7293.0, 6787.423069039187, 6804.6257455840405, 6841.62371069596, 6845.315468453115, 6846.897296540456, 4806.375171227858, 4886.853394398174, 4902.754862256802, 4911.582515486224, 4980.551750342486, 4122.0107174278555, 4463.843765468193, 4829.13075646387, 4852.975735121302, 4873.293372461044], "value": 4358.499394799429, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9027511196417, 307.3330872007743, 310.3188501239778, 313.6643791143476, 313.8791565247379, 4593.655475839309, 5285.86785330366, 5406.945930540694, 5430.3755068167175, 5437.048516509605, 4846.539578740023, 4940.505044952294, 5306.310179676637, 5323.494268044539, 5332.440093790155, 3445.8277086145717, 3837.656820112966, 3974.383970484565, 3992.4011398290345, 4068.4710987571575, 3371.853569786272, 4044.1911617676437, 4053.250148722481, 4090.897727556805, 4096.6927480438935, 4058.585141840615, 4078.6125318094882, 4081.1429599784083, 4132.938005929914, 4142.937855932163, 3248.59392575928, 3997.0, 4007.238624661334, 4071.9389209161886, 4078.5105787305415, 2905.6513218413925, 3440.501127336522, 3459.238967427162, 3462.549868517102, 3487.0, 4950.257461380786, 5134.358205224347, 5170.811642569531, 5183.922241166386, 5216.817411390621, 4317.438732964726, 4590.898184435711, 4619.399478067863, 4622.9075418491775, 4656.0, 4906.607471402279, 5016.949830501685, 5593.412691667357, 5607.355154157283, 5741.0, 3835.4822099016556, 3838.7120965927666, 3839.462475253467, 3853.9807300963316, 3867.8259478323544, 4664.766761661904, 4952.108620448318, 5440.727963601844, 5791.594588378815, 5879.0, 3262.624798148196, 3315.93368132636, 3684.8710295140077, 3808.0, 3815.389537673959, 3310.6358300586917, 3949.822257998397, 3953.7034722395933, 3959.208158368338, 3967.4048892666333, 4000.6199411055904, 4092.2429350569646, 4576.078047220176, 4577.656675749332, 4597.6896634423165, 5068.290439338495, 5330.840074797762, 5573.386927437979, 5578.63738856973, 5707.771689132388, 1221.5113954418305, 1267.2333238390825, 1283.608499407671, 1289.1427200911476, 1293.5084667826354, 690.3752104345475, 721.055417403193, 816.3917881178442, 865.264525153627, 875.6646114675044, 4750.5249475052215, 4768.33108412053, 5056.595472362203, 5108.773894265424, 5379.757910894019, 4775.73733444657, 5465.781368745285, 5504.587155963319, 5818.2436283282905, 5843.487300460758, 4710.976445117821, 4771.5228477152, 5271.525562699376, 5306.867328316823, 5360.0083984463, 2750.2024412920027, 2757.379589592326, 2824.8587570621385, 2905.7675385969073, 2914.1548950804454, 5378.273933019071, 5695.689991301941, 5697.373288938214, 5707.315122185364, 5723.54211663066, 4957.20684690455, 5079.212722028018, 5523.917141242885, 5652.745626446737, 5657.2928383952, 4502.617277531446, 5255.894882102337, 5590.664560126324, 5704.543636509069, 5734.3692193857805, 4799.368214806982, 4821.421429428491, 4851.805927762991, 5320.44135365781, 5547.750351234285, 4966.832794293266, 5122.923156152586, 5253.422123566328, 5416.106342453451, 5472.91790623149, 5071.568916641979, 5089.720065396439, 5692.31692196939, 5707.514861236841, 5740.3685594585395, 3386.5428167197115, 3684.59469458354, 3703.833327500215, 3703.8703645372284, 3720.377134120493, 4526.736631684158, 4931.926021109614, 5853.824385268449, 5872.94127058741, 5880.735916672026, 4356.411884395693, 5396.947595218895, 5724.4561766632105, 5734.426557344233, 5749.453801888814, 5020.949790502048, 5376.301080859464, 5748.568857335633, 5750.7284622573015, 5802.245708057925, 4550.749708765918, 5566.721663916707, 5612.045952188219, 5627.465390787868, 5641.26663533738, 5521.5306698931045, 5575.776968921197, 5613.691246981291, 5761.193432919478, 5777.191193233035, 4774.761261936958, 5340.706261155674, 5617.494425501724, 5632.324121105494, 5639.802606908738], "value": 4011.0976216615277, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.5220660607848, 669.6538967526235, 677.0386051806438, 758.5600351795958, 765.2538774694673, 13939.163650180997, 14882.330295136719, 14885.404583816662, 14899.106053636775, 14948.103113813166, 13387.59837204883, 13539.729205415886, 13550.18698878068, 13555.59333220005, 13589.980751443656, 639.7536948274925, 654.9050387693782, 793.0562630469738, 813.3046245460106, 814.6782021101661, 789.159545084486, 805.2390490986021, 818.4843548564426, 820.9630566624517, 823.8599438095508, 852.9360297977645, 855.5209082913589, 858.1161403754112, 860.1269711243093, 862.9137086291352, 847.3475423923607, 850.0479463001478, 851.0425771007616, 851.6678495386835, 851.9701810436636, 640.5708175522411, 640.5868215001298, 641.4547634510675, 642.9003504456816, 643.009764961958, 13562.525311614047, 13585.252811095386, 13744.312784360842, 13744.65638359049, 13751.862481375258, 10849.620263290826, 11546.36494992775, 12623.684407889785, 12894.290814005226, 13393.330333483384, 11065.225434219608, 11138.721531961684, 11193.440327983568, 11271.26736762116, 11271.943640281828, 3534.964650353489, 3539.3983022886173, 3539.539859818233, 3633.1280492681567, 3684.0053185639727, 60520.69739651273, 78659.42681146403, 82150.0, 83025.0, 89681.0, 10139.898601014043, 10204.336718113298, 10468.371897686164, 10513.789724205473, 10721.678349649523, 19732.0, 19735.605287894163, 19744.30894918671, 19825.405237842886, 19831.30590429328, 7408.222136675571, 7610.847783044308, 7804.0, 7837.177096404909, 7845.0585929688805, 4123.670106391482, 4285.807138678767, 4292.871213863589, 4320.222359975234, 4332.393464914914, 2902.782291328159, 3259.1852036990754, 3347.966520334814, 3364.6635336466156, 3393.9490907636405, 584.1238142785821, 627.8322320483896, 635.5700765663411, 649.7336092202174, 655.1646650520624, 489.87997940504806, 637.528229110455, 665.2947875252248, 669.1869378704799, 678.375894177358, 7713.8457230855065, 8842.557872106368, 8966.378558401355, 9258.95370523143, 9279.39683920543, 580.3616022375353, 582.5146372928384, 627.0813258576208, 641.377863472431, 665.351282499559, 3774.735768496234, 3832.80835958201, 3971.72197946141, 4395.516493185747, 4532.0709254602725, 78064.0, 91239.0, 107867.46066269619, 114846.27730584049, 116730.832691672, 79299.0, 94083.58874616749, 97931.51034244926, 99951.0, 103733.44399834008, 76174.23825761782, 76617.0, 77288.0, 88965.0, 89898.55050724835, 8946.642134314814, 9112.316576256675, 9508.381955172892, 9924.751881203028, 9961.153301969414, 37230.0, 50967.74516127395, 55345.0, 55568.44431555633, 55849.72075139598, 74835.0, 74905.87641185496, 75884.0, 76996.84504732545, 78733.60633196797, 12403.317817519763, 12492.625221243377, 12586.559470418491, 12605.684857878627, 12749.043821713223, 13777.724445510834, 14195.64510887236, 14196.503122390666, 14433.278336082943, 15127.405173829022, 12636.494540218288, 13012.28432436225, 13752.587422377344, 14122.293885305899, 14124.92937535347, 12874.356282186041, 12957.935210323889, 13450.663733406744, 13501.0, 13949.372278247707, 12828.935855320664, 13628.659283517594, 13690.794638080635, 13743.656408589864, 13798.586042418743, 10460.537409365654, 10721.678349649523, 11223.663290100983, 11270.83093753578, 11495.080393568662, 9476.763080923032, 10439.37363758177, 11429.828552571551, 11562.0, 11576.768464630828], "value": 7172.923301384118, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 119.0, 122.0, 119.0, 122.0, 122.0, 121.0, 121.0, 121.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 112.0, 143.0, 112.0, 114.0, 130.0, 116.0, 146.0, 112.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 202.0, 198.0, 193.0, 197.0, 199.0, 198.0, 200.0, 197.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 88.0, 59.0, 67.0, 73.0, 83.0, 98.0, 59.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 89.0, 99.0, 90.0, 71.0, 87.0, 68.0, 73.0, 88.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 90.0, 93.0, 90.0, 95.0, 76.0, 121.0, 90.0, 91.0, 89.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 98.0, 98.0, 99.0, 97.0, 97.0, 103.0, 130.0, 99.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 106.0, 134.0, 82.0, 142.0, 141.0, 90.0, 89.0, 88.0, 87.0], "value": 107.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 58.0, 60.0, 59.0, 60.0, 64.0, 51.0, 56.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 79.0, 73.0, 70.0, 71.0, 75.0, 63.0, 71.0, 83.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 96.0, 105.0, 84.0, 87.0, 101.0, 106.0, 106.0, 84.0, 86.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 157.0, 133.0, 144.0, 151.0, 148.0, 179.0, 158.0, 221.0, 176.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 167.0, 185.0, 230.0, 228.0, 193.0, 169.0, 165.0, 166.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 70.0, 69.0, 81.0, 91.0, 89.0, 87.0, 81.0], "value": 77.6, "unit": "score"}]}]} 09:07:10 INFO - Return code: 0 09:07:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:07:10 ERROR - # TBPL SUCCESS # 09:07:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:10 INFO - 2017-01-07 09:07:10.474 screenresolution[1668:11935] starting screenresolution argv=screenresolution get 09:07:10 INFO - 2017-01-07 09:07:10.477 screenresolution[1668:11935] Display 0: 1600x1200x32@60 09:07:10 INFO - 2017-01-07 09:07:10.483 screenresolution[1669:11938] starting screenresolution argv=screenresolution list 09:07:10 INFO - Available Modes on Display 0 09:07:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:10 INFO - Intel Iris: 09:07:10 INFO - Chipset Model: Intel Iris 09:07:10 INFO - Type: GPU 09:07:10 INFO - Bus: Built-In 09:07:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:10 INFO - Vendor: Intel (0x8086) 09:07:10 INFO - Device ID: 0x0a2e 09:07:10 INFO - Revision ID: 0x0009 09:07:10 INFO - Displays: 09:07:10 INFO - Display: 09:07:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:10 INFO - Display Serial Number: 1600x1200 60 09:07:10 INFO - Main Display: Yes 09:07:10 INFO - Mirror: Off 09:07:10 INFO - Online: Yes 09:07:10 INFO - Rotation: Supported 09:07:10 INFO - Return code: 0 09:07:10 INFO - Running post-action listener: _resource_record_post_action 09:07:10 INFO - [mozharness: 2017-01-07 17:07:10.730140Z] Finished run-tests step (success) 09:07:10 INFO - Running post-run listener: _resource_record_post_run 09:07:10 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 34562560; Write bytes: 662571520; Read time: 322; Write time: 2044 09:07:10 INFO - TinderboxPrint: CPU usage
54.6% 09:07:10 INFO - TinderboxPrint: I/O read bytes / time
34,562,560 / 322 09:07:10 INFO - TinderboxPrint: I/O write bytes / time
662,571,520 / 2,044 09:07:10 INFO - TinderboxPrint: CPU idle
2,199.5 (45.4%) 09:07:10 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 09:07:10 INFO - TinderboxPrint: CPU user
1,656.9 (34.2%) 09:07:10 INFO - TinderboxPrint: Swap in / out
380,346,368 / 0 09:07:10 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 389120; Write bytes: 352572416; Read time: 12; Write time: 670 09:07:10 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 30048768; Write bytes: 308422144; Read time: 283; Write time: 1368 09:07:11 INFO - Running post-run listener: _upload_blobber_files 09:07:11 INFO - Blob upload gear active. 09:07:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:11 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 09:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:11 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:07:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:07:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:12 INFO - (blobuploader) - INFO - Done attempting. 09:07:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:07:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:13 INFO - (blobuploader) - INFO - Done attempting. 09:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:16 INFO - (blobuploader) - INFO - Done attempting. 09:07:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:16 INFO - Return code: 0 09:07:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009df3c58a41a647d7998784f748b4dd4aa6670faa5696334fe94631259b0451fbc03609a1b2ed30eb82056ba738f2bf77fbf9c07fa9339333a7d886514ff9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a96413b0faf1a26c65ef8c3e4c61de5d6ca1eebf21f936a2ea03c93084618fac10719058223126859905938307a11d3576cc9501d987f6f17f4a2475de1d53ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db6eafe433f239026dd9f3faf209946408d407b0806ca6eb3fc362543f8ec83cfbfcb7c69f1ac20807131a96f01e04b92630203b568da2c433f1a2c07fd8ecf2"} 09:07:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:16 INFO - Contents: 09:07:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009df3c58a41a647d7998784f748b4dd4aa6670faa5696334fe94631259b0451fbc03609a1b2ed30eb82056ba738f2bf77fbf9c07fa9339333a7d886514ff9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a96413b0faf1a26c65ef8c3e4c61de5d6ca1eebf21f936a2ea03c93084618fac10719058223126859905938307a11d3576cc9501d987f6f17f4a2475de1d53ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db6eafe433f239026dd9f3faf209946408d407b0806ca6eb3fc362543f8ec83cfbfcb7c69f1ac20807131a96f01e04b92630203b568da2c433f1a2c07fd8ecf2"} 09:07:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:16 INFO - Copying logs to upload dir... 09:07:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.584276 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-07 09:07:16.240938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:16.245325) ========= 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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009df3c58a41a647d7998784f748b4dd4aa6670faa5696334fe94631259b0451fbc03609a1b2ed30eb82056ba738f2bf77fbf9c07fa9339333a7d886514ff9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a96413b0faf1a26c65ef8c3e4c61de5d6ca1eebf21f936a2ea03c93084618fac10719058223126859905938307a11d3576cc9501d987f6f17f4a2475de1d53ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db6eafe433f239026dd9f3faf209946408d407b0806ca6eb3fc362543f8ec83cfbfcb7c69f1ac20807131a96f01e04b92630203b568da2c433f1a2c07fd8ecf2"} build_url:https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/NudW4T75SUCktpFslPDHvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009df3c58a41a647d7998784f748b4dd4aa6670faa5696334fe94631259b0451fbc03609a1b2ed30eb82056ba738f2bf77fbf9c07fa9339333a7d886514ff9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a96413b0faf1a26c65ef8c3e4c61de5d6ca1eebf21f936a2ea03c93084618fac10719058223126859905938307a11d3576cc9501d987f6f17f4a2475de1d53ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db6eafe433f239026dd9f3faf209946408d407b0806ca6eb3fc362543f8ec83cfbfcb7c69f1ac20807131a96f01e04b92630203b568da2c433f1a2c07fd8ecf2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:16.280565) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:16.280880) ========= 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.7v8WLmJpH0/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.ceNgxQcjKB/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:16.330325) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:07:16.330605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:07:16.333288) ========= ========= Total master_lag: 0.27 =========